1046378 - assertion "stm->draining" failed - during various b2g-emulator tests on taskcluster docker image only

Status: RESOLVED INVALID

(Core :: Audio/Video, defect)

Description

[Geoff Brown [:gbrown]]

In bug 1027249, I am trying to run b2g-emulator mochitests in a new environment (in a docker image). Most tests are running and passing, but many media tests fail intermittently with:

 assertion "stm->draining" failed: file "../../../../gecko/media/libcubeb/src/cubeb_opensl.c", line 79, function "play_callback"

As far as I can tell, tests are being run in the new, experimental environment the same way as on tbpl: the same arguments to the same mozharness script. Of course, I expect there are timing differences between tbpl and the new, experimental environment. There may also be missing system packages, differences in environment variables, or other unknown differences.

We have also recently had trouble with OpenSL for the Android 2.3 emulator tests -- bug 1020227 -- and I think there are on-going problems with timeouts in media tests on tbpl.

Here's more context:

02:15:31     INFO -  925 INFO TEST-START | /tests/content/media/webaudio/test/test_audioBufferSourceNodeLoop.html
02:17:15     INFO -  926 INFO TEST-OK | /tests/content/media/webaudio/test/test_audioBufferSourceNodeLoop.html | took 103317ms
02:17:39     INFO -  927 INFO TEST-START | /tests/content/media/webaudio/test/test_audioBufferSourceNodeLoopStartEnd.html
02:19:37     INFO -  928 INFO TEST-OK | /tests/content/media/webaudio/test/test_audioBufferSourceNodeLoopStartEnd.html | took 117081ms
02:20:05     INFO -  929 INFO TEST-START | /tests/content/media/webaudio/test/test_audioBufferSourceNodeLoopStartEndSame.html
02:20:14     INFO -  930 INFO assertion "stm->draining" failed: file "../../../../gecko/media/libcubeb/src/cubeb_opensl.c", line 79, function "play_callback"
02:20:14     INFO -  931 INFO [Parent 725] WARNING: pipe error (73): Connection reset by peer: file ../../../gecko/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 452
02:20:14     INFO -  932 INFO ############ ErrorPage.js
02:20:14     INFO -  933 INFO System JS : ERROR chrome://specialpowers/content/SpecialPowersObserver.js:96 - NS_ERROR_NOT_INITIALIZED: Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIMessageSender.sendAsyncMessage]
02:20:15     INFO -  934 INFO System JS : ERROR chrome://specialpowers/content/SpecialPowersObserver.js:96 - NS_ERROR_NOT_INITIALIZED: Component returned failure code: 0xc1f30001 (NS_ERROR_NOT_INITIALIZED) [nsIMessageSender.sendAsyncMessage]
02:20:15     INFO -  935 INFO -*- NetworkService: NetworkService shutdown
02:20:15     INFO -  936 INFO Stopping web server


We would like to get tests running reliably in the new environment as soon as possible. Skipping tests does not appear to be option here, as this failure occurs intermittently in a wide range of media tests.

Comment 1

[Geoff Brown [:gbrown]]

:rjesup -- Any ideas?

Comment 2

[Randell Jesup [:jesup] (needinfo me)]

over to paul/matthew

Comment 3

[JW Wang [:jwwang] [:jw_wang]]

Also include Bruce.

Comment 4

[Paul Adenot (:padenot)]

Leaving this one to Bruce, I'm busy doing other things, but feel free to NI again if needed.

Comment 5

[Matthew Gregan [:kinetik]]

Bruce, is it possible the callback fires more than once?  We could revert the assert to the if (stm->draining) you had before, but I'd like to understand what's going on here first...

Comment 6

[Geoff Brown [:gbrown]]

I checked and, for the stream that causes the assert, there is only the one call to play_callback, and draining is never set to 1.

Comment 7

[Matthew Gregan [:kinetik]]

Attachment: Handle spurious marker callback in OpenSL backend.

Comment 8

[Matthew Gregan [:kinetik]]

Strange.  The OpenSL ES spec says there's no marker set by default, and we only set a marker after setting stm->draining = 1.  Can you please try the attached patch?

Comment 9

[Geoff Brown [:gbrown]]

