Closed Bug 1689577 Opened 4 years ago Closed 4 years ago

Intermittent LeakSanitizer | leak at PR_Realloc, PORT_Realloc_Util, sslBuffer_Grow, ssl_CallCustomExtensionSenders

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=328186140&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/H6S9p3yLRa68X0XLhY2Hng/runs/0/artifacts/public/logs/live_backing.log


...
[task 2021-01-29T10:35:20.174Z] 10:35:20     INFO - TEST-OK | /webrtc/simplecall.https.html | took 2521ms
[task 2021-01-29T10:35:20.174Z] 10:35:20     INFO - No more tests
[task 2021-01-29T10:35:20.187Z] 10:35:20     INFO - PID 13963 | 1611916520185	Marionette	INFO	Stopped listening on port 41234
[task 2021-01-29T10:35:20.191Z] 10:35:20     INFO - PID 13963 | [Child 21096: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:629: SendAudioFrame Engine not transmitting
[task 2021-01-29T10:35:20.191Z] 10:35:20     INFO - PID 13963 | [Child 21096: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:629: SendAudioFrame Engine not transmitting
[task 2021-01-29T10:35:20.348Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.350Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.351Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.358Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.438Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.438Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.449Z] 10:35:20     INFO - PID 13963 | [Child 21096: Main Thread]: D/DataChannel Shutting down SCTP
[task 2021-01-29T10:35:20.480Z] 10:35:20     INFO - PID 13963 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-29T10:35:20.535Z] 10:35:20     INFO - PID 13963 | Timecard created 1611916514.755494
[task 2021-01-29T10:35:20.536Z] 10:35:20     INFO - PID 13963 |  Timestamp   | Delta       | Event                          | File                         | Function
[task 2021-01-29T10:35:20.538Z] 10:35:20     INFO - PID 13963 | ======================================================================================================================
[task 2021-01-29T10:35:20.539Z] 10:35:20     INFO - PID 13963 |     0.000269 |    0.000269 | Constructor Completed          | PeerConnectionImpl.cpp:326   | PeerConnectionImpl
...
[task 2021-01-29T10:35:20.843Z] 10:35:20     INFO - PID 13963 |     5.847179 |    5.662257 | Destructor Invoked             | PeerConnectionImpl.cpp:335   | ~PeerConnectionImpl
[task 2021-01-29T10:35:20.843Z] 10:35:20     INFO - PID 13963 | [Child 21096: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:356: PeerConnectionCtx is already gone. Ignoring...
[task 2021-01-29T10:35:20.844Z] 10:35:20     INFO - PID 13963 | [Child 21096: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {1dd67bd9-6328-4207-a9a2-54fc2ff6d1a3}
[task 2021-01-29T10:35:20.845Z] 10:35:20     INFO - PID 13963 | [Child 21096: Main Thread]: D/DataChannel 0x60f000106b10: Close()ing 0x6110003bb040
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 |      14        448 nsComponentManagerImpl
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 |       2        288 libfontconfig.so
[task 2021-01-29T10:35:21.595Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 |      14        448 nsComponentManagerImpl
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 |       2        288 libfontconfig.so
[task 2021-01-29T10:35:21.835Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:21.843Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:21.845Z] 10:35:21     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:21.845Z] 10:35:21     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:21.845Z] 10:35:21     INFO - PID 13963 |      14        448 nsComponentManagerImpl
[task 2021-01-29T10:35:21.845Z] 10:35:21     INFO - PID 13963 |       2        288 libfontconfig.so
[task 2021-01-29T10:35:21.845Z] 10:35:21     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:22.018Z] 10:35:22     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:22.020Z] 10:35:22     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:22.020Z] 10:35:22     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:22.021Z] 10:35:22     INFO - PID 13963 |      14        448 nsComponentManagerImpl
[task 2021-01-29T10:35:22.023Z] 10:35:22     INFO - PID 13963 |       2        288 libfontconfig.so
[task 2021-01-29T10:35:22.023Z] 10:35:22     INFO - PID 13963 |       2      65568 nr_reg_local_init
[task 2021-01-29T10:35:22.023Z] 10:35:22     INFO - PID 13963 |     195      18592 r_log_register
[task 2021-01-29T10:35:22.023Z] 10:35:22     INFO - PID 13963 |      69       3176 nr_reg_set
[task 2021-01-29T10:35:22.023Z] 10:35:22     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:22.053Z] 10:35:22     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:22.054Z] 10:35:22     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:22.055Z] 10:35:22     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:22.056Z] 10:35:22     INFO - PID 13963 |       1         32 nsComponentManagerImpl
[task 2021-01-29T10:35:22.056Z] 10:35:22     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:23.069Z] 10:35:23     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:23.070Z] 10:35:23     INFO - PID 13963 | Suppressions used:
[task 2021-01-29T10:35:23.071Z] 10:35:23     INFO - PID 13963 |   count      bytes template
[task 2021-01-29T10:35:23.071Z] 10:35:23     INFO - PID 13963 |      11        344 nsComponentManagerImpl
[task 2021-01-29T10:35:23.072Z] 10:35:23     INFO - PID 13963 |     633      18083 libfontconfig.so
[task 2021-01-29T10:35:23.072Z] 10:35:23     INFO - PID 13963 |       3        624 mozJSComponentLoader
[task 2021-01-29T10:35:23.073Z] 10:35:23     INFO - PID 13963 | -----------------------------------------------------
[task 2021-01-29T10:35:23.151Z] 10:35:23     INFO - Browser exited with return code 0
[task 2021-01-29T10:35:23.152Z] 10:35:23     INFO - PROCESS LEAKS None
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - ERROR | LeakSanitizer | SUMMARY: AddressSanitizer: 66633 byte(s) leaked in 2 allocation(s).
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - Allowed depth was 4
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PR_Realloc, PORT_Realloc_Util, sslBuffer_Grow, ssl_CallCustomExtensionSenders
[task 2021-01-29T10:35:23.154Z] 10:35:23     INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PR_Realloc, PORT_Realloc_Util, sslBuffer_Grow, sslBuffer_Append
[task 2021-01-29T10:35:23.155Z] 10:35:23     INFO - Closing logging queue
[task 2021-01-29T10:35:23.155Z] 10:35:23     INFO - queue closed
[task 2021-01-29T10:35:23.156Z] 10:35:23     INFO - PROCESS LEAKS None
[task 2021-01-29T10:35:23.156Z] 10:35:23     INFO - LeakSanitizer | To show the addresses of leaked objects add report_objects=1 to LSAN_OPTIONS
[task 2021-01-29T10:35:23.156Z] 10:35:23     INFO - This can be done in testing/mozbase/mozrunner/mozrunner/utils.py
[task 2021-01-29T10:35:23.156Z] 10:35:23     INFO - Allowed depth was 4
[task 2021-01-29T10:35:23.157Z] 10:35:23     INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PR_Realloc, PORT_Realloc_Util, sslBuffer_Grow, ssl_CallCustomExtensionSenders
[task 2021-01-29T10:35:23.157Z] 10:35:23     INFO - TEST-UNEXPECTED-FAIL | LeakSanitizer | leak at PR_Realloc, PORT_Realloc_Util, sslBuffer_Grow, sslBuffer_Append
[task 2021-01-29T10:35:23.172Z] 10:35:23     INFO - Got 0 unexpected results
[task 2021-01-29T10:35:23.172Z] 10:35:23     INFO - SUITE-END | took 749s
[task 2021-01-29T10:35:23.219Z] 10:35:23     INFO - Removed font: Ahem.ttf
[task 2021-01-29T10:35:23.273Z] 10:35:23     INFO - Closing logging queue
[task 2021-01-29T10:35:23.274Z] 10:35:23     INFO - queue closed
[task 2021-01-29T10:35:23.635Z] 10:35:23     INFO - Return code: 0
[task 2021-01-29T10:35:23.636Z] 10:35:23    ERROR - Got 5 unexpected statuses
[task 2021-01-29T10:35:23.636Z] 10:35:23  WARNING - # TBPL WARNING #
[task 2021-01-29T10:35:23.636Z] 10:35:23  WARNING - setting return code to 1```
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.