Closed
Bug 1782866
Opened 3 years ago
Closed 3 years ago
Intermittent [tier 2] automation.py | application terminated with exit code 3221225501
Categories
(Testing :: Mochitest, defect, P5)
Testing
Mochitest
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=386223360&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YWZ7GlMOQOKIzqKcHlHKyg/runs/0/artifacts/public/logs/live_backing.log
[task 2022-08-03T04:18:35.609Z] 04:18:35 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-08-03T04:18:35.611Z] 04:18:35 INFO - runtests.py | Running tests: end.
[task 2022-08-03T04:18:35.707Z] 04:18:35 INFO - Buffered messages finished
[task 2022-08-03T04:18:35.709Z] 04:18:35 INFO - Running manifest: dom\events\test\pointerevents\mochitest.ini
[task 2022-08-03T04:18:35.709Z] 04:18:35 INFO - The following extra prefs will be set:
[task 2022-08-03T04:18:35.709Z] 04:18:35 INFO - gfx.font_loader.delay=0
[task 2022-08-03T04:18:35.709Z] 04:18:35 INFO - gfx.font_loader.interval=0
[task 2022-08-03T04:18:36.673Z] 04:18:36 INFO - PID 1892 | Z:\task_165949132840258\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2022-08-03T04:18:36.849Z] 04:18:36 INFO - MochitestServer : launching ['Z:\\task_165949132840258\\build\\tests\\bin\\xpcshell.exe', '-g', 'Z:\\task_165949132840258\\build\\application\\firefox', '-f', 'Z:\\task_165949132840258\\build\\tests\\bin\\components\\httpd.js', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_165949132840258\\\\AppData\\\\Local\\\\Temp\\\\tmpccxbll_d.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\\task_165949132840258\\build\\tests\\mochitest\\server.js']
[task 2022-08-03T04:18:36.853Z] 04:18:36 INFO - runtests.py | Server pid: 628
[task 2022-08-03T04:18:36.856Z] 04:18:36 INFO - runtests.py | Websocket server pid: 5104
[task 2022-08-03T04:18:36.860Z] 04:18:36 INFO - DLL blocklist was unable to intercept AppInit DLLs.
[task 2022-08-03T04:18:36.861Z] 04:18:36 INFO - runtests.py | SSL tunnel pid: 3192
[task 2022-08-03T04:18:36.920Z] 04:18:36 INFO - Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2022-08-03T04:18:36.934Z] 04:18:36 INFO - [Parent 628, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:124
[task 2022-08-03T04:18:36.935Z] 04:18:36 INFO - [Parent 628, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:124
[task 2022-08-03T04:18:36.962Z] 04:18:36 INFO - [Parent 628, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:427
[task 2022-08-03T04:18:36.991Z] 04:18:36 INFO - runtests.py | Running with scheme: http
[task 2022-08-03T04:18:36.994Z] 04:18:36 INFO - runtests.py | Running with e10s: True
[task 2022-08-03T04:18:36.995Z] 04:18:36 INFO - runtests.py | Running with fission: True
[task 2022-08-03T04:18:36.995Z] 04:18:36 INFO - runtests.py | Running with cross-origin iframes: True
[task 2022-08-03T04:18:36.995Z] 04:18:36 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-08-03T04:18:36.996Z] 04:18:36 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-08-03T04:18:36.996Z] 04:18:36 INFO - runtests.py | Running tests: start.
[task 2022-08-03T04:18:36.996Z] 04:18:36 INFO -
[task 2022-08-03T04:18:37.054Z] 04:18:37 INFO - Application command: Z:\task_165949132840258\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_165949132840258\AppData\Local\Temp\tmpccxbll_d.mozrunner
[task 2022-08-03T04:18:37.068Z] 04:18:37 INFO - runtests.py | Application pid: 7944
[task 2022-08-03T04:18:37.069Z] 04:18:37 INFO - TEST-INFO | started process GECKO(7944)
[task 2022-08-03T04:18:37.112Z] 04:18:37 INFO - GECKO(7944) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpccxbll_d.mozrunner\runtests_leaks.log
[task 2022-08-03T04:18:37.123Z] 04:18:37 INFO - GECKO(7944) | [1128, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:18:37.150Z] 04:18:37 INFO - GECKO(7944) | [Parent 1128, Main Thread] WARNING: Rejected attempt to change type of pref extensions.formautofill.creditCards.available's user value from bool to string: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:1748
[task 2022-08-03T04:21:37.305Z] 04:21:37 INFO - runtests.py | Waiting for browser...
[task 2022-08-03T04:21:37.306Z] 04:21:37 INFO - TEST-INFO | Main app process: exit c000001d
[task 2022-08-03T04:21:37.307Z] 04:21:37 INFO - Buffered messages finished
[task 2022-08-03T04:21:37.307Z] 04:21:37 ERROR - TEST-UNEXPECTED-FAIL | automation.py | application terminated with exit code 3221225501
[task 2022-08-03T04:21:37.308Z] 04:21:37 INFO - runtests.py | Application ran for: 0:03:00.250324
[task 2022-08-03T04:21:37.308Z] 04:21:37 INFO - zombiecheck | Reading PID log: C:\Users\task_165949132840258\AppData\Local\Temp\tmpstpjdhropidlog
[task 2022-08-03T04:21:37.308Z] 04:21:37 INFO - Traceback (most recent call last):
[task 2022-08-03T04:21:37.309Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\tests\mochitest\runtests.py", line 3506, in doTests
[task 2022-08-03T04:21:37.309Z] 04:21:37 INFO - ret, _ = self.runApp(
[task 2022-08-03T04:21:37.310Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\tests\mochitest\runtests.py", line 2835, in runApp
[task 2022-08-03T04:21:37.310Z] 04:21:37 INFO - six.reraise(exc, value, tb)
[task 2022-08-03T04:21:37.310Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2022-08-03T04:21:37.310Z] 04:21:37 INFO - raise value
[task 2022-08-03T04:21:37.311Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\tests\mochitest\runtests.py", line 2695, in runApp
[task 2022-08-03T04:21:37.311Z] 04:21:37 INFO - self.marionette.start_session()
[task 2022-08-03T04:21:37.311Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-03T04:21:37.311Z] 04:21:37 INFO - m._handle_socket_failure()
[task 2022-08-03T04:21:37.312Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\marionette_driver\marionette.py", line 710, in _handle_socket_failure
[task 2022-08-03T04:21:37.312Z] 04:21:37 INFO - reraise(exc_cls, exc, tb)
[task 2022-08-03T04:21:37.312Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2022-08-03T04:21:37.312Z] 04:21:37 INFO - raise value
[task 2022-08-03T04:21:37.313Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-03T04:21:37.313Z] 04:21:37 INFO - return func(*args, **kwargs)
[task 2022-08-03T04:21:37.313Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1232, in start_session
[task 2022-08-03T04:21:37.313Z] 04:21:37 INFO - self.raise_for_port(timeout=timeout)
[task 2022-08-03T04:21:37.314Z] 04:21:37 INFO - File "Z:\task_165949132840258\build\venv\lib\site-packages\marionette_driver\marionette.py", line 634, in raise_for_port
[task 2022-08-03T04:21:37.314Z] 04:21:37 INFO - raise socket.timeout(
[task 2022-08-03T04:21:37.314Z] 04:21:37 INFO - socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2022-08-03T04:21:37.315Z] 04:21:37 ERROR - Automation Error: Received unexpected exception while running application
[task 2022-08-03T04:21:37.315Z] 04:21:37 ERROR -
[task 2022-08-03T04:21:37.315Z] 04:21:37 INFO - Stopping web server
[task 2022-08-03T04:21:37.326Z] 04:21:37 INFO - Server shut down.
[task 2022-08-03T04:21:37.360Z] 04:21:37 INFO - Web server killed.
[task 2022-08-03T04:21:37.362Z] 04:21:37 INFO - Stopping web socket server
[task 2022-08-03T04:21:37.389Z] 04:21:37 INFO - Stopping ssltunnel
[task 2022-08-03T04:21:37.418Z] 04:21:37 INFO - leakcheck | Processing log file C:\Users\task_165949132840258\AppData\Local\Temp\tmpccxbll_d.mozrunner\runtests_leaks.log
[task 2022-08-03T04:21:37.422Z] 04:21:37 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.423Z] 04:21:37 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 1000 bytes
[task 2022-08-03T04:21:37.424Z] 04:21:37 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2022-08-03T04:21:37.424Z] 04:21:37 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.425Z] 04:21:37 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.426Z] 04:21:37 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.427Z] 04:21:37 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.427Z] 04:21:37 INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.428Z] 04:21:37 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.428Z] 04:21:37 INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2022-08-03T04:21:37.429Z] 04:21:37 INFO - leakcheck | Processing leak log file C:\Users\task_165949132840258\AppData\Local\Temp\tmpccxbll_d.mozrunner\runtests_leaks.log
[task 2022-08-03T04:21:37.430Z] 04:21:37 INFO - TEST-UNEXPECTED-FAIL | leakcheck | default missing output line for total leaks!
[task 2022-08-03T04:21:37.430Z] 04:21:37 INFO - runtests.py | Running tests: end.
[task 2022-08-03T04:21:37.431Z] 04:21:37 INFO - Buffered messages finished
[task 2022-08-03T04:21:37.431Z] 04:21:37 INFO - Running manifest: dom\html\test\forms\mochitest.ini
[task 2022-08-03T04:21:37.432Z] 04:21:37 INFO - The following extra prefs will be set:
[task 2022-08-03T04:21:37.432Z] 04:21:37 INFO - formhelper.autozoom.force-disable.test-only=true
[task 2022-08-03T04:21:38.272Z] 04:21:38 INFO - PID 7928 | Z:\task_165949132840258\build\tests\bin\pk12util.exe: PKCS12 IMPORT SUCCESSFUL
[task 2022-08-03T04:21:38.476Z] 04:21:38 INFO - MochitestServer : launching ['Z:\\task_165949132840258\\build\\tests\\bin\\xpcshell.exe', '-g', 'Z:\\task_165949132840258\\build\\application\\firefox', '-f', 'Z:\\task_165949132840258\\build\\tests\\bin\\components\\httpd.js', '-e', "const _PROFILE_PATH = 'C:\\\\Users\\\\task_165949132840258\\\\AppData\\\\Local\\\\Temp\\\\tmpxzswxxzq.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', 'Z:\\task_165949132840258\\build\\tests\\mochitest\\server.js']
[task 2022-08-03T04:21:38.483Z] 04:21:38 INFO - runtests.py | Server pid: 4696
[task 2022-08-03T04:21:38.487Z] 04:21:38 INFO - runtests.py | Websocket server pid: 8420
[task 2022-08-03T04:21:38.490Z] 04:21:38 INFO - DLL blocklist was unable to intercept AppInit DLLs.
[task 2022-08-03T04:21:38.533Z] 04:21:38 INFO - runtests.py | SSL tunnel pid: 7692
[task 2022-08-03T04:21:38.571Z] 04:21:38 INFO - Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2022-08-03T04:21:38.581Z] 04:21:38 INFO - [Parent 4696, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:124
[task 2022-08-03T04:21:38.582Z] 04:21:38 INFO - [Parent 4696, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:124
[task 2022-08-03T04:21:38.625Z] 04:21:38 INFO - [Parent 4696, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:427
[task 2022-08-03T04:21:38.654Z] 04:21:38 INFO - runtests.py | Running with scheme: http
[task 2022-08-03T04:21:38.659Z] 04:21:38 INFO - runtests.py | Running with e10s: True
[task 2022-08-03T04:21:38.660Z] 04:21:38 INFO - runtests.py | Running with fission: True
[task 2022-08-03T04:21:38.660Z] 04:21:38 INFO - runtests.py | Running with cross-origin iframes: True
[task 2022-08-03T04:21:38.661Z] 04:21:38 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-08-03T04:21:38.662Z] 04:21:38 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-08-03T04:21:38.662Z] 04:21:38 INFO - runtests.py | Running tests: start.
[task 2022-08-03T04:21:38.662Z] 04:21:38 INFO -
[task 2022-08-03T04:21:38.736Z] 04:21:38 INFO - Application command: Z:\task_165949132840258\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner
[task 2022-08-03T04:21:38.750Z] 04:21:38 INFO - runtests.py | Application pid: 5188
[task 2022-08-03T04:21:38.751Z] 04:21:38 INFO - TEST-INFO | started process GECKO(5188)
[task 2022-08-03T04:21:38.809Z] 04:21:38 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks.log
[task 2022-08-03T04:21:38.820Z] 04:21:38 INFO - GECKO(5188) | [2600, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:38.847Z] 04:21:38 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: Rejected attempt to change type of pref extensions.formautofill.creditCards.available's user value from bool to string: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:1748
[task 2022-08-03T04:21:39.467Z] 04:21:39 INFO - GECKO(5188) | [2022-08-03T04:21:39Z WARN rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-08-03T04:21:39.757Z] 04:21:39 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_gpu_pid6284.log
[task 2022-08-03T04:21:39.764Z] 04:21:39 INFO - GECKO(5188) | [6284, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:39.822Z] 04:21:39 INFO - GECKO(5188) | 1659500499832 Marionette INFO Marionette enabled
[task 2022-08-03T04:21:39.838Z] 04:21:39 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:2542
[task 2022-08-03T04:21:39.841Z] 04:21:39 INFO - GECKO(5188) | 1659500499836 Marionette TRACE Received observer notification final-ui-startup
[task 2022-08-03T04:21:39.842Z] 04:21:39 INFO - GECKO(5188) | 1659500499840 Marionette INFO Listening on port 2828
[task 2022-08-03T04:21:39.842Z] 04:21:39 INFO - GECKO(5188) | 1659500499840 Marionette DEBUG Marionette is listening
[task 2022-08-03T04:21:40.041Z] 04:21:40 INFO - GECKO(5188) | 1659500500049 Marionette DEBUG Accepted connection 0 from 127.0.0.1:50539
[task 2022-08-03T04:21:40.057Z] 04:21:40 INFO - GECKO(5188) | WARN: driver_utils.cpp:184 (GetVendorString): ! Unimplemented: GetVendorString(/builds/worker/checkouts/gecko/gfx/angle/checkout/src/libANGLE/renderer/driver_utils.cpp:184)
[task 2022-08-03T04:21:40.057Z] 04:21:40 INFO - GECKO(5188) | WARN: driver_utils.cpp:184 (GetVendorString): ! Unimplemented: GetVendorString(/builds/worker/checkouts/gecko/gfx/angle/checkout/src/libANGLE/renderer/driver_utils.cpp:184)
[task 2022-08-03T04:21:40.058Z] 04:21:40 INFO - GECKO(5188) | [GPU 6284, Renderer] WARNING: Failed to bind API to GL!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:617
[task 2022-08-03T04:21:40.059Z] 04:21:40 INFO - GECKO(5188) | [GPU 6284, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1153
[task 2022-08-03T04:21:40.060Z] 04:21:40 INFO - GECKO(5188) | [GPU 6284, Renderer] WARNING: Failed to create EGLContext with khr_rbab_attribs: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:715
[task 2022-08-03T04:21:40.060Z] 04:21:40 INFO - GECKO(5188) | Initializing context 000001F51E19F000 surface 000001F51C646D60 on display 000001F51E131600
[task 2022-08-03T04:21:40.061Z] 04:21:40 INFO - GECKO(5188) | 1659500500056 Marionette DEBUG Accepted connection 1 from 127.0.0.1:50540
[task 2022-08-03T04:21:40.061Z] 04:21:40 INFO - GECKO(5188) | WARN: driver_utils.cpp:184 (GetVendorString): ! Unimplemented: GetVendorString(/builds/worker/checkouts/gecko/gfx/angle/checkout/src/libANGLE/renderer/driver_utils.cpp:184)
[task 2022-08-03T04:21:40.062Z] 04:21:40 INFO - GECKO(5188) | WARN: driver_utils.cpp:184 (GetVendorString): ! Unimplemented: GetVendorString(/builds/worker/checkouts/gecko/gfx/angle/checkout/src/libANGLE/renderer/driver_utils.cpp:184)
[task 2022-08-03T04:21:40.062Z] 04:21:40 INFO - GECKO(5188) | GL_VENDOR: Google Inc. (Unknown)
[task 2022-08-03T04:21:40.063Z] 04:21:40 INFO - GECKO(5188) | mVendor: Unknown
[task 2022-08-03T04:21:40.064Z] 04:21:40 INFO - GECKO(5188) | GL_RENDERER: ANGLE (Unknown, Microsoft Basic Render Driver Direct3D11 vs_5_0 ps_5_0, D3D11-10.0.19041.546)
[task 2022-08-03T04:21:40.065Z] 04:21:40 INFO - GECKO(5188) | mRenderer: Microsoft Basic Render Driver
[task 2022-08-03T04:21:40.065Z] 04:21:40 INFO - GECKO(5188) | mIsMesa: 0
[task 2022-08-03T04:21:40.066Z] 04:21:40 INFO - GECKO(5188) | [GPU 6284, Renderer] WARNING: framebuffer_multisample marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2022-08-03T04:21:40.066Z] 04:21:40 INFO - GECKO(5188) | 1659500500065 Marionette DEBUG Closed connection 0
[task 2022-08-03T04:21:40.156Z] 04:21:40 INFO - GECKO(5188) | 1659500500155 Marionette DEBUG Closed connection 1
[task 2022-08-03T04:21:40.157Z] 04:21:40 INFO - GECKO(5188) | 1659500500156 Marionette DEBUG Accepted connection 2 from 127.0.0.1:50541
[task 2022-08-03T04:21:40.216Z] 04:21:40 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1234
[task 2022-08-03T04:21:40.534Z] 04:21:40 INFO - GECKO(5188) | 1659500500533 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-08-03T04:21:40.541Z] 04:21:40 INFO - GECKO(5188) | 1659500500540 Marionette DEBUG Waiting for initial application window
[task 2022-08-03T04:21:40.678Z] 04:21:40 INFO - GECKO(5188) | [Parent 2600, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1754
[task 2022-08-03T04:21:40.690Z] 04:21:40 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid5252.log
[task 2022-08-03T04:21:40.690Z] 04:21:40 INFO - GECKO(5188) | [5252, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:40.718Z] 04:21:40 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: '!scrollbar', file /builds/worker/checkouts/gecko/widget/Theme.cpp:1073
[task 2022-08-03T04:21:40.877Z] 04:21:40 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid1316.log
[task 2022-08-03T04:21:40.877Z] 04:21:40 INFO - GECKO(5188) | [1316, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:40.971Z] 04:21:40 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:40.992Z] 04:21:40 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:40.999Z] 04:21:40 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:41.002Z] 04:21:41 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:41.016Z] 04:21:41 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:41.072Z] 04:21:41 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid8788.log
[task 2022-08-03T04:21:41.076Z] 04:21:41 INFO - GECKO(5188) | [8788, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:41.347Z] 04:21:41 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: '!scrollbar', file /builds/worker/checkouts/gecko/widget/Theme.cpp:1073
[task 2022-08-03T04:21:41.398Z] 04:21:41 INFO - GECKO(5188) | [GPU 6284, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2022-08-03T04:21:41.402Z] 04:21:41 INFO - GECKO(5188) | [GPU 6284, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2022-08-03T04:21:41.554Z] 04:21:41 INFO - GECKO(5188) | [GPU 6284, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] Send(msgname=PTexture::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1876
[task 2022-08-03T04:21:41.565Z] 04:21:41 INFO - GECKO(5188) | [GPU 6284, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1876
[task 2022-08-03T04:21:41.566Z] 04:21:41 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1600
[task 2022-08-03T04:21:41.608Z] 04:21:41 INFO - GECKO(5188) | [Child 5252, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-08-03T04:21:41.622Z] 04:21:41 INFO - GECKO(5188) | [2022-08-03T04:21:41Z WARN audioipc2::ipccore] AudioIPC Server RPC: EventLoop drop - closing connection for Token(0) failed: Custom { kind: NotFound, error: "I/O source not registered with `Registry`" }
[task 2022-08-03T04:21:41.623Z] 04:21:41 INFO - GECKO(5188) | [Child 5252, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-08-03T04:21:41.633Z] 04:21:41 INFO - GECKO(5188) | [Child 5252, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4595
[task 2022-08-03T04:21:41.708Z] 04:21:41 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid8756.log
[task 2022-08-03T04:21:41.708Z] 04:21:41 INFO - GECKO(5188) | [8756, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:41.739Z] 04:21:41 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid6380.log
[task 2022-08-03T04:21:41.739Z] 04:21:41 INFO - GECKO(5188) | [6380, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:41.772Z] 04:21:41 INFO - GECKO(5188) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_165949132840258\AppData\Local\Temp\tmpxzswxxzq.mozrunner\runtests_leaks_tab_pid5016.log
[task 2022-08-03T04:21:41.772Z] 04:21:41 INFO - GECKO(5188) | [5016, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-08-03T04:21:42.472Z] 04:21:42 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-08-03T04:21:42.488Z] 04:21:42 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-08-03T04:21:42.509Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: WebProgress Ignored: BrowsingContext is null or discarded: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:3073
[task 2022-08-03T04:21:42.520Z] 04:21:42 INFO - GECKO(5188) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_165949132840258\\AppData\\Local\\Temp\\tmpxzswxxzq.mozrunner\\search.json.mozlz4", (void 0)))
[task 2022-08-03T04:21:42.726Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1234
[task 2022-08-03T04:21:42.786Z] 04:21:42 INFO - GECKO(5188) | [Child 1316, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2822
[task 2022-08-03T04:21:42.942Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:818
[task 2022-08-03T04:21:42.944Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:632
[task 2022-08-03T04:21:42.945Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, QuotaManager IO] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_DEFAULT) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:750
[task 2022-08-03T04:21:42.946Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, QuotaManager IO] WARNING: QM_TRY failure (WARNING): '"OpenDatabase(aStorageService, aFileURL, aTelemetryId) .map([](auto connection) -> ConnectionType { return Some(std::move(connection)); })" failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:774
[task 2022-08-03T04:21:42.947Z] 04:21:42 INFO - GECKO(5188) | [Parent 2600, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '3870112724rsegmnoittet-es.sqlite', retrying for up to 10 seconds: file /builds/worker/checkouts/gecko/dom/indexedDB/ActorsParent.cpp:786
[task 2022-08-03T04:21:43.645Z] 04:21:43 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp:421
[task 2022-08-03T04:21:43.647Z] 04:21:43 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1234
[task 2022-08-03T04:21:43.690Z] 04:21:43 INFO - GECKO(5188) | [Parent 2600, BgIOThreadPool #2] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1516
[task 2022-08-03T04:21:43.694Z] 04:21:43 INFO - GECKO(5188) | [Parent 2600, BgIOThreadPool #2] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1516
[task 2022-08-03T04:21:44.286Z] 04:21:44 INFO - GECKO(5188) | 1659500504287 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2022-08-03T04:21:44.298Z] 04:21:44 INFO - GECKO(5188) | 1659500504298 RemoteAgent TRACE [27] Document already finished loading: about:blank
[task 2022-08-03T04:21:44.310Z] 04:21:44 INFO - GECKO(5188) | 1659500504315 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"832b894a-2066-493d-b223-bc2d4dad5c9d","capabilities":{"browserName":"firefox","browserVersion":"105.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-08-03T04:21:44.328Z] 04:21:44 INFO - GECKO(5188) | 1659500504337 Marionette DEBUG 2 -> [0,2,"Addon:Install",{"path":"C:\\Users\\task_165949132840258\\AppData\\Local\\Temp\\tmpvu6k9jke.zip","temporary":false}]
[task 2022-08-03T04:21:44.471Z] 04:21:44 INFO - GECKO(5188) | 1659500504481 Marionette DEBUG 2 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-08-03T04:21:44.510Z] 04:21:44 INFO - GECKO(5188) | 1659500504514 Marionette DEBUG 2 -> [0,3,"Addon:Install",{"path":"C:\\Users\\task_165949132840258\\AppData\\Local\\Temp\\tmpgm1p92hz.zip","temporary":false}]
[task 2022-08-03T04:21:44.571Z] 04:21:44 INFO - GECKO(5188) | 1659500504573 Marionette DEBUG 2 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-08-03T04:21:44.576Z] 04:21:44 INFO - GECKO(5188) | 1659500504582 Marionette DEBUG 2 -> [0,4,"Marionette:GetContext",{}]
[task 2022-08-03T04:21:44.583Z] 04:21:44 INFO - GECKO(5188) | 1659500504582 Marionette DEBUG 2 <- [1,4,null,{"value":"content"}]
[task 2022-08-03T04:21:44.585Z] 04:21:44 INFO - GECKO(5188) | 1659500504585 Marionette DEBUG 2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-08-03T04:21:44.586Z] 04:21:44 INFO - GECKO(5188) | 1659500504585 Marionette DEBUG 2 <- [1,5,null,{"value":null}]
[task 2022-08-03T04:21:44.589Z] 04:21:44 INFO - GECKO(5188) | 1659500504588 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":2015,"filename":"Z:\\task_165949132840258\\build\\tests\\mochitest\\runtests.py"}]
[task 2022-08-03T04:21:44.598Z] 04:21:44 INFO - GECKO(5188) | 1659500504596 Marionette TRACE [9] MarionetteCommands actor created for window id 4
[task 2022-08-03T04:21:44.609Z] 04:21:44 INFO - GECKO(5188) | 1659500504610 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2022-08-03T04:21:44.640Z] 04:21:44 INFO - GECKO(5188) | 1659500504638 Marionette DEBUG 2 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-08-03T04:21:44.640Z] 04:21:44 INFO - GECKO(5188) | 1659500504640 Marionette DEBUG 2 <- [1,7,null,{"value":null}]
[task 2022-08-03T04:21:44.650Z] 04:21:44 INFO - GECKO(5188) | 1659500504648 Marionette DEBUG 2 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-08-03T04:21:44.655Z] 04:21:44 INFO - GECKO(5188) | 1659500504653 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2022-08-03T04:21:44.655Z] 04:21:44 INFO - runtests.py | Waiting for browser...
[task 2022-08-03T04:21:44.656Z] 04:21:44 INFO - GECKO(5188) | 1659500504655 Marionette DEBUG Closed connection 2
[task 2022-08-03T04:21:45.034Z] 04:21:45 INFO - GECKO(5188) | [Parent 2600, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1127
[task 2022-08-03T04:21:45.097Z] 04:21:45 INFO - SimpleTest START
[task 2022-08-03T04:21:45.098Z] 04:21:45 INFO - Dumping test context:
[task 2022-08-03T04:21:45.098Z] 04:21:45 INFO - fission.autostart=true
[task 2022-08-03T04:21:45.099Z] 04:21:45 INFO - TEST-START | dom/html/test/forms/test_MozEditableElement_setUserInput.html
|
||
Comment 1•3 years ago
|
||
[task 2022-08-03T04:21:37.307Z] 04:21:37 ERROR - TEST-UNEXPECTED-FAIL | automation.py | application terminated with exit code 3221225501
"automation.py" comes from https://searchfox.org/mozilla-central/rev/560bbebd595cf2f6a9861f3f67dd0739f4c4c559/testing/mochitest/runtests.py#933, not mozharness' automation.py.
This was probably a firefox crash, but let's say it is a mochitest bug, for bad error handling and not dumping a stack.
Component: Applications: MozharnessCore → Mochitest
Product: Release Engineering → Testing
QA Contact: aki
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•