Closed Bug 1717978 Opened 3 years ago Closed 3 years ago

Intermittent LeakSanitizer | leak at nsTSubstring, Gecko_StartBulkWriteString, nsstring::nsAString::start_bulk_write_impl, nsstring::nsAString::bulk_write

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

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
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.