Closed
Bug 1742675
Opened 4 years ago
Closed 4 years ago
Intermittent testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | AssertionError: Items in the first set but not the second:
Categories
(Testing :: Firefox UI Tests, defect, P5)
Testing
Firefox UI Tests
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1718697
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: assertion, intermittent-failure)
Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=358997799&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RMhmEWdjT6O5Reesv_cF6Q/runs/0/artifacts/public/logs/live_backing.log
task 2021-11-23T18:52:34.939Z] 18:52:34 INFO - TEST-START | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart
[task 2021-11-23T18:52:34.939Z] 18:52:34 INFO - 1637693554939 Marionette DEBUG Accepted connection 2 from 127.0.0.1:49366
[task 2021-11-23T18:52:34.940Z] 18:52:34 INFO - 1637693554939 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-23T18:52:34.942Z] 18:52:34 INFO - 1637693554941 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"3788fe9d-daab-4d60-8fe1-9d862694919e","capabilities":{"browserName":"firefox","browserVersion":"95.0" ... 0.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-11-23T18:52:34.942Z] 18:52:34 INFO - 1637693554942 Marionette DEBUG 2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2021-11-23T18:52:34.942Z] 18:52:34 INFO - 1637693554942 Marionette DEBUG 2 <- [1,2,null,{"value":null}]
[task 2021-11-23T18:52:34.943Z] 18:52:34 INFO - 1637693554942 Marionette DEBUG 2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2021-11-23T18:52:34.943Z] 18:52:34 INFO - 1637693554943 Marionette DEBUG 2 <- [1,3,null,{"value":null}]
[task 2021-11-23T18:52:34.943Z] 18:52:34 INFO - 1637693554943 Marionette DEBUG 2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2021-11-23T18:52:34.944Z] 18:52:34 INFO - 1637693554943 Marionette DEBUG 2 <- [1,4,null,{"value":null}]
[task 2021-11-23T18:52:34.945Z] 18:52:34 INFO - 1637693554944 Marionette DEBUG 2 -> [0,5,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:34.945Z] 18:52:34 INFO - 1637693554944 Marionette DEBUG 2 <- [1,5,null,{"value":"content"}]
[task 2021-11-23T18:52:34.946Z] 18:52:34 INFO - 1637693554946 Marionette DEBUG 2 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:34.946Z] 18:52:34 INFO - 1637693554946 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2021-11-23T18:52:34.947Z] 18:52:34 INFO - 1637693554946 Marionette DEBUG 2 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2021-11-23T18:52:34.947Z] 18:52:34 INFO - 1637693554946 Marionette DEBUG 2 <- [1,7,null,{"value":"3ec1df47-eedc-4473-b778-ade989c3f4f0"}]
[task 2021-11-23T18:52:34.948Z] 18:52:34 INFO - 1637693554947 Marionette DEBUG 2 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-23T18:52:34.948Z] 18:52:34 INFO - 1637693554948 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2021-11-23T18:52:34.950Z] 18:52:34 INFO - 1637693554950 Marionette DEBUG 2 -> [0,9,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:34.951Z] 18:52:34 INFO - 1637693554950 Marionette DEBUG 2 <- [1,9,null,{"value":"content"}]
[task 2021-11-23T18:52:34.951Z] 18:52:34 INFO - 1637693554951 Marionette DEBUG 2 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:34.952Z] 18:52:34 INFO - 1637693554951 Marionette DEBUG 2 <- [1,10,null,{"value":null}]
[task 2021-11-23T18:52:34.952Z] 18:52:34 INFO - 1637693554952 Marionette DEBUG 2 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2021-11-23T18:52:34.953Z] 18:52:34 INFO - 1637693554952 Marionette DEBUG 2 <- [1,11,null,["3ec1df47-eedc-4473-b778-ade989c3f4f0"]]
[task 2021-11-23T18:52:34.953Z] 18:52:34 INFO - 1637693554953 Marionette DEBUG 2 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-23T18:52:34.953Z] 18:52:34 INFO - 1637693554953 Marionette DEBUG 2 <- [1,12,null,{"value":null}]
[task 2021-11-23T18:52:34.954Z] 18:52:34 INFO - 1637693554953 Marionette DEBUG 2 -> [0,13,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:34.954Z] 18:52:34 INFO - 1637693554953 Marionette DEBUG 2 <- [1,13,null,{"value":"content"}]
[task 2021-11-23T18:52:43.671Z] 18:52:43 INFO - 1637693563670 Marionette DEBUG 1 -> [0,34,"WebDriver:ExecuteScript",{"script":"return gBrowser.tabs.map(tab => {\n return tab.linkedBrowser.cu ... andbox":"default","line":165,"filename":"tests/testing/firefox-ui/tests/functional/sessionstore/session_store_test_case.py"}]
[task 2021-11-23T18:52:43.671Z] 18:52:43 INFO - 1637693563671 Marionette TRACE [36] MarionetteCommands actor created for window id 12
[task 2021-11-23T18:52:43.672Z] 18:52:43 INFO - 1637693563672 Marionette DEBUG 1 <- [1,34,null,{"value":["http://127.0.0.1:49257/layout/mozilla_organizations.html","http://127.0.0.1:49257/layout/mozilla_community.html"]}]
[task 2021-11-23T18:52:43.673Z] 18:52:43 INFO - 1637693563672 Marionette DEBUG 1 -> [0,35,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:43.673Z] 18:52:43 INFO - 1637693563672 Marionette DEBUG 1 <- [1,35,null,{"value":null}]
[task 2021-11-23T18:52:43.674Z] 18:52:43 INFO - 1637693563673 Marionette DEBUG 1 -> [0,36,"WebDriver:SwitchToWindow",{"handle":"edd4d9b0-1231-4508-aa53-2c35938dc0c8","focus":true}]
[task 2021-11-23T18:52:43.674Z] 18:52:43 INFO - 1637693563674 Marionette DEBUG 1 <- [1,36,null,{"value":null}]
[task 2021-11-23T18:52:43.675Z] 18:52:43 INFO - 1637693563675 Marionette DEBUG 1 -> [0,37,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:43.675Z] 18:52:43 INFO - 1637693563675 Marionette DEBUG 1 <- [1,37,null,{"value":"chrome"}]
[task 2021-11-23T18:52:43.676Z] 18:52:43 INFO - 1637693563676 Marionette DEBUG 1 -> [0,38,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:43.676Z] 18:52:43 INFO - 1637693563676 Marionette DEBUG 1 <- [1,38,null,{"value":null}]
[task 2021-11-23T18:52:43.677Z] 18:52:43 INFO - 1637693563676 Marionette DEBUG 1 -> [0,39,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2021-11-23T18:52:43.752Z] 18:52:43 INFO - 1637693563752 Marionette DEBUG 1 <- [1,39,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPNCAYAAADWQsenAAAgAElEQVR4nOy9aVRb96Hu3Y93ret8s1cS37Oa077tyu3blfOe3pyT1bxv ... QgAAAAAIwJQAAAAAAYE4AAAAAAMCYAAQAAAGBMAAIAAADAmAAEAAAAgDEBCAAAAABjAhAAAAAAxgQgAAAAAIwJQAAAAAAYC1NRN7qstq6DAAAAAElFTkSuQmCC"}]
[task 2021-11-23T18:52:43.758Z] 18:52:43 INFO - 1637693563757 Marionette DEBUG 1 -> [0,40,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:43.758Z] 18:52:43 INFO - 1637693563757 Marionette DEBUG 1 <- [1,40,null,{"value":null}]
[task 2021-11-23T18:52:43.758Z] 18:52:43 INFO - 1637693563758 Marionette DEBUG 1 -> [0,41,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:43.758Z] 18:52:43 INFO - 1637693563758 Marionette DEBUG 1 <- [1,41,null,{"value":"chrome"}]
[task 2021-11-23T18:52:43.759Z] 18:52:43 INFO - 1637693563759 Marionette DEBUG 1 -> [0,42,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-23T18:52:43.759Z] 18:52:43 INFO - 1637693563759 Marionette DEBUG 1 <- [1,42,null,{"value":null}]
[task 2021-11-23T18:52:43.760Z] 18:52:43 INFO - 1637693563760 Marionette DEBUG 1 -> [0,43,"WebDriver:GetPageSource",{}]
[task 2021-11-23T18:52:43.762Z] 18:52:43 INFO - 1637693563762 Marionette TRACE [41] MarionetteCommands actor created for window id 6442450945
[task 2021-11-23T18:52:43.765Z] 18:52:43 INFO - 1637693563765 Marionette DEBUG 1 <- [1,43,null,{"value":"<html dir=\"ltr\" lang=\"en\"><head>\n <title>Mozilla Community</title>\n <link rel=\"shortcut ico ... look at the other information referenced\n throughout this site.\n </p>\n </div>\n\n\n</body></html>"}]
[task 2021-11-23T18:52:43.766Z] 18:52:43 INFO - 1637693563766 Marionette DEBUG 1 -> [0,44,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:43.766Z] 18:52:43 INFO - 1637693563766 Marionette DEBUG 1 <- [1,44,null,{"value":null}]
[task 2021-11-23T18:52:43.768Z] 18:52:43 INFO - Child process with id "1017" has been marked as detached because it is no longer in the managed process group. Keeping reference to the process id "1032" which is the new child process.
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - TEST-UNEXPECTED-FAIL | testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py TestSessionStoreDisabled.test_restore_with_restart | AssertionError: Items in the first set but not the second:
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - ('about:blank',)
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - Items in the second set but not the first:
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - ('http://127.0.0.1:49257/layout/mozilla.html',) : Non private browsing windows should have
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - been restored. Expected {('http://127.0.0.1:49257/layout/mozilla.html',), ('http://127.0.0.1:49257/layout/mozilla_governance.html', 'http://127.0.0.1:49257/layout/mozilla_grants.html'), ('http://127.0.0.1:49257/layout/mozilla_organizations.html', 'http://127.0.0.1:49257/layout/mozilla_community.html')}, got {('http://127.0.0.1:49257/layout/mozilla_governance.html', 'http://127.0.0.1:49257/layout/mozilla_grants.html'), ('about:blank',), ('http://127.0.0.1:49257/layout/mozilla_organizations.html', 'http://127.0.0.1:49257/layout/mozilla_community.html')}.
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - Traceback (most recent call last):
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - File "/opt/worker/tasks/task_163769346281916/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - testMethod()
[task 2021-11-23T18:52:44.005Z] 18:52:44 INFO - File "/opt/worker/tasks/task_163769346281916/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/sessionstore/test_restore_windows_after_restart_and_quit.py", line 110, in test_restore_with_restart
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - self.test_windows, current_windows_set
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - TEST-INFO took 8828ms
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - 1637693563950 Marionette DEBUG 1 -> [0,45,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - 1637693563950 Marionette DEBUG 1 <- [1,45,null,{"value":"chrome"}]
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - 1637693563951 Marionette DEBUG 1 -> [0,46,"WebDriver:DeleteSession",{}]
[task 2021-11-23T18:52:44.006Z] 18:52:44 INFO - 1637693563952 Marionette DEBUG 1 <- [1,46,null,{"value":null}]
[task 2021-11-23T18:52:44.007Z] 18:52:44 INFO - Application command: /opt/worker/tasks/task_163769346281916/build/application/Firefox.app/Contents/MacOS/firefox -no-remote -marionette -foreground -profile /opt/worker/tasks/task_163769346281916/build/tmpzneayvlk.mozrunner
[task 2021-11-23T18:52:44.311Z] 18:52:44 INFO - 1637693564311 Marionette INFO Marionette enabled
[task 2021-11-23T18:52:44.365Z] 18:52:44 INFO - 1637693564364 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-11-23T18:52:45.059Z] 18:52:45 INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /opt/worker/tasks/task_163769346281916/build/tmpzneayvlk.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-11-23T18:52:45.535Z] 18:52:45 INFO - console.error: BackgroundUpdate:
[task 2021-11-23T18:52:45.536Z] 18:52:45 INFO - _reasonsToNotScheduleUpdates: Failed to check for Maintenance Service Registry Key: [Exception... "Component returned failure code: 0x80004001 (NS_ERROR_NOT_IMPLEMENTED) [nsIUpdateProcessor.getServiceRegKeyExists]" nsresult: "0x80004001 (NS_ERROR_NOT_IMPLEMENTED)" location: "JS frame :: resource://gre/modules/BackgroundUpdate.jsm :: _reasonsToNotScheduleUpdates :: line 243" data: no]
[task 2021-11-23T18:52:45.571Z] 18:52:45 INFO - 1637693565570 Marionette TRACE Received observer notification marionette-startup-requested
[task 2021-11-23T18:52:45.571Z] 18:52:45 INFO - 1637693565570 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2021-11-23T18:52:45.577Z] 18:52:45 INFO - 1637693565577 Marionette TRACE All scripts recorded.
[task 2021-11-23T18:52:45.579Z] 18:52:45 INFO - 1637693565578 Marionette INFO Listening on port 2828
[task 2021-11-23T18:52:45.579Z] 18:52:45 INFO - 1637693565579 Marionette DEBUG Marionette is listening
[task 2021-11-23T18:52:45.655Z] 18:52:45 INFO - 1637693565655 Marionette DEBUG Accepted connection 0 from 127.0.0.1:49486
[task 2021-11-23T18:52:45.657Z] 18:52:45 INFO - 1637693565657 Marionette DEBUG Closed connection 0
[task 2021-11-23T18:52:45.658Z] 18:52:45 INFO - 1637693565657 Marionette DEBUG Accepted connection 1 from 127.0.0.1:49487
[task 2021-11-23T18:52:45.661Z] 18:52:45 INFO - 1637693565660 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-23T18:52:45.668Z] 18:52:45 INFO - 1637693565667 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"02df6da7-547d-454b-b455-117882601b7e","capabilities":{"browserName":"firefox","browserVersion":"95.0" ... k.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-11-23T18:52:45.670Z] 18:52:45 INFO - 1637693565669 Marionette DEBUG 1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:45.670Z] 18:52:45 INFO - 1637693565669 Marionette DEBUG 1 <- [1,2,null,{"value":null}]
[task 2021-11-23T18:52:45.671Z] 18:52:45 INFO - 1637693565670 Marionette DEBUG 1 -> [0,3,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:45.671Z] 18:52:45 INFO - 1637693565670 Marionette DEBUG 1 <- [1,3,null,{"value":"chrome"}]
[task 2021-11-23T18:52:45.671Z] 18:52:45 INFO - 1637693565671 Marionette DEBUG 1 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:45.672Z] 18:52:45 INFO - 1637693565671 Marionette DEBUG 1 <- [1,4,null,{"value":null}]
[task 2021-11-23T18:52:45.672Z] 18:52:45 INFO - 1637693565672 Marionette DEBUG 1 -> [0,5,"WebDriver:GetWindowHandles",{}]
[task 2021-11-23T18:52:45.673Z] 18:52:45 INFO - 1637693565672 Marionette DEBUG 1 <- [1,5,null,["65177671-6cc2-47c9-a274-5ac55a34deda"]]
[task 2021-11-23T18:52:45.673Z] 18:52:45 INFO - 1637693565673 Marionette DEBUG 1 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:45.673Z] 18:52:45 INFO - 1637693565673 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2021-11-23T18:52:45.674Z] 18:52:45 INFO - 1637693565673 Marionette DEBUG 1 -> [0,7,"Marionette:GetContext",{}]
[task 2021-11-23T18:52:45.674Z] 18:52:45 INFO - 1637693565673 Marionette DEBUG 1 <- [1,7,null,{"value":"chrome"}]
[task 2021-11-23T18:52:45.675Z] 18:52:45 INFO - 1637693565674 Marionette DEBUG 1 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-23T18:52:45.675Z] 18:52:45 INFO - 1637693565674 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2021-11-23T18:52:45.676Z] 18:52:45 INFO - 1637693565675 Marionette DEBUG 1 -> [0,9,"WebDriver:GetWindowHandles",{}]
[task 2021-11-23T18:52:45.676Z] 18:52:45 INFO - 1637693565675 Marionette DEBUG 1 <- [1,9,null,["e8e8ef97-8fc1-4bdc-82e2-d7c4de235e19"]]
[task 2021-11-23T18:52:45.677Z] 18:52:45 INFO - 1637693565676 Marionette DEBUG 1 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-23T18:52:45.677Z] 18:52:45 INFO - 1637693565677 Marionette DEBUG 1 <- [1,10,null,{"value":null}]
[task 2021-11-23T18:52:45.678Z] 18:52:45 INFO - 1637693565677 Marionette DEBUG 1 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2021-11-23T18:52:45.680Z] 18:52:45 INFO - 1637693565679 Marionette DEBUG 1 <- [1,11,null,{"value":null}]
[task 2021-11-23T18:52:45.680Z] 18:52:45 INFO - 1637693565680 Marionette DEBUG Closed connection 1
|
||
Updated•4 years ago
|
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•