Intermittent LeakSanitizer | leak at nsTSubstring, Gecko_StartBulkWriteString, nsstring::nsAString::start_bulk_write_impl, nsstring::nsAString::bulk_write
Categories
(Core :: WebRTC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=343698410&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eUQvLlloQ3e_uSuJrbI3Jg/runs/0/artifacts/public/logs/live_backing.log
[task 2021-06-24T02:08:00.361Z] 02:08:00 INFO - TEST-OK | /webrtc/simplecall.https.html | took 2537ms
[task 2021-06-24T02:08:00.390Z] 02:08:00 INFO - Restarting browser for new test group
[task 2021-06-24T02:08:00.393Z] 02:08:00 INFO - PID 16719 | 1624500480391 Marionette INFO Stopped listening on port 54989
[task 2021-06-24T02:08:00.605Z] 02:08:00 INFO - PID 16719 | JavaScript error: resource:///modules/Interactions.jsm, line 348: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-06-24T02:08:00.627Z] 02:08:00 INFO - PID 16719 | [Child 17029: Main Thread]: D/DataChannel Shutting down SCTP
[task 2021-06-24T02:08:00.637Z] 02:08:00 INFO - PID 16719 | [Child 17157: Main Thread]: D/DataChannel Shutting down SCTP
[task 2021-06-24T02:08:00.739Z] 02:08:00 INFO - PID 16719 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-24T02:08:00.740Z] 02:08:00 INFO - PID 16719 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-24T02:08:00.742Z] 02:08:00 INFO - PID 16719 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-24T02:08:00.745Z] 02:08:00 INFO - PID 16719 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-24T02:08:00.747Z] 02:08:00 INFO - PID 16719 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-24T02:08:00.973Z] 02:08:00 INFO - PID 16719 | Timecard created 1624500476.079181
[task 2021-06-24T02:08:00.974Z] 02:08:00 INFO - PID 16719 | Timestamp | Delta | Event | File | Function
[task 2021-06-24T02:08:00.974Z] 02:08:00 INFO - PID 16719 | ======================================================================================================================
[task 2021-06-24T02:08:00.975Z] 02:08:00 INFO - PID 16719 | 0.000467 | 0.000467 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-06-24T02:08:00.976Z] 02:08:00 INFO - PID 16719 | 0.001133 | 0.000666 | Initializing PC Ctx | PeerConnectionImpl.cpp:450 | Initialize
[task 2021-06-24T02:08:00.976Z] 02:08:00 INFO - PID 16719 | 0.026222 | 0.025089 | Create Offer | PeerConnectionImpl.cpp:1212 | CreateOffer
[task 2021-06-24T02:08:00.976Z] 02:08:00 INFO - PID 16719 | 0.029102 | 0.002880 | Set Local Description | PeerConnectionImpl.cpp:1281 | SetLocalDescription
[task 2021-06-24T02:08:00.976Z] 02:08:00 INFO - PID 16719 | 0.100395 | 0.071293 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2537 | IceGatheringStateChange
[task 2021-06-24T02:08:00.977Z] 02:08:00 INFO - PID 16719 | 0.121059 | 0.020664 | Ice gathering state: complete | PeerConnectionImpl.cpp:2540 | IceGatheringStateChange
[task 2021-06-24T02:08:00.977Z] 02:08:00 INFO - PID 16719 | 0.125567 | 0.004508 | Set Remote Description | PeerConnectionImpl.cpp:1381 | SetRemoteDescription
[task 2021-06-24T02:08:00.978Z] 02:08:00 INFO - PID 16719 | 0.163045 | 0.037478 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.978Z] 02:08:00 INFO - PID 16719 | 0.168642 | 0.005597 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.978Z] 02:08:00 INFO - PID 16719 | 0.169097 | 0.000455 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.979Z] 02:08:00 INFO - PID 16719 | 0.172352 | 0.003255 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.979Z] 02:08:00 INFO - PID 16719 | 0.172881 | 0.000529 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.979Z] 02:08:00 INFO - PID 16719 | 0.173488 | 0.000607 | Ice state: checking | PeerConnectionImpl.cpp:2481 | IceConnectionStateChange
[task 2021-06-24T02:08:00.981Z] 02:08:00 INFO - PID 16719 | 0.173782 | 0.000294 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.982Z] 02:08:00 INFO - PID 16719 | 0.174256 | 0.000474 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.982Z] 02:08:00 INFO - PID 16719 | 0.174531 | 0.000275 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.983Z] 02:08:00 INFO - PID 16719 | 0.174553 | 0.000022 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.983Z] 02:08:00 INFO - PID 16719 | 0.175224 | 0.000671 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.984Z] 02:08:00 INFO - PID 16719 | 0.175658 | 0.000434 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.989Z] 02:08:00 INFO - PID 16719 | 0.176667 | 0.001009 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:00.990Z] 02:08:00 INFO - PID 16719 | 0.178259 | 0.001592 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.991Z] 02:08:00 INFO - PID 16719 | 0.178276 | 0.000017 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:00.992Z] 02:08:00 INFO - PID 16719 | 0.185241 | 0.006965 | Ice state: connected | PeerConnectionImpl.cpp:2484 | IceConnectionStateChange
[task 2021-06-24T02:08:00.993Z] 02:08:00 INFO - PID 16719 | 4.883614 | 4.698373 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-06-24T02:08:00.994Z] 02:08:00 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2021-06-24T02:08:00.995Z] 02:08:00 INFO - PID 16719 | [Child 17157: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {76cbe0c7-41b0-480d-a76c-3d63768f8121}
[task 2021-06-24T02:08:00.996Z] 02:08:00 INFO - PID 16719 | Timecard created 1624500476.113404
[task 2021-06-24T02:08:00.996Z] 02:08:00 INFO - PID 16719 | Timestamp | Delta | Event | File | Function
[task 2021-06-24T02:08:01.000Z] 02:08:00 INFO - PID 16719 | ======================================================================================================================
[task 2021-06-24T02:08:01.000Z] 02:08:01 INFO - PID 16719 | 0.000114 | 0.000114 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-06-24T02:08:01.001Z] 02:08:01 INFO - PID 16719 | 0.000694 | 0.000580 | Initializing PC Ctx | PeerConnectionImpl.cpp:450 | Initialize
[task 2021-06-24T02:08:01.002Z] 02:08:01 INFO - PID 16719 | 0.002537 | 0.001843 | Set Remote Description | PeerConnectionImpl.cpp:1381 | SetRemoteDescription
[task 2021-06-24T02:08:01.004Z] 02:08:01 INFO - PID 16719 | 0.065557 | 0.063020 | Create Answer | PeerConnectionImpl.cpp:1243 | CreateAnswer
[task 2021-06-24T02:08:01.005Z] 02:08:01 INFO - PID 16719 | 0.089605 | 0.024048 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.008Z] 02:08:01 INFO - PID 16719 | 0.098258 | 0.008653 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.009Z] 02:08:01 INFO - PID 16719 | 0.108253 | 0.009995 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.010Z] 02:08:01 INFO - PID 16719 | 0.109095 | 0.000842 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.011Z] 02:08:01 INFO - PID 16719 | 0.109564 | 0.000469 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.011Z] 02:08:01 INFO - PID 16719 | 0.109941 | 0.000377 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.013Z] 02:08:01 INFO - PID 16719 | 0.110427 | 0.000486 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.014Z] 02:08:01 INFO - PID 16719 | 0.110844 | 0.000417 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.016Z] 02:08:01 INFO - PID 16719 | 0.111315 | 0.000471 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.017Z] 02:08:01 INFO - PID 16719 | 0.111664 | 0.000349 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.020Z] 02:08:01 INFO - PID 16719 | 0.111968 | 0.000304 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.020Z] 02:08:01 INFO - PID 16719 | 0.112274 | 0.000306 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.021Z] 02:08:01 INFO - PID 16719 | 0.112588 | 0.000314 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.022Z] 02:08:01 INFO - PID 16719 | 0.112888 | 0.000300 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.024Z] 02:08:01 INFO - PID 16719 | 0.113184 | 0.000296 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.025Z] 02:08:01 INFO - PID 16719 | 0.113537 | 0.000353 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.028Z] 02:08:01 INFO - PID 16719 | 0.113867 | 0.000330 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.029Z] 02:08:01 INFO - PID 16719 | 0.114197 | 0.000330 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.031Z] 02:08:01 INFO - PID 16719 | 0.114616 | 0.000419 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.032Z] 02:08:01 INFO - PID 16719 | 0.114988 | 0.000372 | Set Local Description | PeerConnectionImpl.cpp:1281 | SetLocalDescription
[task 2021-06-24T02:08:01.033Z] 02:08:01 INFO - PID 16719 | 0.126338 | 0.011350 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.036Z] 02:08:01 INFO - PID 16719 | 0.126464 | 0.000126 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.037Z] 02:08:01 INFO - PID 16719 | 0.126572 | 0.000108 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.038Z] 02:08:01 INFO - PID 16719 | 0.126648 | 0.000076 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.044Z] 02:08:01 INFO - PID 16719 | 0.126722 | 0.000074 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.045Z] 02:08:01 INFO - PID 16719 | 0.126797 | 0.000075 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.046Z] 02:08:01 INFO - PID 16719 | 0.126902 | 0.000105 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.047Z] 02:08:01 INFO - PID 16719 | 0.127020 | 0.000118 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.049Z] 02:08:01 INFO - PID 16719 | 0.127263 | 0.000243 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2537 | IceGatheringStateChange
[task 2021-06-24T02:08:01.050Z] 02:08:01 INFO - PID 16719 | 0.133515 | 0.006252 | Ice gathering state: complete | PeerConnectionImpl.cpp:2540 | IceGatheringStateChange
[task 2021-06-24T02:08:01.052Z] 02:08:01 INFO - PID 16719 | 0.135172 | 0.001657 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.053Z] 02:08:01 INFO - PID 16719 | 0.135766 | 0.000594 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.054Z] 02:08:01 INFO - PID 16719 | 0.135908 | 0.000142 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.056Z] 02:08:01 INFO - PID 16719 | 0.136080 | 0.000172 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.060Z] 02:08:01 INFO - PID 16719 | 0.136336 | 0.000256 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.061Z] 02:08:01 INFO - PID 16719 | 0.136543 | 0.000207 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.076Z] 02:08:01 INFO - PID 16719 | 0.136712 | 0.000169 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.077Z] 02:08:01 INFO - PID 16719 | 0.136866 | 0.000154 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.079Z] 02:08:01 INFO - PID 16719 | 0.138900 | 0.002034 | Ice state: checking | PeerConnectionImpl.cpp:2481 | IceConnectionStateChange
[task 2021-06-24T02:08:01.083Z] 02:08:01 INFO - PID 16719 | 0.141726 | 0.002826 | Ice state: connected | PeerConnectionImpl.cpp:2484 | IceConnectionStateChange
[task 2021-06-24T02:08:01.084Z] 02:08:01 INFO - PID 16719 | 4.849975 | 4.708249 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-06-24T02:08:01.085Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2021-06-24T02:08:01.086Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {a86513f4-7706-48e3-bb57-a7c5870d21e1}
[task 2021-06-24T02:08:01.087Z] 02:08:01 INFO - PID 16719 | Timecard created 1624500478.335128
[task 2021-06-24T02:08:01.089Z] 02:08:01 INFO - PID 16719 | Timestamp | Delta | Event | File | Function
[task 2021-06-24T02:08:01.092Z] 02:08:01 INFO - PID 16719 | ======================================================================================================================
[task 2021-06-24T02:08:01.093Z] 02:08:01 INFO - PID 16719 | 0.000184 | 0.000184 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-06-24T02:08:01.094Z] 02:08:01 INFO - PID 16719 | 0.000990 | 0.000806 | Initializing PC Ctx | PeerConnectionImpl.cpp:450 | Initialize
[task 2021-06-24T02:08:01.096Z] 02:08:01 INFO - PID 16719 | 0.025962 | 0.024972 | Create Offer | PeerConnectionImpl.cpp:1212 | CreateOffer
[task 2021-06-24T02:08:01.097Z] 02:08:01 INFO - PID 16719 | 0.028747 | 0.002785 | Set Local Description | PeerConnectionImpl.cpp:1281 | SetLocalDescription
[task 2021-06-24T02:08:01.100Z] 02:08:01 INFO - PID 16719 | 0.094025 | 0.065278 | Set Remote Description | PeerConnectionImpl.cpp:1381 | SetRemoteDescription
[task 2021-06-24T02:08:01.101Z] 02:08:01 INFO - PID 16719 | 0.101517 | 0.007492 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2537 | IceGatheringStateChange
[task 2021-06-24T02:08:01.102Z] 02:08:01 INFO - PID 16719 | 0.121709 | 0.020192 | Ice gathering state: complete | PeerConnectionImpl.cpp:2540 | IceGatheringStateChange
[task 2021-06-24T02:08:01.104Z] 02:08:01 INFO - PID 16719 | 0.140018 | 0.018309 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.110Z] 02:08:01 INFO - PID 16719 | 0.148985 | 0.008967 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.112Z] 02:08:01 INFO - PID 16719 | 0.149648 | 0.000663 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.113Z] 02:08:01 INFO - PID 16719 | 0.151376 | 0.001728 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.114Z] 02:08:01 INFO - PID 16719 | 0.151856 | 0.000480 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.115Z] 02:08:01 INFO - PID 16719 | 0.152633 | 0.000777 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.116Z] 02:08:01 INFO - PID 16719 | 0.153122 | 0.000489 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.117Z] 02:08:01 INFO - PID 16719 | 0.156085 | 0.002963 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.118Z] 02:08:01 INFO - PID 16719 | 0.156615 | 0.000530 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.119Z] 02:08:01 INFO - PID 16719 | 0.156805 | 0.000190 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.120Z] 02:08:01 INFO - PID 16719 | 0.156815 | 0.000010 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.121Z] 02:08:01 INFO - PID 16719 | 0.157623 | 0.000808 | Ice state: checking | PeerConnectionImpl.cpp:2481 | IceConnectionStateChange
[task 2021-06-24T02:08:01.122Z] 02:08:01 INFO - PID 16719 | 0.159471 | 0.001848 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.128Z] 02:08:01 INFO - PID 16719 | 0.159593 | 0.000122 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.129Z] 02:08:01 INFO - PID 16719 | 0.159608 | 0.000015 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.130Z] 02:08:01 INFO - PID 16719 | 0.160265 | 0.000657 | Ice state: connected | PeerConnectionImpl.cpp:2484 | IceConnectionStateChange
[task 2021-06-24T02:08:01.131Z] 02:08:01 INFO - PID 16719 | 2.628788 | 2.468523 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-06-24T02:08:01.133Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2021-06-24T02:08:01.134Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {cb633b29-cf34-4dc2-9bdf-3c392a554f4f}
[task 2021-06-24T02:08:01.135Z] 02:08:01 INFO - PID 16719 | Timecard created 1624500478.368146
[task 2021-06-24T02:08:01.136Z] 02:08:01 INFO - PID 16719 | Timestamp | Delta | Event | File | Function
[task 2021-06-24T02:08:01.138Z] 02:08:01 INFO - PID 16719 | ======================================================================================================================
[task 2021-06-24T02:08:01.140Z] 02:08:01 INFO - PID 16719 | 0.000372 | 0.000372 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-06-24T02:08:01.141Z] 02:08:01 INFO - PID 16719 | 0.001037 | 0.000665 | Initializing PC Ctx | PeerConnectionImpl.cpp:450 | Initialize
[task 2021-06-24T02:08:01.144Z] 02:08:01 INFO - PID 16719 | 0.003665 | 0.002628 | Set Remote Description | PeerConnectionImpl.cpp:1381 | SetRemoteDescription
[task 2021-06-24T02:08:01.145Z] 02:08:01 INFO - PID 16719 | 0.035302 | 0.031637 | Create Answer | PeerConnectionImpl.cpp:1243 | CreateAnswer
[task 2021-06-24T02:08:01.146Z] 02:08:01 INFO - PID 16719 | 0.037758 | 0.002456 | Set Local Description | PeerConnectionImpl.cpp:1281 | SetLocalDescription
[task 2021-06-24T02:08:01.148Z] 02:08:01 INFO - PID 16719 | 0.092518 | 0.054760 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.151Z] 02:08:01 INFO - PID 16719 | 0.092822 | 0.000304 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2537 | IceGatheringStateChange
[task 2021-06-24T02:08:01.152Z] 02:08:01 INFO - PID 16719 | 0.112376 | 0.019554 | Ice gathering state: complete | PeerConnectionImpl.cpp:2540 | IceGatheringStateChange
[task 2021-06-24T02:08:01.153Z] 02:08:01 INFO - PID 16719 | 0.113781 | 0.001405 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.156Z] 02:08:01 INFO - PID 16719 | 0.114942 | 0.001161 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.157Z] 02:08:01 INFO - PID 16719 | 0.116888 | 0.001946 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.158Z] 02:08:01 INFO - PID 16719 | 0.117322 | 0.000434 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.159Z] 02:08:01 INFO - PID 16719 | 0.118290 | 0.000968 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.161Z] 02:08:01 INFO - PID 16719 | 0.118329 | 0.000039 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.164Z] 02:08:01 INFO - PID 16719 | 0.119013 | 0.000684 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.165Z] 02:08:01 INFO - PID 16719 | 0.119461 | 0.000448 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.166Z] 02:08:01 INFO - PID 16719 | 0.120335 | 0.000874 | Ice state: checking | PeerConnectionImpl.cpp:2481 | IceConnectionStateChange
[task 2021-06-24T02:08:01.168Z] 02:08:01 INFO - PID 16719 | 0.120733 | 0.000398 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.169Z] 02:08:01 INFO - PID 16719 | 0.122857 | 0.002124 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.171Z] 02:08:01 INFO - PID 16719 | 0.123877 | 0.001020 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.172Z] 02:08:01 INFO - PID 16719 | 0.123886 | 0.000009 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.178Z] 02:08:01 INFO - PID 16719 | 0.124922 | 0.001036 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.179Z] 02:08:01 INFO - PID 16719 | 0.125962 | 0.001040 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.180Z] 02:08:01 INFO - PID 16719 | 0.126626 | 0.000664 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.181Z] 02:08:01 INFO - PID 16719 | 0.126669 | 0.000043 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.183Z] 02:08:01 INFO - PID 16719 | 0.126961 | 0.000292 | Ice state: connected | PeerConnectionImpl.cpp:2484 | IceConnectionStateChange
[task 2021-06-24T02:08:01.184Z] 02:08:01 INFO - PID 16719 | 0.127474 | 0.000513 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.185Z] 02:08:01 INFO - PID 16719 | 0.128218 | 0.000744 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.186Z] 02:08:01 INFO - PID 16719 | 0.145515 | 0.017297 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.187Z] 02:08:01 INFO - PID 16719 | 0.145606 | 0.000091 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.189Z] 02:08:01 INFO - PID 16719 | 0.146949 | 0.001343 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.190Z] 02:08:01 INFO - PID 16719 | 0.147827 | 0.000878 | Look up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.197Z] 02:08:01 INFO - PID 16719 | 0.149154 | 0.001327 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.199Z] 02:08:01 INFO - PID 16719 | 0.152456 | 0.003302 | Add Ice Candidate | PeerConnectionImpl.cpp:1529 | AddIceCandidate
[task 2021-06-24T02:08:01.200Z] 02:08:01 INFO - PID 16719 | 0.152674 | 0.000218 | Done looking up mDNS name | PeerConnectionImpl.cpp:1817 | StampTimecard
[task 2021-06-24T02:08:01.202Z] 02:08:01 INFO - PID 16719 | 2.596254 | 2.443580 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-06-24T02:08:01.203Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2021-06-24T02:08:01.204Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {09128ffa-d14d-45ec-a4ef-b51084e8d0c6}
[task 2021-06-24T02:08:01.847Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: D/DataChannel 60f000360be0: Close()ing 6110005061c0
[task 2021-06-24T02:08:01.847Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: D/DataChannel 60f00031fd20: Close()ing 61100054e380
[task 2021-06-24T02:08:01.852Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:01.852Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:01.853Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:01.854Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:01.859Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:01.861Z] 02:08:01 INFO - PID 16719 | [Child 17157: Main Thread]: E/signaling [main|MediaTransportHandler] MediaTransportHandler.cpp:685: Unable to dispatch to STS: why has the XPCOM shutdown handler not been invoked?
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:02.703Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.800Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.801Z] 02:08:02 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:02.801Z] 02:08:02 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:02.801Z] 02:08:02 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:02.801Z] 02:08:02 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:02.802Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.803Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.803Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.804Z] 02:08:02 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:02.805Z] 02:08:02 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:02.806Z] 02:08:02 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:02.806Z] 02:08:02 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:02.807Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.957Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:02.958Z] 02:08:02 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:02.958Z] 02:08:02 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:02.958Z] 02:08:02 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:02.958Z] 02:08:02 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:02.958Z] 02:08:02 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.123Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.125Z] 02:08:03 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:03.126Z] 02:08:03 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:03.127Z] 02:08:03 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:03.128Z] 02:08:03 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:03.129Z] 02:08:03 INFO - PID 16719 | 2 65568 nr_reg_local_init
[task 2021-06-24T02:08:03.130Z] 02:08:03 INFO - PID 16719 | 195 18592 r_log_register
[task 2021-06-24T02:08:03.131Z] 02:08:03 INFO - PID 16719 | 69 3176 nr_reg_set
[task 2021-06-24T02:08:03.132Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.369Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.369Z] 02:08:03 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:03.369Z] 02:08:03 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:03.369Z] 02:08:03 INFO - PID 16719 | 1 32 nsComponentManagerImpl
[task 2021-06-24T02:08:03.370Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.586Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:03.589Z] 02:08:03 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:03.590Z] 02:08:03 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:03.590Z] 02:08:03 INFO - PID 16719 | 14 448 nsComponentManagerImpl
[task 2021-06-24T02:08:03.590Z] 02:08:03 INFO - PID 16719 | 2 288 libfontconfig.so
[task 2021-06-24T02:08:03.591Z] 02:08:03 INFO - PID 16719 | 2 65568 nr_reg_local_init
[task 2021-06-24T02:08:03.592Z] 02:08:03 INFO - PID 16719 | 195 18592 r_log_register
[task 2021-06-24T02:08:03.593Z] 02:08:03 INFO - PID 16719 | 69 3176 nr_reg_set
[task 2021-06-24T02:08:03.593Z] 02:08:03 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:04.628Z] 02:08:04 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:04.629Z] 02:08:04 INFO - PID 16719 | Suppressions used:
[task 2021-06-24T02:08:04.632Z] 02:08:04 INFO - PID 16719 | count bytes template
[task 2021-06-24T02:08:04.632Z] 02:08:04 INFO - PID 16719 | 11 344 nsComponentManagerImpl
[task 2021-06-24T02:08:04.633Z] 02:08:04 INFO - PID 16719 | 633 18083 libfontconfig.so
[task 2021-06-24T02:08:04.633Z] 02:08:04 INFO - PID 16719 | 16 199 libglib-2.0.so
[task 2021-06-24T02:08:04.633Z] 02:08:04 INFO - PID 16719 | 3 624 mozJSComponentLoader
[task 2021-06-24T02:08:04.634Z] 02:08:04 INFO - PID 16719 | -----------------------------------------------------
[task 2021-06-24T02:08:04.691Z] 02:08:04 INFO - Browser exited with return code 0
[task 2021-06-24T02:08:04.692Z] 02:08:04 INFO - ERROR | LeakSanitizer | SUMMARY: AddressSanitizer: 2640 byte(s) leaked in 17 allocation(s).
[task 2021-06-24T02:08:04.693Z] 02:08:04 INFO - LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2021-06-24T02:08:04.693Z] 02:08:04 INFO - This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2021-06-24T02:08:04.694Z] 02:08:04 INFO - Allowed depth was 4
[task 2021-06-24T02:08:04.695Z] 02:08:04 INFO - TEST-FAIL | LeakSanitizer | leak at NS_NewDOMDataChannel, mozilla::PeerConnectionImpl::CreateDataChannel, mozilla::PeerConnectionImpl::CreateDataChannel, mozilla::dom::PeerConnectionImpl_Binding::createDataChannel
[task 2021-06-24T02:08:04.695Z] 02:08:04 INFO - INFO | LeakSanitizer | Frame NS_NewDOMDataChannel matched a expected leak
[task 2021-06-24T02:08:04.696Z] 02:08:04 INFO - TEST-FAIL | LeakSanitizer | leak at Alloc, nsTSubstring, Gecko_StartBulkWriteString, nsstring::nsAString::start_bulk_write_impl
[task 2021-06-24T02:08:04.696Z] 02:08:04 INFO - INFO | LeakSanitizer | Frame Alloc matched a expected leak
[task 2021-06-24T02:08:04.697Z] 02:08:04 INFO - TEST-FAIL | LeakSanitizer | leak at mozilla::DataChannelConnection::Create, mozilla::PeerConnectionImpl::EnsureDataConnection, mozilla::PeerConnectionImpl::CreateDataChannel, mozilla::PeerConnectionImpl::CreateDataChannel
[task 2021-06-24T02:08:04.697Z] 02:08:04 INFO - INFO | LeakSanitizer | Frame mozilla::DataChannelConnection::Create matched a expected leak
[task 2021-06-24T02:08:04.697Z] 02:08:04 INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at nsTSubstring, Gecko_StartBulkWriteString, nsstring::nsAString::start_bulk_write_impl, nsstring::nsAString::bulk_write
[task 2021-06-24T02:08:04.698Z] 02:08:04 INFO - TEST-FAIL | LeakSanitizer | leak at Alloc, nsTSubstring, nsTSubstring, nsTSubstring
[task 2021-06-24T02:08:04.698Z] 02:08:04 INFO - INFO | LeakSanitizer | Frame Alloc matched a expected leak
[task 2021-06-24T02:08:04.698Z] 02:08:04 INFO - TEST-FAIL | LeakSanitizer | leak at mozilla::dom::DocGroup::Create, operator, OrInsertWith, operator
Comment hidden (Intermittent Failures Robot) |
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment 3•3 years ago
|
||
Recent failure log : https://treeherder.mozilla.org/logviewer?job_id=352080516&repo=mozilla-central&lineNumber=90516
Comment hidden (Intermittent Failures Robot) |
Comment 5•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Comment 6•3 years ago
|
||
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=358622853&repo=mozilla-release&lineNumber=6576
Comment hidden (Intermittent Failures Robot) |
Comment 8•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Description
•