(In reply to Matthew Gregan [:kinetik] from comment #8)
> Can you please try the attached patch?

Unfortunately, with the attached patch, tests frequently time out:

INFO -  DeviceRunner TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | application timed out after 330.0 seconds with no output

I don't see any helpful diagnostics in the logcat:

23:19:59     INFO -  08-04 23:06:18.573 I/GeckoDump(  795): ~E�ⰲ겿{"action":"test_start","time":1407193576832,"thread":null,,
"pid":null,"source":"mochitest","test":"/tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:07:04.263 I/GeckoDump(  795): TEST DEVICES: No test devices found (in media.{audio,video}_loopback_dev, using fake streams.
23:19:59     INFO -  08-04 23:07:35.864 I/GeckoDump(  795): ~E�ⰲ겿{"action":"log","time":1407193655153,"thread":null,"pid":nn
ull,"source":"mochitest","level":"INFO","message":"Network interface is not ready, required additional network setup"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:07:38.653 I/GeckoDump(  795): ~E�ⰲ겿{"action":"log","time":1407193658660,"thread":null,"pid":nn
ull,"source":"mochitest","level":"INFO","message":"Setting up network interface"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:07:52.273 I/GeckoBluetooth(  722): ServiceChanged: 1 client 0. new mClientId 0
23:19:59     INFO -  08-04 23:08:09.463 I/GeckoDump(  795): ~E�ⰲ겿{"action":"log","time":1407193688640,"thread":null,"pid":nn
ull,"source":"mochitest","level":"INFO","message":"Network interface is ready"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:08:20.513 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:549: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for c80e8aa1e4c444f6
23:19:59     INFO -  08-04 23:08:20.934 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1747: CloseInt: Closing PeerConnectionImpl c80e8aa1e4c444f6; ending call
23:19:59     INFO -  08-04 23:08:20.934 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1446: SIPCC-CC_API: 1/2, cc_int_onhook: UI -> GSM: ONHOOK
23:19:59     INFO -  08-04 23:08:20.934 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1448: (1/2) On-hook called from ../../../../../gecko/media/webrtc/signaling/src/sipcc/core/ccapp/ccprovider.c:657
23:19:59     INFO -  08-04 23:08:20.934 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
23:19:59     INFO -  08-04 23:08:21.353 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:549: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 3013aca1707d473c
23:19:59     INFO -  08-04 23:08:22.003 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1747: CloseInt: Closing PeerConnectionImpl 3013aca1707d473c; ending call
23:19:59     INFO -  08-04 23:08:22.003 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1446: SIPCC-CC_API: 1/1, cc_int_onhook: UI -> GSM: ONHOOK
23:19:59     INFO -  08-04 23:08:22.003 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1448: (1/1) On-hook called from ../../../../../gecko/media/webrtc/signaling/src/sipcc/core/ccapp/ccprovider.c:657
23:19:59     INFO -  08-04 23:08:22.003 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
23:19:59     INFO -  08-04 23:08:26.954 E/GeckoConsole(  722): PAC file installed from data: URI
23:19:59     INFO -  08-04 23:08:26.983 I/GeckoDump(  795): ~E�ⰲ겿{"action":"log","time":1407193706990,"thread":null,"pid":nn
ull,"source":"mochitest","level":"INFO","message":"Creating PeerConnectionWrapper (pcLocal)"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:08:28.983 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:528: PeerConnectionImpl: PeerConnectionImpl constructor for
23:19:59     INFO -  08-04 23:08:29.443 I/PRLog   (  795): 1074443400[40245080]: [main|CC_SIPCCCall] CC_SIPCCCall.cpp:36: Creating  CC_SIPCCCall 65539
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1165: SIPCC-CC_API: 1/3, cc_int_feature2: UI -> GSM: SETPEERCONNECTION
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 22  :(DCSM_READY:SETPEERCONNECTION )
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e8000
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_new_fcb                    : fcb= 0x438e8000
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e8020
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_new_fcb                    : fcb= 0x438e8020
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e8040
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_new_fcb                    : fcb= 0x438e8040
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e8060
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_new_fcb                    : fcb= 0x438e8060
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 3   : 0x40d74415x: sm entry: (IDLE:SETPEERCONNECTION)
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:3923: SIPCC-FSM: fsmdef_ev_setpeerconnection: Entered.
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:919: SIPCC-FSM: DEF 0   /0: fsmdef_get_dcb_by_call_id          : dcb= 0x438de000
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:1448: SIPCC-FSM: DEF 3   /0: fsmdef_get_new_dcb                 : dcb= 0x438de000
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:1293: SIPCC-FSM: 1/3, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e8000
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e8020
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e8040
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 3   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e8060
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:3962: SIPCC-FSM: fsmdef_ev_setpeerconnection: Setting peerconnection handle for (1/3) to 49c0950b694a5ec8
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 1/3, fsm_change_state: DEF: IDLE -> STABLE
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 3 : DCSM_READY --> DCSM_READY
23:19:59     INFO -  08-04 23:08:30.914 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] sm.c:65: SIPCC-GSM: 1/3, sm_process_event: DEF   :(IDLE:SETPEERCONNECTION )
23:19:59     INFO -  08-04 23:08:49.443 E/GeckoConsole(  795): PAC file installed from data: URI
23:19:59     INFO -  08-04 23:11:10.633 E/GeckoConsole(  820): PAC file installed from data: URI
23:19:59     INFO -  08-04 23:12:06.023 I/GeckoDump(  795): ~E�ⰲ겿{"acti{n":"log","time":1407193926027,"thread":null,"pid":nn    null,"source":"mochitest","level":"INFO","message":"Creating PeerConnectionWrapper (pcRemote)"}~E�ⰲ겿
23:19:59     INFO -  08-04 23:12:06.443 I/PRLog   (  795): 1074443400[40245080]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:528: PeerConnectionImpl: PeerConnectionImpl constructor for
23:19:59     INFO -  08-04 23:12:07.313 I/PRLog   (  795): 1074443400[40245080]: [main|CC_SIPCCCall] CC_SIPCCCall.cpp:36: Creating  CC_SIPCCCall 65540
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162496[44fed900]: [CCAPP Task|def] ccapi.c:1165: SIPCC-CC_API: 1/4, cc_int_feature2: UI -> GSM: SETPEERCONNECTION
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 22  :(DCSM_READY:SETPEERCONNECTION )
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e8080
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_new_fcb                    : fcb= 0x438e8080
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e80a0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_new_fcb                    : fcb= 0x438e80a0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e80c0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_new_fcb                    : fcb= 0x438e80c0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:232: SIPCC-GSM_DBG_PTR: FSM 0   : fsm_get_fcb_by_call_id             : fcb= 0x438e80e0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:266: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_new_fcb                    : fcb= 0x438e80e0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 4   : 0x40d74415x: sm entry: (IDLE:SETPEERCONNECTION)
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:3923: SIPCC-FSM: fsmdef_ev_setpeerconnection: Entered.
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:919: SIPCC-FSM: DEF 0   /0: fsmdef_get_dcb_by_call_id          : dcb= 0x438de1cc
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:1448: SIPCC-FSM: DEF 4   /0: fsmdef_get_new_dcb                 : dcb= 0x438de1cc
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:1293: SIPCC-FSM: 1/4, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e8080
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e80a0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e80c0
23:19:59     INFO -  08-04 23:12:08.403 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 4   : fsm_get_fcb_by_call_id_and_type    : fcb= 0x438e80e0
23:19:59     INFO -  08-04 23:12:08.413 I/PRLog   (  795): 162688[44fed980]: [GSM Task|fsm_sm] fsmdef.c:3962: SIPCC-FSM: fsmdef_ev_setpeerconnection: Setting peerconnection handle for (1/4) to 1cb00a7a8bdcf6e1
23:19:59     INFO -  08-04 23:12:08.413 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 1/4, fsm_change_state: DEF: IDLE -> STABLE
23:19:59     INFO -  08-04 23:12:08.413 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 4 : DCSM_READY --> DCSM_READY
23:19:59     INFO -  08-04 23:12:08.413 I/PRLog   (  795): 162688[44fed980]: [GSM Task|def] sm.c:65: SIPCC-GSM: 1/4, sm_process_event: DEF   :(IDLE:SETPEERCONNECTION )
23:19:59     INFO -  08-04 23:17:38.523 I/ServiceManager(   33): service 'media.resource_manager' died
23:19:59     INFO -  08-04 23:17:38.523 I/ServiceManager(   33): service 'permission' died
23:19:59     INFO - Return code: 0

Comment 10

[Geoff Brown [:gbrown]]

:kinetik -- Is there anything else I can do to help here?

Comment 11

[Matthew Gregan [:kinetik]]

Is that causing tests that were passing consistently to time out? Or is this tests that weren't reached due to crashes?

Comment 12

[Geoff Brown [:gbrown]]

I believe it is the same tests: Tests that failed with the draining assertion now fail with a timeout, once the patch is applied.

It is difficult to make a full comparison because in both cases, the failure is intermittent and fatal -- I need to run a full test chunk to find one failure, then skip that test and run again to find another. I have determined that these tests (as well as others) from dom/media/tests/mochitest fail with the draining assertion:

test_dataChannel_bug1013809.html
test_peerConnection_basicAudio.html
test_peerConnection_bug1013809.html

With the patch applied, these same tests (as well as others) timeout. So far I have not found any test that succeeds with the patch and fails without the patch, or vice versa.

Comment 13

[Dan Minor [:dminor]]

I noticed a qemu error locally: warning: opening audio output failed. Adding pulseaudio to the docker image fixes this but I'm not able to run the tests locally yet (due to OpenGL lib problems) so I'm not sure if this makes any difference to the test execution.

Comment 14

[Geoff Brown [:gbrown]]

Thankyou!!

I will run more tests on Monday to make sure, but initial results with pulseaudio look very good.

(Sorry, I thought I had checked on pulseaudio before. Now I see that several libpulse packages are installed on the image, but not pulseaudio!)

Comment 15

[Geoff Brown [:gbrown]]

With pulseaudio installed, the stm->draining assertion does not occur in any test, and all mochitests, crashtests, and xpcshell tests succeed (no frequent failures).