Closed
Bug 1702817
Opened 4 years ago
Closed 4 years ago
Intermittent dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | Test timed out.
Categories
(Core :: WebRTC: Audio/Video, defect, P5)
Core
WebRTC: Audio/Video
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335290935&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KeuT_jTRR8e0-9AMfGGb7Q/runs/0/artifacts/public/logs/live_backing.log
[task 2021-04-02T15:48:46.349Z] 15:48:46 INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html
[task 2021-04-02T15:48:46.491Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-04-02T15:48:46.492Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-04-02T15:48:46.535Z] 15:48:46 INFO - GECKO(15500) | Timecard created 1617378524.919000
[task 2021-04-02T15:48:46.537Z] 15:48:46 INFO - GECKO(15500) | Timestamp | Delta | Event | File | Function
[task 2021-04-02T15:48:46.538Z] 15:48:46 INFO - GECKO(15500) | ======================================================================================================================
[task 2021-04-02T15:48:46.540Z] 15:48:46 INFO - GECKO(15500) | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-04-02T15:48:46.542Z] 15:48:46 INFO - GECKO(15500) | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-04-02T15:48:46.544Z] 15:48:46 INFO - GECKO(15500) | 0.114000 | 0.113000 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-04-02T15:48:46.545Z] 15:48:46 INFO - GECKO(15500) | 0.119000 | 0.005000 | Set Local Description | PeerConnectionImpl.cpp:1275 | SetLocalDescription
[task 2021-04-02T15:48:46.547Z] 15:48:46 INFO - GECKO(15500) | 0.318000 | 0.199000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2523 | IceGatheringStateChange
[task 2021-04-02T15:48:46.548Z] 15:48:46 INFO - GECKO(15500) | 0.359000 | 0.041000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2526 | IceGatheringStateChange
[task 2021-04-02T15:48:46.551Z] 15:48:46 INFO - GECKO(15500) | 0.426000 | 0.067000 | Set Remote Description | PeerConnectionImpl.cpp:1375 | SetRemoteDescription
[task 2021-04-02T15:48:46.553Z] 15:48:46 INFO - GECKO(15500) | 0.600000 | 0.174000 | Add Ice Candidate | PeerConnectionImpl.cpp:1523 | AddIceCandidate
[task 2021-04-02T15:48:46.566Z] 15:48:46 INFO - GECKO(15500) | 1.163000 | 0.563000 | Ice state: checking | PeerConnectionImpl.cpp:2467 | IceConnectionStateChange
[task 2021-04-02T15:48:46.567Z] 15:48:46 INFO - GECKO(15500) | 1.600000 | 0.437000 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-04-02T15:48:46.571Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {1c332710-2236-454e-a844-71afb753a2f5}
[task 2021-04-02T15:48:46.572Z] 15:48:46 INFO - GECKO(15500) | Timecard created 1617378524.921000
[task 2021-04-02T15:48:46.575Z] 15:48:46 INFO - GECKO(15500) | Timestamp | Delta | Event | File | Function
[task 2021-04-02T15:48:46.579Z] 15:48:46 INFO - GECKO(15500) | ======================================================================================================================
[task 2021-04-02T15:48:46.581Z] 15:48:46 INFO - GECKO(15500) | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-04-02T15:48:46.585Z] 15:48:46 INFO - GECKO(15500) | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-04-02T15:48:46.589Z] 15:48:46 INFO - GECKO(15500) | 0.131000 | 0.130000 | Set Remote Description | PeerConnectionImpl.cpp:1375 | SetRemoteDescription
[task 2021-04-02T15:48:46.592Z] 15:48:46 INFO - GECKO(15500) | 0.312000 | 0.181000 | Create Answer | PeerConnectionImpl.cpp:1237 | CreateAnswer
[task 2021-04-02T15:48:46.593Z] 15:48:46 INFO - GECKO(15500) | 0.377000 | 0.065000 | Set Local Description | PeerConnectionImpl.cpp:1275 | SetLocalDescription
[task 2021-04-02T15:48:46.596Z] 15:48:46 INFO - GECKO(15500) | 0.446000 | 0.069000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2523 | IceGatheringStateChange
[task 2021-04-02T15:48:46.601Z] 15:48:46 INFO - GECKO(15500) | 0.579000 | 0.133000 | Add Ice Candidate | PeerConnectionImpl.cpp:1523 | AddIceCandidate
[task 2021-04-02T15:48:46.602Z] 15:48:46 INFO - GECKO(15500) | 0.684000 | 0.105000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2526 | IceGatheringStateChange
[task 2021-04-02T15:48:46.605Z] 15:48:46 INFO - GECKO(15500) | 0.883000 | 0.199000 | Ice state: checking | PeerConnectionImpl.cpp:2467 | IceConnectionStateChange
[task 2021-04-02T15:48:46.606Z] 15:48:46 INFO - GECKO(15500) | 1.615000 | 0.732000 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-04-02T15:48:46.609Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {24743de3-9438-4a56-8d13-b46118242954}
[task 2021-04-02T15:48:46.656Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:325: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2021-04-02T15:48:46.658Z] 15:48:46 INFO - GECKO(15500) | [Parent 16344: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2021-04-02T15:48:46.660Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:325: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2021-04-02T15:48:46.661Z] 15:48:46 INFO - GECKO(15500) | [Parent 16344: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2021-04-02T15:48:46.693Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-04-02T15:48:46.695Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-04-02T15:48:46.696Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:75: OnStunAddrsAvailable: receiving (3) stun addrs
[task 2021-04-02T15:48:46.697Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:75: OnStunAddrsAvailable: receiving (3) stun addrs
[task 2021-04-02T15:48:46.761Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-04-02T15:48:46.762Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-04-02T15:48:46.783Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-04-02T15:48:46.784Z] 15:48:46 INFO - GECKO(15500) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-04-02T15:48:46.917Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 2311de94350
[task 2021-04-02T15:48:46.924Z] 15:48:46 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/jsep [{484bff9c-580d-4345-ab7a-68eb42743113} 1617378526640000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc]: stable -> have-local-offer
[task 2021-04-02T15:48:46.927Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:625 function nr_socket_multi_tcp_listen failed with error 3
[task 2021-04-02T15:48:46.939Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) ICE(PC:{484bff9c-580d-4345-ab7a-68eb42743113} 1617378526640000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc): failed to create passive TCP host candidate: 3
[task 2021-04-02T15:48:46.941Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:625 function nr_socket_multi_tcp_listen failed with error 3
[task 2021-04-02T15:48:46.943Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) ICE(PC:{484bff9c-580d-4345-ab7a-68eb42743113} 1617378526640000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc): failed to create passive TCP host candidate: 3
[task 2021-04-02T15:48:46.946Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:625 function nr_socket_multi_tcp_listen failed with error 3
[task 2021-04-02T15:48:46.953Z] 15:48:46 INFO - GECKO(15500) | (ice/WARNING) ICE(PC:{484bff9c-580d-4345-ab7a-68eb42743113} 1617378526640000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc): failed to create passive TCP host candida
[task 2021-04-02T15:48:46.954Z] 15:48:46 INFO - GECKO(15500) | te: 3
[task 2021-04-02T15:54:14.143Z] 15:54:14 INFO - TEST-PASS | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2021-04-02T15:54:14.144Z] 15:54:14 INFO - TEST-PASS | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2021-04-02T15:54:14.146Z] 15:54:14 INFO - TEST-PASS | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2021-04-02T15:54:14.147Z] 15:54:14 INFO - Buffered messages finished
[task 2021-04-02T15:54:14.153Z] 15:54:14 INFO - TEST-UNEXPECTED-FAIL | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | Test timed out.
[task 2021-04-02T15:54:14.154Z] 15:54:14 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:417:16
[task 2021-04-02T15:54:14.154Z] 15:54:14 INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:147:24
[task 2021-04-02T15:54:14.155Z] 15:54:14 INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:170:18
[task 2021-04-02T15:54:14.156Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2764: GetStats: Found no pipelines matching selector.
[task 2021-04-02T15:54:14.642Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.665Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.688Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.701Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.723Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.732Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.754Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.768Z] 15:54:14 INFO - {"EMEInfo":{"keySystem":"","sessionsInfo":""},"compositorDroppedFrames":0,"decoder":{"PlayState":"","channels":0,"containerType":"","hasAudio":false,"hasVideo":false,"instance":"","rate":0,"reader":{"audioChannels":0,"audioDecoderName":"","audioFramesDecoded":0,"audioRate":0,"audioState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"audioType":"","frameStats":{"droppedCompositorFrames":0,"droppedDecodedFrames":0,"droppedSinkFrames":0},"videoDecoderName":"","videoHardwareAccelerated":false,"videoHeight":0,"videoNumSamplesOutputTotal":0,"videoNumSamplesSkippedTotal":0,"videoRate":0,"videoState":{"demuxEOS":0,"demuxQueueSize":0,"drainState":0,"hasDecoder":false,"hasDemuxRequest":false,"hasPromise":false,"lastStreamSourceID":0,"needInput":false,"numSamplesInput":0,"numSamplesOutput":0,"pending":0,"queueSize":0,"timeTreshold":0,"timeTresholdHasSeeked":false,"waitingForData":false,"waitingForKey":false,"waitingPromise":false},"videoType":"","videoWidth":0},"resource":{"cacheStream":{"cacheSuspended":false,"channelEnded":false,"channelOffset":0,"loadID":0,"streamLength":0}},"stateMachine":{"audioCompleted":false,"audioRequestStatus":"","clock":0,"decodedAudioEndTime":0,"decodedVideoEndTime":0,"duration":0,"isPlaying":false,"mediaSink":{"audioSinkWrapper":{"audioEnded":false,"audioSink":{"audioEnded":false,"hasErrored":false,"isPlaying":false,"isStarted":false,"lastGoodPosition":0,"outputRate":0,"playbackComplete":false,"startTime":0,"written":0},"isPlaying":false,"isStarted":false},"decodedStream":{"audioQueueFinished":false,"audioQueueSize":0,"data":{"audioFramesWritten":0,"haveSentFinishAudio":false,"haveSentFinishVideo":false,"instance":"","lastVideoEndTime":0,"lastVideoStartTime":0,"nextAudioTime":0,"streamAudioWritten":0,"streamVideoWritten":0},"instance":"","lastAudio":0,"lastOutputTime":0,"playing":0,"startTime":0},"videoSink":{"endPromiseHolderIsEmpty":false,"finished":false,"hasVideo":false,"isPlaying":false,"isStarted":false,"size":0,"videoFrameEndTime":0,"videoSinkEndRequestExists":false}},"mediaTime":0,"playState":0,"sentFirstFrameLoadedEvent":false,"state":"","stateObj":{"isPrerolling":false},"videoCompleted":false,"videoRequestStatus":""}}}
[task 2021-04-02T15:54:14.769Z] 15:54:14 INFO - GECKO(15500) | MEMORY STAT | vsize 6765MB | vsizeMaxContiguous 67564046MB | residentFast 126MB | heapAllocated 29MB
[task 2021-04-02T15:54:14.771Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2764: GetStats: Found no pipelines matching selector.
[task 2021-04-02T15:54:14.773Z] 15:54:14 INFO - TEST-OK | dom/media/webrtc/tests/mochitests/test_peerConnection_constructedStream.html | took 328419ms
[task 2021-04-02T15:54:14.785Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2095: CloseInt: Closing PeerConnectionImpl {484bff9c-580d-4345-ab7a-68eb42743113}; ending call
[task 2021-04-02T15:54:14.787Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/jsep [{484bff9c-580d-4345-ab7a-68eb42743113} 1617378526640000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc]: stable -> closed
[task 2021-04-02T15:54:14.789Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2095: CloseInt: Closing PeerConnectionImpl {39c58f49-c345-4a35-8c4c-3588704b4f48}; ending call
[task 2021-04-02T15:54:14.790Z] 15:54:14 INFO - GECKO(15500) | [Child 5924: Main Thread]: I/jsep [{39c58f49-c345-4a35-8c4c-3588704b4f48} 1617378526643000 (id=2147484038 url=https://example.com/tests/dom/media/webrtc/tests/moc]: stable -> closed```
Comment hidden (Intermittent Failures Robot) |
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•