599795 - 'debug test mochitest-other' fails to unpack dmg on 10.5.8

Status: RESOLVED FIXED

(Release Engineering :: General, defect, P2)

Description

[Nick Thomas [:nthomas] (UTC+12)]

.. and yet works for every other debug test on the same talos-r3-leopard-XXX slaves. This is 100% repeatable and weird beyond belief. Even if you take a single prod slave and try each suite consecutively.

bash ../tools/buildfarm/utils/installdmg.sh firefox-4.0b7pre.en-US.mac.dmg
 in dir /Users/cltbld/talos-slave/mozilla-central_leopard-old-debug_test-mochitest-other/build (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '../tools/buildfarm/utils/installdmg.sh', u'firefox-4.0b7pre.en-US.mac.dmg']
 environment:
  Apple_PubSub_Socket_Render=/tmp/launch-SsOw9F/Render
  DISPLAY=/tmp/launch-nRMxip/:0
  HOME=/Users/cltbld
  LOGNAME=cltbld
  PATH=/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/bin:/usr/X11/bin
  PWD=/Users/cltbld/talos-slave/mozilla-central_leopard-old-debug_test-mochitest-other/build
  PYTHONPATH=/Library/Python/2.5/site-packages
  SHELL=/bin/bash
  SSH_AUTH_SOCK=/tmp/launch-6D9zsV/Listeners
  TMPDIR=/var/folders/Xr/Xr--yJnSEY0U11ET5NZuMU+++TM/-Tmp-/
  USER=cltbld
 closing stdin
 using PTY: False
