1648199 - Perma Win7 TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] getSynchronizationSources() eventually returns a non-empty list - Test timed out

Status: RESOLVED WORKSFORME

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=307405476&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GAi36ahRSsWPDlxiuDQaHg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-06-24T17:37:33.046Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2751: GetStats: Found no pipelines matching selector.
[task 2020-06-24T17:37:33.389Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.389Z] 17:37:33 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] getSynchronizationSources() eventually returns a non-empty list - Test timed out
[task 2020-06-24T17:37:33.389Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.390Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] RTCRtpSynchronizationSource.timestamp is a number - expected PASS
[task 2020-06-24T17:37:33.390Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.390Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] RTCRtpSynchronizationSource.rtpTimestamp is a number [0, 2^32-1] - expected PASS
[task 2020-06-24T17:37:33.391Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.391Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] getSynchronizationSources() does not contain SSRCs older than 10 seconds - expected PASS
[task 2020-06-24T17:37:33.392Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.392Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] RTCRtpSynchronizationSource.timestamp is comparable to performance.timeOrigin + performance.now() - expected PASS
[task 2020-06-24T17:37:33.393Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.393Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio] RTCRtpSynchronizationSource.source is a number - expected PASS
[task 2020-06-24T17:37:33.393Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.393Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] getSynchronizationSources() eventually returns a non-empty list - expected PASS
[task 2020-06-24T17:37:33.393Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] RTCRtpSynchronizationSource.timestamp is a number - expected PASS
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] RTCRtpSynchronizationSource.rtpTimestamp is a number [0, 2^32-1] - expected PASS
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] getSynchronizationSources() does not contain SSRCs older than 10 seconds - expected PASS
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] RTCRtpSynchronizationSource.timestamp is comparable to performance.timeOrigin + performance.now() - expected PASS
[task 2020-06-24T17:37:33.394Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [video] RTCRtpSynchronizationSource.source is a number - expected PASS
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio-only] RTCRtpSynchronizationSource.audioLevel is a number [0, 1] - expected PASS
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO -
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | [audio-only] RTCRtpSynchronizationSource.voiceActivityFlag is a boolean - expected FAIL
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-INFO | expected FAIL
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCRtpReceiver-getSynchronizationSources.https.html | expected OK
[task 2020-06-24T17:37:33.395Z] 17:37:33 INFO - TEST-INFO took 180733ms
[task 2020-06-24T17:37:33.410Z] 17:37:33 INFO - PID 1148 | 1593020253377 Marionette INFO Stopped listening on port 49375
[task 2020-06-24T17:37:33.585Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2116: CloseInt: Closing PeerConnectionImpl bfa57a973e37f67e; ending call
[task 2020-06-24T17:37:33.585Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: I/jsep [1593020073278000 (id=38654705706 url=https://web-platform.test:8443/webrtc/RTCRtpReceiver-getSynchronizationSources.https.html)]: stable -> closed
[task 2020-06-24T17:37:33.586Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2751: GetStats: Found no pipelines matching selector.
[task 2020-06-24T17:37:33.587Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2116: CloseInt: Closing PeerConnectionImpl ad4ac4cf35eaf71d; ending call
[task 2020-06-24T17:37:33.587Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: I/jsep [1593020073281000 (id=38654705706 url=https://web-platform.test:8443/webrtc/RTCRtpReceiver-getSynchronizationSources.https.html)]: stable -> closed
[task 2020-06-24T17:37:33.608Z] 17:37:33 INFO - PID 1148 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-24T17:37:33.707Z] 17:37:33 INFO - PID 1148 | [Child 5200, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:33.746Z] 17:37:33 INFO - PID 1148 | [Child 5384, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:33.766Z] 17:37:33 INFO - PID 1148 | [2020-06-24T17:37:33Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-24T17:37:33.766Z] 17:37:33 INFO - PID 1148 | [2020-06-24T17:37:33Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-24T17:37:33.881Z] 17:37:33 INFO - PID 1148 | [Child 4564: Main Thread]: D/DataChannel Shutting down SCTP
[task 2020-06-24T17:37:34.700Z] 17:37:34 INFO - PID 1148 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-24T17:37:34.707Z] 17:37:34 INFO - PID 1148 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-24T17:37:34.734Z] 17:37:34 INFO - PID 1148 | [Child 2504, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:34.849Z] 17:37:34 INFO - PID 1148 | Timecard created 1593020073.277000
[task 2020-06-24T17:37:34.849Z] 17:37:34 INFO - PID 1148 | Timestamp | Delta | Event | File | Function
[task 2020-06-24T17:37:34.849Z] 17:37:34 INFO - PID 1148 | ======================================================================================================================
[task 2020-06-24T17:37:34.850Z] 17:37:34 INFO - PID 1148 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2020-06-24T17:37:34.850Z] 17:37:34 INFO - PID 1148 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:458 | Initialize
[task 2020-06-24T17:37:34.851Z] 17:37:34 INFO - PID 1148 | 0.023000 | 0.022000 | Create Offer | PeerConnectionImpl.cpp:1239 | CreateOffer
[task 2020-06-24T17:37:34.854Z] 17:37:34 INFO - PID 1148 | 0.036000 | 0.013000 | Set Local Description | PeerConnectionImpl.cpp:1308 | SetLocalDescription
[task 2020-06-24T17:37:34.855Z] 17:37:34 INFO - PID 1148 | 0.069000 | 0.033000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2525 | IceGatheringStateChange
[task 2020-06-24T17:37:34.856Z] 17:37:34 INFO - PID 1148 | 0.077000 | 0.008000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2528 | IceGatheringStateChange
[task 2020-06-24T17:37:34.856Z] 17:37:34 INFO - PID 1148 | 0.080000 | 0.003000 | Set Remote Description | PeerConnectionImpl.cpp:1403 | SetRemoteDescription
[task 2020-06-24T17:37:34.857Z] 17:37:34 INFO - PID 1148 | 0.089000 | 0.009000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.857Z] 17:37:34 INFO - PID 1148 | 0.093000 | 0.004000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.857Z] 17:37:34 INFO - PID 1148 | 0.096000 | 0.003000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.857Z] 17:37:34 INFO - PID 1148 | 26.563000 | 26.467000 | Ice state: checking | PeerConnectionImpl.cpp:2470 | IceConnectionStateChange
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | 38.186000 | 11.623000 | Ice state: failed | PeerConnectionImpl.cpp:2479 | IceConnectionStateChange
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | 181.562000 | 143.376000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | [Child 4564: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | [Child 4564: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for bfa57a973e37f67e
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | Timecard created 1593020073.280000
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | Timestamp | Delta | Event | File | Function
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | ======================================================================================================================
[task 2020-06-24T17:37:34.858Z] 17:37:34 INFO - PID 1148 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:458 | Initialize
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.043000 | 0.042000 | Set Remote Description | PeerConnectionImpl.cpp:1403 | SetRemoteDescription
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.055000 | 0.012000 | Create Answer | PeerConnectionImpl.cpp:1270 | CreateAnswer
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.064000 | 0.009000 | Set Local Description | PeerConnectionImpl.cpp:1308 | SetLocalDescription
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.076000 | 0.012000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.080000 | 0.004000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2525 | IceGatheringStateChange
[task 2020-06-24T17:37:34.859Z] 17:37:34 INFO - PID 1148 | 0.082000 | 0.002000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 0.088000 | 0.006000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2528 | IceGatheringStateChange
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 0.089000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 0.093000 | 0.004000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 0.094000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 10.612000 | 10.518000 | Ice state: failed | PeerConnectionImpl.cpp:2479 | IceConnectionStateChange
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | 181.565000 | 170.953000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2020-06-24T17:37:34.860Z] 17:37:34 INFO - PID 1148 | [Child 4564: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2020-06-24T17:37:34.861Z] 17:37:34 INFO - PID 1148 | [Child 4564: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for ad4ac4cf35eaf71d
[task 2020-06-24T17:37:34.936Z] 17:37:34 INFO - PID 1148 | [Child 6032, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:35.055Z] 17:37:35 INFO - PID 1148 | [Parent 1524, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1279
[task 2020-06-24T17:37:35.102Z] 17:37:35 INFO - PID 1148 | [Parent 1524, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 227
[task 2020-06-24T17:37:35.102Z] 17:37:35 INFO - PID 1148 | [Parent 1524, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 254
[task 2020-06-24T17:37:35.497Z] 17:37:35 INFO - PID 1148 | [Child 4564, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:35.772Z] 17:37:35 INFO - PID 1148 | [Parent 1524, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-24T17:37:35.879Z] 17:37:35 INFO - Browser exited with return code 0
[task 2020-06-24T17:37:35.879Z] 17:37:35 INFO - PROCESS LEAKS c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424.log
[task 2020-06-24T17:37:35.879Z] 17:37:35 INFO - leakcheck | Processing log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424.log for scope /fetch/api/request
[task 2020-06-24T17:37:35.879Z] 17:37:35 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 51200 bytes
[task 2020-06-24T17:37:35.879Z] 17:37:35 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 768000 bytes
[task 2020-06-24T17:37:35.880Z] 17:37:35 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.881Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424.log
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 2932
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.882Z] 17:37:35 INFO - 0 |TOTAL | 23 0| 4369250 0|
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO - nsTraceRefcnt::DumpStatistics: 2261 entries
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424_rdd_pid4836.log
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.897Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, rdd process 4836
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - 0 |TOTAL | 14 0| 3886 0|
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - nsTraceRefcnt::DumpStatistics: 151 entries
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - TEST-PASS | leakcheck | rdd no leaks detected!
[task 2020-06-24T17:37:35.898Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424_tab_pid1504.log
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1504
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.899Z] 17:37:35 INFO - 0 |TOTAL | 23 0| 358581 0|
[task 2020-06-24T17:37:35.902Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.902Z] 17:37:35 INFO - nsTraceRefcnt::DumpStatistics: 1471 entries
[task 2020-06-24T17:37:35.902Z] 17:37:35 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-06-24T17:37:35.902Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424_tab_pid4636.log
[task 2020-06-24T17:37:35.905Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.905Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 4636
[task 2020-06-24T17:37:35.905Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.905Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.905Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.906Z] 17:37:35 INFO - 0 |TOTAL | 24 0| 14599 0|
[task 2020-06-24T17:37:35.906Z] 17:37:35 INFO - Browser exited with return code 0
[task 2020-06-24T17:37:35.906Z] 17:37:35 INFO - PROCESS LEAKS c:\users\task_1593017732\appdata\local\temp\tmpygiubk\runtests_leaks_1424.log
[task 2020-06-24T17:37:35.907Z] 17:37:35 INFO - leakcheck | Processing log file c:\users\task_1593017732\appdata\local\temp\tmpygiubk\runtests_leaks_1424.log for scope /webrtc
[task 2020-06-24T17:37:35.908Z] 17:37:35 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 51200 bytes
[task 2020-06-24T17:37:35.908Z] 17:37:35 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.909Z] 17:37:35 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-06-24T17:37:35.910Z] 17:37:35 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.910Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.911Z] 17:37:35 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.911Z] 17:37:35 INFO - nsTraceRefcnt::DumpStatistics: 417 entries
[task 2020-06-24T17:37:35.911Z] 17:37:35 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2020-06-24T17:37:35.911Z] 17:37:35 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-06-24T17:37:35.912Z] 17:37:35 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.912Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpduhkvz\runtests_leaks_1424_tab_pid5208.log
[task 2020-06-24T17:37:35.912Z] 17:37:35 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-06-24T17:37:35.913Z] 17:37:35 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-06-24T17:37:35.913Z] 17:37:35 INFO - leakcheck | Processing leak log file c:\users\task_1593017732\appdata\local\temp\tmpygiubk\runtests_leaks_1424.log
[task 2020-06-24T17:37:35.914Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.914Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 1524
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO - 0 |TOTAL | 23 0|44257259 0|
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.915Z] 17:37:35 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 5208
[task 2020-06-24T17:37:35.916Z] 17:37:35 INFO -
[task 2020-06-24T17:37:35.916Z] 17:37:35 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-06-24T17:37:35.917Z] 17:37:35 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-06-24T17:37:35.917Z] 17:37:35 INFO - 0 |TOTAL | 25 0| 76786 0|
[task 2020-06-24T17:37:35.940Z] 17:37:35 INFO -

Comment 1

[Intermittent Failures Robot]

16 failures in 1046 pushes (0.015 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 16

Platform breakdown:

• windows7-32: 16

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1648199&startday=2020-06-24&endday=2020-06-24&tree=all

Comment 2

[Alexandru Michis [:malexandru]]

This looks to have been fixed by the changes in Bug 1643058.

Comment 3

[Intermittent Failures Robot]

29 failures in 5252 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 29

Platform breakdown:

• windows7-32: 29

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1648199&startday=2020-06-22&endday=2020-06-28&tree=all