Closed Bug 1594271 Opened 5 years ago Closed 5 years ago

Intermittent SUMMARY: AddressSanitizer: heap-use-after-free z:\build\build\src\security\nss\lib\cryptohi\seckey.c:1025 in SECKEY_PublicKeyStrengthInBits

Categories

(NSS :: Libraries, defect)

defect
Not set
normal

Tracking

(Not tracked)

RESOLVED DUPLICATE of bug 1593401

People

(Reporter: cbrindusan, Unassigned)

Details

(Keywords: intermittent-failure)

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=274748339&repo=autoland&lineNumber=4200

[task 2019-11-06T01:03:51.475Z] 01:03:51 INFO - TEST-START | browser/components/syncedtabs/test/browser/browser_sidebar_syncedtabslist.js
[task 2019-11-06T01:03:53.199Z] 01:03:53 INFO - GECKO(9368) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2019-11-06T01:03:53.199Z] 01:03:53 INFO - GECKO(9368) | MEMORY STAT | vsize 19406259MB | vsizeMaxContiguous 65104537MB | residentFast 950MB
[task 2019-11-06T01:03:53.199Z] 01:03:53 INFO - TEST-OK | browser/components/syncedtabs/test/browser/browser_sidebar_syncedtabslist.js | took 1720ms
[task 2019-11-06T01:03:53.238Z] 01:03:53 INFO - checking window state
[task 2019-11-06T01:03:55.252Z] 01:03:55 INFO - GECKO(9368) | Completed ShutdownLeaks collections in process 4012
[task 2019-11-06T01:03:55.270Z] 01:03:55 INFO - GECKO(9368) | Completed ShutdownLeaks collections in process 8764
[task 2019-11-06T01:03:55.300Z] 01:03:55 INFO - GECKO(9368) | Completed ShutdownLeaks collections in process 6196
[task 2019-11-06T01:03:55.370Z] 01:03:55 INFO - GECKO(9368) | Completed ShutdownLeaks collections in process 6068
[task 2019-11-06T01:03:56.669Z] 01:03:56 INFO - GECKO(9368) | Completed ShutdownLeaks collections in process 4548
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - TEST-START | Shutdown
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - Browser Chrome Test Summary
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - Passed: 221
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - Failed: 0
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - Todo: 0
[task 2019-11-06T01:03:56.670Z] 01:03:56 INFO - Mode: e10s
[task 2019-11-06T01:03:56.671Z] 01:03:56 INFO - *** End BrowserChrome Test Results ***
[task 2019-11-06T01:03:56.934Z] 01:03:56 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.980Z] 01:03:56 INFO - GECKO(9368) | [Child 6196, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.980Z] 01:03:56 INFO - GECKO(9368) | [Child 6196, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.990Z] 01:03:56 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.990Z] 01:03:56 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.990Z] 01:03:56 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.990Z] 01:03:56 INFO - GECKO(9368) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:56.992Z] 01:03:56 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.992Z] 01:03:56 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.994Z] 01:03:56 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:56.994Z] 01:03:56 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.074Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.075Z] 01:03:57 INFO - GECKO(9368) | [Child 2572, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.075Z] 01:03:57 INFO - GECKO(9368) | [Child 2572, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.075Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.076Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.076Z] 01:03:57 INFO - GECKO(9368) | [Child 4012, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.077Z] 01:03:57 INFO - GECKO(9368) | [Child 4012, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.077Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.078Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.078Z] 01:03:57 INFO - GECKO(9368) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:57.080Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.081Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.081Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341[task 2019-11-06T01:03:57.086Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.088Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.088Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.088Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ip
[task 2019-11-06T01:03:57.089Z] 01:03:57 INFO - GECKO(9368) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:57.089Z] 01:03:57 INFO - GECKO(9368) | c/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.089Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.091Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.093Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.093Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.098Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.108Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.110Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.112Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.114Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.114Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.114Z] 01:03:57 INFO - GECKO(9368) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:57.116Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.118Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.121Z] 01:03:57 INFO - GECKO(9368) | [GPU 1
[task 2019-11-06T01:03:57.121Z] 01:03:57 INFO - GECKO(9368) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:57.121Z] 01:03:57 INFO - GECKO(9368) | 256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.121Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.121Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.122Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.131Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.131Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.534Z] 01:03:57 INFO - GECKO(9368) | 1573002237530 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2019-11-06T01:03:57.534Z] 01:03:57 INFO - GECKO(9368) | 1573002237530 Marionette INFO Stopped listening on port 2828
[task 2019-11-06T01:03:57.534Z] 01:03:57 INFO - GECKO(9368) | 1573002237530 Marionette DEBUG Remote service is inactive
[task 2019-11-06T01:03:57.625Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/
[task 2019-11-06T01:03:57.625Z] 01:03:57 INFO - GECKO(9368) | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-11-06T01:03:57.625Z] 01:03:57 INFO - GECKO(9368) | src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.626Z] 01:03:57 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.628Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341[task 2019-11-06T01:03:57.630Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:57.630Z] 01:03:57 INFO - GECKO(9368) | [Parent 4548, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:58.504Z] 01:03:58 INFO - GECKO(9368) | [GPU 1256, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-11-06T01:03:59.523Z] 01:03:59 INFO - TEST-INFO | Main app process: exit 0
[task 2019-11-06T01:03:59.523Z] 01:03:59 INFO - runtests.py | Application ran for: 0:00:18.035000
[task 2019-11-06T01:03:59.524Z] 01:03:59 INFO - zombiecheck | Reading PID log: c:\users\task_1572999657\appdata\local\temp\tmpvtg7awpidlog
[task 2019-11-06T01:03:59.524Z] 01:03:59 INFO - ==> process 4548 launched child process 1256 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.0.294340010\944377722" -parentBuildID 20191105231241 -prefsHandle 2160 -prefMapHandle 2152 -prefsLen 1 -prefMapSize 221008 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 gpu)
[task 2019-11-06T01:03:59.525Z] 01:03:59 INFO - ==> process 4548 launched child process 8764 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.6.974331560\1960537320" -childID 1 -isForBrowser -prefsHandle 2948 -prefMapHandle 2052 -prefsLen 2111 -prefMapSize 221008 -parentBuildID 20191105231241 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 tab)
[task 2019-11-06T01:03:59.525Z] 01:03:59 INFO - ==> process 4548 launched child process 6196 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.13.2008155430\579765381" -childID 2 -isForBrowser -prefsHandle 3140 -prefMapHandle 3136 -prefsLen 2577 -prefMapSize 221008 -parentBuildID 20191105231241 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 tab)
[task 2019-11-06T01:03:59.526Z] 01:03:59 INFO - ==> process 4548 launched child process 6068 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.20.1342781354\1939009196" -childID 3 -isForBrowser -prefsHandle 3352 -prefMapHandle 3348 -prefsLen 2638 -prefMapSize 221008 -parentBuildID 20191105231241 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 tab)
[task 2019-11-06T01:03:59.526Z] 01:03:59 INFO - ==> process 4548 launched child process 4012 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.27.1888584573\958880863" -childID 4 -isForBrowser -prefsHandle 4312 -prefMapHandle 4304 -prefsLen 11454 -prefMapSize 221008 -parentBuildID 20191105231241 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 tab)
[task 2019-11-06T01:03:59.526Z] 01:03:59 INFO - ==> process 4548 launched child process 2572 ("Z:\task_1572999657\build\application\firefox\firefox.exe" -contentproc --channel="4548.34.101483749\984847927" -childID 5 -isForBrowser -prefsHandle 4528 -prefMapHandle 4524 -prefsLen 11454 -prefMapSize 221008 -parentBuildID 20191105231241 -greomni "Z:\task_1572999657\build\application\firefox\omni.ja" -appomni "Z:\task_1572999657\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1572999657\build\application\firefox\browser" - 4548 tab)
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 1256
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 2572
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 4012
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 6196
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 6068
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - zombiecheck | Checking for orphan process with PID: 8764
[task 2019-11-06T01:03:59.527Z] 01:03:59 INFO - Stopping web server
[task 2019-11-06T01:03:59.536Z] 01:03:59 INFO - Stopping web socket server
[task 2019-11-06T01:03:59.555Z] 01:03:59 INFO - Stopping ssltunnel
[task 2019-11-06T01:03:59.595Z] 01:03:59 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2019-11-06T01:03:59.595Z] 01:03:59 INFO - runtests.py | Running tests: end.
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - Buffered messages finished
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - Running manifest: browser\components\tests\browser\whats_new_page\browser.ini
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - The following extra prefs will be set:
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - app.update.altUpdateDirPath='<test-root>/browser/components/tests/browser/whats_new_page'
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - app.update.disabledForTesting=false
[task 2019-11-06T01:03:59.635Z] 01:03:59 INFO - browser.startup.homepage_override.mstone="60.0"
[task 2019-11-06T01:03:59.674Z] 01:03:59 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1572999657\build\application\firefox\llvm-symbolizer.exe
[task 2019-11-06T01:03:59.734Z] 01:03:59 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-11-06T01:04:00.671Z] 01:04:00 INFO - Z:\task_1572999657\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2019-11-06T01:04:00.953Z] 01:04:00 INFO - Increasing default timeout to 90 seconds[task 2019-11-06T01:04:00.953Z] 01:04:00 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1572999657\build\application\firefox\llvm-symbolizer.exe
[task 2019-11-06T01:04:01.012Z] 01:04:01 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-11-06T01:04:01.012Z] 01:04:01 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1572999657\build\application\firefox\llvm-symbolizer.exe
[task 2019-11-06T01:04:01.072Z] 01:04:01 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-11-06T01:04:01.072Z] 01:04:01 INFO - MochitestServer : launching [u'Z:\task_1572999657\build\tests\bin\xpcshell.exe', '-g', 'Z:\task_1572999657\build\application\firefox', '-f', 'Z:\task_1572999657\build\tests\bin\components\httpd.js', '-e', "const _PROFILE_PATH = 'c:\\users\\task_1572999657\\appdata\\local\\temp\\tmp9qzklk.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\task_1572999657\build\tests\mochitest\server.js']
[task 2019-11-06T01:04:01.072Z] 01:04:01 INFO - runtests.py | Server pid: 9600
[task 2019-11-06T01:04:01.072Z] 01:04:01 INFO - runtests.py | Websocket server pid: 9204
[task 2019-11-06T01:04:01.073Z] 01:04:01 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_1572999657\build\application\firefox\llvm-symbolizer.exe
[task 2019-11-06T01:04:01.131Z] 01:04:01 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-11-06T01:04:01.136Z] 01:04:01 INFO - runtests.py | SSL tunnel pid: 9328
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running with scheme: http
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running with e10s: True
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running with fission: False
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO - runtests.py | Running tests: start.
[task 2019-11-06T01:04:01.543Z] 01:04:01 INFO -
[task 2019-11-06T01:04:01.622Z] 01:04:01 INFO - Application command: Z:\task_1572999657\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile c:\users\task_1572999657\appdata\local\temp\tmp9qzklk.mozrunner
[task 2019-11-06T01:04:01.622Z] 01:04:01 INFO - runtests.py | Application pid: 2536
[task 2019-11-06T01:04:01.623Z] 01:04:01 INFO - TEST-INFO | started process GECKO(2536)
[task 2019-11-06T01:04:03.631Z] 01:04:03 INFO - GECKO(2536) | 1573002243617 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-11-06T01:04:03.631Z] 01:04:03 INFO - GECKO(2536) | 1573002243618 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2019-11-06T01:04:03.631Z] 01:04:03 INFO - GECKO(2536) | 1573002243618 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-11-06T01:04:03.631Z] 01:04:03 INFO - GECKO(2536) | 1573002243619 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2019-11-06T01:04:03.988Z] 01:04:03 INFO - GECKO(2536) | 1573002243986 Marionette TRACE Received observer notification profile-after-change
[task 2019-11-06T01:04:04.169Z] 01:04:04 INFO - GECKO(2536) | 1573002244159 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-11-06T01:04:04.363Z] 01:04:04 INFO - GECKO(2536) | 1573002244356 Marionette TRACE Received observer notification command-line-startup
[task 2019-11-06T01:04:04.363Z] 01:04:04 INFO - GECKO(2536) | 1573002244357 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-11-06T01:04:04.725Z] 01:04:04 INFO - GECKO(2536) | 1573002244716 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-11-06T01:04:07.793Z] 01:04:07 INFO - =================================================================
[task 2019-11-06T01:04:07.793Z] 01:04:07 ERROR - ==9328==ERROR: AddressSanitizer: heap-use-after-free on address 0x1215cce5d2a8 at pc 0x7ffd12eede3f bp 0x00c6393bc800 sp 0x00c6393bc848
[task 2019-11-06T01:04:07.793Z] 01:04:07 INFO - READ of size 4 at 0x1215cce5d2a8 thread T3
[task 2019-11-06T01:04:07.832Z] 01:04:07 INFO - #0 0x7ffd12eede3e in SECKEY_PublicKeyStrengthInBits z:\build\build\src\security\nss\lib\cryptohi\seckey.c:1025
[task 2019-11-06T01:04:07.833Z] 01:04:07 INFO - #1 0x7ffd12eedb68 in SECKEY_PublicKeyStrength z:\build\build\src\security\nss\lib\cryptohi\seckey.c:1010
[task 2019-11-06T01:04:07.833Z] 01:04:07 INFO - #2 0x7ffd130a931b in ssl_GenerateSelfEncryptKeysOnce z:\build\build\src\security\nss\lib\ssl\sslsnce.c
[task 2019-11-06T01:04:07.833Z] 01:04:07 INFO - #3 0x7ffd12e88e39 in PR_CallOnceWithArg z:\build\build\src\nsprpub\pr\src\misc\prinit.c:807
[task 2019-11-06T01:04:07.833Z] 01:04:07 INFO - #4 0x7ffd130a8be7 in ssl_GetSelfEncryptKeys z:\build\build\src\security\nss\lib\ssl\sslsnce.c:1820
[task 2019-11-06T01:04:07.834Z] 01:04:07 INFO - #5 0x7ffd13040839 in ssl_SelfEncryptProtect z:\build\build\src\security\nss\lib\ssl\selfencrypt.c:290[task 2019-11-06T01:04:07.834Z] 01:04:07 INFO - #6 0x7ffd13087296 in ssl3_EncodeSessionTicket z:\build\build\src\security\nss\lib\ssl\ssl3exthandle.c:824
[task 2019-11-06T01:04:07.834Z] 01:04:07 INFO - #7 0x7ffd130d0520 in tls13_SendNewSessionTicket z:\build\build\src\security\nss\lib\ssl\tls13con.c:4939
[task 2019-11-06T01:04:07.834Z] 01:04:07 INFO - #8 0x7ffd130c4237 in tls13_HandlePostHelloHandshakeMessage z:\build\build\src\security\nss\lib\ssl\tls13con.c:942
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #9 0x7ffd1306227b in ssl3_HandleHandshakeMessage z:\build\build\src\security\nss\lib\ssl\ssl3con.c:12048
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #10 0x7ffd1306f0ba in ssl3_HandleNonApplicationData z:\build\build\src\security\nss\lib\ssl\ssl3con.c:12739
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #11 0x7ffd1307167c in ssl3_HandleRecord z:\build\build\src\security\nss\lib\ssl\ssl3con.c:13021
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #12 0x7ffd1308d86e in ssl3_GatherCompleteHandshake z:\build\build\src\security\nss\lib\ssl\ssl3gthr.c:512
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #13 0x7ffd13092dfa in ssl_GatherRecord1stHandshake z:\build\build\src\security\nss\lib\ssl\sslcon.c:73
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #14 0x7ffd130a1e04 in ssl_SecureRecv z:\build\build\src\security\nss\lib\ssl\sslsecur.c:808
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #15 0x7ffd130bc055 in ssl_Recv z:\build\build\src\security\nss\lib\ssl\sslsock.c:3104
[task 2019-11-06T01:04:07.835Z] 01:04:07 INFO - #16 0x7ff7a2525032 (Z:\task_1572999657\build\tests\bin\ssltunnel.exe+0x140005032)
[task 2019-11-06T01:04:07.836Z] 01:04:07 INFO - #17 0x7ffd12e9ba1a in wstart z:\build\build\src\nsprpub\pr\src\misc\prtpool.c:209
[task 2019-11-06T01:04:07.836Z] 01:04:07 INFO - #18 0x7ffd12ea73dd in _PR_NativeRunThread z:\build\build\src\nsprpub\pr\src\threads\combined\pruthr.c:399
[task 2019-11-06T01:04:07.836Z] 01:04:07 INFO - #19 0x7ffd12e773f4 in pr_root z:\build\build\src\nsprpub\pr\src\md\windows\w95thred.c:139
[task 2019-11-06T01:04:07.837Z] 01:04:07 INFO - #20 0x7ffd289ec4bd (C:\Windows\System32\ucrtbase.dll+0x18001c4bd)
[task 2019-11-06T01:04:07.837Z] 01:04:07 INFO - #21 0x7ffd132bf838 (Z:\task_1572999657\build\tests\bin\clang_rt.asan_dynamic-x86_64.dll+0x18003f838)
[task 2019-11-06T01:04:07.838Z] 01:04:07 INFO - #22 0x7ffd2a443033 (C:\Windows\System32\KERNEL32.DLL+0x180013033)
[task 2019-11-06T01:04:07.838Z] 01:04:07 INFO - #23 0x7ffd2b781460 (C:\Windows\SYSTEM32\ntdll.dll+0x180071460)
[task 2019-11-06T01:04:07.839Z] 01:04:07 INFO - 0x1215cce5d2a8 is located 40 bytes inside of 2048-byte region [0x1215cce5d280,0x1215cce5da80)
[task 2019-11-06T01:04:07.839Z] 01:04:07 INFO - freed by thread T6 here:
[task 2019-11-06T01:04:07.839Z] 01:04:07 INFO - #0 0x7ffd132b4ae4 (Z:\task_1572999657\build\tests\bin\clang_rt.asan_dynamic-x86_64.dll+0x180034ae4)
[task 2019-11-06T01:04:07.839Z] 01:04:07 INFO - #1 0x7ffd130044f4 in PL_FinishArenaPool z:\build\build\src\nsprpub\lib\ds\plarena.c:227
[task 2019-11-06T01:04:07.840Z] 01:04:07 INFO - #2 0x7ffd12ff3014 in PORT_FreeArena_Util z:\build\build\src\security\nss\lib\util\secport.c:380
[task 2019-11-06T01:04:07.840Z] 01:04:07 INFO - #3 0x7ffd130a8a97 in ssl_SetSelfEncryptKeyPair z:\build\build\src\security\nss\lib\ssl\sslsnce.c:1715
[task 2019-11-06T01:04:07.841Z] 01:04:07 INFO - #4 0x7ffd1309260c in ssl_PopulateKeyPair z:\build\build\src\security\nss\lib\ssl\sslcert.c:289
[task 2019-11-06T01:04:07.841Z] 01:04:07 INFO - #5 0x7ffd13092337 in SSL_ConfigSecureServerWithCertChain z:\build\build\src\security\nss\lib\ssl\sslcert.c:892
[task 2019-11-06T01:04:07.842Z] 01:04:07 INFO - #6 0x7ffd13092026 in SSL_ConfigSecureServer z:\build\build\src\security\nss\lib\ssl\sslcert.c:703
[task 2019-11-06T01:04:07.842Z] 01:04:07 INFO - #7 0x7ff7a2522d68 (Z:\task_1572999657\build\tests\bin\ssltunnel.exe+0x140002d68)
[task 2019-11-06T01:04:07.843Z] 01:04:07 INFO - #8 0x7ff7a25255f3 (Z:\task_1572999657\build\tests\bin\ssltunnel.exe+0x1400055f3)
[task 2019-11-06T01:04:07.843Z] 01:04:07 INFO - #9 0x7ffd12e9ba1a in wstart z:\build\build\src\nsprpub\pr\src\misc\prtpool.c:209
[task 2019-11-06T01:04:07.844Z] 01:04:07 INFO - #10 0x7ffd12ea73dd in _PR_NativeRunThread z:\build\build\src\nsprpub\pr\src\threads\combined\pruthr.c:399
[task 2019-11-06T01:04:07.844Z] 01:04:07 INFO - #11 0x7ffd12e773f4 in pr_root z:\build\build\src\nsprpub\pr\src\md\windows\w95thred.c:139
[task 2019-11-06T01:04:07.844Z] 01:04:07 INFO - #12 0x7ffd289ec4bd (C:\Windows\System32\ucrtbase.dll+0x18001c4bd)
[task 2019-11-06T01:04:07.845Z] 01:04:07 INFO - #13 0x7ffd132bf838 (Z:\task_1572999657\build\tests\bin\clang_rt.asan_dynamic-x86_64.dll+0x18003f838)
[task 2019-11-06T01:04:07.845Z] 01:04:07 INFO - #14 0x7ffd2a443033 (C:\Windows\System32\KERNEL32.DLL+0x180013033)
[task 2019-11-06T01:04:07.845Z] 01:04:07 INFO - #15 0x7ffd2b781460 (C:\Windows\SYSTEM32\ntdll.dll+0x180071460)
[task 2019-11-06T01:04:07.846Z] 01:04:07 INFO - previously allocated by thread T3 here:[task 2019-11-06T01:04:07.846Z] 01:04:07 INFO - #0 0x7ffd132b4bf4 (Z:\task_1572999657\build\tests\bin\clang_rt.asan_dynamic-x86_64.dll+0x180034bf4)
[task 2019-11-06T01:04:07.847Z] 01:04:07 INFO - #1 0x7ffd13003e73 in PL_ArenaAllocate z:\build\build\src\nsprpub\lib\ds\plarena.c:134
[task 2019-11-06T01:04:07.847Z] 01:04:07 INFO - #2 0x7ffd12ff2e13 in PORT_ArenaAlloc_Util z:\build\build\src\security\nss\lib\util\secport.c:318
[task 2019-11-06T01:04:07.848Z] 01:04:07 INFO - #3 0x7ffd12ff2f49 in PORT_ArenaZAlloc_Util z:\build\build\src\security\nss\lib\util\secport.c:339
[task 2019-11-06T01:04:07.848Z] 01:04:07 INFO - #4 0x7ffd12eee483 in SECKEY_CopyPublicKey z:\build\build\src\security\nss\lib\cryptohi\seckey.c:1131
[task 2019-11-06T01:04:07.848Z] 01:04:07 INFO - #5 0x7ffd130a8a61 in ssl_SetSelfEncryptKeyPair z:\build\build\src\security\nss\lib\ssl\sslsnce.c:1701
[task 2019-11-06T01:04:07.849Z] 01:04:07 INFO - #6 0x7ffd1309260c in ssl_PopulateKeyPair z:\build\build\src\security\nss\lib\ssl\sslcert.c:289
[task 2019-11-06T01:04:07.849Z] 01:04:07 INFO - #7 0x7ffd13092337 in SSL_ConfigSecureServerWithCertChain z:\build\build\src\security\nss\lib\ssl\sslcert.c:892
[task 2019-11-06T01:04:07.849Z] 01:04:07 INFO - #8 0x7ffd13092026 in SSL_ConfigSecureServer z:\build\build\src\security\nss\lib\ssl\sslcert.c:703
[task 2019-11-06T01:04:07.849Z] 01:04:07 INFO - #9 0x7ff7a2522d68 (Z:\task_1572999657\build\tests\bin\ssltunnel.exe+0x140002d68)
[task 2019-11-06T01:04:07.849Z] 01:04:07 INFO - #10 0x7ff7a25255f3 (Z:\task_1572999657\build\tests\bin\ssltunnel.exe+0x1400055f3)
[task 2019-11-06T01:04:07.850Z] 01:04:07 INFO - #11 0x7ffd12e9ba1a in wstart z:\build\build\src\nsprpub\pr\src\misc\prtpool.c:209
[task 2019-11-06T01:04:07.850Z] 01:04:07 INFO - #12 0x7ffd12ea73dd in _PR_NativeRunThread z:\build\build\src\nsprpub\pr\src\threads\combined\pruthr.c:399
[task 2019-11-06T01:04:07.850Z] 01:04:07 INFO - #13 0x7ffd12e773f4 in pr_root z:\build\build\src\nsprpub\pr\src\md\windows\w95thred.c:139
[task 2019-11-06T01:04:07.851Z] 01:04:07 INFO - #14 0x7ffd289ec4bd (C:\Windows\System32\ucrtbase.dll+0x18001c4bd)
[task 2019-11-06T01:04:07.851Z] 01:04:07 INFO - #15 0x7ffd132bf838 (Z:\task_1572999657\build\tests\bin\clang_rt.asan_dynamic-x86_64.dll+0x18003f838)
[task 2019-11-06T01:04:07.851Z] 01:04:07 INFO - #16 0x7ffd2a443033 (C:\Windows\System32\KERNEL32.DLL+0x180013033)
[task 2019-11-06T01:04:07.852Z] 01:04:07 INFO - #17 0x7ffd2b781460 (C:\Windows\SYSTEM32\ntdll.dll+0x180071460)

Aryx, please close this bug if duplicate and update component if necessary.

Flags: needinfo?(aryx.bugmail)
Status: NEW → RESOLVED
Closed: 5 years ago
Flags: needinfo?(aryx.bugmail)
Resolution: --- → DUPLICATE
Group: crypto-core-security
You need to log in before you can comment on or make changes to this bug.