Closed
Bug 1920786
Opened 8 months ago
Closed 8 months ago
Intermittent [tier 2] A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down | followed by JavaScript error: resource:///modules/Discovery.sys.mjs, line 95: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ER
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1772350
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=475643437&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fVoZvco-R7C_ymzjUeVauQ/runs/0/artifacts/public/logs/live_backing.log
[task 2024-09-24T17:46:06.473Z] 17:46:06 INFO - TEST-START | browser/components/search/test/marionette/telemetry/test_ping_submitted.py TestPingSubmitted.test_ping_submit_on_start
[task 2024-09-24T17:46:06.474Z] 17:46:06 INFO - 1727199966474 Marionette DEBUG Closed connection 1
[task 2024-09-24T17:46:06.475Z] 17:46:06 INFO - 1727199966475 Marionette DEBUG Accepted connection 2 from 127.0.0.1:37812
[task 2024-09-24T17:46:06.477Z] 17:46:06 INFO - 1727199966476 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-09-24T17:46:06.487Z] 17:46:06 INFO - 1727199966486 Marionette DEBUG Waiting for initial application window
[task 2024-09-24T17:46:06.489Z] 17:46:06 INFO - 1727199966488 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-09-24T17:46:06.489Z] 17:46:06 INFO - 1727199966488 RemoteAgent TRACE [9] ProgressListener Setting unload timer (80000ms)
[task 2024-09-24T17:46:06.490Z] 17:46:06 INFO - 1727199966488 RemoteAgent TRACE [9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-09-24T17:46:06.490Z] 17:46:06 INFO - 1727199966489 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2024-09-24T17:46:06.491Z] 17:46:06 INFO - 1727199966489 RemoteAgent TRACE [9] ProgressListener Stop: has error=false url=about:blank
[task 2024-09-24T17:46:06.494Z] 17:46:06 INFO - 1727199966493 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"61336ed6-5e5f-47fa-a6fb-e8b4d184a891","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"132.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0","moz:buildID":"20240924162557","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":1099,"moz:profile":"/tmp/tmpvl8zxkfn.mozrunner","moz:shutdownTimeout":180000,"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-09-24T17:46:06.495Z] 17:46:06 INFO - 1727199966495 Marionette DEBUG 2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2024-09-24T17:46:06.496Z] 17:46:06 INFO - 1727199966496 Marionette DEBUG 2 <- [1,2,null,{"value":null}]
[task 2024-09-24T17:46:06.497Z] 17:46:06 INFO - 1727199966497 Marionette DEBUG 2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2024-09-24T17:46:06.498Z] 17:46:06 INFO - 1727199966498 Marionette DEBUG 2 <- [1,3,null,{"value":null}]
[task 2024-09-24T17:46:06.499Z] 17:46:06 INFO - 1727199966499 Marionette DEBUG 2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2024-09-24T17:46:06.500Z] 17:46:06 INFO - 1727199966500 Marionette DEBUG 2 <- [1,4,null,{"value":null}]
[task 2024-09-24T17:46:06.502Z] 17:46:06 INFO - 1727199966502 Marionette DEBUG 2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T17:46:06.504Z] 17:46:06 INFO - 1727199966503 Marionette DEBUG 2 <- [1,5,null,{"value":null}]
[task 2024-09-24T17:46:06.505Z] 17:46:06 INFO - 1727199966505 Marionette DEBUG 2 -> [0,6,"Marionette:GetContext",{}]
[task 2024-09-24T17:46:06.506Z] 17:46:06 INFO - 1727199966505 Marionette DEBUG 2 <- [1,6,null,{"value":"chrome"}]
[task 2024-09-24T17:46:06.507Z] 17:46:06 INFO - 1727199966507 Marionette DEBUG 2 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T17:46:06.508Z] 17:46:06 INFO - 1727199966507 Marionette DEBUG 2 <- [1,7,null,{"value":null}]
[task 2024-09-24T17:46:06.510Z] 17:46:06 INFO - 1727199966509 Marionette DEBUG 2 -> [0,8,"WebDriver:ExecuteScript",{"script":"let prefInterface = Components.classes[\"@mozilla.org/preferences-service;1\"]\n .g ... ref).toString();\n case prefInterface.PREF_INVALID:\n return false;\n }","args":[],"newSandbox":true,"sandbox":"default","line":1021,"filename":"../../venv/lib/python3.8/site-packages/marionette_driver/marionette.py"}]
[task 2024-09-24T17:46:06.542Z] 17:46:06 INFO - 1727199966532 RemoteAgent TRACE WebDriverProcessData actor created for PID 1099
[task 2024-09-24T17:46:06.542Z] 17:46:06 INFO - 1727199966535 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2024-09-24T17:46:06.560Z] 17:46:06 INFO - 1727199966559 Marionette DEBUG 2 <- [1,8,null,{"value":false}]
[task 2024-09-24T17:46:06.561Z] 17:46:06 INFO - 1727199966561 Marionette DEBUG 2 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T17:46:06.562Z] 17:46:06 INFO - 1727199966561 Marionette DEBUG 2 <- [1,9,null,{"value":null}]
[task 2024-09-24T17:46:06.563Z] 17:46:06 INFO - 1727199966562 Marionette DEBUG 2 -> [0,10,"Marionette:GetContext",{}]
[task 2024-09-24T17:46:06.564Z] 17:46:06 INFO - 1727199966563 Marionette DEBUG 2 <- [1,10,null,{"value":"chrome"}]
[task 2024-09-24T17:46:06.564Z] 17:46:06 INFO - 1727199966564 Marionette DEBUG 2 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2024-09-24T17:46:06.565Z] 17:46:06 INFO - 1727199966565 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2024-09-24T17:46:06.566Z] 17:46:06 INFO - 1727199966566 Marionette DEBUG 2 <- [1,11,null,{"value":null}]
[task 2024-09-24T17:46:06.600Z] 17:46:06 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmp11hyumpw.mozrunner
[task 2024-09-24T17:46:06.653Z] 17:46:06 INFO - [CodeCoverage] Setting handlers for process 1271.
[task 2024-09-24T17:46:07.298Z] 17:46:07 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T17:46:07.343Z] 17:46:07 INFO - 1727199967342 Marionette INFO Marionette enabled
[task 2024-09-24T17:46:07.345Z] 17:46:07 INFO - 1727199967345 Marionette TRACE Received observer notification final-ui-startup
[task 2024-09-24T17:46:07.434Z] 17:46:07 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T17:46:07.461Z] 17:46:07 INFO - 1727199967460 Marionette INFO Listening on port 2828
[task 2024-09-24T17:46:07.463Z] 17:46:07 INFO - 1727199967463 Marionette DEBUG Marionette is listening
[task 2024-09-24T17:46:07.500Z] 17:46:07 INFO - 1727199967499 Marionette DEBUG Accepted connection 0 from 127.0.0.1:37830
[task 2024-09-24T17:46:07.536Z] 17:46:07 INFO - 1727199967535 Marionette DEBUG Closed connection 0
[task 2024-09-24T17:46:07.536Z] 17:46:07 INFO - 1727199967536 Marionette DEBUG Accepted connection 1 from 127.0.0.1:37832
[task 2024-09-24T17:46:07.764Z] 17:46:07 INFO - 1727199967764 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-09-24T17:46:07.770Z] 17:46:07 INFO - 1727199967770 Marionette DEBUG Waiting for initial application window
[task 2024-09-24T17:46:08.460Z] 17:46:08 INFO - [CodeCoverage] Setting handlers for process 1367.
[task 2024-09-24T17:46:08.590Z] 17:46:08 INFO - [CodeCoverage] Setting handlers for process 1373.
[task 2024-09-24T17:46:10.005Z] 17:46:10 INFO - console.debug: SearchService: "init"
[task 2024-09-24T17:46:10.058Z] 17:46:10 INFO - console.error: ({})
[task 2024-09-24T17:46:10.137Z] 17:46:10 INFO - console.debug: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open `/tmp/tmp11hyumpw.mozrunner/search.json.mozlz4': file does not exist (NS_ERROR_FILE_NOT_FOUND)", (void 0)))
[task 2024-09-24T17:46:10.463Z] 17:46:10 INFO - console.debug: SearchService: "#handleIgnoreListUpdated"
[task 2024-09-24T17:46:10.481Z] 17:46:10 INFO - console.debug: SearchEngineSelector: "fetchEngineConfiguration en-US:unknown:default:::Firefox:132.0a1"
[task 2024-09-24T17:46:10.485Z] 17:46:10 INFO - console.debug: SearchEngineSelector: "fetchEngineConfiguration: google,ddg,bing,wikipedia"
[task 2024-09-24T17:46:10.486Z] 17:46:10 INFO - console.debug: SearchService: "#loadEngines: start"
[task 2024-09-24T17:46:10.486Z] 17:46:10 INFO - console.debug: SearchService: "#loadEnginesFromConfig"
[task 2024-09-24T17:46:10.502Z] 17:46:10 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Google"
[task 2024-09-24T17:46:10.503Z] 17:46:10 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "DuckDuckGo"
[task 2024-09-24T17:46:10.509Z] 17:46:10 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Bing"
[task 2024-09-24T17:46:10.510Z] 17:46:10 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Wikipedia (en)"
[task 2024-09-24T17:46:10.510Z] 17:46:10 INFO - console.debug: SearchService: "#loadStartupEngines: loading" 0 "engines reported by AddonManager startup"
[task 2024-09-24T17:46:10.511Z] 17:46:10 INFO - console.debug: SearchService: "#loadEngines: done"
[task 2024-09-24T17:46:10.549Z] 17:46:10 INFO - console.debug: SearchService: "Completed #init"
[task 2024-09-24T17:46:10.550Z] 17:46:10 INFO - console.debug: SearchService: "#buildSortedEngineList: using default orders"
[task 2024-09-24T17:46:10.856Z] 17:46:10 INFO - console.debug: SearchService: "getEngines: getting all engines"
[task 2024-09-24T17:46:11.287Z] 17:46:11 INFO - console.debug: SearchService: "removeWebExtensionEngine:" "screenshots@mozilla.org"
[task 2024-09-24T17:46:11.287Z] 17:46:11 INFO - console.debug: SearchService: "getEngines: getting all engines for" "screenshots@mozilla.org"
[task 2024-09-24T17:46:11.414Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initialize SERP categorizer."
[task 2024-09-24T17:46:11.415Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initializing domain-to-categories map."
[task 2024-09-24T17:46:11.416Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Setting up domain-to-categories map."
[task 2024-09-24T17:46:11.421Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initializing domain-to-categories store."
[task 2024-09-24T17:46:11.429Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Create store."
[task 2024-09-24T17:46:11.516Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initialized domain-to-categories store."
[task 2024-09-24T17:46:11.565Z] 17:46:11 INFO - console.debug: SearchTelemetry: "No records found for domain-to-categories map."
[task 2024-09-24T17:46:11.567Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initialized domain-to-categories map."
[task 2024-09-24T17:46:11.568Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Initializing categorization event scheduler."
[task 2024-09-24T17:46:11.611Z] 17:46:11 INFO - console.debug: SearchSettings: "batchTask: Invalidating engine settings"
[task 2024-09-24T17:46:11.612Z] 17:46:11 INFO - console.debug: SearchSettings: "_write: Writing to settings file."
[task 2024-09-24T17:46:11.614Z] 17:46:11 INFO - console.debug: SearchSettings: "_write: settings file written to disk."
[task 2024-09-24T17:46:11.669Z] 17:46:11 INFO - 1727199971669 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2024-09-24T17:46:11.683Z] 17:46:11 INFO - 1727199971683 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-09-24T17:46:11.684Z] 17:46:11 INFO - 1727199971683 RemoteAgent TRACE [9] ProgressListener Setting unload timer (80000ms)
[task 2024-09-24T17:46:11.685Z] 17:46:11 INFO - 1727199971684 RemoteAgent TRACE [9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-09-24T17:46:11.685Z] 17:46:11 INFO - 1727199971685 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2024-09-24T17:46:11.686Z] 17:46:11 INFO - 1727199971685 RemoteAgent TRACE [9] ProgressListener Stop: has error=false url=about:blank
[task 2024-09-24T17:46:11.710Z] 17:46:11 INFO - 1727199971709 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"20132c19-1eca-4943-85fa-f3d3c069ba17","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"132.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0","moz:buildID":"20240924162557","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":1271,"moz:profile":"/tmp/tmp11hyumpw.mozrunner","moz:shutdownTimeout":180000,"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-09-24T17:46:11.720Z] 17:46:11 INFO - 1727199971719 Marionette DEBUG 1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T17:46:11.722Z] 17:46:11 INFO - 1727199971720 Marionette DEBUG 1 <- [1,2,null,{"value":null}]
[task 2024-09-24T17:46:11.725Z] 17:46:11 INFO - 1727199971724 Marionette DEBUG 1 -> [0,3,"WebDriver:ExecuteAsyncScript",{"script":"let [outerResolve] = arguments;\n (async () => {\n if (!Services.prefs.getBoolPref(\"browser.search.se ... metryCategorization.enabled\", true);\n await inited;\n }\n })().then(outerResolve);","args":[],"newSandbox":true,"sandbox":"default","scriptTimeout":null,"line":43,"filename":"tests/browser/components/search/test/marionette/telemetry/test_ping_submitted.py"}]
[task 2024-09-24T17:46:11.734Z] 17:46:11 INFO - 1727199971734 RemoteAgent TRACE WebDriverProcessData actor created for PID 1271
[task 2024-09-24T17:46:11.737Z] 17:46:11 INFO - 1727199971736 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2024-09-24T17:46:11.744Z] 17:46:11 INFO - 1727199971743 Marionette DEBUG 1 <- [1,3,null,{"value":null}]
[task 2024-09-24T17:46:11.746Z] 17:46:11 INFO - 1727199971745 Marionette DEBUG 1 -> [0,4,"WebDriver:ExecuteScript",{"script":"const { SERPCategorizationRecorder } = ChromeUtils.importESModule(\"resource:///modules/SearchSERPTelemetry.sys.mjs\");\n ... num_ads_hidden: \"0\",\n num_ads_loaded: \"2\",\n num_ads_visible: \"2\",\n });","args":[],"newSandbox":true,"sandbox":"default","line":47,"filename":"tests/browser/components/search/test/marionette/telemetry/test_ping_submitted.py"}]
[task 2024-09-24T17:46:11.749Z] 17:46:11 INFO - console.debug: SearchTelemetry: "Reporting the following categorization result:" ({organic_category:"3", organic_num_domains:"1", organic_num_inconclusive:"0", organic_num_unknown:"0", sponsored_category:"4", sponsored_num_domains:"2", sponsored_num_inconclusive:"0", sponsored_num_unknown:"0", mappings_version:"1", app_version:"124", channel:"nightly", region:"US", partner_code:"ff", provider:"example", tagged:"true", num_ads_clicked:"0", num_ads_hidden:"0", num_ads_loaded:"2", num_ads_visible:"2"})
[task 2024-09-24T17:46:11.750Z] 17:46:11 INFO - 1727199971750 Marionette DEBUG 1 <- [1,4,null,{"value":null}]
[task 2024-09-24T17:46:11.752Z] 17:46:11 INFO - 1727199971751 Marionette DEBUG 1 -> [0,5,"WebDriver:ExecuteScript",{"script":"return (Glean.serp.categorization.testGetValue()?.length ?? 0) == 1;","args":[],"newSandbox":true,"sandbox":"default","line":75,"filename":"tests/browser/components/search/test/marionette/telemetry/test_ping_submitted.py"}]
[task 2024-09-24T17:46:11.756Z] 17:46:11 INFO - 1727199971756 Marionette DEBUG 1 <- [1,5,null,{"value":true}]
[task 2024-09-24T17:46:11.757Z] 17:46:11 INFO - 1727199971757 Marionette DEBUG 1 -> [0,6,"Marionette:GetContext",{}]
[task 2024-09-24T17:46:11.758Z] 17:46:11 INFO - 1727199971757 Marionette DEBUG 1 <- [1,6,null,{"value":"chrome"}]
[task 2024-09-24T17:46:11.759Z] 17:46:11 INFO - 1727199971759 Marionette DEBUG 1 -> [0,7,"Marionette:AcceptConnections",{"value":false}]
[task 2024-09-24T17:46:11.760Z] 17:46:11 INFO - 1727199971759 Marionette INFO Stopped listening on port 2828
[task 2024-09-24T17:46:11.760Z] 17:46:11 INFO - 1727199971759 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2024-09-24T17:46:11.763Z] 17:46:11 INFO - 1727199971763 Marionette DEBUG 1 -> [0,8,"Marionette:Quit",{"flags":["eRestart"]}]
[task 2024-09-24T17:46:11.828Z] 17:46:11 INFO - 1727199971827 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2024-09-24T17:46:12.223Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Uninit SERP categorizer."
[task 2024-09-24T17:46:12.224Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Un-initializing domain-to-categories map."
[task 2024-09-24T17:46:12.224Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Removing Remote Settings client."
[task 2024-09-24T17:46:12.224Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Un-initializing domain-to-categories store."
[task 2024-09-24T17:46:12.224Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Closing connection."
[task 2024-09-24T17:46:12.253Z] 17:46:12 INFO - 1727199972252 Marionette TRACE Received observer notification quit-application
[task 2024-09-24T17:46:12.253Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Un-initializing categorization event scheduler."
[task 2024-09-24T17:46:12.258Z] 17:46:12 INFO - 1727199972257 Marionette TRACE Received observer notification quit-application
[task 2024-09-24T17:46:12.259Z] 17:46:12 INFO - 1727199972258 Marionette DEBUG Marionette stopped listening
[task 2024-09-24T17:46:12.259Z] 17:46:12 INFO - 1727199972259 Marionette DEBUG 1 <- [1,8,null,{"cause":"restart","forced":false,"in_app":true}]
[task 2024-09-24T17:46:12.261Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Un-initialized domain-to-categories store."
[task 2024-09-24T17:46:12.262Z] 17:46:12 INFO - console.debug: SearchTelemetry: "Un-initialized domain-to-categories map."
[task 2024-09-24T17:46:12.280Z] 17:46:12 INFO - 1727199972279 Marionette DEBUG Closed connection 1
[task 2024-09-24T17:46:27.257Z] 17:46:27 INFO - console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.sys.mjs", 414))
[task 2024-09-24T17:46:30.211Z] 17:46:30 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fbb69fd1900","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""},{"name":"ContentParent: id=7fbb6c956000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""},{"name":"ContentParent: id=7fbb69f63b00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""}] Barrier: profile-before-change
[task 2024-09-24T17:46:32.211Z] 17:46:32 ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2024-09-24T17:46:32.212Z] 17:46:32 INFO - [Parent 1271, IPC I/O Parent] WARNING: process 1446 exited on signal 15: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:335
[task 2024-09-24T17:46:32.214Z] 17:46:32 INFO - [Parent 1271, IPC I/O Parent] WARNING: process 1450 exited on signal 15: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:335
[task 2024-09-24T17:46:32.215Z] 17:46:32 ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2024-09-24T17:46:32.216Z] 17:46:32 ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2024-09-24T17:46:32.217Z] 17:46:32 INFO - [Parent 1271, IPC I/O Parent] WARNING: process 1451 exited on signal 15: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:335
[task 2024-09-24T17:46:32.223Z] 17:46:32 INFO - JavaScript error: resource:///modules/Discovery.sys.mjs, line 95: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2024-09-24T17:46:33.183Z] 17:46:33 INFO - [CodeCoverage] Setting handlers for process 1473.
[task 2024-09-24T17:46:33.325Z] 17:46:33 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T17:46:33.352Z] 17:46:33 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-09-24T17:46:33.597Z] 17:46:33 INFO - 1727199993597 Marionette INFO Marionette enabled
[task 2024-09-24T17:46:33.598Z] 17:46:33 INFO - 1727199993597 Marionette TRACE Received observer notification profile-after-change
[task 2024-09-24T17:46:33.624Z] 17:46:33 INFO - 1727199993624 Marionette TRACE Received observer notification command-line-startup
[task 2024-09-24T17:46:33.625Z] 17:46:33 INFO - 1727199993625 Marionette TRACE Received observer notification final-ui-startup
[task 2024-09-24T17:46:33.660Z] 17:46:33 INFO - 1727199993660 Marionette INFO Listening on port 2828
[task 2024-09-24T17:46:33.661Z] 17:46:33 INFO - 1727199993660 Marionette DEBUG Marionette is listening
[task 2024-09-24T17:46:33.724Z] 17:46:33 INFO - 1727199993723 Marionette DEBUG Accepted connection 0 from 127.0.0.1:38288
[task 2024-09-24T17:46:33.732Z] 17:46:33 INFO - 1727199993732 Marionette DEBUG Closed connection 0
[task 2024-09-24T17:46:33.733Z] 17:46:33 INFO - 1727199993733 Marionette DEBUG Accepted connection 1 from 127.0.0.1:38290
[task 2024-09-24T17:46:33.864Z] 17:46:33 INFO - 1727199993864 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-09-24T17:46:33.867Z] 17:46:33 INFO - 1727199993866 Marionette DEBUG Waiting for initial application window
[task 2024-09-24T17:46:34.617Z] 17:46:34 INFO - [CodeCoverage] Setting handlers for process 1569.
[task 2024-09-24T17:46:34.674Z] 17:46:34 INFO - console.debug: SearchService: "Delaying removing extension engine on startup:" "default-theme@mozilla.org"
[task 2024-09-24T17:46:35.760Z] 17:46:35 INFO - [CodeCoverage] Setting handlers for process 1597.
[task 2024-09-24T17:46:35.805Z] 17:46:35 INFO - [CodeCoverage] Setting handlers for process 1599.
[task 2024-09-24T17:46:35.885Z] 17:46:35 INFO - [CodeCoverage] Setting handlers for process 1600.
[task 2024-09-24T17:46:35.950Z] 17:46:35 INFO - console.debug: SearchService: "init"
[task 2024-09-24T17:46:35.981Z] 17:46:35 INFO - console.error: ({})
[task 2024-09-24T17:46:36.209Z] 17:46:36 INFO - console.debug: SearchEngineSelector: "fetchEngineConfiguration en-US:unknown:default:::Firefox:132.0a1"
[task 2024-09-24T17:46:36.212Z] 17:46:36 INFO - console.debug: SearchEngineSelector: "fetchEngineConfiguration: google,ddg,bing,wikipedia"
[task 2024-09-24T17:46:36.212Z] 17:46:36 INFO - console.debug: SearchService: "#loadEngines: start"
[task 2024-09-24T17:46:36.212Z] 17:46:36 INFO - console.debug: SearchService: "#loadEnginesFromConfig"
[task 2024-09-24T17:46:36.217Z] 17:46:36 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Google"
[task 2024-09-24T17:46:36.219Z] 17:46:36 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "DuckDuckGo"
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Bing"
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#addEngineToStore: Adding engine:" "Wikipedia (en)"
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#loadStartupEngines: loading" 0 "engines reported by AddonManager startup"
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#loadEnginesFromSettings: Loading" 4 "engines from settings"
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#loadEnginesFromSettings: skipped" 4 "built-in/policy engines."
[task 2024-09-24T17:46:36.221Z] 17:46:36 INFO - console.debug: SearchService: "#loadEngines: done"
[task 2024-09-24T17:46:36.253Z] 17:46:36 INFO - console.debug: SearchService: "Completed #init"
[task 2024-09-24T17:46:36.279Z] 17:46:36 INFO - console.debug: SearchService: "Removing delayed extension engines"
[task 2024-09-24T17:46:36.279Z] 17:46:36 INFO - console.debug: SearchService: "getEngines: getting all engines for" "default-theme@mozilla.org"
[task 2024-09-24T17:46:36.279Z] 17:46:36 INFO - console.debug: SearchService: "#buildSortedEngineList: using default orders"
[task 2024-09-24T17:46:36.493Z] 17:46:36 INFO - console.debug: SearchService: "#handleIgnoreListUpdated"
[task 2024-09-24T17:46:36.628Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initialize SERP categorizer."
[task 2024-09-24T17:46:36.629Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initializing domain-to-categories map."
[task 2024-09-24T17:46:36.631Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Setting up domain-to-categories map."
[task 2024-09-24T17:46:36.633Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initializing domain-to-categories store."
[task 2024-09-24T17:46:36.664Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Create store."
[task 2024-09-24T17:46:36.707Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initialized domain-to-categories store."
[task 2024-09-24T17:46:36.715Z] 17:46:36 INFO - console.debug: SearchTelemetry: "No records found for domain-to-categories map."
[task 2024-09-24T17:46:36.715Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initialized domain-to-categories map."
[task 2024-09-24T17:46:36.715Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Initializing categorization event scheduler."
[task 2024-09-24T17:46:36.717Z] 17:46:36 INFO - console.debug: SearchTelemetry: "No targetExperiment found."
[task 2024-09-24T17:46:36.719Z] 17:46:36 INFO - console.debug: SearchTelemetry: "Submitting SERP categorization ping:" "startup"
[task 2024-09-24T17:46:36.760Z] 17:46:36 INFO - 1727199996759 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2024-09-24T17:46:36.771Z] 17:46:36 INFO - 1727199996771 RemoteAgent TRACE [3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-09-24T17:46:36.773Z] 17:46:36 INFO - 1727199996771 RemoteAgent TRACE [3] ProgressListener Setting unload timer (80000ms)
[task 2024-09-24T17:46:36.774Z] 17:46:36 INFO - 1727199996772 RemoteAgent TRACE [3] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-09-24T17:46:36.774Z] 17:46:36 INFO - 1727199996772 RemoteAgent TRACE [3] Document already finished loading: about:blank
[task 2024-09-24T17:46:36.774Z] 17:46:36 INFO - 1727199996772 RemoteAgent TRACE [3] ProgressListener Stop: has error=false url=about:blank
[task 2024-09-24T17:46:36.781Z] 17:46:36 INFO - 1727199996780 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"6f26e334-9a04-4ae9-a8fd-1defd179ad18","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"132.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:132.0) Gecko/20100101 Firefox/132.0","moz:buildID":"20240924162557","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":1473,"moz:profile":"/tmp/tmp11hyumpw.mozrunner","moz:shutdownTimeout":180000,"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-09-24T17:46:36.784Z] 17:46:36 INFO - 1727199996784 Marionette DEBUG 1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-09-24T17:46:36.786Z] 17:46:36 INFO - 1727199996785 Marionette DEBUG 1 <- [1,2,null,{"value":null}]
[task 2024-09-24T17:46:36.787Z] 17:46:36 INFO - 1727199996787 Marionette DEBUG 1 -> [0,3,"WebDriver:ExecuteScript",{"script":"return (Glean.serp.categorization.testGetValue()?.length ?? 0) == 0;","args":[],"newSandbox":true,"sandbox":"default","line":86,"filename":"tests/browser/components/search/test/marionette/telemetry/test_ping_submitted.py"}]
[task 2024-09-24T17:46:36.794Z] 17:46:36 INFO - 1727199996793 RemoteAgent TRACE WebDriverProcessData actor created for PID 1473
[task 2024-09-24T17:46:36.797Z] 17:46:36 INFO - 1727199996796 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2024-09-24T17:46:36.989Z] 17:46:36 INFO - [CodeCoverage] Setting handlers for process 1658.
[task 2024-09-24T17:46:37.115Z] 17:46:37 INFO - 1727199997114 Marionette DEBUG 1 <- [1,3,null,{"value":true}]
[task 2024-09-24T17:46:37.128Z] 17:46:37 INFO - 1727199997127 Marionette DEBUG 1 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2024-09-24T17:46:37.132Z] 17:46:37 INFO - 1727199997131 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2024-09-24T17:46:37.140Z] 17:46:37 INFO - 1727199997139 Marionette DEBUG 1 <- [1,4,null,{"value":null}]
[task 2024-09-24T17:46:37.143Z] 17:46:37 INFO - TEST-PASS | browser/components/search/test/marionette/telemetry/test_ping_submitted.py TestPingSubmitted.test_ping_submit_on_start | took 30669ms
[task 2024-09-24T17:46:37.144Z] 17:46:37 INFO - TEST-START | browser/components/search/test/marionette/test_engines_on_restart.py TestEnginesOnRestart.test_engines
|
||
Comment 1•8 months ago
|
||
This is a shutdown hang:
[task 2024-09-24T17:46:30.211Z] 17:46:30 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fbb69fd1900","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""},{"name":"ContentParent: id=7fbb6c956000","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""},{"name":"ContentParent: id=7fbb69f63b00","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3713,"stack":""}] Barrier: profile-before-change
And as per log line a dupe of bug 1772350.
You need to log in
before you can comment on or make changes to this bug.
Description
•