Closed Bug 1614678 Opened 6 years ago Closed 5 years ago

Intermittent browser/components/migration/tests/marionette/test_refresh_firefox.py TestFirefoxRefresh.testResetEverything | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: Process unexpectedly quit without restarting (exit code: 0))

Categories

(Firefox :: Migration, defect, P5)

Product:
Firefox
Component:
Migration
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=288342099&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ahUWCdi_Riaaif5Mls9_XA/runs/0/artifacts/public/logs/live_backing.log

[task 2020-02-11T12:25:00.654Z] 12:25:00 INFO - TEST-START | browser/components/migration/tests/marionette/test_refresh_firefox.py TestFirefoxRefresh.testResetEverything
[task 2020-02-11T12:25:00.654Z] 12:25:00 INFO - 1581423900644 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2020-02-11T12:25:00.659Z] 12:25:00 INFO - 1581423900655 Marionette DEBUG Accepted connection 2 from 127.0.0.1:54437
[task 2020-02-11T12:25:00.659Z] 12:25:00 INFO - 1581423900657 Marionette DEBUG Closed connection 1
[task 2020-02-11T12:25:00.665Z] 12:25:00 INFO - 1581423900659 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2020-02-11T12:25:00.665Z] 12:25:00 INFO - 1581423900662 Marionette TRACE [19] Frame script loaded
[task 2020-02-11T12:25:00.670Z] 12:25:00 INFO - 1581423900664 Marionette TRACE [19] Frame script registered
[task 2020-02-11T12:25:00.671Z] 12:25:00 INFO - 1581423900668 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"6022f0ba-e1c8-0b4d-8c84-1338d4ad1ed2","capabilities":{"browserName":"firefox","browserVersion":"75.0a ... /T/tmpNGPKs4.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2020-02-11T12:25:00.674Z] 12:25:00 INFO - 1581423900671 Marionette DEBUG 2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2020-02-11T12:25:00.674Z] 12:25:00 INFO - 1581423900672 Marionette DEBUG 2 <- [1,2,null,{"value":null}]
[task 2020-02-11T12:25:00.681Z] 12:25:00 INFO - 1581423900674 Marionette DEBUG 2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2020-02-11T12:25:00.681Z] 12:25:00 INFO - 1581423900676 Marionette DEBUG 2 <- [1,3,null,{"value":null}]
[task 2020-02-11T12:25:00.681Z] 12:25:00 INFO - 1581423900678 Marionette DEBUG 2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2020-02-11T12:25:00.686Z] 12:25:00 INFO - 1581423900680 Marionette DEBUG 2 <- [1,4,null,{"value":null}]
[task 2020-02-11T12:25:00.686Z] 12:25:00 INFO - 1581423900682 Marionette DEBUG 2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-02-11T12:25:00.686Z] 12:25:00 INFO - 1581423900683 Marionette DEBUG 2 <- [1,5,null,{"value":null}]
[task 2020-02-11T12:25:00.690Z] 12:25:00 INFO - 1581423900684 Marionette DEBUG 2 -> [0,6,"WebDriver:ExecuteScript",{"script":"window.global = {};\n global.LoginInfo = Components.Constructor("@mozilla ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:00.709Z] 12:25:00 INFO - 1581423900690 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2020-02-11T12:25:00.709Z] 12:25:00 INFO - 1581423900692 Marionette DEBUG 2 -> [0,7,"WebDriver:ExecuteScript",{"script":"try {\n global.formAutofillStorage = Cu.import("resource://formautofill ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:00.710Z] 12:25:00 INFO - 1581423900696 Marionette DEBUG 2 <- [1,7,null,{"value":true}]
[task 2020-02-11T12:25:00.710Z] 12:25:00 INFO - 1581423900698 Marionette DEBUG 2 -> [0,8,"WebDriver:ExecuteScript",{"script":"let myLogin = new global.LoginInfo(\n "test.marionette.mozilla.com",\n ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:00.718Z] 12:25:00 INFO - 1581423900712 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2020-02-11T12:25:00.718Z] 12:25:00 INFO - 1581423900715 Marionette DEBUG 2 -> [0,9,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["about:mozilla","Some bookmark from Mari ... s.bookmarks.menuGuid, url, title\n }).then(() => resolve(false), resolve);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900737 Marionette DEBUG 2 <- [1,9,null,{"value":false}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900741 Marionette DEBUG 2 -> [0,10,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... olbarGuid,\n children\n }).then(() => resolve(false), resolve);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900768 Marionette DEBUG 2 <- [1,10,null,{"value":false}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900771 Marionette DEBUG 2 -> [0,11,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["http://firefox-refresh.marionette-test ... e),\n ex => resolve("Unexpected error in adding visit: " + ex));","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900782 Marionette DEBUG 2 <- [1,11,null,{"value":false}]
[task 2020-02-11T12:25:00.799Z] 12:25:00 INFO - 1581423900784 Marionette DEBUG 2 -> [0,12,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["some-very-unique-marionette-only-firef ... ) {\n resolve(false);\n }\n }\n });","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.817Z] 12:25:00 INFO - 1581423900805 Marionette DEBUG 2 <- [1,12,null,{"value":false}]
[task 2020-02-11T12:25:00.817Z] 12:25:00 INFO - 1581423900807 Marionette DEBUG 2 -> [0,13,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... bal.formAutofillStorage.addresses.add(TEST_ADDRESS_1);\n }).then(resolve);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.861Z] 12:25:00 INFO - 1581423900852 Marionette DEBUG 2 <- [1,13,null,{"value":"39b097b8940e"}]
[task 2020-02-11T12:25:00.861Z] 12:25:00 INFO - 1581423900856 Marionette DEBUG 2 -> [0,14,"WebDriver:ExecuteScript",{"script":"// Expire in 15 minutes:\n let expireTime = Math.floor(Date.now() / 1000) ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:00.861Z] 12:25:00 INFO - 1581423900860 Marionette DEBUG 2 <- [1,14,null,{"value":null}]
[task 2020-02-11T12:25:00.871Z] 12:25:00 INFO - 1581423900862 Marionette DEBUG 2 -> [0,15,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[["about:robots","about:mozilla"]],"file ... abs.has(tab)) {\n gBrowser.removeTab(tab);\n }\n }","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:00.916Z] 12:25:00 INFO - 1581423900896 Marionette TRACE [22] Frame script loaded
[task 2020-02-11T12:25:00.916Z] 12:25:00 INFO - 1581423900897 Marionette TRACE [22] Frame script registered
[task 2020-02-11T12:25:00.948Z] 12:25:00 INFO - 1581423900930 Marionette TRACE [24] Frame script loaded
[task 2020-02-11T12:25:00.948Z] 12:25:00 INFO - 1581423900930 Marionette TRACE [24] Frame script registered
[task 2020-02-11T12:25:01.136Z] 12:25:01 INFO - 1581423901122 Marionette DEBUG 2 <- [1,15,null,{"value":null}][task 2020-02-11T12:25:01.168Z] 12:25:01 INFO - [Child 2029, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2020-02-11T12:25:01.171Z] 12:25:01 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-11T12:25:01.196Z] 12:25:01 INFO - 1581423901189 Marionette DEBUG 2 -> [0,16,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... ;\n storage.initialize(data);\n storage.finalize().then(resolve);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:01.260Z] 12:25:01 INFO - 1581423901236 Marionette DEBUG 2 <- [1,16,null,{"value":null}]
[task 2020-02-11T12:25:01.282Z] 12:25:01 INFO - [Child 2029, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-11T12:25:01.287Z] 12:25:01 INFO - nsStringStats
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mAllocCount: 7616
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mReallocCount: 0
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mFreeCount: 7616
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mShareCount: 6526
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mAdoptCount: 344
[task 2020-02-11T12:25:01.288Z] 12:25:01 INFO - => mAdoptFreeCount: 344
[task 2020-02-11T12:25:01.298Z] 12:25:01 INFO - => Process ID: 2029, Thread ID: 4623201728
[task 2020-02-11T12:25:01.309Z] 12:25:01 INFO - 1581423901304 Marionette DEBUG 2 -> [0,17,"WebDriver:ExecuteScript",{"script":"Services.prefs.setStringPref("services.sync.username", "test@test.com");","new ... :[],"filename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"default","line":215}]
[task 2020-02-11T12:25:01.337Z] 12:25:01 INFO - 1581423901318 Marionette DEBUG 2 <- [1,17,null,{"value":null}]
[task 2020-02-11T12:25:02.213Z] 12:25:02 INFO - 1581423902172 Marionette DEBUG 2 -> [0,18,"WebDriver:ExecuteScript",{"script":"let ary = Services.logins.findLogins(\n "test.marionette.mozilla.com" ... :[],"filename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"default","line":224}]
[task 2020-02-11T12:25:02.213Z] 12:25:02 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/chrome/nsChromeRegistry.cpp, line 175
[task 2020-02-11T12:25:02.214Z] 12:25:02 INFO - 1581423902185 Marionette DEBUG 2 <- [1,18,null,{"value":[{"init":{},"QueryInterface":{},"guid":"{018d7166-3e93-394a-be79-4ba15e663606}","timeCreated":15814239007 ... om/some/form/","httpRealm":null,"usernameField":"username","passwordField":"password","equals":{},"matches":{},"clone":{}}]}]
[task 2020-02-11T12:25:02.214Z] 12:25:02 INFO - 1581423902190 Marionette DEBUG 2 -> [0,19,"WebDriver:ExecuteScript",{"script":"return Services.logins.getAllLogins().length;","newSandbox":true,"args":[],"filename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"default","line":231}]
[task 2020-02-11T12:25:02.214Z] 12:25:02 INFO - 1581423902200 Marionette DEBUG 2 <- [1,19,null,{"value":2}]
[task 2020-02-11T12:25:02.214Z] 12:25:02 INFO - 1581423902204 Marionette DEBUG 2 -> [0,20,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["about:mozilla"],"filename":"tests/brow ... lve(bookmark ? bookmark.title : ""),\n ex => resolve(ex)\n );","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.214Z] 12:25:02 INFO - 1581423902212 Marionette DEBUG 2 <- [1,20,null,{"value":"Some bookmark from Marionette"}]
[task 2020-02-11T12:25:02.228Z] 12:25:02 INFO - 1581423902215 Marionette DEBUG 2 -> [0,21,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["http://firefox-refresh.marionette-test ... {\n resolve("Unexpected error in fetching page: " + e);\n });","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.263Z] 12:25:02 INFO - 1581423902222 Marionette DEBUG 2 <- [1,21,null,{"value":{"guid":"hPpNMlWwFW0t","url":"http://firefox-refresh.marionette-test.mozilla.org/","frecency":100,"title":"Test visit for Firefox Reset"}}]
[task 2020-02-11T12:25:02.263Z] 12:25:02 INFO - 1581423902224 Marionette DEBUG 2 -> [0,22,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":["some-very-unique-marionette-only-firef ... andleCompletion() {\n resolve(results);\n },\n });","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.263Z] 12:25:02 INFO - 1581423902230 Marionette DEBUG 2 <- [1,22,null,{"value":[{"value":"special-pumpkin-value"}]}]
[task 2020-02-11T12:25:02.263Z] 12:25:02 INFO - 1581423902232 Marionette DEBUG 2 -> [0,23,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... ;\n },\n };\n global.FormHistory.count({}, callbacks);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.263Z] 12:25:02 INFO - 1581423902237 Marionette DEBUG 2 <- [1,23,null,{"value":1}]
[task 2020-02-11T12:25:02.264Z] 12:25:02 INFO - 1581423902241 Marionette DEBUG 2 -> [0,24,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... return global.formAutofillStorage.addresses.getAll()\n }).then(resolve);","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.264Z] 12:25:02 INFO - 1581423902246 Marionette DEBUG 2 <- [1,24,null,{"value":[{"given-name":"John","additional-name":"R.","family-name":"Smith","organization":"World Wide Web Consort ... tel-national":"5195555555","tel-area-code":"519","tel-local":"5555555","tel-local-prefix":"555","tel-local-suffix":"5555"}]}]
[task 2020-02-11T12:25:02.264Z] 12:25:02 INFO - 1581423902248 Marionette DEBUG 2 -> [0,25,"WebDriver:ExecuteScript",{"script":"try {\n let cookies = Services.cookies.getCookiesFromHost(arguments[0], ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:02.264Z] 12:25:02 INFO - 1581423902252 Marionette DEBUG 2 <- [1,25,null,{"value":{"path":"some/cookie/path","name":"somecookie","value":"some cookie value"}}]
[task 2020-02-11T12:25:02.264Z] 12:25:02 INFO - 1581423902255 Marionette DEBUG 2 -> [0,26,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":false,"args":[],"filename":"tests/browser/components/ ... ;\n }).catch(err => {\n resolve(err.toString());\n });","sandbox":"firefox-refresh","line":485}]
[task 2020-02-11T12:25:02.267Z] 12:25:02 INFO - 1581423902264 Marionette DEBUG 2 <- [1,26,null,{"value":{"accountData":{"email":"test@test.com","uid":"uid","keyFetchToken":"top-secret"}}}]
[task 2020-02-11T12:25:02.273Z] 12:25:02 INFO - 1581423902267 Marionette DEBUG 2 -> [0,27,"WebDriver:ExecuteScript",{"script":"return Services.prefs.getStringPref("services.sync.username", null);","newSandbo ... :[],"filename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"default","line":426}]
[task 2020-02-11T12:25:02.273Z] 12:25:02 INFO - 1581423902272 Marionette DEBUG 2 <- [1,27,null,{"value":"test@test.com"}][task 2020-02-11T12:25:02.276Z] 12:25:02 INFO - 1581423902275 Marionette DEBUG 2 -> [0,28,"WebDriver:ExecuteScript",{"script":"// Ensure the current (temporary) profile is in profiles.ini:\n let profD ... ename":"tests/browser/components/migration/tests/marionette/test_refresh_firefox.py","sandbox":"firefox-refresh","line":478}]
[task 2020-02-11T12:25:02.299Z] 12:25:02 INFO - 1581423902282 Marionette DEBUG 2 <- [1,28,null,{"value":null}]
[task 2020-02-11T12:25:02.299Z] 12:25:02 INFO - 1581423902284 Marionette DEBUG 2 -> [0,29,"Marionette:GetContext",{}]
[task 2020-02-11T12:25:02.299Z] 12:25:02 INFO - 1581423902285 Marionette DEBUG 2 <- [1,29,null,{"value":"chrome"}]
[task 2020-02-11T12:25:02.299Z] 12:25:02 INFO - 1581423902287 Marionette DEBUG 2 -> [0,30,"Marionette:AcceptConnections",{"value":false}]
[task 2020-02-11T12:25:02.299Z] 12:25:02 INFO - 1581423902287 Marionette INFO Stopped listening on port 2828
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902287 Marionette DEBUG 2 <- [1,30,null,{"value":null}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902289 Marionette DEBUG 2 -> [0,31,"Marionette:GetContext",{}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902290 Marionette DEBUG 2 <- [1,31,null,{"value":"chrome"}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902291 Marionette DEBUG 2 -> [0,32,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902292 Marionette DEBUG 2 <- [1,32,null,{"value":null}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902293 Marionette DEBUG 2 -> [0,33,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n ... args":[],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":901}]
[task 2020-02-11T12:25:02.300Z] 12:25:02 INFO - 1581423902298 Marionette DEBUG 2 <- [1,33,null,{"value":false}]
[task 2020-02-11T12:25:02.304Z] 12:25:02 INFO - 1581423902300 Marionette DEBUG 2 -> [0,34,"Marionette:SetContext",{"value":"chrome"}]
[task 2020-02-11T12:25:02.304Z] 12:25:02 INFO - 1581423902301 Marionette DEBUG 2 <- [1,34,null,{"value":null}]
[task 2020-02-11T12:25:02.304Z] 12:25:02 INFO - 1581423902302 Marionette DEBUG 2 -> [0,35,"Marionette:Quit",{"flags":["eForceQuit","eRestart"]}]
[task 2020-02-11T12:25:02.459Z] 12:25:02 INFO - 1581423902435 Marionette TRACE Received observer notification quit-application
[task 2020-02-11T12:25:02.459Z] 12:25:02 INFO - 1581423902443 Marionette DEBUG 2 <- [1,35,null,{"cause":"restart"}]
[task 2020-02-11T12:25:02.511Z] 12:25:02 INFO - [Parent 2027, Main Thread] WARNING: '!inner', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActorService.cpp, line 182
[task 2020-02-11T12:25:02.511Z] 12:25:02 INFO - [Parent 2027, Main Thread] WARNING: '!inner', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActorService.cpp, line 182
[task 2020-02-11T12:25:02.511Z] 12:25:02 INFO - [Child 2028, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2020-02-11T12:25:02.512Z] 12:25:02 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-11T12:25:02.512Z] 12:25:02 INFO - [Parent 2027, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 279
[task 2020-02-11T12:25:02.513Z] 12:25:02 INFO - 1581423902510 Marionette DEBUG Closed connection 2
[task 2020-02-11T12:25:02.758Z] 12:25:02 INFO - [Child 2028, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-11T12:25:02.758Z] 12:25:02 INFO - nsStringStats
[task 2020-02-11T12:25:02.758Z] 12:25:02 INFO - => mAllocCount: 22495
[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => mReallocCount: 0
[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => mFreeCount: 22495
[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => mShareCount: 12334
[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => mAdoptCount: 432[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => mAdoptFreeCount: 468
[task 2020-02-11T12:25:02.759Z] 12:25:02 INFO - => Process ID: 2028, Thread ID: 4519581120
[task 2020-02-11T12:25:02.848Z] 12:25:02 INFO - [2020-02-11T12:25:02Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-02-11T12:25:02.848Z] 12:25:02 INFO - [2020-02-11T12:25:02Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-02-11T12:25:03.717Z] 12:25:03 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/cache/nsCacheService.cpp, line 855
[task 2020-02-11T12:25:03.718Z] 12:25:03 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/cache/nsCacheService.cpp, line 818
[task 2020-02-11T12:25:03.718Z] 12:25:03 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111: file /builds/worker/workspace/build/src/netwerk/cache/nsApplicationCacheService.cpp, line 161
[task 2020-02-11T12:25:04.058Z] 12:25:04 INFO - [Child 2030, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2020-02-11T12:25:04.058Z] 12:25:04 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-11T12:25:04.087Z] 12:25:04 INFO - nsStringStats
[task 2020-02-11T12:25:04.087Z] 12:25:04 INFO - => mAllocCount: 4907
[task 2020-02-11T12:25:04.087Z] 12:25:04 INFO - => mReallocCount: 0
[task 2020-02-11T12:25:04.087Z] 12:25:04 INFO - => mFreeCount: 4907
[task 2020-02-11T12:25:04.087Z] 12:25:04 INFO - => mShareCount: 3516
[task 2020-02-11T12:25:04.088Z] 12:25:04 INFO - => mAdoptCount: 73
[task 2020-02-11T12:25:04.088Z] 12:25:04 INFO - => mAdoptFreeCount: 73
[task 2020-02-11T12:25:04.088Z] 12:25:04 INFO - => Process ID: 2030, Thread ID: 4657092032
[task 2020-02-11T12:25:04.161Z] 12:25:04 INFO - [Parent 2027, Main Thread] WARNING: No DBState! Profile already closed?: file /builds/worker/workspace/build/src/netwerk/cookie/nsCookieService.cpp, line 2526
[task 2020-02-11T12:25:04.161Z] 12:25:04 INFO - [Parent 2027, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/cookie/nsCookieService.cpp, line 2586
[task 2020-02-11T12:25:04.161Z] 12:25:04 INFO - JavaScript error: resource:///modules/Discovery.jsm, line 104: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICookieManager.remove]
[task 2020-02-11T12:25:04.293Z] 12:25:04 INFO - 1581423904250 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-02-11T12:25:04.293Z] 12:25:04 INFO - 1581423904250 Marionette DEBUG Marionette stopped listening
[task 2020-02-11T12:25:04.321Z] 12:25:04 INFO - [Parent 2027, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2020-02-11T12:25:04.659Z] 12:25:04 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 228
[task 2020-02-11T12:25:04.659Z] 12:25:04 INFO - [Parent 2027, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 255
[task 2020-02-11T12:25:05.658Z] 12:25:05 INFO - [Parent 2027, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-11T12:25:05.708Z] 12:25:05 INFO - nsStringStats
[task 2020-02-11T12:25:05.708Z] 12:25:05 INFO - => mAllocCount: 126238
[task 2020-02-11T12:25:05.708Z] 12:25:05 INFO - => mReallocCount: 0
[task 2020-02-11T12:25:05.708Z] 12:25:05 INFO - => mFreeCount: 126238
[task 2020-02-11T12:25:05.709Z] 12:25:05 INFO - => mShareCount: 101832
[task 2020-02-11T12:25:05.709Z] 12:25:05 INFO - => mAdoptCount: 2493
[task 2020-02-11T12:25:05.709Z] 12:25:05 INFO - => mAdoptFreeCount: 2563
[task 2020-02-11T12:25:05.709Z] 12:25:05 INFO - => Process ID: 2027, Thread ID: 4565235136
[task 2020-02-11T12:25:05.761Z] 12:25:05 INFO - [2053, Unnamed thread 10e4412e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:05.761Z] 12:25:05 INFO - [2053, Unnamed thread 10e4412e0] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:06.108Z] 12:25:06 INFO - [2053, Main Thread] WARNING: dependent window created without a parent: file /builds/worker/workspace/build/src/toolkit/components/startup/nsAppStartup.cpp, line 629
[task 2020-02-11T12:25:06.509Z] 12:25:06 INFO - [2053, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/workspace/build/src/layout/base/nsDocumentViewer.cpp, line 3135
[task 2020-02-11T12:25:06.641Z] 12:25:06 INFO - [2053, Main Thread] WARNING: Workers don't support the 'mem.mem.' preference!: file /builds/worker/workspace/build/src/dom/workers/RuntimeService.cpp, line 536
[task 2020-02-11T12:25:12.078Z] 12:25:12 INFO - [2053, Main Thread] WARNING: NS_ENSURE_TRUE(root) failed: file /builds/worker/workspace/build/src/layout/base/nsDocumentViewer.cpp, line 3135[task 2020-02-11T12:25:12.403Z] 12:25:12 INFO - [2054, Unnamed thread 106e1e080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:12.403Z] 12:25:12 INFO - [2054, Unnamed thread 106e1e080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:12.404Z] 12:25:12 INFO - [2054, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:12.404Z] 12:25:12 INFO - [2054, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:12.941Z] 12:25:12 INFO - 1581423912931 addons.webextension.doh-rollout@mozilla.org WARN Loading extension 'doh-rollout@mozilla.org': Reading manifest: Invalid extension permission: networkStatus
[task 2020-02-11T12:25:13.852Z] 12:25:13 INFO - 1581423913832 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-02-11T12:25:13.852Z] 12:25:13 INFO - 1581423913832 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2020-02-11T12:25:13.852Z] 12:25:13 INFO - 1581423913833 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-02-11T12:25:13.852Z] 12:25:13 INFO - 1581423913833 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2020-02-11T12:25:13.952Z] 12:25:13 INFO - [2053, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/workspace/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1570
[task 2020-02-11T12:25:13.974Z] 12:25:13 INFO - [2053, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/presentation/provider/MulticastDNSDeviceProvider.cpp, line 124
[task 2020-02-11T12:25:13.974Z] 12:25:13 INFO - [2053, Main Thread] WARNING: 'NS_FAILED(rv = Init())', file /builds/worker/workspace/build/src/dom/presentation/provider/MulticastDNSDeviceProvider.cpp, line 568
[task 2020-02-11T12:25:14.040Z] 12:25:14 INFO - 1581423914006 Marionette TRACE Received observer notification command-line-startup
[task 2020-02-11T12:25:14.121Z] 12:25:14 INFO - 1581423914112 Marionette TRACE Received observer notification toplevel-window-ready
[task 2020-02-11T12:25:14.983Z] 12:25:14 INFO - [2055, Unnamed thread 10a550080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:14.983Z] 12:25:14 INFO - [2055, Unnamed thread 10a550080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:14.985Z] 12:25:14 INFO - [2055, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:14.985Z] 12:25:14 INFO - [2055, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:15.049Z] 12:25:15 INFO - [Child 2055, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-02-11T12:25:15.185Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Failed to get base domain!: file /builds/worker/workspace/build/src/ipc/glue/BackgroundUtils.cpp, line 351
[task 2020-02-11T12:25:15.315Z] 12:25:15 INFO - [Child 2055, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2020-02-11T12:25:15.366Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1402
[task 2020-02-11T12:25:15.366Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: '!aWin', file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 1402
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [2056, Unnamed thread 107550080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [2056, Unnamed thread 107550080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [2056, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [2056, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-02-11T12:25:15.458Z] 12:25:15 INFO - [Parent 2053, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/workspace/build/src/widget/PuppetWidget.cpp, line 1095
[task 2020-02-11T12:25:15.468Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-02-11T12:25:15.734Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2020-02-11T12:25:15.734Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2748
[task 2020-02-11T12:25:15.734Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2748
[task 2020-02-11T12:25:15.734Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2748
[task 2020-02-11T12:25:15.735Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2748[task 2020-02-11T12:25:15.735Z] 12:25:15 INFO - [Child 2056, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/workspace/build/src/dom/ipc/BrowserChild.cpp, line 2748
[task 2020-02-11T12:25:16.061Z] 12:25:16 INFO - [Parent 2053, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/netwerk/cache/nsCacheService.cpp, line 154
[task 2020-02-11T12:25:16.102Z] 12:25:16 INFO - [2057, Unnamed thread 113750080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:16.103Z] 12:25:16 INFO - [2057, Unnamed thread 113750080] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:16.103Z] 12:25:16 INFO - [2057, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:16.103Z] 12:25:16 INFO - [2057, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/workspace/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2020-02-11T12:25:16.134Z] 12:25:16 INFO - [Child 2057, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-02-11T12:25:16.375Z] 12:25:16 INFO - [Child 2057, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2020-02-11T12:25:16.517Z] 12:25:16 INFO - [Child 2056, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:16.575Z] 12:25:16 INFO - [Child 2055, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2020-02-11T12:25:16.576Z] 12:25:16 INFO - [Child 2056, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:16.577Z] 12:25:16 INFO - [Child 2056, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:16.577Z] 12:25:16 INFO - [Child 2056, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:16.577Z] 12:25:16 INFO - [Child 2056, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:16.921Z] 12:25:16 INFO - [Child 2057, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/workspace/build/src/widget/cocoa/nsAppShell.mm, line 725
[task 2020-02-11T12:25:16.921Z] 12:25:16 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-02-11T12:25:16.921Z] 12:25:16 INFO - [Child 2057, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2020-02-11T12:25:16.921Z] 12:25:16 INFO - [2020-02-11T12:25:16Z WARN rkv::backend::impl_safe::environment] Ignoring map_size=16777216
[task 2020-02-11T12:25:16.971Z] 12:25:16 INFO - [Child 2057, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-02-11T12:25:17.001Z] 12:25:17 INFO - nsStringStats
[task 2020-02-11T12:25:17.001Z] 12:25:17 INFO - => mAllocCount: 5430
[task 2020-02-11T12:25:17.001Z] 12:25:17 INFO - => mReallocCount: 0
[task 2020-02-11T12:25:17.001Z] 12:25:17 INFO - => mFreeCount: 5430
[task 2020-02-11T12:25:17.001Z] 12:25:17 INFO - => mShareCount: 4200
[task 2020-02-11T12:25:17.002Z] 12:25:17 INFO - => mAdoptCount: 203
[task 2020-02-11T12:25:17.002Z] 12:25:17 INFO - => mAdoptFreeCount: 203
[task 2020-02-11T12:25:17.002Z] 12:25:17 INFO - => Process ID: 2057, Thread ID: 4682397120
[task 2020-02-11T12:25:17.083Z] 12:25:17 INFO - [Child 2055, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 64
[task 2020-02-11T12:25:17.830Z] 12:25:17 INFO - Exiting due to channel error.
[task 2020-02-11T12:25:17.830Z] 12:25:17 INFO - Exiting due to channel error.
[task 2020-02-11T12:25:17.831Z] 12:25:17 INFO - [Socket 2054, Main Thread] WARNING: Shutting down Socket process early due to a crash!: file /builds/worker/workspace/build/src/netwerk/ipc/SocketProcessChild.cpp, line 110
[task 2020-02-11T12:26:12.550Z] 12:26:12 INFO - TEST-UNEXPECTED-ERROR | browser/components/migration/tests/marionette/test_refresh_firefox.py TestFirefoxRefresh.testResetEverything | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: Process unexpectedly quit without restarting (exit code: 0))

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=301208003&repo=autoland&lineNumber=56006

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

This seems to happen more often with my patch from bug 1634650, but those changes should have no effect on this test.
Does anyone have ideas about why this test may fail here with "message": "InvalidSessionIdException: Please start a session"?

Flags: needinfo?(hskupin)

Note that this bug is about an exit code of 0 whilst yours show an exit code of 11. As Gijs mentioned this is a crash. Interesting that no minidump files are found or processed.

The test restarts Firefox a couple of times. It would be good if someone could check which specific restart caused this crash. With that I might be able to give some hints to further investigate this problem.

Flags: needinfo?(hskupin)

Note that exit code 0 could also mean a content process crashed, and Firefox shutdown itself. But I cannot find such a line in the log.

Also only happens on linux1804 similar to bug 1636706. Note that on the other bug we will temporarily disable the test for this platform.

Depends on: 1636706

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 6 years ago → 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.