1278113 - Intermittent test_peerConnection_verifyAudioAfterRenegotiation.html | Test timed out

Status: RESOLVED FIXED

(Core :: WebRTC, defect, P2)

Description

[Phil Ringnalda (:philor)]

https://treeherder.mozilla.org/logviewer.html#?job_id=29509328&repo=mozilla-inbound

Comment 1

[Intermittent Failures Robot]

5 automation job failures were associated with this bug in the last 7 days.

Repository breakdown:
* try: 3
* mozilla-aurora: 1
* fx-team: 1

Platform breakdown:
* windows7-32-vm: 5

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1278113&startday=2016-06-27&endday=2016-07-03&tree=all

Comment 2

[Joel Maher ( :jmaher ) (UTC -8)]

I am looking into migrating jobs from hardware to AWS instances, specifically for windows 7.  I run into this bug often:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=7a4482c92162a6208922211588f2fa399ffbefbe&group_state=expanded&filter-searchStr=vm%20mda

out of 100 jobs of mochitest-media-e10s (50 opt/ 50 debug), there are 20 failures, about 10 of them have this specific failure.

:k17e, can you find someone to help us debug this and figure it out?

Comment 3

[Joel Maher ( :jmaher ) (UTC -8)]

here is information from a win 7 debug log (http://archive.mozilla.org/pub/firefox/try-builds/[e-mail]-7a4482c92162a6208922211588f2fa399ffbefbe/try-win32-debug/try_win7_vm-debug_test-mochitest-media-e10s-bm129-tests1-windows-build290.txt.gz):
00:34:32     INFO -  2728 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcLocal: SDP contains end-of-candidates
00:34:32     INFO -  2729 INFO CLINE-NO-DEFAULT-ADDR-SDP: "v=0\r\no=mozilla...THIS_IS_SDPARTA-50.0a1 3580511800835664041 0 IN IP4 0.0.0.0\r\ns=-\r\nt=0 0\r\na=sendrecv\r\na=fingerprint:sha-256 9D:09:2F:A5:87:9F:0B:3B:74:2D:1F:D6:28:B6:0A:52:C3:36:E5:85:E9:51:01:18:53:C1:6A:2D:4A:15:48:84\r\na=ice-options:trickle\r\na=msid-semantic:WMS *\r\nm=audio 60039 UDP/TLS/RTP/SAVPF 109 9 0 8\r\nc=IN IP4 10.132.157.198\r\na=candidate:0 1 UDP 2122252543 10.132.157.198 60039 typ host\r\na=candidate:0 2 UDP 2122252542 10.132.157.198 60040 typ host\r\na=sendrecv\r\na=end-of-candidates\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=ice-pwd:c56d24844ce77ec614adcf2165ea078d\r\na=ice-ufrag:d99a213c\r\na=mid:sdparta_0\r\na=msid:{7099bdb6-bf3a-42f8-aeac-4c89d9a1a14d} {4cf9c98a-03da-4b9b-9109-f236c281d4e2}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=setup:actpass\r\na=ssrc:305653524 cname:{e37ffa44-18a0-46c5-a188-ee36841cf068}\r\n"
00:34:32     INFO -  2730 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcLocal: SDP contains non-zero IP c line
00:34:32     INFO -  2731 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcLocal: SDP contains rtcp-mux
00:34:32     INFO -  2732 INFO Run step 54: PC_REMOTE_VERIFY_SDP_AFTER_END_OF_TRICKLE
00:34:32     INFO -  2733 INFO Run step 55: CHECK_ASSUMPTIONS
00:34:32     INFO -  2734 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcLocal should have one media element
00:34:32     INFO -  2735 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcRemote should have one media element
00:34:32     INFO -  2736 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcLocal should have one stream
00:34:32     INFO -  2737 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | pcRemote should have one stream
00:34:32     INFO -  2738 INFO Run step 56: CHECK_AUDIO
00:34:32     INFO -  2739 INFO Checking local audio enabled
00:34:32     INFO -  2740 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | Test timed out.
00:34:32     INFO -      reportError@SimpleTest/TestRunner.js:114:7
00:34:32     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:135:7
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:156:5
00:34:32     INFO -      TestRunner.runTests@SimpleTest/TestRunner.js:367:5
00:34:32     INFO -      RunSet.runtests@SimpleTest/setup.js:186:3
00:34:32     INFO -      RunSet.runall@SimpleTest/setup.js:165:5
00:34:32     INFO -      hookupTests@SimpleTest/setup.js:258:5
00:34:32     INFO -  parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
00:34:32     INFO -  getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
00:34:32     INFO -  EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
00:34:32     INFO -      hookup@SimpleTest/setup.js:238:5
00:34:32     INFO -  EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=c%3A%5Cusers%5Ccltbld%5Cappdata%5Clocal%5Ctemp:11:1
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  (stun/INFO) STUN-CLIENT(consent): Received response; processing
00:34:32     INFO -  (ice/INFO) ICE(PC:1468999767951000 (id=4294967568 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_verifyAudioAfter)/STREAM(0-1468999767951000 (id=4294967568 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_verifyAudioAfter aLevel=0)/COMP(1): Consent refreshed
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:32     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  MEMORY STAT | vsize 515MB | vsizeMaxContiguous 705MB | residentFast 46MB | heapAllocated 22MB
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  2741 INFO TEST-OK | dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html | took 305869ms
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  ++DOMWINDOW == 5 (0AD20C00) [pid = 3576] [serial = 273] [outer = 0D4AE800]
00:34:33     INFO -  [Child 3576] WARNING: MediaEngineDefaultAudioSource data underrun: '!data || data->IsEnded() || aDesiredTime <= aSource->GetEndOfAppendedData(aId)', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\webrtc\MediaEngineDefault.h, line 141
00:34:33     INFO -  [Parent 3548] WARNING: pipe error: 109: file c:/builds/moz2_slave/try-w32-d-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
00:34:33     INFO -  [Child 3576] WARNING: Audio Buffer is not full by the end of the callback.: 'Available() == 0 || mSampleWriteOffset == 0', file c:\builds\moz2_slave\try-w32-d-00000000000000000000\build\src\dom\media\AudioBufferUtils.h, line 87
00:34:33     INFO -  2742 INFO TEST-START | dom/media/tests/mochitest/test_peerConnection_verifyVideoAfterRenegotiation.html
00:34:33     INFO -  ++DOMWINDOW == 6 (0A73CC00) [pid = 3576] [serial = 274] [outer = 0D4AE800]
00:34:34     INFO -  TEST DEVICES: No test devices found (in media.{audio,video}_loopback_dev, using fake streams.
00:34:34     INFO -  --DOMWINDOW == 5 (0D9D0000) [pid = 3576] [serial = 272] [outer = 00000000] [url = http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_verifyAudioAfterRenegotiation.html]
00:34:34     INFO -  --DOMWINDOW == 4 (0AD20C00) [pid = 3576] [serial = 273] [outer = 00000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]

Comment 4

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

My rough guess would be this is certainly timing, and something is failing (or jittering enough to break the test) on the VMs due to VM load.  It *appears* the signaling worked and ICE completed, so streams should flow -- but if there's enough load the data received and played bakc may be fragmentary - perhaps modifying the test to dump RTCP stats periodically would provide some breadcrumbs, or capturing audio (like we capture screenshots).  Or if we don't have a good pass after (say) 30 or 60 seconds, snapshot the output audio with webaudio and dump it into the log/save it/dump a frequency graph/bins/etc - Paul?  Nils?

Comment 5

[Nils Ohlmeier [:drno]]

Yeah I don't think this is a transport issue. ICE is happy. And even consent freshness appears to stay in good shape.

I'm wondering if the printing of the audio analyzer node is causing problems. I would start with trying to turn that off and see if that changes anything:
https://dxr.mozilla.org/mozilla-central/source/dom/media/tests/mochitest/head.js#789

Another option is to add some logging into the audio analyzer loop here:
https://dxr.mozilla.org/mozilla-central/source/dom/media/tests/mochitest/head.js#123
to log something (getStats() or some status from webAudio) while we are still waiting for it to pass.

Comment 6

[Nils Ohlmeier [:drno]]

Attachment: Bug 1278113: diabled audio stream analyzer debug canvas output

Review commit: https://reviewboard.mozilla.org/r/65636/diff/#index_header
See other reviews: https://reviewboard.mozilla.org/r/65636/

Comment 7

[Nils Ohlmeier [:drno]]

:jmaher can you give it a try with the patch I just attached?

Comment 8

[Intermittent Failures Robot]

7 automation job failures were associated with this bug in the last 7 days.

Repository breakdown:
* try: 4
* mozilla-inbound: 2
* autoland: 1

Platform breakdown:
* windows7-32-vm: 7

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1278113&startday=2016-07-18&endday=2016-07-24&tree=all

Comment 9

[Paul Adenot (:padenot)]

Waiting for jmaher's answer.

Comment 10

[Joel Maher ( :jmaher ) (UTC -8)]

the rate is greatly reduced:
https://treeherder.mozilla.org/#/jobs?repo=try&author=[e-mail]&selectedJob=24454216

60 jobs (30 opt, 30 debug), 3 instances (1 opt e10s, 2 debug e10s) of this test timing out.

Comment 11

[Paul Adenot (:padenot)]

Comment on attachment 8772972 [details]
Bug 1278113: diabled audio stream analyzer debug canvas output

https://reviewboard.mozilla.org/r/65636/#review63588

ship it !

Comment 12

[Intermittent Failures Robot]

10 automation job failures were associated with this bug in the last 7 days.

Repository breakdown:
* try: 6
* mozilla-inbound: 2
* autoland: 2

Platform breakdown:
* windows7-32-vm: 10

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1278113&startday=2016-07-25&endday=2016-07-31&tree=all

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/mozilla-inbound/rev/3ecc089e99ff
disabled audio stream analyzer debug canvas output r=padenot

Comment 14

[Carsten Book [:Tomcat]]

https://hg.mozilla.org/mozilla-central/rev/3ecc089e99ff

Comment 15

[Intermittent Failures Robot]

14 automation job failures were associated with this bug in the last 7 days.

Repository breakdown:
* try: 6
* mozilla-inbound: 3
* mozilla-central: 2
* autoland: 2
* mozilla-beta: 1

Platform breakdown:
* windows7-32-vm: 14

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1278113&startday=2016-08-01&endday=2016-08-07&tree=all

Comment 16

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-beta/rev/d6db17715a5f

Comment 17

[Ryan VanderMeulen [:RyanVM]]

https://hg.mozilla.org/releases/mozilla-aurora/rev/8a43440d1d25