Closed Bug 1468109 Opened 7 years ago Closed 7 years ago

Intermittent testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestSessionStoreWindowsShutdown.test_with_variety | MarionetteException: Please start a session

Categories

(Testing :: Firefox UI Tests, defect, P5)

Product:
Testing
Component:
Firefox UI Tests
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: aiakab [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=182514982&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/ed30JKfpTzuV6zFV6sXPaQ/runs/0/artifacts/public/logs/live_backing.log 13:50:15 INFO - 1528465815306 Marionette TRACE 3 -> [0,154,"WebDriver:SwitchToWindow",{"focus":true,"name":"8589934593"}] 13:50:15 INFO - 1528465815339 Marionette TRACE 3 <- [1,154,null,{"value":null}] 13:50:15 INFO - 1528465815386 Marionette TRACE 3 -> [0,155,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"element-6066-11e4-a52e-4f735466cecf":"3f2c ... eteer\\ui\\browser\\tabbar.py","script":"return arguments[0].linkedBrowser.currentURI.spec;","sandbox":"default","line":248}] 13:50:15 INFO - 1528465815390 Marionette TRACE 3 <- [1,155,null,{"value":"http://127.0.0.1:49826/layout/mozilla_governance.html"}] 13:50:15 INFO - 1528465815427 Marionette TRACE 3 -> [0,156,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"element-6066-11e4-a52e-4f735466cecf":"da6f ... n) {\n return null;\n }\n return win.outerWindowID.toString();","sandbox":"default","line":204}] 13:50:15 INFO - 1528465815431 Marionette TRACE 3 <- [1,156,null,{"value":"4294967297"}] 13:50:15 INFO - 1528465815433 Marionette TRACE 3 -> [0,157,"WebDriver:SwitchToWindow",{"focus":true,"name":"4294967297"}] 13:50:15 INFO - 1528465815472 Marionette TRACE 3 <- [1,157,null,{"value":null}] 13:50:15 INFO - 1528465815492 Marionette TRACE 3 -> [0,158,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"element-6066-11e4-a52e-4f735466cecf":"da6f ... eteer\\ui\\browser\\tabbar.py","script":"return arguments[0].linkedBrowser.currentURI.spec;","sandbox":"default","line":248}] 13:50:15 INFO - 1528465815496 Marionette TRACE 3 <- [1,158,null,{"value":"http://127.0.0.1:49826/layout/mozilla_grants.html"}] 13:50:15 INFO - 1528465815503 Marionette TRACE 3 -> [0,159,"WebDriver:GetChromeWindowHandles",{}] 13:50:15 INFO - 1528465815504 Marionette TRACE 3 <- [1,159,null,["3","10","12"]] 13:50:15 INFO - 1528465815506 Marionette TRACE 3 -> [0,160,"WebDriver:SwitchToWindow",{"focus":true,"name":"12"}] 13:50:15 INFO - 1528465815507 Marionette TRACE 3 <- [1,160,null,{"value":null}] 13:50:15 INFO - 1528465815509 Marionette TRACE 3 -> [0,161,"WebDriver:GetChromeWindowHandle",{}] 13:50:15 INFO - 1528465815510 Marionette TRACE 3 <- [1,161,null,{"value":"12"}] 13:50:15 INFO - 1528465815511 Marionette TRACE 3 -> [0,162,"Marionette:GetContext",{}] 13:50:15 INFO - 1528465815512 Marionette TRACE 3 <- [1,162,null,{"value":"chrome"}] 13:50:15 INFO - 1528465815518 Marionette TRACE 3 -> [0,163,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815519 Marionette TRACE 3 <- [1,163,null,{"value":null}] 13:50:15 INFO - 1528465815521 Marionette TRACE 3 -> [0,164,"WebDriver:GetChromeWindowHandle",{}] 13:50:15 INFO - 1528465815522 Marionette TRACE 3 <- [1,164,null,{"value":"12"}] 13:50:15 INFO - 1528465815523 Marionette TRACE 3 -> [0,165,"Marionette:GetWindowType",{}] 13:50:15 INFO - 1528465815524 Marionette TRACE 3 <- [1,165,null,{"value":"navigator:browser"}] 13:50:15 INFO - 1528465815526 Marionette TRACE 3 -> [0,166,"WebDriver:GetChromeWindowHandles",{}] 13:50:15 INFO - 1528465815527 Marionette TRACE 3 <- [1,166,null,["3","10","12"]] 13:50:15 INFO - 1528465815531 Marionette TRACE 3 -> [0,167,"Marionette:GetContext",{}] 13:50:15 INFO - 1528465815532 Marionette TRACE 3 <- [1,167,null,{"value":"chrome"}] 13:50:15 INFO - 1528465815533 Marionette TRACE 3 -> [0,168,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815534 Marionette TRACE 3 <- [1,168,null,{"value":null}] 13:50:15 INFO - 1528465815536 Marionette TRACE 3 -> [0,169,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["12"],"filename":"Z:\\task_1528464173\\build ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}] 13:50:15 INFO - 1528465815540 Marionette TRACE 3 <- [1,169,null,{"value":true}] 13:50:15 INFO - 1528465815541 Marionette TRACE 3 -> [0,170,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815542 Marionette TRACE 3 <- [1,170,null,{"value":null}] 13:50:15 INFO - 1528465815544 Marionette TRACE 3 -> [0,171,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815544 Marionette TRACE 3 <- [1,171,null,{"value":null}] 13:50:15 INFO - 1528465815549 Marionette TRACE 3 -> [0,172,"WebDriver:GetChromeWindowHandles",{}] 13:50:15 INFO - 1528465815550 Marionette TRACE 3 <- [1,172,null,["3","10","12"]] 13:50:15 INFO - 1528465815552 Marionette TRACE 3 -> [0,173,"WebDriver:SwitchToWindow",{"focus":true,"name":"12"}] 13:50:15 INFO - 1528465815553 Marionette TRACE 3 <- [1,173,null,{"value":null}] 13:50:15 INFO - 1528465815555 Marionette TRACE 3 -> [0,174,"WebDriver:GetChromeWindowHandle",{}] 13:50:15 INFO - 1528465815556 Marionette TRACE 3 <- [1,174,null,{"value":"12"}] 13:50:15 INFO - 1528465815557 Marionette TRACE 3 -> [0,175,"Marionette:GetContext",{}] 13:50:15 INFO - 1528465815558 Marionette TRACE 3 <- [1,175,null,{"value":"chrome"}] 13:50:15 INFO - 1528465815563 Marionette TRACE 3 -> [0,176,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815563 Marionette TRACE 3 <- [1,176,null,{"value":null}] 13:50:15 INFO - 1528465815570 Marionette TRACE 3 -> [0,177,"WebDriver:GetChromeWindowHandle",{}] 13:50:15 INFO - 1528465815570 Marionette TRACE 3 <- [1,177,null,{"value":"12"}] 13:50:15 INFO - 1528465815572 Marionette TRACE 3 -> [0,178,"Marionette:GetWindowType",{}] 13:50:15 INFO - 1528465815572 Marionette TRACE 3 <- [1,178,null,{"value":"navigator:browser"}] 13:50:15 INFO - 1528465815578 Marionette TRACE 3 -> [0,179,"WebDriver:GetChromeWindowHandles",{}] 13:50:15 INFO - 1528465815583 Marionette TRACE 3 <- [1,179,null,["3","10","12"]] 13:50:15 INFO - 1528465815594 Marionette TRACE 3 -> [0,180,"Marionette:GetContext",{}] 13:50:15 INFO - 1528465815595 Marionette TRACE 3 <- [1,180,null,{"value":"chrome"}] 13:50:15 INFO - 1528465815598 Marionette TRACE 3 -> [0,181,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815599 Marionette TRACE 3 <- [1,181,null,{"value":null}] 13:50:15 INFO - 1528465815601 Marionette TRACE 3 -> [0,182,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["12"],"filename":"Z:\\task_1528464173\\build ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":159}] 13:50:15 INFO - 1528465815605 Marionette TRACE 3 <- [1,182,null,{"value":true}] 13:50:15 INFO - 1528465815611 Marionette TRACE 3 -> [0,183,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815611 Marionette TRACE 3 <- [1,183,null,{"value":null}] 13:50:15 INFO - 1528465815613 Marionette TRACE 3 -> [0,184,"Marionette:SetContext",{"value":"chrome"}] 13:50:15 INFO - 1528465815613 Marionette TRACE 3 <- [1,184,null,{"value":null}] 13:50:15 INFO - 1528465815615 Marionette TRACE 3 -> [0,185,"WebDriver:FindElement",{"using":"css selector","value":":root"}] 13:50:15 INFO - 1528465815617 Marionette TRACE 3 <- [1,185,null,{"value":{"chromeelement-9fc5-4b51-a3c8-01716eedeb04":"5dff25a2-1cfa-4b1a-bc3b-d1b3d0dfeb10","ELEMENT":"5dff25a2-1cfa-4b1a-bc3b-d1b3d0dfeb10"}}] 13:50:15 INFO - 1528465815619 Marionette TRACE 3 -> [0,186,"WebDriver:FindElement",{"using":"id","value":"tabbrowser-tabs","element":"5dff25a2-1cfa-4b1a-bc3b-d1b3d0dfeb10"}] 13:50:15 INFO - 1528465815637 Marionette TRACE 3 <- [1,186,null,{"value":{"chromeelement-9fc5-4b51-a3c8-01716eedeb04":"083cba38-1bf1-4304-9f10-bd79faa4aa3b","ELEMENT":"083cba38-1bf1-4304-9f10-bd79faa4aa3b"}}] 13:50:15 INFO - 1528465815643 Marionette TRACE 3 -> [0,187,"WebDriver:FindElements",{"using":"tag name","value":"tab","element":"083cba38-1bf1-4304-9f10-bd79faa4aa3b"}] 13:50:15 INFO - 1528465815646 Marionette TRACE 3 <- [1,187,null,[{"chromeelement-9fc5-4b51-a3c8-01716eedeb04":"0e5f5915-19ab-4d63-9334-94d09078660f","ELEMENT":"0e5f5915-19ab-4d63-9334-94d09078660f"}]] 13:50:15 INFO - 1528465815648 Marionette TRACE 3 -> [0,188,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"element-6066-11e4-a52e-4f735466cecf":"0e5f ... n) {\n return null;\n }\n return win.outerWindowID.toString();","sandbox":"default","line":204}] 13:50:15 INFO - 1528465815652 Marionette TRACE 3 <- [1,188,null,{"value":"6442450945"}] 13:50:15 INFO - 1528465815657 Marionette TRACE 3 -> [0,189,"WebDriver:SwitchToWindow",{"focus":true,"name":"6442450945"}] 13:50:15 INFO - 1528465815659 Marionette TRACE 3 <- [1,189,null,{"value":null}] 13:50:15 INFO - 1528465815661 Marionette TRACE 3 -> [0,190,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"element-6066-11e4-a52e-4f735466cecf":"0e5f ... eteer\\ui\\browser\\tabbar.py","script":"return arguments[0].linkedBrowser.currentURI.spec;","sandbox":"default","line":248}] 13:50:15 INFO - 1528465815664 Marionette TRACE 3 <- [1,190,null,{"value":"http://127.0.0.1:49826/layout/mozilla.html"}] 13:50:15 INFO - 1528465815665 Marionette TRACE 3 -> [0,191,"Marionette:GetContext",{}] 13:50:15 INFO - 1528465815666 Marionette TRACE 3 <- [1,191,null,{"value":"chrome"}] 13:50:15 INFO - 1528465815668 Marionette TRACE 3 -> [0,192,"WebDriver:DeleteSession",{}] 13:50:15 INFO - 1528465815673 Marionette TRACE 3 <- [1,192,null,{"value":null}] 13:50:15 INFO - Application command: Z:\task_1528464173\build\application\firefox\firefox.exe -no-remote -marionette -profile Z:\task_1528464173\build\tmpugp_os.mozrunner 13:50:18 INFO - 1528465818200 Marionette DEBUG Received observer notification profile-after-change 13:50:18 INFO - 1528465818415 Marionette DEBUG Received observer notification command-line-startup 13:50:18 INFO - 1528465818415 Marionette DEBUG Received observer notification nsPref:changed 13:50:18 INFO - 1528465818416 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) 13:54:17 INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\functional\sessionstore\test_restore_windows_after_windows_shutdown.py TestSessionStoreWindowsShutdown.test_with_variety | MarionetteException: Please start a session 13:54:17 INFO - Traceback (most recent call last): 13:54:17 INFO - File "Z:\task_1528464173\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 190, in run 13:54:17 INFO - self.tearDown() 13:54:17 INFO - File "Z:\task_1528464173\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\sessionstore\session_store_test_case.py", line 62, in tearDown 13:54:17 INFO - super(SessionStoreTestCase, self).tearDown() 13:54:17 INFO - File "Z:\task_1528464173\build\venv\lib\site-packages\firefox_puppeteer\mixins.py", line 97, in tearDown 13:54:17 INFO - self.marionette.set_context('chrome') 13:54:17 INFO - File "Z:\task_1528464173\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1414, in set_context 13:54:17 INFO - {"value": context}) 13:54:17 INFO - File "Z:\task_1528464173\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _ 13:54:17 INFO - return func(*args, **kwargs) 13:54:17 INFO - File "Z:\task_1528464173\build\venv\lib\site-packages\marionette_driver\marionette.py", line 729, in _send_message 13:54:17 INFO - raise errors.MarionetteException("Please start a session") 13:54:17 INFO - TEST-INFO took 276559ms 13:54:17 INFO - 13:54:17 INFO - SUMMARY 13:54:17 INFO - ------- 13:54:17 INFO - passed: 58 13:54:17 INFO - failed: 1 13:54:17 INFO - todo: 2 13:54:17 INFO - 13:54:17 INFO - FAILED TESTS 13:54:17 INFO - ------- 13:54:17 INFO - test_restore_windows_after_windows_shutdown.py test_restore_windows_after_windows_shutdown.TestSessionStoreWindowsShutdown.test_with_variety 13:54:17 INFO - SUITE-END | took 577s 13:54:17 ERROR - Return code: 10 13:54:17 WARNING - # TBPL WARNING # 13:54:17 WARNING - setting return code to 1 13:54:17 INFO - Running post-action listener: _package_coverage_data 13:54:17 INFO - Running post-action listener: _resource_record_post_action 13:54:17 INFO - [mozharness: 2018-06-08 13:54:17.681000Z] Finished run-tests step (success) 13:54:17 INFO - [mozharness: 2018-06-08 13:54:17.681000Z] Running uninstall step. 13:54:17 INFO - Running pre-action listener: _resource_record_pre_action 13:54:17 INFO - Running main action method: uninstall 13:54:17 INFO - Getting output from command: ['Z:\\task_1528464173\\build\\venv\\Scripts\\mozuninstall', 'Z:\\task_1528464173\\build\\application'] 13:54:17 INFO - Copy/paste: Z:\task_1528464173\build\venv\Scripts\mozuninstall Z:\task_1528464173\build\application 13:54:18 INFO - Using _rmtree_windows ... 13:54:18 INFO - Running command: del /F /Q "Z:\task_1528464173\tmpfile_stderr" 13:54:18 INFO - Return code: 0 13:54:18 INFO - Using _rmtree_windows ... 13:54:18 INFO - Running command: del /F /Q "Z:\task_1528464173\tmpfile_stdout" 13:54:18 INFO - Return code: 0 13:54:18 INFO - Running post-action listener: _resource_record_post_action 13:54:18 INFO - [mozharness: 2018-06-08 13:54:18.179000Z] Finished uninstall step (success) 13:54:18 INFO - Running post-run listener: _resource_record_post_run 13:54:18 INFO - Total resource usage - Wall time: 598s; CPU: 14.0%; Read bytes: 18447360; Write bytes: 1198675968; Read time: 0; Write time: 16 13:54:18 INFO - TinderboxPrint: CPU usage<br/>14.4% 13:54:18 INFO - TinderboxPrint: I/O read bytes / time<br/>18,447,360 / 0 13:54:18 INFO - TinderboxPrint: I/O write bytes / time<br/>1,198,675,968 / 16 13:54:18 INFO - TinderboxPrint: CPU idle<br/>4,095.5 (85.6%) 13:54:18 INFO - TinderboxPrint: CPU system<br/>189.2 (4.0%) 13:54:18 INFO - TinderboxPrint: CPU user<br/>495.7 (10.4%) 13:54:18 INFO - install - Wall time: 6s; CPU: 16.0%; Read bytes: 49152; Write bytes: 178523648; Read time: 0; Write time: 2 13:54:18 INFO - run-tests - Wall time: 591s; CPU: 14.0%; Read bytes: 17663488; Write bytes: 932940288; Read time: 0; Write time: 13 13:54:18 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:54:18 INFO - Running post-run listener: copy_logs_to_upload_dir
Missing `sessionstore-windows-restored` notification. So it is blocked on bug 1382162.
Depends on: 1382162
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.