853007 - Could not successfully complete transport of message to Gecko, socket closed?

Status: RESOLVED DUPLICATE of bug 834016

(Testing :: General, defect)

Description

[Ralph Giles (:rillian)]

On a try job today I got some random failures on B2G Arm opt ref tests:

18:17:56     INFO -  restarting B2G
18:17:56     INFO -  Traceback (most recent call last):
18:17:56     INFO -    File "runreftestb2g.py", line 580, in <module>
18:17:56     INFO -      sys.exit(main())
18:17:56     INFO -    File "runreftestb2g.py", line 500, in main
18:17:56     INFO -      marionette = Marionette.getMarionetteOrExit(**kwargs)
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/marionette.py", line 240, in getMarionetteOrExit
18:17:56     INFO -      m = cls(*args, **kwargs)
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/marionette.py", line 215, in __init__
18:17:56     INFO -      busybox=busybox)
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/emulator.py", line 347, in setup
18:17:56     INFO -      self.wait_for_system_message(marionette)
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/emulator.py", line 250, in wait_for_system_message
18:17:56     INFO -      marionette.start_session()
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/marionette.py", line 383, in start_session
18:17:56     INFO -      self.session = self._send_message('newSession', 'value')
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/marionette.py", line 284, in _send_message
18:17:56     INFO -      response = self.client.send(message)
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/client.py", line 85, in send
18:17:56     INFO -      self.connect()
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/client.py", line 78, in connect
18:17:56     INFO -      response = self.send({'to':'root', 'type': 'getMarionetteID'})
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/client.py", line 95, in send
18:17:56     INFO -      response = self.receive()
18:17:56     INFO -    File "/home/cltbld/talos-slave/test/build/venv/lib/python2.6/site-packages/marionette/client.py", line 58, in receive
18:17:56     INFO -      status=ErrorCodes.INVALID_RESPONSE)
18:17:56    ERROR -  marionette.errors.InvalidResponseException: Could not successfully complete transport of message to Gecko, socket closed?
18:17:56    ERROR - Return code: 1
18:17:56     INFO - dumping logcat
18:17:56     INFO - Running command: ['cat', '/home/cltbld/talos-slave/test/build/emulator-5554.log']
18:17:56     INFO - Copy/paste: cat /home/cltbld/talos-slave/test/build/emulator-5554.log
18:17:56     INFO -  I/DEBUG   (   35): debuggerd: Feb 20 2013 00:48:05
18:17:56     INFO -  I/qemu-props(   33): connected to 'boot-properties' qemud service.
18:17:56     INFO -  I/qemu-props(   33): receiving..
18:17:56     INFO -  I/qemu-props(   33): received: dalvik.vm.heapsize=48m
18:17:56     INFO -  I/qemu-props(   33): receiving..
18:17:56     INFO -  I/qemu-props(   33): received: qemu.sf.lcd_density=160
18:17:56     INFO -  I/Vold    (   32): Vold 2.1 (the revenge) firing up
18:17:56     INFO -  I/qemu-props(   33): receiving..
18:17:56     INFO -  I/qemu-props(   33): received: qemu.hw.mainkeys=1
18:17:56     INFO -  I/qemu-props(   33): receiving..
18:17:56     INFO -  I/qemu-props(   33): received: qemu.sf.fake_camera=back
18:17:56     INFO -  I/qemu-props(   33): receiving..
18:17:56     INFO -  I/qemu-props(   33): exiting (4 properties set).
18:17:56     INFO -  D/Vold    (   32): Volume sdcard state changing -1 (Initializing) -> 0 (No-Media)
18:17:56     INFO -  I/Netd    (   34): Netd 1.0 starting
18:17:56     INFO -  D/Vold    (   32): Volume sdcard state changing 0 (No-Media) -> 1 (Idle-Unmounted)
18:17:56     INFO -  E/Netd    (   34): Unable to bind netlink socket: No such file or directory
18:17:56     INFO -  E/Netd    (   34): Unable to open quota2 logging socket
18:17:56     INFO -  I/        (   37): ServiceManager: 0x18910
18:17:56     INFO -  I/        (   38): ServiceManager: 0xf958
18:17:56     INFO -  I/AudioFlinger(   38): Loaded primary audio interface from LEGACY Audio HW HAL (audio)
18:17:56     INFO -  I/AudioFlinger(   38): Using 'LEGACY Audio HW HAL' (audio.primary) as the primary audio interface
18:17:56     INFO -  D/AudioHardwareInterface(   38): setMode(NORMAL)
18:17:56     INFO -  I/CameraService(   38): CameraService started (pid=38)
18:17:56     INFO -  D/EmulatedCamera_QemuClient(   38): Emulated camera list:
18:17:56     INFO -  D/EmulatedCamera_FakeCamera(   38): Initialize: Fake camera is facing back
18:17:56     INFO -  V/EmulatedCamera_Factory(   38): 1 cameras are being emulated. Fake camera ID is 0
18:17:56     INFO -  I/AudioFlinger(   38): AudioFlinger's thread 0x11008 ready to run
18:17:56     INFO -  W/AudioFlinger(   38): Thread AudioOut_1 cannot connect to the power manager service
18:17:56     INFO -  W/AudioFlinger(   38): Thread AudioOut_1 cannot connect to the power manager service
18:17:56     INFO -  I/AudioPolicyService(   38): Loaded audio policy from LEGACY Audio Policy HAL (audio_policy)
18:17:56     INFO -  D/        (   43): HostConnection::get() New Host Connection established 0x403033c0, tid 43
18:17:56     INFO -  E/profiler(   43): Registering start signal
18:17:56     INFO -  W/Gonk    (   43): Could not open boot animation
18:17:56     INFO -  I/Gecko:MemoryInfoDumper(   43): FifoWatcher::OpenFifo unlink failed; errno=2.  Continuing despite error.
18:17:56     INFO -  I/Gecko   (   43): 1363742156829	Marionette	INFO	MarionetteComponent loaded
[...]
18:17:56     INFO -  E/AutoMounter(  626): Command 'volume mount sdcard' failed: 400 'volume operation failed (Invalid argument)'
18:17:56     INFO -  I/Gonk    (  626): Unable to open file /sys/class/android_usb/android0/f_rndis/ethaddr.
18:17:56     INFO -  I/Gonk    (  626): fail to give rndis interface an address
18:17:56     INFO -  I/Gonk    (  626): Connected to netd
18:17:56     INFO -  I/EventHub(  626): New device: id=1, fd=44, path='/dev/input/event0', name='qwerty2', classes=0x2d, configuration='/system/usr/idc/qwerty2.idc', keyLayout='/system/usr/keylayout/qwerty.kl', keyCharacterMap='/system/usr/keychars/qwerty2.kcm', builtinKeyboard=true
18:17:56     INFO -  E/EventHub(  626): could not get driver version for /dev/input/mouse0, Not a typewriter
18:17:56     INFO -  E/EventHub(  626): could not get driver version for /dev/input/mice, Not a typewriter
18:17:56     INFO -  I/InputReader(  626): Device reconfigured: id=0, name='qwerty2', surface size is now 800x1000, mode is 1
18:17:56     INFO -  I/InputReader(  626): Device added: id=0, name='qwerty2', sources=0x00011307
18:17:56     INFO -  I/Gecko   (  626): 1363742250574	Marionette	INFO	marionette initializing at final-ui-startup
18:17:56     INFO -  I/Gecko   (  626): 1363742253385	Marionette	INFO	marionette-actors.js loaded
18:17:56     INFO -  I/Gecko   (  626): 1363742253423	Marionette	INFO	marionette listener opened
18:17:56     INFO -  I/Gonk    (  626): Socket open for RIL
18:17:56     INFO -  I/FMRadio (  626): FMRadio is initialized.
18:17:56     INFO -  I/Gecko   (  626): 1363742257798	Marionette	INFO	sendToClient: {"from":"conn0.marionette1","ok":true}, {d87a3d5b-8bd5-4315-9f14-030c18be7778}, {d87a3d5b-8bd5-4315-9f14-030c18be7778}
18:17:56  WARNING -  E/GeckoConsole(  626): [JavaScript Error: "[Exception... "Component returned failure code: 0x80470002 (NS_BASE_STREAM_CLOSED) [nsIOutputStream.write]"  nsresult: "0x80470002 (NS_BASE_STREAM_CLOSED)"  location: "JS frame :: chrome://global/content/devtools/dbg-transport.js :: DT_onOutputStreamReady :: line 94"  data: no]" {file: "chrome://global/content/devtools/dbg-transport.js" line: 94}]
18:17:56    ERROR -  F/libc    (  626): Fatal signal 11 (SIGSEGV) at 0x00000000 (code=1)
18:17:56    ERROR -  This usually indicates the B2G process has crashed
18:17:56     INFO - Return code: 0
18:17:56     INFO - TinderboxPrint: reftest<br/><em class="testfail">T-FAIL</em>
18:17:56    ERROR - # TBPL FAILURE #
18:17:56    ERROR - The reftest suite: reftest-8 ran with return status: FAILURE
18:17:56     INFO - Copying logs to upload dir...
18:17:56     INFO - mkdir: /home/cltbld/talos-slave/test/build/upload/logs
program finished with exit code 0
elapsedTime=339.021542
========= Finished '/tools/buildbot/bin/python scripts/scripts/b2g_emulator_unittest.py ...' failed (results: 2, elapsed: 5 mins, 39 secs) (at 2013-03-19 18:17:56.427590) =========