Closed Bug 1690513 Opened 4 years ago Closed 4 years ago

Intermittent tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | IOError: Process has been unexpectedly closed (Exit code: -27) (Reason: Process unexpectedly quit without restarting (exit code: -27))

Categories

(Core :: XPConnect, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=328685583&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y7WfoxHyTsGHZ9_-0k5i6w/runs/0/artifacts/public/logs/live_backing.log


...
[task 2021-02-03T11:15:44.561Z] 11:15:44     INFO -  DEBUG: Finished phase profile-before-change
[task 2021-02-03T11:15:44.562Z] 11:15:44     INFO -  DEBUG: Starting phase profile-before-change-telemetry
[task 2021-02-03T11:15:44.562Z] 11:15:44     INFO -  DEBUG: Spinning the event loop
[task 2021-02-03T11:15:44.608Z] 11:15:44     INFO -  DEBUG: Adding blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2021-02-03T11:15:44.629Z] 11:15:44     INFO -  DEBUG: Adding blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2021-02-03T11:15:44.645Z] 11:15:44     INFO -  DEBUG: Completed blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2021-02-03T11:15:44.645Z] 11:15:44     INFO -  DEBUG: Completed blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2021-02-03T11:15:44.661Z] 11:15:44     INFO -  DEBUG: Completed blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2021-02-03T11:15:44.661Z] 11:15:44     INFO -  DEBUG: Finished phase profile-before-change-telemetry
[task 2021-02-03T11:15:44.661Z] 11:15:44     INFO -  1612350944653	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2021-02-03T11:15:44.662Z] 11:15:44     INFO -  1612350944653	Marionette	DEBUG	Marionette stopped listening
[task 2021-02-03T11:15:44.662Z] 11:15:44     INFO -  DEBUG: Starting phase xpcom-will-shutdown
[task 2021-02-03T11:15:44.662Z] 11:15:44     INFO -  DEBUG: Spinning the event loop
[task 2021-02-03T11:15:44.663Z] 11:15:44     INFO -  DEBUG: Completed blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2021-02-03T11:15:44.664Z] 11:15:44     INFO -  DEBUG: Finished phase xpcom-will-shutdown
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO - TEST-UNEXPECTED-ERROR | js/xpconnect/tests/marionette/test_preloader_telemetry.py TestScriptPreloader.test_preloader_requests_histogram | IOError: Process has been unexpectedly closed (Exit code: -27) (Reason: Process unexpectedly quit without restarting (exit code: -27))
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO - Traceback (most recent call last):
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 214, in run
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -     testMethod()
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/js/xpconnect/tests/marionette/test_preloader_telemetry.py", line 36, in test_preloader_requests_histogram
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -     self.marionette.restart(clean=False, in_app=True)
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -     m._handle_socket_failure()
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 716, in _handle_socket_failure
[task 2021-02-03T11:18:54.524Z] 11:18:54     INFO -     IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -     return func(*args, **kwargs)
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1108, in restart
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -     tb,
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1088, in restart
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -     self.raise_for_port(timeout=timeout_restart, check_process_status=False)
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in raise_for_port
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO -     self.host, self.port
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO - TEST-INFO took 203202ms
[task 2021-02-03T11:18:54.525Z] 11:18:54     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpMXc7DN.mozrunner
[task 2021-02-03T11:18:54.692Z] 11:18:54     INFO -  DEBUG: Adding blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2021-02-03T11:18:54.693Z] 11:18:54     INFO -  DEBUG: Adding blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2021-02-03T11:18:54.695Z] 11:18:54     INFO -  DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2021-02-03T11:18:54.715Z] 11:18:54     INFO -  DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2021-02-03T11:18:54.756Z] 11:18:54     INFO -  DEBUG: Adding blocker Flush WebExtension StartupCache for phase profile-before-change
[task 2021-02-03T11:18:54.832Z] 11:18:54     INFO -  DEBUG: Adding blocker JSON store: writing data for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-03T11:18:54.833Z] 11:18:54     INFO -  DEBUG: Adding blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-03T11:18:54.833Z] 11:18:54     INFO -  DEBUG: Completed blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-03T11:18:54.836Z] 11:18:54     INFO -  DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2021-02-03T11:18:54.838Z] 11:18:54     INFO -  DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-03T11:18:54.839Z] 11:18:54     INFO -  DEBUG: Adding blocker PluginProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-03T11:18:54.839Z] 11:18:54     INFO -  DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2021-02-03T11:18:54.840Z] 11:18:54     INFO -  DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change for phase OS.File: Waiting for clients before profileBeforeChange
[task 2021-02-03T11:18:54.855Z] 11:18:54     INFO -  DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2021-02-03T11:18:54.911Z] 11:18:54     INFO -  DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2021-02-03T11:18:54.927Z] 11:18:54     INFO -  DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2021-02-03T11:18:54.927Z] 11:18:54     INFO -  1612351134921	Marionette	TRACE	Marionette enabled
[task 2021-02-03T11:18:55.002Z] 11:18:55     INFO -  1612351134999	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-02-03T11:18:55.151Z] 11:18:55     INFO -  DEBUG: Adding blocker MediaShutdownManager: shutdown for phase profile-before-change
[task 2021-02-03T11:18:55.166Z] 11:18:55     INFO -  DEBUG: Adding blocker JSON store: writing data for phase profile-before-change
[task 2021-02-03T11:18:55.194Z] 11:18:55     INFO -  DEBUG: Adding blocker JSON store: writing data for phase profile-before-change
[task 2021-02-03T11:18:55.194Z] 11:18:55     INFO -  DEBUG: Adding blocker Remote Settings profile-before-change for phase profile-before-change
[task 2021-02-03T11:18:55.250Z] 11:18:55     INFO -  DEBUG: Adding blocker GMPProvider for phase AddonManager: Waiting for providers to shut down.
[task 2021-02-03T11:18:55.253Z] 11:18:55     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c8034000 for phase xpcom-will-shutdown
[task 2021-02-03T11:18:55.254Z] 11:18:55     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c8034000 for phase profile-before-change
[task 2021-02-03T11:18:55.315Z] 11:18:55     INFO -  DEBUG: Adding blocker ServiceWorkerShutdownBlocker: shutting down Service Workers for phase profile-change-teardown
[task 2021-02-03T11:18:55.343Z] 11:18:55     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c758b000 for phase xpcom-will-shutdown
[task 2021-02-03T11:18:55.344Z] 11:18:55     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c758b000 for phase profile-before-change
[task 2021-02-03T11:18:56.646Z] 11:18:56     INFO -  DEBUG: Adding blocker Places Clients shutdown for phase profile-change-teardown
[task 2021-02-03T11:18:56.646Z] 11:18:56     INFO -  DEBUG: Adding blocker Places Connection shutdown for phase profile-before-change
[task 2021-02-03T11:18:56.648Z] 11:18:56     INFO -  DEBUG: Adding blocker PageActions: purging unregistered actions from cache for phase profile-before-change
[task 2021-02-03T11:18:56.689Z] 11:18:56     INFO -  DEBUG: Adding blocker sanitize.js: Sanitize on shutdown for phase Places Clients shutdown
[task 2021-02-03T11:18:56.709Z] 11:18:56     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2021-02-03T11:18:56.773Z] 11:18:56     INFO -  DEBUG: Adding blocker Sqlite.jsm shutdown blocker for phase profile-before-change
[task 2021-02-03T11:18:56.773Z] 11:18:56     INFO -  DEBUG: Adding blocker content-prefs.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2021-02-03T11:18:56.774Z] 11:18:56     INFO -  DEBUG: Adding blocker Closing ContentPrefService2 connection. for phase Sqlite.jsm: wait until all clients have completed their task
[task 2021-02-03T11:18:56.782Z] 11:18:56     INFO -  DEBUG: Adding blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:56.783Z] 11:18:56     INFO -  DEBUG: Adding blocker DoHController: clear state and remove observers for phase profile-before-change
[task 2021-02-03T11:18:56.820Z] 11:18:56     INFO -  DEBUG: Adding blocker JSON store: writing data for phase profile-before-change
[task 2021-02-03T11:18:56.886Z] 11:18:56     INFO -  DEBUG: Adding blocker IOUtils Blocker for phase profile-before-change
[task 2021-02-03T11:18:56.887Z] 11:18:56     INFO -  DEBUG: Completed blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:56.903Z] 11:18:56     INFO -  DEBUG: Adding blocker DownloadAutoSaveView: writing data for phase profile-before-change
[task 2021-02-03T11:18:56.959Z] 11:18:56     INFO -  DEBUG: Adding blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:56.959Z] 11:18:56     INFO -  DEBUG: Adding blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:56.983Z] 11:18:56     INFO -  DEBUG: Adding blocker places.sqlite#1: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2021-02-03T11:18:56.983Z] 11:18:56     INFO -  DEBUG: Adding blocker Places Expiration: shutdown for phase Places Connection shutdown
[task 2021-02-03T11:18:56.985Z] 11:18:56     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2021-02-03T11:18:56.986Z] 11:18:56     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2021-02-03T11:18:56.989Z] 11:18:56     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesExpiration.jsm: setup (0) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:56.989Z] 11:18:56     INFO -  DEBUG: Adding blocker places.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2021-02-03T11:18:56.989Z] 11:18:56     INFO -  DEBUG: Adding blocker PlacesUtils read-only connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2021-02-03T11:18:56.989Z] 11:18:56     INFO -  DEBUG: Adding blocker PlacesUtils read-only connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2021-02-03T11:18:57.004Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesExpiration.jsm: setup (0) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.004Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:57.032Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2021-02-03T11:18:57.040Z] 11:18:57     INFO -  DEBUG: Adding blocker Transaction (1) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.040Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: eraseEverything (2) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.080Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (1) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.081Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: eraseEverything (2) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.082Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: fetchBookmark (3) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.089Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: fetchBookmark (3) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.090Z] 11:18:57     INFO -  DEBUG: Adding blocker Transaction (4) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.091Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: insertBookmarkTree (5) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.127Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (4) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.128Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: insertBookmarkTree (5) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.128Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: GuidHelper.getItemId (6) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.143Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: GuidHelper.getItemId (6) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.143Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesUtils.keywords.insert (7) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.143Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesUtils: promiseKeywordsCache (8) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.143Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesUtils: promiseKeywordsCache (8) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.143Z] 11:18:57     INFO -  DEBUG: Adding blocker Transaction (9) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.159Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: fetchBookmarksByURL (10) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.159Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (9) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.160Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: fetchBookmarksByURL (10) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.160Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesUtils.keywords.insert (7) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.160Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesUtils.keywords.insert (11) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.160Z] 11:18:57     INFO -  DEBUG: Adding blocker Transaction (12) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.180Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: fetchBookmarksByURL (13) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.180Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (12) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.180Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: fetchBookmarksByURL (13) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.180Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesUtils.keywords.insert (11) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.181Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: fetchBookmark (14) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.189Z] 11:18:57     INFO -  DEBUG: Adding blocker Search service: shutting down for phase OS.File: Waiting for clients before profileBeforeChange
[task 2021-02-03T11:18:57.210Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: fetchBookmark (14) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.211Z] 11:18:57     INFO -  DEBUG: Adding blocker Transaction (15) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.211Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: Bookmarks.jsm: insertBookmarkTree (16) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.227Z] 11:18:57     INFO -  DEBUG: Completed blocker Transaction (15) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.227Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: Bookmarks.jsm: insertBookmarkTree (16) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.228Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: GuidHelper.getItemId (17) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.228Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: GuidHelper.getItemId (17) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.296Z] 11:18:57     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c3642000 for phase xpcom-will-shutdown
[task 2021-02-03T11:18:57.297Z] 11:18:57     INFO -  DEBUG: Adding blocker ContentParent: id=7ff5c3642000 for phase profile-before-change
[task 2021-02-03T11:18:57.337Z] 11:18:57     INFO -  DEBUG: Adding blocker ContextualIdentityService: writing data for phase profile-before-change
[task 2021-02-03T11:18:57.357Z] 11:18:57     INFO -  DEBUG: Adding blocker JSON store: writing data for phase profile-before-change
[task 2021-02-03T11:18:57.364Z] 11:18:57     INFO -  DEBUG: Adding blocker JSON store: writing data for phase profile-before-change
[task 2021-02-03T11:18:57.445Z] 11:18:57     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesUIUtils: maybeAddImportButton (18) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.445Z] 11:18:57     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesUIUtils: maybeAddImportButton (18) for phase places.sqlite#1: waiting for clients
[task 2021-02-03T11:18:57.574Z] 11:18:57     INFO -  1612351137569	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-02-03T11:18:57.574Z] 11:18:57     INFO -  1612351137569	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-02-03T11:18:57.590Z] 11:18:57     INFO -  1612351137588	Marionette	TRACE	All scripts recorded.
[task 2021-02-03T11:18:57.591Z] 11:18:57     INFO -  1612351137589	Marionette	INFO	Listening on port 2828
[task 2021-02-03T11:18:57.592Z] 11:18:57     INFO -  1612351137589	Marionette	DEBUG	Marionette is listening
[task 2021-02-03T11:18:57.653Z] 11:18:57     INFO -  1612351137650	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:35926
[task 2021-02-03T11:18:57.660Z] 11:18:57     INFO -  1612351137658	Marionette	DEBUG	Closed connection 0
[task 2021-02-03T11:18:57.661Z] 11:18:57     INFO -  1612351137658	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:35928
[task 2021-02-03T11:18:57.668Z] 11:18:57     INFO -  1612351137665	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-02-03T11:18:57.684Z] 11:18:57     INFO -  1612351137676	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"49f944b1-03b2-456e-a493-6b671fda4c44","capabilities":{"browserName":"firefox","browserVersion":"87.0a ... mp/tmpMXc7DN.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2021-02-03T11:18:57.692Z] 11:18:57     INFO -  1612351137686	Marionette	DEBUG	1 -> [0,2,"WebDriver:DeleteSession",{}]
[task 2021-02-03T11:18:57.692Z] 11:18:57     INFO -  1612351137689	Marionette	DEBUG	1 <- [1,2,null,{"value":null}]
[task 2021-02-03T11:18:57.698Z] 11:18:57     INFO -  1612351137691	Marionette	DEBUG	Closed connection 1```
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.