Closed Bug 1473323 Opened 7 years ago Closed 7 years ago

Intermittent testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_restart_and_quit.py TestSessionStoreEnabledAllWindows.test_with_variety | IOError: Process killed because the connection to Marionette server is lost

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Product:
Testing
Component:
Marionette Client and Harness
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Filed by: rgurzau [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=186422524&repo=mozilla-central https://queue.taskcluster.net/v1/task/DWqd3A6ZSTOrN7Rfq6UUCQ/runs/0/artifacts/public/logs/live_backing.log 12:45:06 INFO - TEST-START | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_restart_and_quit.py TestSessionStoreEnabledAllWindows.test_with_variety 12:45:06 INFO - 1530708306894 Marionette DEBUG Closed connection 3 12:45:06 INFO - 1530708306897 Marionette DEBUG Accepted connection 4 from 127.0.0.1:50271 12:45:06 INFO - 1530708306899 Marionette TRACE 4 -> [0,1,"WebDriver:NewSession",{}] 12:45:06 INFO - 1530708306901 Marionette DEBUG [6442450945] Frame script loaded 12:45:06 INFO - 1530708306902 Marionette DEBUG [6442450945] Frame script registered 12:45:06 INFO - 1530708306903 Marionette TRACE 4 <- [1,1,null,{"sessionId":"2fce5ae4-f841-4ba8-bdd5-f7c118dec3a8","capabilities":{"browserName":"firefox","browserVersion":"63.0a ... ":"Z:\\task_1530707603\\build\\tmptd1xhl.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] 12:45:06 INFO - 1530708306904 Marionette TRACE 4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}] 12:45:06 INFO - 1530708306905 Marionette TRACE 4 <- [1,2,null,{"value":null}] 12:45:06 INFO - 1530708306906 Marionette TRACE 4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}] 12:45:06 INFO - 1530708306906 Marionette TRACE 4 <- [1,3,null,{"value":null}] 12:45:06 INFO - 1530708306907 Marionette TRACE 4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}] 12:45:06 INFO - 1530708306907 Marionette TRACE 4 <- [1,4,null,{"value":null}] 12:45:06 INFO - 1530708306909 Marionette TRACE 4 -> [0,5,"WebDriver:GetWindowHandles",{}] 12:45:06 INFO - 1530708306909 Marionette TRACE 4 <- [1,5,null,["6442450945"]] 12:45:06 INFO - 1530708306910 Marionette TRACE 4 -> [0,6,"WebDriver:GetWindowHandles",{}] 12:45:06 INFO - 1530708306910 Marionette TRACE 4 <- [1,6,null,["6442450945"]] 12:45:06 INFO - 1530708306911 Marionette TRACE 4 -> [0,7,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306912 Marionette TRACE 4 <- [1,7,null,{"value":null}] 12:45:06 INFO - 1530708306913 Marionette TRACE 4 -> [0,8,"WebDriver:GetChromeWindowHandle",{}] 12:45:06 INFO - 1530708306913 Marionette TRACE 4 <- [1,8,null,{"value":"5"}] 12:45:06 INFO - 1530708306914 Marionette TRACE 4 -> [0,9,"WebDriver:GetChromeWindowHandle",{}] 12:45:06 INFO - 1530708306915 Marionette TRACE 4 <- [1,9,null,{"value":"5"}] 12:45:06 INFO - 1530708306916 Marionette TRACE 4 -> [0,10,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306916 Marionette TRACE 4 <- [1,10,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306917 Marionette TRACE 4 -> [0,11,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306917 Marionette TRACE 4 <- [1,11,null,{"value":null}] 12:45:06 INFO - 1530708306918 Marionette TRACE 4 -> [0,12,"WebDriver:GetChromeWindowHandle",{}] 12:45:06 INFO - 1530708306919 Marionette TRACE 4 <- [1,12,null,{"value":"5"}] 12:45:06 INFO - 1530708306920 Marionette TRACE 4 -> [0,13,"Marionette:GetWindowType",{}] 12:45:06 INFO - 1530708306921 Marionette TRACE 4 <- [1,13,null,{"value":"navigator:browser"}] 12:45:06 INFO - 1530708306922 Marionette TRACE 4 -> [0,14,"WebDriver:GetChromeWindowHandles",{}] 12:45:06 INFO - 1530708306922 Marionette TRACE 4 <- [1,14,null,["5"]] 12:45:06 INFO - 1530708306923 Marionette TRACE 4 -> [0,15,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306923 Marionette TRACE 4 <- [1,15,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306924 Marionette TRACE 4 -> [0,16,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306924 Marionette TRACE 4 <- [1,16,null,{"value":null}] 12:45:06 INFO - 1530708306926 Marionette TRACE 4 -> [0,17,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["5"],"filename":"Z:\\task_1530707603\\build\\ ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}] 12:45:06 INFO - 1530708306927 Marionette TRACE 4 <- [1,17,null,{"value":true}] 12:45:06 INFO - 1530708306928 Marionette TRACE 4 -> [0,18,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306928 Marionette TRACE 4 <- [1,18,null,{"value":null}] 12:45:06 INFO - 1530708306929 Marionette TRACE 4 -> [0,19,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306929 Marionette TRACE 4 <- [1,19,null,{"value":null}] 12:45:06 INFO - 1530708306930 Marionette TRACE 4 -> [0,20,"WebDriver:GetChromeWindowHandles",{}] 12:45:06 INFO - 1530708306930 Marionette TRACE 4 <- [1,20,null,["5"]] 12:45:06 INFO - 1530708306931 Marionette TRACE 4 -> [0,21,"WebDriver:SwitchToWindow",{"focus":true,"name":"5"}] 12:45:06 INFO - 1530708306932 Marionette TRACE 4 <- [1,21,null,{"value":null}] 12:45:06 INFO - 1530708306933 Marionette TRACE 4 -> [0,22,"WebDriver:GetChromeWindowHandle",{}] 12:45:06 INFO - 1530708306933 Marionette TRACE 4 <- [1,22,null,{"value":"5"}] 12:45:06 INFO - 1530708306934 Marionette TRACE 4 -> [0,23,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306934 Marionette TRACE 4 <- [1,23,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306935 Marionette TRACE 4 -> [0,24,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306935 Marionette TRACE 4 <- [1,24,null,{"value":null}] 12:45:06 INFO - 1530708306937 Marionette TRACE 4 -> [0,25,"WebDriver:GetChromeWindowHandle",{}] 12:45:06 INFO - 1530708306937 Marionette TRACE 4 <- [1,25,null,{"value":"5"}] 12:45:06 INFO - 1530708306938 Marionette TRACE 4 -> [0,26,"Marionette:GetWindowType",{}] 12:45:06 INFO - 1530708306938 Marionette TRACE 4 <- [1,26,null,{"value":"navigator:browser"}] 12:45:06 INFO - 1530708306939 Marionette TRACE 4 -> [0,27,"WebDriver:GetChromeWindowHandles",{}] 12:45:06 INFO - 1530708306939 Marionette TRACE 4 <- [1,27,null,["5"]] 12:45:06 INFO - 1530708306940 Marionette TRACE 4 -> [0,28,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306940 Marionette TRACE 4 <- [1,28,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306941 Marionette TRACE 4 -> [0,29,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306941 Marionette TRACE 4 <- [1,29,null,{"value":null}] 12:45:06 INFO - 1530708306944 Marionette TRACE 4 -> [0,30,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["5"],"filename":"Z:\\task_1530707603\\build\\ ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}] 12:45:06 INFO - 1530708306945 Marionette TRACE 4 <- [1,30,null,{"value":true}] 12:45:06 INFO - 1530708306946 Marionette TRACE 4 -> [0,31,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306946 Marionette TRACE 4 <- [1,31,null,{"value":null}] 12:45:06 INFO - 1530708306947 Marionette TRACE 4 -> [0,32,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306947 Marionette TRACE 4 <- [1,32,null,{"value":null}] 12:45:06 INFO - 1530708306948 Marionette TRACE 4 -> [0,33,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306948 Marionette TRACE 4 <- [1,33,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306949 Marionette TRACE 4 -> [0,34,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306949 Marionette TRACE 4 <- [1,34,null,{"value":null}] 12:45:06 INFO - 1530708306951 Marionette TRACE 4 -> [0,35,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"Z:\\task_1530707603\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\ui\\windows.py","script":"window.focus();","sandbox":"default","line":140}] 12:45:06 INFO - 1530708306953 Marionette TRACE 4 <- [1,35,null,{"value":null}] 12:45:06 INFO - 1530708306954 Marionette TRACE 4 -> [0,36,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306954 Marionette TRACE 4 <- [1,36,null,{"value":null}] 12:45:06 INFO - 1530708306955 Marionette TRACE 4 -> [0,37,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306955 Marionette TRACE 4 <- [1,37,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306956 Marionette TRACE 4 -> [0,38,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306956 Marionette TRACE 4 <- [1,38,null,{"value":null}] 12:45:06 INFO - 1530708306957 Marionette TRACE 4 -> [0,39,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"Z:\\task_1530707603\\build\\ven ... .outerWindowID.toString();\n }\n\n return null;","sandbox":"default","line":61}] 12:45:06 INFO - 1530708306959 Marionette TRACE 4 <- [1,39,null,{"value":"5"}] 12:45:06 INFO - 1530708306959 Marionette TRACE 4 -> [0,40,"Marionette:SetContext",{"value":"chrome"}] 12:45:06 INFO - 1530708306960 Marionette TRACE 4 <- [1,40,null,{"value":null}] 12:45:06 INFO - 1530708306961 Marionette TRACE 4 -> [0,41,"Marionette:GetContext",{}] 12:45:06 INFO - 1530708306961 Marionette TRACE 4 <- [1,41,null,{"value":"chrome"}] 12:45:06 INFO - 1530708306962 Marionette TRACE 4 -> [0,42,"Marionette:SetContext",{"value":"content"}] 12:45:06 INFO - 1530708306962 Marionette TRACE 4 <- [1,42,null,{"value":null}] 12:45:06 INFO - 1530708306963 Marionette TRACE 4 -> [0,43,"WebDriver:Navigate",{"url":"about:about"}] 12:45:06 INFO - 1530708306968 Marionette DEBUG [6442450945] Received DOM event beforeunload for about:blank 12:45:06 INFO - 1530708306976 Marionette DEBUG [6442450945] Received DOM event pagehide for about:blank 12:45:06 INFO - 1530708306977 Marionette DEBUG [6442450945] Received DOM event unload for about:blank 12:45:06 INFO - 1530708306978 Marionette DEBUG [6442450945] Received observer notification outer-window-destroyed for 6442450945 12:45:06 INFO - 1530708306994 Marionette DEBUG [19] Frame script loaded 12:45:06 INFO - 1530708306994 Marionette DEBUG [19] Frame script registered 12:45:07 INFO - 1530708307034 Marionette DEBUG [19] Check readyState interactive for about:blank 12:45:07 INFO - 1530708307036 Marionette DEBUG [19] Received DOM event DOMContentLoaded for about:blank 12:45:07 INFO - [Parent 1856, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 12:45:07 INFO - [Child 5632, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 12:45:07 INFO - [Parent 1856, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 188 12:45:07 INFO - 1530708307096 Marionette DEBUG [19] Received DOM event DOMContentLoaded for about:about 12:45:07 INFO - 1530708307109 Marionette DEBUG [19] Received DOM event pageshow for about:about 12:45:07 INFO - 1530708307128 Marionette TRACE 4 <- [1,43,null,{"value":null}] 12:45:07 INFO - 1530708307130 Marionette TRACE 4 -> [0,44,"Marionette:SetContext",{"value":"chrome"}] 12:45:07 INFO - 1530708307130 Marionette TRACE 4 <- [1,44,null,{"value":null}] 12:45:07 INFO - 1530708307133 Marionette TRACE 4 -> [0,45,"Marionette:GetContext",{}] 12:45:07 INFO - 1530708307133 Marionette TRACE 4 <- [1,45,null,{"value":"chrome"}] 12:45:07 INFO - 1530708307134 Marionette TRACE 4 -> [0,46,"Marionette:SetContext",{"value":"chrome"}] 12:45:07 INFO - 1530708307134 Marionette TRACE 4 <- [1,46,null,{"value":null}] 12:45:07 INFO - 1530708307137 Marionette TRACE 4 -> [0,47,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"Z:\\task_1530707603\\build\\ven ... case prefInterface.PREF_INVALID:\n return false;\n }","sandbox":"default","line":1006}] 12:45:07 INFO - 1530708307138 Marionette TRACE 4 <- [1,47,null,{"value":false}] 12:45:07 INFO - 1530708307145 Marionette TRACE 4 -> [0,48,"Marionette:SetContext",{"value":"chrome"}] 12:45:07 INFO - 1530708307145 Marionette TRACE 4 <- [1,48,null,{"value":null}] 12:45:07 INFO - 1530708307146 Marionette TRACE 4 -> [0,49,"Marionette:GetContext",{}] 12:45:07 INFO - 1530708307147 Marionette TRACE 4 <- [1,49,null,{"value":"chrome"}] 12:45:07 INFO - 1530708307148 Marionette TRACE 4 -> [0,50,"WebDriver:DeleteSession",{}] 12:45:07 INFO - 1530708307149 Marionette TRACE 4 <- [1,50,null,{"value":null}] 12:45:07 INFO - 1530708307152 Marionette DEBUG Closed connection 4 12:45:07 INFO - Application command: Z:\task_1530707603\build\application\firefox\firefox.exe -no-remote -marionette -profile Z:\task_1530707603\build\tmpwm0mpa.mozrunner 12:45:07 INFO - 1530708307802 Marionette DEBUG Received observer notification profile-after-change 12:49:08 INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_restart_and_quit.py TestSessionStoreEnabledAllWindows.test_with_variety | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)
Hang during startup of Firefox. Depends on bug 1414495.
Depends on: 1414495
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.