Closed
Bug 1770777
Opened 4 years ago
Closed 4 years ago
Intermittent tools/profiler/tests/browser/browser_test_feature_ipcmessages.js | application terminated with exit code 3221226505
Categories
(Core :: Gecko Profiler, defect, P5)
Core
Gecko Profiler
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378966682&repo=mozilla-esr91
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/cfBMAEk6QdmsbnXQ3lPZEw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-05-23T13:21:21.767Z] 13:21:21 INFO - TEST-START | tools/profiler/tests/browser/browser_test_feature_ipcmessages.js
[task 2022-05-23T13:21:23.801Z] 13:21:23 INFO - TEST-INFO | Main app process: exit c0000409
[task 2022-05-23T13:21:23.806Z] 13:21:23 INFO - Buffered messages logged at 13:21:21
[task 2022-05-23T13:21:23.807Z] 13:21:23 INFO - Entering test bound test_profile_feature_ipcmessges
[task 2022-05-23T13:21:23.808Z] 13:21:23 INFO - TEST-PASS | tools/profiler/tests/browser/browser_test_feature_ipcmessages.js | The profiler is not currently active - true == true -
[task 2022-05-23T13:21:23.808Z] 13:21:23 INFO - Open a tab while profiling IPC messages.
[task 2022-05-23T13:21:23.809Z] 13:21:23 INFO - Buffered messages finished
[task 2022-05-23T13:21:23.810Z] 13:21:23 ERROR - TEST-UNEXPECTED-FAIL | tools/profiler/tests/browser/browser_test_feature_ipcmessages.js | application terminated with exit code 3221226505
[task 2022-05-23T13:21:23.811Z] 13:21:23 INFO - runtests.py | Application ran for: 0:00:12.411282
[task 2022-05-23T13:21:23.811Z] 13:21:23 INFO - zombiecheck | Reading PID log: C:\Users\task_165330916370691\AppData\Local\Temp\tmpubokuj6npidlog
[task 2022-05-23T13:21:23.812Z] 13:21:23 INFO - ==> process 7040 launched child process 8568 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.0.393129585\58817455" -parentBuildID 20220523115307 -prefsHandle 2124 -prefMapHandle 2120 -prefsLen 1 -prefMapSize 249087 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 gpu)
[task 2022-05-23T13:21:23.813Z] 13:21:23 INFO - ==> process 7040 launched child process 7432 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.1.1038774171\643673315" -childID 1 -isForBrowser -prefsHandle 2740 -prefMapHandle 2736 -prefsLen 1808 -prefMapSize 249087 -jsInit 1364 285636 -parentBuildID 20220523115307 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 tab)
[task 2022-05-23T13:21:23.815Z] 13:21:23 INFO - ==> process 7040 launched child process 8664 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.3.1628406613\2023544947" -childID 2 -isForBrowser -prefsHandle 3016 -prefMapHandle 3012 -prefsLen 1950 -prefMapSize 249087 -jsInit 1364 285636 -parentBuildID 20220523115307 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 tab)
[task 2022-05-23T13:21:23.816Z] 13:21:23 INFO - ==> process 7040 launched child process 3348 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.5.1548077650\1931546544" -childID 3 -isForBrowser -prefsHandle 3144 -prefMapHandle 3140 -prefsLen 1990 -prefMapSize 249087 -jsInit 1364 285636 -parentBuildID 20220523115307 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 tab)
[task 2022-05-23T13:21:23.817Z] 13:21:23 INFO - ==> process 7040 launched child process 1920 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.7.1904155850\1561034001" -childID 4 -isForBrowser -prefsHandle 4064 -prefMapHandle 4060 -prefsLen 8986 -prefMapSize 249087 -jsInit 1364 285636 -parentBuildID 20220523115307 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 tab)
[task 2022-05-23T13:21:23.818Z] 13:21:23 INFO - ==> process 7040 launched child process 2564 ("Z:\task_165330916370691\build\application\firefox\firefox.exe" -contentproc --channel="7040.9.2063889267\282757071" -childID 5 -isForBrowser -prefsHandle 4524 -prefMapHandle 4504 -prefsLen 10231 -prefMapSize 249087 -jsInit 1364 285636 -parentBuildID 20220523115307 -appdir "Z:\task_165330916370691\build\application\firefox\browser" - 7040 tab)
[task 2022-05-23T13:21:23.818Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 1920
[task 2022-05-23T13:21:23.819Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 2564
[task 2022-05-23T13:21:23.819Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 8664
[task 2022-05-23T13:21:23.820Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 7432
[task 2022-05-23T13:21:23.820Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 3348
[task 2022-05-23T13:21:23.821Z] 13:21:23 INFO - zombiecheck | Checking for orphan process with PID: 8568
[task 2022-05-23T13:21:23.821Z] 13:21:23 INFO - Stopping web server
[task 2022-05-23T13:21:23.823Z] 13:21:23 INFO - Server shut down.
[task 2022-05-23T13:21:23.852Z] 13:21:23 INFO - Web server killed.
[task 2022-05-23T13:21:23.859Z] 13:21:23 INFO - Stopping web socket server
[task 2022-05-23T13:21:23.888Z] 13:21:23 INFO - Stopping ssltunnel
[task 2022-05-23T13:21:23.918Z] 13:21:23 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-05-23T13:21:23.920Z] 13:21:23 INFO - runtests.py | Running tests: end.
[task 2022-05-23T13:21:23.964Z] 13:21:23 INFO - Buffered messages finished
[task 2022-05-23T13:21:23.977Z] 13:21:23 INFO - Running manifest: widget\tests\browser\browser.ini
[task 2022-05-23T13:21:24.006Z] 13:21:24 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_165330916370691\build\application\firefox\llvm-symbolizer.exe
[task 2022-05-23T13:21:24.104Z] 13:21:24 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2022-05-23T13:21:25.097Z] 13:21:25 INFO - PID 8340 | Z:\task_165330916370691\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2022-05-23T13:21:25.364Z] 13:21:25 INFO - Increasing default timeout to 90 seconds
[task 2022-05-23T13:21:25.375Z] 13:21:25 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_165330916370691\build\application\firefox\llvm-symbolizer.exe
[task 2022-05-23T13:21:25.450Z] 13:21:25 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2022-05-23T13:21:25.452Z] 13:21:25 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_165330916370691\build\application\firefox\llvm-symbolizer.exe
[task 2022-05-23T13:21:25.527Z] 13:21:25 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2022-05-23T13:21:25.532Z] 13:21:25 INFO - MochitestServer : launching ['Z:\\task_165330916370691\\build\\tests\\bin\\xpcshell.exe', '-g', 'Z:\\task_165330916370691\\build\\application\\firefox', '-f', 'Z:\\task_165330916370691\\build\\tests\\bin\\components\\httpd.js', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_165330916370691\\\\AppData\\\\Local\\\\Temp\\\\tmpv89_pvvi.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\\task_165330916370691\\build\\tests\\mochitest\\server.js']
[task 2022-05-23T13:21:25.532Z] 13:21:25 INFO - runtests.py | Server pid: 5544
[task 2022-05-23T13:21:25.535Z] 13:21:25 INFO - runtests.py | Websocket server pid: 1124
[task 2022-05-23T13:21:25.536Z] 13:21:25 INFO - INFO | runtests.py | ASan using symbolizer at Z:\task_165330916370691\build\application\firefox\llvm-symbolizer.exe
[task 2022-05-23T13:21:25.612Z] 13:21:25 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2022-05-23T13:21:25.622Z] 13:21:25 INFO - runtests.py | SSL tunnel pid: 7152
[task 2022-05-23T13:21:25.932Z] 13:21:25 INFO - runtests.py | Running with scheme: http
[task 2022-05-23T13:21:25.934Z] 13:21:25 INFO - runtests.py | Running with e10s: True
[task 2022-05-23T13:21:25.934Z] 13:21:25 INFO - runtests.py | Running with fission: False
[task 2022-05-23T13:21:25.935Z] 13:21:25 INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-05-23T13:21:25.935Z] 13:21:25 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-05-23T13:21:25.935Z] 13:21:25 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-05-23T13:21:25.936Z] 13:21:25 INFO - runtests.py | Running tests: start.
[task 2022-05-23T13:21:25.936Z] 13:21:25 INFO -
[task 2022-05-23T13:21:26.012Z] 13:21:26 INFO - Application command: Z:\task_165330916370691\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_165330916370691\AppData\Local\Temp\tmpv89_pvvi.mozrunner
[task 2022-05-23T13:21:26.022Z] 13:21:26 INFO - runtests.py | Application pid: 7756
[task 2022-05-23T13:21:26.023Z] 13:21:26 INFO - TEST-INFO | started process GECKO(7756)
[task 2022-05-23T13:21:27.834Z] 13:21:27 INFO - GECKO(7756) | 1653312087835 Marionette INFO Marionette enabled
[task 2022-05-23T13:21:28.104Z] 13:21:28 INFO - GECKO(7756) | 1653312088107 RemoteAgent DEBUG CDP enabled
[task 2022-05-23T13:21:28.122Z] 13:21:28 INFO - GECKO(7756) | 1653312088127 Marionette TRACE Received observer notification toplevel-window-ready
[task 2022-05-23T13:21:30.537Z] 13:21:30 INFO - GECKO(7756) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2022-05-23T13:21:32.123Z] 13:21:32 INFO - GECKO(7756) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_165330916370691\\AppData\\Local\\Temp\\tmpv89_pvvi.mozrunner\\search.json.mozlz4", (void 0)))
[task 2022-05-23T13:21:34.633Z] 13:21:34 INFO - GECKO(7756) | 1653312094646 Marionette TRACE Received observer notification marionette-startup-requested
[task 2022-05-23T13:21:34.648Z] 13:21:34 INFO - GECKO(7756) | 1653312094647 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2022-05-23T13:21:34.687Z] 13:21:34 INFO - GECKO(7756) | 1653312094693 Marionette TRACE All scripts recorded.
[task 2022-05-23T13:21:34.702Z] 13:21:34 INFO - GECKO(7756) | 1653312094701 Marionette INFO Listening on port 2828
[task 2022-05-23T13:21:34.703Z] 13:21:34 INFO - GECKO(7756) | 1653312094702 Marionette DEBUG Marionette is listening
[task 2022-05-23T13:21:35.150Z] 13:21:35 INFO - GECKO(7756) | 1653312095162 Marionette DEBUG Accepted connection 0 from 127.0.0.1:52630
[task 2022-05-23T13:21:35.169Z] 13:21:35 INFO - GECKO(7756) | 1653312095168 Marionette DEBUG Closed connection 0
[task 2022-05-23T13:21:35.229Z] 13:21:35 INFO - GECKO(7756) | 1653312095231 Marionette DEBUG Accepted connection 1 from 127.0.0.1:52631
[task 2022-05-23T13:21:35.233Z] 13:21:35 INFO - GECKO(7756) | 1653312095233 Marionette DEBUG Closed connection 1
[task 2022-05-23T13:21:35.234Z] 13:21:35 INFO - GECKO(7756) | 1653312095234 Marionette DEBUG Accepted connection 2 from 127.0.0.1:52632
[task 2022-05-23T13:21:35.272Z] 13:21:35 INFO - GECKO(7756) | 1653312095281 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-05-23T13:21:35.357Z] 13:21:35 INFO - GECKO(7756) | 1653312095366 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"3ce33309-777f-4c04-8456-820f955cb4d8","capabilities":{"browserName":"firefox","browserVersion":"91.10 ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-05-23T13:21:35.381Z] 13:21:35 INFO - GECKO(7756) | 1653312095394 Marionette DEBUG 2 -> [0,2,"Addon:Install",{"path":"C:\\Users\\task_165330916370691\\AppData\\Local\\Temp\\tmpo2gtxvih.zip","temporary":false}]
[task 2022-05-23T13:21:35.605Z] 13:21:35 INFO - GECKO(7756) | 1653312095616 Marionette DEBUG 2 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-05-23T13:21:35.646Z] 13:21:35 INFO - GECKO(7756) | 1653312095645 Marionette DEBUG 2 -> [0,3,"Addon:Install",{"path":"C:\\Users\\task_165330916370691\\AppData\\Local\\Temp\\tmpez_qo7go.zip","temporary":false}]
[task 2022-05-23T13:21:35.736Z] 13:21:35 INFO - GECKO(7756) | 1653312095736 Marionette DEBUG 2 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-05-23T13:21:35.743Z] 13:21:35 INFO - GECKO(7756) | 1653312095742 Marionette DEBUG 2 -> [0,4,"Marionette:GetContext",{}]
[task 2022-05-23T13:21:35.744Z] 13:21:35 INFO - GECKO(7756) | 1653312095743 Marionette DEBUG 2 <- [1,4,null,{"value":"content"}]
[task 2022-05-23T13:21:35.751Z] 13:21:35 INFO - GECKO(7756) | 1653312095750 Marionette DEBUG 2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-05-23T13:21:35.752Z] 13:21:35 INFO - GECKO(7756) | 1653312095751 Marionette DEBUG 2 <- [1,5,null,{"value":null}]
[task 2022-05-23T13:21:35.757Z] 13:21:35 INFO - GECKO(7756) | 1653312095756 Marionette DEBUG 2 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... ewSandbox":true,"sandbox":"default","line":1933,"filename":"Z:\\task_165330916370691\\build\\tests\\mochitest\\runtests.py"}]
[task 2022-05-23T13:21:35.774Z] 13:21:35 INFO - GECKO(7756) | 1653312095775 Marionette TRACE [10] MarionetteCommands actor created for window id 4
[task 2022-05-23T13:21:35.778Z] 13:21:35 INFO - GECKO(7756) | 1653312095777 Marionette TRACE [44] MarionetteEvents actor created for window id 8589934593
[task 2022-05-23T13:21:35.792Z] 13:21:35 INFO - GECKO(7756) | 1653312095792 Marionette TRACE [24] MarionetteEvents actor created for window id 4294967297
[task 2022-05-23T13:21:35.838Z] 13:21:35 INFO - GECKO(7756) | 1653312095849 Marionette TRACE Received observer notification domwindowopened
[task 2022-05-23T13:21:35.874Z] 13:21:35 INFO - GECKO(7756) | 1653312095873 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2022-05-23T13:21:35.881Z] 13:21:35 INFO - GECKO(7756) | 1653312095880 Marionette DEBUG 2 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-05-23T13:21:35.881Z] 13:21:35 INFO - GECKO(7756) | 1653312095881 Marionette DEBUG 2 <- [1,7,null,{"value":null}]
[task 2022-05-23T13:21:35.894Z] 13:21:35 INFO - GECKO(7756) | 1653312095906 Marionette TRACE [24] MarionetteEvents actor created for window id 4294967298
[task 2022-05-23T13:21:35.967Z] 13:21:35 INFO - GECKO(7756) | 1653312095967 Marionette DEBUG 2 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-05-23T13:21:35.970Z] 13:21:35 INFO - GECKO(7756) | 1653312095972 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2022-05-23T13:21:36.017Z] 13:21:36 INFO - runtests.py | Waiting for browser...
[task 2022-05-23T13:21:36.032Z] 13:21:36 INFO - GECKO(7756) | 1653312096044 Marionette DEBUG Closed connection 2
[task 2022-05-23T13:21:36.507Z] 13:21:36 INFO - TEST-START | widget/tests/browser/browser_test_clipboardcache.js
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
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.
Description
•