Initializing…
DIBackingStoreInstantiatorProbe: interface  0, score [32m     100[0m, CBSDBackingStore
DIBackingStoreInstantiatorProbe: interface  1, score [31m   -1000[0m, CBundleBackingStore
DIBackingStoreInstantiatorProbe: interface  2, score [31m   -1000[0m, CRAMBackingStore
DIBackingStoreInstantiatorProbe: interface  3, score [32m     100[0m, CCarbonBackingStore
DIBackingStoreInstantiatorProbe: interface  4, score [31m   -1000[0m, CDevBackingStore
DIBackingStoreInstantiatorProbe: interface  5, score [31m   -1000[0m, CCURLBackingStore
DIBackingStoreInstantiatorProbe: interface  6, score [31m   -1000[0m, CVectoredBackingStore
DIBackingStoreInstantiatorProbe: selecting CBSDBackingStore
DIBackingStoreInstantiatorProbe: interface  0, score [32m     100[0m, CBSDBackingStore
DIBackingStoreInstantiatorProbe: interface  1, score [31m   -1000[0m, CBundleBackingStore
DIBackingStoreInstantiatorProbe: interface  2, score [31m   -1000[0m, CRAMBackingStore
DIBackingStoreInstantiatorProbe: interface  3, score [32m     100[0m, CCarbonBackingStore
DIBackingStoreInstantiatorProbe: interface  4, score [31m   -1000[0m, CDevBackingStore
DIBackingStoreInstantiatorProbe: interface  5, score [31m   -1000[0m, CCURLBackingStore
DIBackingStoreInstantiatorProbe: interface  6, score [31m   -1000[0m, CVectoredBackingStore
DIBackingStoreInstantiatorProbe: selecting CBSDBackingStore
DIFileEncodingInstantiatorProbe: interface  0, score [31m   -1000[0m, CMacBinaryEncoding
DIFileEncodingInstantiatorProbe: interface  1, score [31m   -1000[0m, CAppleSingleEncoding
DIFileEncodingInstantiatorProbe: interface  2, score [31m   -1000[0m, CEncryptedEncoding
DIFileEncodingInstantiatorProbe: nothing to select.
DIFileEncodingInstantiatorProbe: interface  0, score [32m     900[0m, CUDIFEncoding
DIFileEncodingInstantiatorProbe: selecting CUDIFEncoding
DIFileEncodingNewWithBackingStore: CUDIFEncoding
DIFileEncodingInstantiatorProbe: interface  0, score [31m   -1000[0m, CSegmentedNDIFEncoding
DIFileEncodingInstantiatorProbe: interface  1, score [31m   -1000[0m, CSegmentedUDIFEncoding
DIFileEncodingInstantiatorProbe: interface  2, score [31m   -1000[0m, DIFileEncodingNewWithBackingStore: instantiator returned 0
CSegmentedUDIFRawEncoding
DIFileEncodingInstantiatorProbe: nothing to select.
DIDiskImageInstantiatorProbe: interface  0, score        0[0m, CDARTDiskImage
DIDiskImageInstantiatorProbe: interface  1, score        0[0m, CDiskCopy42DiskImage
DIDiskImageInstantiatorProbe: interface  2, score [31m   -1000[0m, CNDIFDiskImage
DIDiskImageInstantiatorProbe: interface  3, score [32m    1000[0m, CUDIFDiskImage
DIDiskImageInstantiatorProbe: interface  5, score [31m    -100[0m, CRawDiskImage
DIDiskImageInstantiatorProbe: interface  6, score [31m    -100[0m, CShadowedDiskImage
DIDiskImageInstantiatorProbe: interface  7, score        0[0m, CSparseDiskImage
DIDiskImageInstantiatorProbe: interface  8, score        0[0m, CSparseBundleDiskImageCRawDiskImage: data fork length 0x000000000102779E (16938910) not a multiple of 512.

DIDiskImageInstantiatorProbe: interface  9, score [31m   -1000[0m, CCFPlugInDiskImage
DIDiskImageInstantiatorProbe: interface 10, score [31m    -100[0m, CWrappedDiskImage
DIDiskImageInstantiatorProbe: selecting CUDIFDiskImage
DIDiskImageNewWithBackingStore: CUDIFDiskImage
DIDiskImageNewWithBackingStore: instantiator returned 0
Verifying…
Checksumming Driver Descriptor Map (DDM : 0)…
     Driver Descriptor Map (DDM : 0): verified   CRC32 $297FCE0D
Checksumming Apple (Apple_partition_map : 1)…
     Apple (Apple_partition_map : 1): verified   CRC32 $6A0587BA
Checksumming DiscRecording 4.0.1d4 (Apple_HFS : 2)…
DiscRecording 4.0.1d4 (Apple_HFS : 2: verified   CRC32 $5097995B
Verification completed…
Error 0 (Unknown error: 0).
verified   CRC32 $2114E78F
Attaching…
2010-09-26 18:17:57.036 diskimages-helper[296:246b] -serveImage: attaching drive
{
    autodiskmount = 1;
    "hdiagent-drive-identifier" = "AB1F2386-4139-445D-B312-DD3C83AC6921";
    "unmount-timeout" = 0;
}
2010-09-26 18:17:57.041 diskimages-helper[296:246b] -serveImage: connecting to myDrive 0x00004707
2010-09-26 18:17:57.042 diskimages-helper[296:246b] -serveImage: register _readBuffer 0x0x4a8000 with myDrive 0x0x0
2010-09-26 18:17:57.042 diskimages-helper[296:246b] -serveImage: activating drive port 0x0x4807
2010-09-26 18:17:57.043 diskimages-helper[296:246b] _serveImage: set cache enabled=TRUE returned SUCCESS.
2010-09-26 18:17:57.043 diskimages-helper[296:246b] _serveImage: set on IO thread=TRUE returned SUCCESS.
2010-09-26 18:17:57.043 diskimages-helper[296:246b] -serveImage: starting server loop - myPort is 0x0x4807
Checking volumes…
Volume check completed…
Mounting…
diskimages-helper: DI_kextDriveGetRequest returned 0x00000025 (37) ((os/kern) object terminated).
Attaching…
Error 109 (no mountable file systems).
Finishing…
hdiutil: attach failed - no mountable file systems
DIHLDiskImageAttach() returned 109
DI_kextWaitQuiet: about to call IOServiceWaitQuiet...
DI_kextWaitQuiet: IOServiceWaitQuiet took 0.000009 seconds
No files found, exiting
hdiutil: detach failed - No such file or directory
mnt
program finished with exit code 1
elapsedTime=95.637538

If you look at a log that 'succeeds' then the os/kern error occurs right at the end

Comment 1

[Nick Thomas [:nthomas] (UTC+12)]

I've hidden
 Rev3 MacOSX Leopard 10.5.8 mozilla-central debug test mochitest-other
for now.

Comment 2

[Chris AtLee [:catlee]]

My suspicion is path length issues.

Comment 4

[Nick Thomas [:nthomas] (UTC+12)]

(In reply to comment #2)
> My suspicion is path length issues.

You're right, take a single character off /Users/cltbld/talos-slave/mozilla-central_leopard-old-debug_test-mochitest-other/build and it unpacks OK.

Comment 5

[Nick Thomas [:nthomas] (UTC+12)]

Attachment: Truncate platform name by two chars

Patch that needs testing.

Comment 6

[Nick Thomas [:nthomas] (UTC+12)]

Comment on attachment 483330 [details] [diff] [review]
Truncate platform name by two chars

Works in staging, green builds for all m-c debug tests and m-1.9.2 talos still works fine.

Comment 7

[Nick Thomas [:nthomas] (UTC+12)]

s/all/all 10.5.8/

Comment 8

[Lukas Blakk [:lsblakk] use ?needinfo]

Comment on attachment 483330 [details] [diff] [review]
Truncate platform name by two chars

http://hg.mozilla.org/build/buildbot-configs/rev/4023a03b1203

with one line added for the recent landing of jetpack on m-c:
http://hg.mozilla.org/build/buildbot-configs/rev/4023a03b1203#l1.40

Comment 9

[Nick Thomas [:nthomas] (UTC+12)]

(In reply to comment #1)
> I've hidden
>  Rev3 MacOSX Leopard 10.5.8 mozilla-central debug test mochitest-other
> for now.

This is undone. It's orange because of bug 605183 but otherwise OK.