Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=469665455&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Oa8oCoAbTImKTzr2uGznfg/runs/0/artifacts/public/logs/live_backing.log
[task 2024-08-08T02:33:11.483Z] 02:33:11 INFO - TEST-START | Shutdown
[task 2024-08-08T02:33:11.484Z] 02:33:11 INFO - Browser Chrome Test Summary
[task 2024-08-08T02:33:11.484Z] 02:33:11 INFO - Passed: 3
[task 2024-08-08T02:33:11.485Z] 02:33:11 INFO - Failed: 0
[task 2024-08-08T02:33:11.485Z] 02:33:11 INFO - Todo: 0
[task 2024-08-08T02:33:11.486Z] 02:33:11 INFO - Mode: e10s
[task 2024-08-08T02:33:11.486Z] 02:33:11 INFO - *** End BrowserChrome Test Results ***
[task 2024-08-08T02:33:12.190Z] 02:33:12 INFO - GECKO(10016) | 1723084392189 Marionette TRACE Received observer notification quit-application
[task 2024-08-08T02:33:12.191Z] 02:33:12 INFO - GECKO(10016) | 1723084392190 Marionette INFO Stopped listening on port 2828
[task 2024-08-08T02:33:12.193Z] 02:33:12 INFO - GECKO(10016) | 1723084392193 Marionette DEBUG Marionette stopped listening
[task 2024-08-08T02:33:14.540Z] 02:33:14 INFO - TEST-INFO | Main app process: exit 0
[task 2024-08-08T02:33:14.541Z] 02:33:14 INFO - runtests.py | Application ran for: 0:00:20.662979
[task 2024-08-08T02:33:14.542Z] 02:33:14 INFO - zombiecheck | Reading PID log: C:\Users\task_172308258379756\AppData\Local\Temp\tmp8yz7pds2pidlog
[task 2024-08-08T02:33:14.543Z] 02:33:14 INFO - ==> process 7148 launched child process 7408 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=2168 -parentBuildID 20240808013316 -prefsHandle 2152 -prefMapHandle 2144 -prefsLen 24685 -prefMapSize 273368 -appDir "D:\task_172308258379756\build\application\firefox\browser" - {5875b61a-b103-4d00-866a-a77b3844ef4f} 7148 1 gpu)
[task 2024-08-08T02:33:14.544Z] 02:33:14 INFO - ==> process 7148 launched child process 8500 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=1744 -isForBrowser -prefsHandle 1724 -prefMapHandle 2816 -prefsLen 24441 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {298d6f9d-3358-44f1-a80f-d02769ee3994} 7148 2 tab)
[task 2024-08-08T02:33:14.545Z] 02:33:14 INFO - ==> process 7148 launched child process 10144 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3108 -isForBrowser -prefsHandle 3268 -prefMapHandle 3264 -prefsLen 26007 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {e0ac6626-7b1c-4b48-bed6-2958194f7ca6} 7148 3 tab)
[task 2024-08-08T02:33:14.546Z] 02:33:14 INFO - ==> process 7148 launched child process 10228 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3504 -isForBrowser -prefsHandle 3496 -prefMapHandle 3492 -prefsLen 24960 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {1b5fd7d3-c7d0-4689-8a98-b445f1d72350} 7148 4 tab)
[task 2024-08-08T02:33:14.547Z] 02:33:14 INFO - ==> process 7148 launched child process 4316 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3900 -isForBrowser -prefsHandle 2932 -prefMapHandle 2928 -prefsLen 31855 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {ac5422c0-c7cd-452f-bb8c-126ba8f3dd11} 7148 5 tab)
[task 2024-08-08T02:33:14.548Z] 02:33:14 INFO - ==> process 7148 launched child process 9224 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=4648 -parentBuildID 20240808013316 -sandboxingKind 0 -prefsHandle 4640 -prefMapHandle 4636 -prefsLen 34390 -prefMapSize 273368 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {1f269ec3-7141-4ec4-a99f-75b78a17b7f6} 7148 6 utility)
[task 2024-08-08T02:33:14.549Z] 02:33:14 INFO - ==> process 7148 launched child process 3968 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=4808 -isForBrowser -prefsHandle 4800 -prefMapHandle 4796 -prefsLen 32065 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {471787e8-b747-4fc8-9d3f-81244c863f44} 7148 7 tab)
[task 2024-08-08T02:33:14.550Z] 02:33:14 INFO - ==> process 7148 launched child process 8668 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=4920 -isForBrowser -prefsHandle 5000 -prefMapHandle 4996 -prefsLen 32065 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {78ea4855-4963-4a74-b92f-719d543a7e8b} 7148 8 tab)
[task 2024-08-08T02:33:14.551Z] 02:33:14 INFO - ==> process 7148 launched child process 8528 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=4800 -isForBrowser -prefsHandle 2848 -prefMapHandle 4620 -prefsLen 34442 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {be697a34-39d0-4e0a-88dc-8055b59e9e57} 7148 9 tab)
[task 2024-08-08T02:33:14.551Z] 02:33:14 INFO - ==> process 7148 launched child process 9764 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=5636 -isForBrowser -prefsHandle 5652 -prefMapHandle 5648 -prefsLen 32151 -prefMapSize 273368 -jsInitHandle 1468 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {0f3fab98-9155-4007-ad70-bf4d40147fb1} 7148 10 tab)
[task 2024-08-08T02:33:14.552Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 10144
[task 2024-08-08T02:33:14.553Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 3968
[task 2024-08-08T02:33:14.553Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 9764
[task 2024-08-08T02:33:14.554Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 9224
[task 2024-08-08T02:33:14.554Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 7408
[task 2024-08-08T02:33:14.554Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 8528
[task 2024-08-08T02:33:14.555Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 8500
[task 2024-08-08T02:33:14.555Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 10228
[task 2024-08-08T02:33:14.556Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 8668
[task 2024-08-08T02:33:14.556Z] 02:33:14 INFO - zombiecheck | Checking for orphan process with PID: 4316
[task 2024-08-08T02:33:14.556Z] 02:33:14 INFO - Stopping web server
[task 2024-08-08T02:33:14.560Z] 02:33:14 INFO - Server shut down.
[task 2024-08-08T02:33:14.560Z] 02:33:14 INFO - Web server killed.
[task 2024-08-08T02:33:14.561Z] 02:33:14 INFO - Stopping web socket server
[task 2024-08-08T02:33:14.561Z] 02:33:14 INFO - Stopping ssltunnel
[task 2024-08-08T02:33:14.562Z] 02:33:14 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-08-08T02:33:14.562Z] 02:33:14 INFO - runtests.py | Running tests: end.
[task 2024-08-08T02:33:14.619Z] 02:33:14 INFO - Buffered messages finished
[task 2024-08-08T02:33:14.630Z] 02:33:14 INFO - Running manifest: browser/base/content/test/popups/browser.toml
[task 2024-08-08T02:33:14.641Z] 02:33:14 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:14.743Z] 02:33:14 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:15.353Z] 02:33:15 INFO - PID 7224 | D:\task_172308258379756\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2024-08-08T02:33:15.353Z] 02:33:15 INFO -
[task 2024-08-08T02:33:15.462Z] 02:33:15 INFO - Increasing default timeout to 90 seconds (asan or debug)
[task 2024-08-08T02:33:15.473Z] 02:33:15 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:15.556Z] 02:33:15 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:15.569Z] 02:33:15 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:15.650Z] 02:33:15 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:15.681Z] 02:33:15 INFO - MochitestServer : launching ['D:\\task_172308258379756\\build\\tests\\bin\\xpcshell.exe', '-g', 'D:\\task_172308258379756\\build\\application\\firefox', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_172308258379756\\\\AppData\\\\Local\\\\Temp\\\\tmptb5a0vai.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = 'D:\\\\task_172308258379756\\\\build\\\\tests\\\\bin\\\\components';", '-f', 'D:\\task_172308258379756\\build\\tests\\mochitest\\server.js']
[task 2024-08-08T02:33:15.682Z] 02:33:15 INFO - runtests.py | Server pid: 8792
[task 2024-08-08T02:33:15.692Z] 02:33:15 INFO - runtests.py | Websocket server pid: 5160
[task 2024-08-08T02:33:15.694Z] 02:33:15 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:15.790Z] 02:33:15 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:15.797Z] 02:33:15 INFO - runtests.py | SSL tunnel pid: 2336
[task 2024-08-08T02:33:15.953Z] 02:33:15 INFO - use http3 server: 0
[task 2024-08-08T02:33:15.956Z] 02:33:15 INFO - runtests.py | Running with scheme: http
[task 2024-08-08T02:33:15.956Z] 02:33:15 INFO - runtests.py | Running with e10s: True
[task 2024-08-08T02:33:15.956Z] 02:33:15 INFO - runtests.py | Running with fission: True
[task 2024-08-08T02:33:15.957Z] 02:33:15 INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-08-08T02:33:15.958Z] 02:33:15 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2024-08-08T02:33:15.958Z] 02:33:15 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-08-08T02:33:15.959Z] 02:33:15 INFO - runtests.py | Running tests: start.
[task 2024-08-08T02:33:15.959Z] 02:33:15 INFO -
[task 2024-08-08T02:33:16.073Z] 02:33:16 INFO - Application command: D:\task_172308258379756\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_172308258379756\AppData\Local\Temp\tmptb5a0vai.mozrunner
[task 2024-08-08T02:33:16.089Z] 02:33:16 INFO - runtests.py | Application pid: 6684
[task 2024-08-08T02:33:16.098Z] 02:33:16 INFO - TEST-INFO | started process GECKO(6684)
[task 2024-08-08T02:33:17.950Z] 02:33:17 INFO - GECKO(6684) | 1723084397955 Marionette INFO Marionette enabled
[task 2024-08-08T02:33:17.967Z] 02:33:17 INFO - GECKO(6684) | 1723084397966 Marionette TRACE Received observer notification final-ui-startup
[task 2024-08-08T02:33:18.247Z] 02:33:18 INFO - GECKO(6684) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-08-08T02:33:18.357Z] 02:33:18 INFO - GECKO(6684) | 1723084398365 Marionette INFO Listening on port 2828
[task 2024-08-08T02:33:18.376Z] 02:33:18 INFO - GECKO(6684) | 1723084398375 Marionette DEBUG Marionette is listening
[task 2024-08-08T02:33:18.545Z] 02:33:18 INFO - GECKO(6684) | 1723084398547 Marionette DEBUG Accepted connection 0 from 127.0.0.1:59505
[task 2024-08-08T02:33:18.577Z] 02:33:18 INFO - GECKO(6684) | 1723084398577 Marionette DEBUG Accepted connection 1 from 127.0.0.1:59508
[task 2024-08-08T02:33:18.579Z] 02:33:18 INFO - GECKO(6684) | 1723084398578 Marionette DEBUG Closed connection 0
[task 2024-08-08T02:33:19.218Z] 02:33:19 INFO - GECKO(6684) | 1723084399225 Marionette DEBUG Closed connection 1
[task 2024-08-08T02:33:19.227Z] 02:33:19 INFO - GECKO(6684) | 1723084399226 Marionette DEBUG Accepted connection 2 from 127.0.0.1:59509
[task 2024-08-08T02:33:19.609Z] 02:33:19 INFO - GECKO(6684) | 1723084399611 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-08-08T02:33:19.624Z] 02:33:19 INFO - GECKO(6684) | 1723084399629 Marionette DEBUG Waiting for initial application window
[task 2024-08-08T02:33:26.762Z] 02:33:26 INFO - GECKO(6684) | AddressSanitizer: CHECK failed: sanitizer_common.h:525 "((i)) < ((size_))" (0x8383ae60, 0x47) (tid=3972)
[task 2024-08-08T02:33:26.764Z] 02:33:26 INFO - GECKO(6684) | AddressSanitizer: CHECK failed: sanitizer_quarantine.h:159 "((cache_size)) >= ((overhead_size))" (0x1b7937c09b4, 0xfff5ca3f3400000) (tid=3972)
[task 2024-08-08T02:33:26.765Z] 02:33:26 INFO - GECKO(6684) | ==1760==WARNING: ASan is ignoring requested __asan_handle_no_return: stack type: default top: 0x000000000000; bottom 0x00ad5276b000; size: 0xffffff52ad895000 (-744412852224)
[task 2024-08-08T02:33:26.765Z] 02:33:26 INFO - GECKO(6684) | False positive error reports may follow
[task 2024-08-08T02:33:26.766Z] 02:33:26 INFO - GECKO(6684) | For details see https://github.com/google/sanitizers/issues/189
[task 2024-08-08T02:33:26.771Z] 02:33:26 INFO - runtests.py | Waiting for browser...
[task 2024-08-08T02:33:26.913Z] 02:33:26 INFO - TEST-INFO | Main app process: exit c000001d
[task 2024-08-08T02:33:26.917Z] 02:33:26 INFO - Buffered messages finished
[task 2024-08-08T02:33:26.917Z] 02:33:26 INFO - runtests.py | Application ran for: 0:00:10.840118
[task 2024-08-08T02:33:26.918Z] 02:33:26 INFO - zombiecheck | Reading PID log: C:\Users\task_172308258379756\AppData\Local\Temp\tmp2km3kqb2pidlog
[task 2024-08-08T02:33:26.919Z] 02:33:26 INFO - ==> process 1760 launched child process 9040 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=2156 -parentBuildID 20240808013316 -prefsHandle 2136 -prefMapHandle 2116 -prefsLen 24685 -prefMapSize 273368 -appDir "D:\task_172308258379756\build\application\firefox\browser" - {1f0e0172-6564-42cf-aabd-3f3389773a5a} 1760 1 gpu)
[task 2024-08-08T02:33:26.920Z] 02:33:26 INFO - ==> process 1760 launched child process 6636 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=2796 -isForBrowser -prefsHandle 2664 -prefMapHandle 2808 -prefsLen 24441 -prefMapSize 273368 -jsInitHandle 1500 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {a4adf92d-5379-41f1-9597-316a4ec2e525} 1760 2 tab)
[task 2024-08-08T02:33:26.921Z] 02:33:26 INFO - ==> process 1760 launched child process 6124 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3236 -isForBrowser -prefsHandle 3228 -prefMapHandle 3224 -prefsLen 26007 -prefMapSize 273368 -jsInitHandle 1500 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {cb06288f-baa1-47c6-9298-c6cfa4a99732} 1760 3 tab)
[task 2024-08-08T02:33:26.922Z] 02:33:26 INFO - ==> process 1760 launched child process 4596 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3268 -isForBrowser -prefsHandle 3256 -prefMapHandle 3376 -prefsLen 24960 -prefMapSize 273368 -jsInitHandle 1500 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {53cfc4f0-c570-4db6-8b64-824aacd17923} 1760 4 tab)
[task 2024-08-08T02:33:26.923Z] 02:33:26 INFO - ==> process 1760 launched child process 7636 ("D:\task_172308258379756\build\application\firefox\firefox.exe" -contentproc --channel=3956 -isForBrowser -prefsHandle 2868 -prefMapHandle 2880 -prefsLen 31902 -prefMapSize 273368 -jsInitHandle 1500 -jsInitLen 234840 -parentBuildID 20240808013316 -win32kLockedDown -appDir "D:\task_172308258379756\build\application\firefox\browser" - {166c447a-f240-48f3-b64e-8cf5ffc8fcb4} 1760 5 tab)
[task 2024-08-08T02:33:26.923Z] 02:33:26 INFO - zombiecheck | Checking for orphan process with PID: 6124
[task 2024-08-08T02:33:26.924Z] 02:33:26 INFO - zombiecheck | Checking for orphan process with PID: 6636
[task 2024-08-08T02:33:26.925Z] 02:33:26 INFO - zombiecheck | Checking for orphan process with PID: 9040
[task 2024-08-08T02:33:26.925Z] 02:33:26 INFO - zombiecheck | Checking for orphan process with PID: 4596
[task 2024-08-08T02:33:26.926Z] 02:33:26 INFO - zombiecheck | Checking for orphan process with PID: 7636
[task 2024-08-08T02:33:26.926Z] 02:33:26 INFO - Traceback (most recent call last):
[task 2024-08-08T02:33:26.926Z] 02:33:26 INFO - File "D:\task_172308258379756\build\tests\mochitest\runtests.py", line 3782, in doTests
[task 2024-08-08T02:33:26.926Z] 02:33:26 INFO - ret, _ = self.runApp(
[task 2024-08-08T02:33:26.926Z] 02:33:26 INFO - ^^^^^^^^^^^^
[task 2024-08-08T02:33:26.927Z] 02:33:26 INFO - File "D:\task_172308258379756\build\tests\mochitest\runtests.py", line 3041, in runApp
[task 2024-08-08T02:33:26.927Z] 02:33:26 INFO - six.reraise(exc, value, tb)
[task 2024-08-08T02:33:26.927Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-08-08T02:33:26.927Z] 02:33:26 INFO - raise value
[task 2024-08-08T02:33:26.928Z] 02:33:26 INFO - File "D:\task_172308258379756\build\tests\mochitest\runtests.py", line 2900, in runApp
[task 2024-08-08T02:33:26.928Z] 02:33:26 INFO - self.marionette.start_session()
[task 2024-08-08T02:33:26.928Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-08-08T02:33:26.928Z] 02:33:26 INFO - m._handle_socket_failure()
[task 2024-08-08T02:33:26.929Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-08-08T02:33:26.929Z] 02:33:26 INFO - reraise(exc_cls, exc, tb)
[task 2024-08-08T02:33:26.929Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-08-08T02:33:26.929Z] 02:33:26 INFO - raise value
[task 2024-08-08T02:33:26.930Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-08-08T02:33:26.930Z] 02:33:26 INFO - return func(*args, **kwargs)
[task 2024-08-08T02:33:26.930Z] 02:33:26 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.930Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 1374, in start_session
[task 2024-08-08T02:33:26.930Z] 02:33:26 INFO - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2024-08-08T02:33:26.931Z] 02:33:26 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.931Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2024-08-08T02:33:26.932Z] 02:33:26 INFO - m._handle_socket_failure()
[task 2024-08-08T02:33:26.932Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 820, in _handle_socket_failure
[task 2024-08-08T02:33:26.932Z] 02:33:26 INFO - reraise(exc_cls, exc, tb)
[task 2024-08-08T02:33:26.932Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\six.py", line 696, in reraise
[task 2024-08-08T02:33:26.932Z] 02:33:26 INFO - raise value
[task 2024-08-08T02:33:26.933Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2024-08-08T02:33:26.933Z] 02:33:26 INFO - return func(*args, **kwargs)
[task 2024-08-08T02:33:26.933Z] 02:33:26 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\marionette.py", line 769, in _send_message
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - msg = self.client.request(name, params)
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\transport.py", line 382, in request
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - return self.receive()
[task 2024-08-08T02:33:26.934Z] 02:33:26 INFO - ^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.935Z] 02:33:26 INFO - File "D:\task_172308258379756\build\venv\Lib\site-packages\marionette_driver\transport.py", line 223, in receive
[task 2024-08-08T02:33:26.935Z] 02:33:26 INFO - chunk = sock.recv(recv_bytes)
[task 2024-08-08T02:33:26.936Z] 02:33:26 INFO - ^^^^^^^^^^^^^^^^^^^^^
[task 2024-08-08T02:33:26.936Z] 02:33:26 INFO - ConnectionResetError: [WinError 10054] An existing connection was forcibly closed by the remote host
[task 2024-08-08T02:33:26.936Z] 02:33:26 ERROR - Automation Error: Received unexpected exception while running application
[task 2024-08-08T02:33:26.936Z] 02:33:26 ERROR -
[task 2024-08-08T02:33:26.937Z] 02:33:26 INFO - Stopping web server
[task 2024-08-08T02:33:26.937Z] 02:33:26 INFO - Server shut down.
[task 2024-08-08T02:33:26.938Z] 02:33:26 INFO - Web server killed.
[task 2024-08-08T02:33:26.938Z] 02:33:26 INFO - Stopping web socket server
[task 2024-08-08T02:33:26.938Z] 02:33:26 INFO - Stopping ssltunnel
[task 2024-08-08T02:33:26.939Z] 02:33:26 INFO - runtests.py | Running tests: end.
[task 2024-08-08T02:33:26.939Z] 02:33:26 INFO - Buffered messages finished
[task 2024-08-08T02:33:26.940Z] 02:33:26 INFO - Running manifest: browser/base/content/test/utilityOverlay/browser.toml
[task 2024-08-08T02:33:26.948Z] 02:33:26 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:27.038Z] 02:33:27 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:27.647Z] 02:33:27 INFO - PID 9780 | D:\task_172308258379756\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2024-08-08T02:33:27.647Z] 02:33:27 INFO -
[task 2024-08-08T02:33:27.757Z] 02:33:27 INFO - Increasing default timeout to 90 seconds (asan or debug)
[task 2024-08-08T02:33:27.762Z] 02:33:27 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:27.851Z] 02:33:27 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:27.854Z] 02:33:27 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:27.945Z] 02:33:27 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:27.961Z] 02:33:27 INFO - MochitestServer : launching ['D:\\task_172308258379756\\build\\tests\\bin\\xpcshell.exe', '-g', 'D:\\task_172308258379756\\build\\application\\firefox', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_172308258379756\\\\AppData\\\\Local\\\\Temp\\\\tmpjffflgc_.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = 'D:\\\\task_172308258379756\\\\build\\\\tests\\\\bin\\\\components';", '-f', 'D:\\task_172308258379756\\build\\tests\\mochitest\\server.js']
[task 2024-08-08T02:33:27.962Z] 02:33:27 INFO - runtests.py | Server pid: 9428
[task 2024-08-08T02:33:27.969Z] 02:33:27 INFO - runtests.py | Websocket server pid: 8156
[task 2024-08-08T02:33:27.972Z] 02:33:27 INFO - INFO | runtests.py | ASan using symbolizer at D:\task_172308258379756\build\application\firefox\llvm-symbolizer.exe
[task 2024-08-08T02:33:28.087Z] 02:33:28 INFO - Failed determine available memory, disabling ASan low-memory configuration
[task 2024-08-08T02:33:28.094Z] 02:33:28 INFO - runtests.py | SSL tunnel pid: 9060
[task 2024-08-08T02:33:28.249Z] 02:33:28 INFO - use http3 server: 0
[task 2024-08-08T02:33:28.252Z] 02:33:28 INFO - runtests.py | Running with scheme: http
[task 2024-08-08T02:33:28.253Z] 02:33:28 INFO - runtests.py | Running with e10s: True
[task 2024-08-08T02:33:28.253Z] 02:33:28 INFO - runtests.py | Running with fission: True
[task 2024-08-08T02:33:28.254Z] 02:33:28 INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-08-08T02:33:28.254Z] 02:33:28 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2024-08-08T02:33:28.255Z] 02:33:28 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-08-08T02:33:28.255Z] 02:33:28 INFO - runtests.py | Running tests: start.
[task 2024-08-08T02:33:28.255Z] 02:33:28 INFO -
[task 2024-08-08T02:33:28.385Z] 02:33:28 INFO - Application command: D:\task_172308258379756\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_172308258379756\AppData\Local\Temp\tmpjffflgc_.mozrunner
[task 2024-08-08T02:33:28.463Z] 02:33:28 INFO - runtests.py | Application pid: 9284
[task 2024-08-08T02:33:28.467Z] 02:33:28 INFO - TEST-INFO | started process GECKO(9284)
[task 2024-08-08T02:33:30.262Z] 02:33:30 INFO - GECKO(9284) | 1723084410268 Marionette INFO Marionette enabled
[task 2024-08-08T02:33:30.280Z] 02:33:30 INFO - GECKO(9284) | 1723084410279 Marionette TRACE Received observer notification final-ui-startup
[task 2024-08-08T02:33:30.543Z] 02:33:30 INFO - GECKO(9284) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-08-08T02:33:30.684Z] 02:33:30 INFO - GECKO(9284) | 1723084410694 Marionette INFO Listening on port 2828
[task 2024-08-08T02:33:30.702Z] 02:33:30 INFO - GECKO(9284) | 1723084410701 Marionette DEBUG Marionette is listening
[task 2024-08-08T02:33:30.855Z] 02:33:30 INFO - GECKO(9284) | 1723084410858 Marionette DEBUG Accepted connection 0 from 127.0.0.1:59526
[task 2024-08-08T02:33:30.950Z] 02:33:30 INFO - GECKO(9284) | 1723084410959 Marionette DEBUG Closed connection 0
[task 2024-08-08T02:33:31.028Z] 02:33:31 INFO - GECKO(9284) | 1723084411028 Marionette DEBUG Accepted connection 1 from 127.0.0.1:59527
[task 2024-08-08T02:33:31.685Z] 02:33:31 INFO - GECKO(9284) | 1723084411688 Marionette DEBUG Closed connection 1
[task 2024-08-08T02:33:31.904Z] 02:33:31 INFO - GECKO(9284) | 1723084411918 Marionette DEBUG Accepted connection 2 from 127.0.0.1:59528
[task 2024-08-08T02:33:31.951Z] 02:33:31 INFO - GECKO(9284) | 1723084411962 Marionette DEBUG Closed connection 2
[task 2024-08-08T02:33:31.964Z] 02:33:31 INFO - GECKO(9284) | 1723084411963 Marionette DEBUG Accepted connection 3 from 127.0.0.1:59529
[task 2024-08-08T02:33:32.093Z] 02:33:32 INFO - GECKO(9284) | 1723084412101 Marionette DEBUG 3 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-08-08T02:33:32.124Z] 02:33:32 INFO - GECKO(9284) | 1723084412126 Marionette DEBUG Waiting for initial application window
[task 2024-08-08T02:33:40.623Z] 02:33:40 INFO - GECKO(9284) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-08T02:33:40.674Z] 02:33:40 INFO - GECKO(9284) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amo: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-08-08T02:33:40.692Z] 02:33:40 INFO - GECKO(9284) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-08T02:33:40.693Z] 02:33:40 INFO - GECKO(9284) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-08-08T02:33:40.763Z] 02:33:40 INFO - GECKO(9284) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-08T02:33:40.765Z] 02:33:40 INFO - GECKO(9284) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Wikipedia: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-08-08T02:33:40.767Z] 02:33:40 INFO - GECKO(9284) | 1723084420767 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2024-08-08T02:33:40.812Z] 02:33:40 INFO - GECKO(9284) | 1723084420811 RemoteAgent TRACE [11] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-08-08T02:33:40.813Z] 02:33:40 INFO - GECKO(9284) | 1723084420812 RemoteAgent TRACE [11] ProgressListener Setting unload timer (40000ms)
[task 2024-08-08T02:33:40.814Z] 02:33:40 INFO - GECKO(9284) | 1723084420812 RemoteAgent TRACE [11] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-08-08T02:33:40.814Z] 02:33:40 INFO - GECKO(9284) | 1723084420812 RemoteAgent TRACE [11] Document already finished loading: about:blank
[task 2024-08-08T02:33:40.815Z] 02:33:40 INFO - GECKO(9284) | 1723084420812 RemoteAgent TRACE [11] ProgressListener Stop: has error=false url=about:blank
[task 2024-08-08T02:33:40.901Z] 02:33:40 INFO - GECKO(9284) | 1723084420898 Marionette DEBUG 3 <- [1,1,null,{"sessionId":"1884f0c9-c5e6-4b91-8090-39376c198274","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"131.0a1","platformName":"windows","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0","moz:buildID":"20240808013316","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":5988,"moz:profile":"C:\\Users\\task_172308258379756\\AppData\\Local\\Temp\\tmpjffflgc_.mozrunner","moz:shutdownTimeout":300000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-08-08T02:33:40.952Z] 02:33:40 INFO - GECKO(9284) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-08T02:33:40.956Z] 02:33:40 INFO - GECKO(9284) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Mdn: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-08-08T02:33:40.965Z] 02:33:40 INFO - GECKO(9284) | 1723084420964 Marionette DEBUG 3 -> [0,2,"Addon:Install",{"path":"C:\\Users\\task_172308258379756\\AppData\\Local\\Temp\\tmpffn152wd.zip","temporary":false}]
[task 2024-08-08T02:33:40.975Z] 02:33:40 INFO - GECKO(9284) | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-08-08T02:33:40.978Z] 02:33:40 INFO - GECKO(9284) | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Yelp: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-08-08T02:33:41.328Z] 02:33:41 INFO - GECKO(9284) | 1723084421327 Marionette DEBUG 3 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-08-08T02:33:41.380Z] 02:33:41 INFO - GECKO(9284) | 1723084421379 Marionette DEBUG 3 -> [0,3,"Addon:Install",{"path":"C:\\Users\\task_172308258379756\\AppData\\Local\\Temp\\tmp_cwhjr4p.zip","temporary":false}]
[task 2024-08-08T02:33:41.461Z] 02:33:41 INFO - GECKO(9284) | 1723084421461 Marionette DEBUG 3 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-08-08T02:33:41.467Z] 02:33:41 INFO - GECKO(9284) | 1723084421466 Marionette DEBUG 3 -> [0,4,"Marionette:GetContext",{}]
[task 2024-08-08T02:33:41.468Z] 02:33:41 INFO - GECKO(9284) | 1723084421467 Marionette DEBUG 3 <- [1,4,null,{"value":"content"}]
[task 2024-08-08T02:33:41.469Z] 02:33:41 INFO - GECKO(9284) | 1723084421469 Marionette DEBUG 3 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-08-08T02:33:41.471Z] 02:33:41 INFO - GECKO(9284) | 1723084421470 Marionette DEBUG 3 <- [1,5,null,{"value":null}]
[task 2024-08-08T02:33:41.475Z] 02:33:41 INFO - GECKO(9284) | 1723084421472 Marionette DEBUG 3 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... s which flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"browser-chrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":2165,"filename":"D:\\task_172308258379756\\build\\tests\\mochitest\\runtests.py"}]
[task 2024-08-08T02:33:41.503Z] 02:33:41 INFO - GECKO(9284) | 1723084421502 RemoteAgent TRACE WebDriverProcessData actor created for PID 5988
[task 2024-08-08T02:33:41.510Z] 02:33:41 INFO - GECKO(9284) | 1723084421509 Marionette TRACE [2] MarionetteCommands actor created for window id 4
[task 2024-08-08T02:33:41.636Z] 02:33:41 INFO - GECKO(9284) | 1723084421635 RemoteAgent TRACE Received observer notification domwindowopened
[task 2024-08-08T02:33:41.637Z] 02:33:41 INFO - GECKO(9284) | 1723084421635 RemoteAgent TRACE Received observer notification domwindowopened
[task 2024-08-08T02:33:41.656Z] 02:33:41 INFO - GECKO(9284) | 1723084421656 Marionette DEBUG 3 <- [1,6,null,{"value":null}]
[task 2024-08-08T02:33:41.660Z] 02:33:41 INFO - GECKO(9284) | 1723084421659 Marionette DEBUG 3 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-08-08T02:33:41.661Z] 02:33:41 INFO - GECKO(9284) | 1723084421659 Marionette DEBUG 3 <- [1,7,null,{"value":null}]
[task 2024-08-08T02:33:41.721Z] 02:33:41 INFO - GECKO(9284) | 1723084421720 Marionette DEBUG 3 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-08-08T02:33:41.722Z] 02:33:41 INFO - GECKO(9284) | 1723084421722 Marionette TRACE [2] MarionetteCommands actor destroyed for window id 4
[task 2024-08-08T02:33:41.731Z] 02:33:41 INFO - GECKO(9284) | 1723084421730 Marionette DEBUG 3 <- [1,8,null,{"value":null}]
[task 2024-08-08T02:33:41.787Z] 02:33:41 INFO - runtests.py | Waiting for browser...
[task 2024-08-08T02:33:41.795Z] 02:33:41 INFO - GECKO(9284) | 1723084421794 Marionette DEBUG Closed connection 3
[task 2024-08-08T02:33:42.222Z] 02:33:42 INFO - *** Start BrowserChrome Test Results ***
[task 2024-08-08T02:33:42.307Z] 02:33:42 INFO - checking window state
[task 2024-08-08T02:33:42.594Z] 02:33:42 INFO - TEST-START | browser/base/content/test/utilityOverlay/browser_openWebLinkIn.js
Description
•