Closed Bug 1713652 Opened 4 years ago Closed 4 years ago

Intermittent /css/css-counter-styles/georgian/css3-counter-styles-010.html | [Exception... "Component not initialized" nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)" location: "JS frame :: chrome://marionette/content/reftest.js :: run/timeoutPromise

Categories

(Core :: Layout: Generated Content, Lists, and Counters, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=341310293&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GCmMe_DBSXmKsSL42lxgvA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GCmMe_DBSXmKsSL42lxgvA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


INFO - TEST-START | /css/css-counter-styles/georgian/css3-counter-styles-010.html
[task 2021-05-31T18:07:48.293Z] 18:07:48     INFO - PID 8861 | Marionette threw an error: NotSupportedError: ChromeUtils.registerWindowActor: 'MarionetteReftest' actor is already registered.
[task 2021-05-31T18:07:48.294Z] 18:07:48     INFO - PID 8861 | setup@chrome://marionette/content/reftest.js:113:17
[task 2021-05-31T18:07:48.294Z] 18:07:48     INFO - PID 8861 | GeckoDriver.prototype.setupReftest@chrome://marionette/content/driver.js:2889:17
[task 2021-05-31T18:07:48.297Z] 18:07:48     INFO - PID 8861 | despatch@chrome://marionette/content/server.js:307:40
[task 2021-05-31T18:07:48.298Z] 18:07:48     INFO - PID 8861 | execute@chrome://marionette/content/server.js:280:16
[task 2021-05-31T18:07:48.298Z] 18:07:48     INFO - PID 8861 | onPacket/<@chrome://marionette/content/server.js:253:20
[task 2021-05-31T18:07:48.299Z] 18:07:48     INFO - PID 8861 | onPacket@chrome://marionette/content/server.js:254:9
[task 2021-05-31T18:07:48.299Z] 18:07:48     INFO - PID 8861 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2021-05-31T18:07:48.329Z] 18:07:48     INFO - Browser exited with return code -15
[task 2021-05-31T18:07:48.330Z] 18:07:48  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2021-05-31T18:07:48.332Z] 18:07:48     INFO - Setting pref dom.animations-api.compositing.enabled (true)
[task 2021-05-31T18:07:48.407Z] 18:07:48     INFO - PID 8861 | 1622484468406	Marionette	INFO	Stopped listening on port 49674
[task 2021-05-31T18:07:48.435Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.437Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.446Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.449Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.453Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.453Z] 18:07:48     INFO - PID 8861 | [Child 9025, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.458Z] 18:07:48     INFO - PID 8861 | [Parent 8861, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-05-31T18:07:48.577Z] 18:07:48     INFO - PID 8861 | Marionette threw an error: [Exception... "Component not initialized"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: chrome://marionette/content/reftest.js :: run/timeoutPromise< :: line 298"  data: no]
[task 2021-05-31T18:07:48.577Z] 18:07:48     INFO - PID 8861 | run/timeoutPromise<@chrome://marionette/content/reftest.js:298:41
[task 2021-05-31T18:07:48.577Z] 18:07:48     INFO - PID 8861 | run@chrome://marionette/content/reftest.js:297:26
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | GeckoDriver.prototype.runReftest@chrome://marionette/content/driver.js:2915:32
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | despatch@chrome://marionette/content/server.js:307:40
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | execute@chrome://marionette/content/server.js:280:16
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | onPacket/<@chrome://marionette/content/server.js:253:20
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | onPacket@chrome://marionette/content/server.js:254:9
[task 2021-05-31T18:07:48.578Z] 18:07:48     INFO - PID 8861 | _onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2021-05-31T18:07:48.580Z] 18:07:48  WARNING - Traceback (most recent call last):
WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 300, in run_test
[task 2021-05-31T18:07:48.582Z] 18:07:48  WARNING -     result = self.do_test(test)
[task 2021-05-31T18:07:48.583Z] 18:07:48  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1013, in do_test
[task 2021-05-31T18:07:48.583Z] 18:07:48  WARNING -     result = self.implementation.run_test(test)
[task 2021-05-31T18:07:48.584Z] 18:07:48  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1097, in run_test
[task 2021-05-31T18:07:48.584Z] 18:07:48  WARNING -     "pageRanges": test.page_ranges})["value"]
[task 2021-05-31T18:07:48.585Z] 18:07:48  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-05-31T18:07:48.586Z] 18:07:48  WARNING -     return func(*args, **kwargs)
[task 2021-05-31T18:07:48.586Z] 18:07:48  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 629, in _send_message
[task 2021-05-31T18:07:48.587Z] 18:07:48  WARNING -     self._handle_error(err)
[task 2021-05-31T18:07:48.588Z] 18:07:48  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 651, in _handle_error
[task 2021-05-31T18:07:48.589Z] 18:07:48  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-05-31T18:07:48.590Z] 18:07:48  WARNING - marionette_driver.errors.UnknownException: [Exception... "Component not initialized"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: chrome://marionette/content/reftest.js :: run/timeoutPromise< :: line 298"  data: no]
[task 2021-05-31T18:07:48.590Z] 18:07:48  WARNING - stacktrace:
[task 2021-05-31T18:07:48.591Z] 18:07:48  WARNING - 	run/timeoutPromise<@chrome://marionette/content/reftest.js:298:41
[task 2021-05-31T18:07:48.592Z] 18:07:48  WARNING - 	run@chrome://marionette/content/reftest.js:297:26
[task 2021-05-31T18:07:48.593Z] 18:07:48  WARNING - 	GeckoDriver.prototype.runReftest@chrome://marionette/content/driver.js:2915:32
[task 2021-05-31T18:07:48.595Z] 18:07:48  WARNING - 	despatch@chrome://marionette/content/server.js:307:40
[task 2021-05-31T18:07:48.596Z] 18:07:48  WARNING - 	execute@chrome://marionette/content/server.js:280:16
[task 2021-05-31T18:07:48.598Z] 18:07:48  WARNING - 	onPacket/<@chrome://marionette/content/server.js:253:20
[task 2021-05-31T18:07:48.599Z] 18:07:48  WARNING - 	onPacket@chrome://marionette/content/server.js:254:9
[task 2021-05-31T18:07:48.600Z] 18:07:48  WARNING - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2021-05-31T18:07:48.602Z] 18:07:48  WARNING - 
[task 2021-05-31T18:07:48.602Z] 18:07:48  WARNING - 
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO - TEST-UNEXPECTED-ERROR | /css/css-counter-styles/georgian/css3-counter-styles-010.html | [Exception... "Component not initialized"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: chrome://marionette/content/reftest.js :: run/timeoutPromise< :: line 298"  data: no]
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO - Traceback (most recent call last):
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 300, in run_test
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     result = self.do_test(test)
INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1013, in do_test
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     result = self.implementation.run_test(test)
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 1097, in run_test
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     "pageRanges": test.page_ranges})["value"]
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     return func(*args, **kwargs)
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 629, in _send_message
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     self._handle_error(err)
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 651, in _handle_error
[task 2021-05-31T18:07:48.603Z] 18:07:48     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - marionette_driver.errors.UnknownException: [Exception... "Component not initialized"  nsresult: "0xc1f30001 (NS_ERROR_NOT_INITIALIZED)"  location: "JS frame :: chrome://marionette/content/reftest.js :: run/timeoutPromise< :: line 298"  data: no]
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - stacktrace:
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	run/timeoutPromise<@chrome://marionette/content/reftest.js:298:41
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	run@chrome://marionette/content/reftest.js:297:26
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	GeckoDriver.prototype.runReftest@chrome://marionette/content/driver.js:2915:32
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	despatch@chrome://marionette/content/server.js:307:40
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	execute@chrome://marionette/content/server.js:280:16
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	onPacket/<@chrome://marionette/content/server.js:253:20
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	onPacket@chrome://marionette/content/server.js:254:9
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 	_onJSONObjectReady/<@chrome://marionette/content/transport.js:504:20
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - 
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - TEST-INFO took 288ms
[task 2021-05-31T18:07:48.604Z] 18:07:48     INFO - PID 8861 | [Parent 8861, Main Thread] WARNING: unable to post continuation event: file /builds/worker/checkouts/gecko/xpcom/io/nsStreamUtils.cpp:454
[task 2021-05-31T18:07:48.666Z] 18:07:48     INFO - Closing logging queue
[task 2021-05-31T18:07:48.667Z] 18:07:48     INFO - queue closed
<...>
INFO - Browser exited with return code 0
[task 2021-05-31T18:07:50.652Z] 18:07:50     INFO - PROCESS LEAKS /tmp/tmpayo95k3o/runtests_leaks_1145.log
[task 2021-05-31T18:07:50.653Z] 18:07:50     INFO - leakcheck | Processing log file /tmp/tmpayo95k3o/runtests_leaks_1145.log for scope /css/css-counter-styles/georgian
[task 2021-05-31T18:07:50.654Z] 18:07:50     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.655Z] 18:07:50     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.655Z] 18:07:50     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-05-31T18:07:50.656Z] 18:07:50     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.656Z] 18:07:50     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.657Z] 18:07:50     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2021-05-31T18:07:50.657Z] 18:07:50     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.658Z] 18:07:50     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2021-05-31T18:07:50.658Z] 18:07:50     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2021-05-31T18:07:50.659Z] 18:07:50     INFO - leakcheck | Processing leak log file /tmp/tmpayo95k3o/runtests_leaks_1145.log
[task 2021-05-31T18:07:50.659Z] 18:07:50     INFO - 
[task 2021-05-31T18:07:50.660Z] 18:07:50     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 8861
[task 2021-05-31T18:07:50.660Z] 18:07:50     INFO - 
[task 2021-05-31T18:07:50.661Z] 18:07:50     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-05-31T18:07:50.661Z] 18:07:50     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-05-31T18:07:50.662Z] 18:07:50     INFO -    0 |TOTAL                                 |       58     1896|  468835       11|
[task 2021-05-31T18:07:50.662Z] 18:07:50     INFO -  540 |Mutex                                 |       72      216|    3449        3|
[task 2021-05-31T18:07:50.663Z] 18:07:50     INFO -  824 |PollableEvent                         |       32       32|       1        1|
[task 2021-05-31T18:07:50.663Z] 18:07:50     INFO -  872 |ReentrantMonitor                      |       40       40|     721        1|
[task 2021-05-31T18:07:50.664Z] 18:07:50     INFO - 1324 |nsAStreamCopier                       |       40       40|     375        1|
[task 2021-05-31T18:07:50.666Z] 18:07:50     INFO - 1593 |nsPipe                                |      224      224|     620        1|
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - 1594 |nsPipeInputStream                     |      152      152|     620        1|
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - 1638 |nsSocketTransport                     |      808      808|       6        1|
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - 1639 |nsSocketTransportService              |      376      376|       1        1|
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - 1648 |nsStringBuffer                        |        8        8|   98452        1|
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - 
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - nsTraceRefcnt::DumpStatistics: 1782 entries
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 3 Mutex
[task 2021-05-31T18:07:50.667Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 PollableEvent
[task 2021-05-31T18:07:50.668Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 ReentrantMonitor
[task 2021-05-31T18:07:50.671Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsAStreamCopier
[task 2021-05-31T18:07:50.671Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsPipe
[task 2021-05-31T18:07:50.671Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsPipeInputStream
[task 2021-05-31T18:07:50.671Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsSocketTransport
[task 2021-05-31T18:07:50.672Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsSocketTransportService
[task 2021-05-31T18:07:50.672Z] 18:07:50     INFO - TEST-INFO | leakcheck | default leaked 1 nsStringBuffer
[task 2021-05-31T18:07:50.672Z] 18:07:50     INFO - TEST-UNEXPECTED-FAIL | leakcheck | default 1896 bytes leaked (Mutex, PollableEvent, ReentrantMonitor, nsAStreamCopier, nsPipe, ...)
[task 2021-05-31T18:07:50.672Z] 18:07:50     INFO - 
[task 2021-05-31T18:07:50.672Z] 18:07:50     INFO - leakcheck | Processing leak log file /tmp/tmpayo95k3o/runtests_leaks_1145_tab_pid8995.log
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.