Closed
Bug 1829742
Opened 2 years ago
Closed 2 years ago
Intermittent [tier 2] testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_no_link | marionette_driver.errors.TimeoutException: Navigation timed out after 300000 ms
Categories
(Testing :: Marionette Client and Harness, defect, P5)
Testing
Marionette Client and Harness
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1675603
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=413605234&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/SpKbj8w2SoKmSZrTp-QP0g/runs/0/artifacts/public/logs/live_backing.log
[task 2023-04-24T18:23:30.761Z] 18:23:30 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_no_link
[task 2023-04-24T18:23:30.763Z] 18:23:30 INFO - DEBUG: Starting phase xpcom-will-shutdown
[task 2023-04-24T18:23:30.764Z] 18:23:30 INFO - 1682360610764 Marionette DEBUG Accepted connection 62 from 127.0.0.1:65492
[task 2023-04-24T18:23:30.765Z] 18:23:30 INFO - DEBUG: Spinning the event loop
[task 2023-04-24T18:23:30.765Z] 18:23:30 INFO - DEBUG: Finished phase xpcom-will-shutdown
[task 2023-04-24T18:23:30.765Z] 18:23:30 INFO - 1682360610765 Marionette DEBUG 62 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-04-24T18:23:30.766Z] 18:23:30 INFO - 1682360610766 Marionette DEBUG Waiting for initial application window
[task 2023-04-24T18:23:30.767Z] 18:23:30 INFO - 1682360610767 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=3200 waitForExplicitStart=false
[task 2023-04-24T18:23:30.768Z] 18:23:30 INFO - 1682360610767 RemoteAgent TRACE [9] ProgressListener Setting unload timer (3200ms)
[task 2023-04-24T18:23:30.768Z] 18:23:30 INFO - 1682360610767 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2023-04-24T18:23:30.768Z] 18:23:30 INFO - 1682360610768 RemoteAgent TRACE [9] ProgressListener Stop: has error=false
[task 2023-04-24T18:23:30.772Z] 18:23:30 INFO - 1682360610770 Marionette DEBUG 62 <- [1,1,null,{"sessionId":"03edc67d-2d0f-43c8-8862-d82aa6a26aab","capabilities":{"browserName":"firefox","browserVersion":"114.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20230424163353","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":3624,"moz:profile":"C:\\Users\\task_168235980160176\\AppData\\Local\\Temp\\tmpkhhqcqcq.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-04-24T18:23:30.773Z] 18:23:30 INFO - 1682360610773 Marionette DEBUG 62 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-04-24T18:23:30.773Z] 18:23:30 INFO - 1682360610773 Marionette DEBUG 62 <- [1,2,null,{"value":null}]
[task 2023-04-24T18:23:30.774Z] 18:23:30 INFO - 1682360610774 Marionette DEBUG 62 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-04-24T18:23:30.775Z] 18:23:30 INFO - 1682360610775 Marionette DEBUG 62 <- [1,3,null,{"value":null}]
[task 2023-04-24T18:23:30.776Z] 18:23:30 INFO - 1682360610776 Marionette DEBUG 62 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-04-24T18:23:30.776Z] 18:23:30 INFO - 1682360610776 Marionette DEBUG 62 <- [1,4,null,{"value":null}]
[task 2023-04-24T18:23:30.778Z] 18:23:30 INFO - 1682360610777 Marionette DEBUG 62 -> [0,5,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:30.779Z] 18:23:30 INFO - 1682360610778 Marionette DEBUG 62 <- [1,5,null,{"value":"content"}]
[task 2023-04-24T18:23:30.816Z] 18:23:30 INFO - 1682360610781 Marionette DEBUG 62 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-24T18:23:30.817Z] 18:23:30 INFO - 1682360610781 Marionette DEBUG 62 <- [1,6,null,{"value":null}]
[task 2023-04-24T18:23:30.817Z] 18:23:30 INFO - 1682360610814 Marionette DEBUG 62 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2023-04-24T18:23:30.817Z] 18:23:30 INFO - 1682360610815 Marionette DEBUG 62 <- [1,7,null,{"value":"f1dead92-f1b1-4a49-a968-fe6fdff5cfdd"}]
[task 2023-04-24T18:23:30.818Z] 18:23:30 INFO - 1682360610816 Marionette DEBUG 62 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.818Z] 18:23:30 INFO - 1682360610816 Marionette DEBUG 62 <- [1,8,null,{"value":null}]
[task 2023-04-24T18:23:30.819Z] 18:23:30 INFO - 1682360610817 Marionette DEBUG 62 -> [0,9,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:30.819Z] 18:23:30 INFO - 1682360610818 Marionette DEBUG 62 <- [1,9,null,{"value":"content"}]
[task 2023-04-24T18:23:30.819Z] 18:23:30 INFO - 1682360610819 Marionette DEBUG 62 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-24T18:23:30.820Z] 18:23:30 INFO - 1682360610819 Marionette DEBUG 62 <- [1,10,null,{"value":null}]
[task 2023-04-24T18:23:30.820Z] 18:23:30 INFO - 1682360610820 Marionette DEBUG 62 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2023-04-24T18:23:30.820Z] 18:23:30 INFO - 1682360610820 Marionette DEBUG 62 <- [1,11,null,["f1dead92-f1b1-4a49-a968-fe6fdff5cfdd"]]
[task 2023-04-24T18:23:30.821Z] 18:23:30 INFO - 1682360610821 Marionette DEBUG 62 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.822Z] 18:23:30 INFO - 1682360610822 Marionette DEBUG 62 <- [1,12,null,{"value":null}]
[task 2023-04-24T18:23:30.823Z] 18:23:30 INFO - 1682360610822 Marionette DEBUG 62 -> [0,13,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:30.823Z] 18:23:30 INFO - 1682360610823 Marionette DEBUG 62 <- [1,13,null,{"value":"content"}]
[task 2023-04-24T18:23:30.824Z] 18:23:30 INFO - 1682360610824 Marionette DEBUG 62 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.824Z] 18:23:30 INFO - 1682360610824 Marionette DEBUG 62 <- [1,14,null,{"value":null}]
[task 2023-04-24T18:23:30.825Z] 18:23:30 INFO - 1682360610825 Marionette DEBUG 62 -> [0,15,"WebDriver:GetWindowHandle",{}]
[task 2023-04-24T18:23:30.825Z] 18:23:30 INFO - 1682360610825 Marionette DEBUG 62 <- [1,15,null,{"value":"f7885a6c-a92c-46e6-8e9c-6caa1ac4bf63"}]
[task 2023-04-24T18:23:30.826Z] 18:23:30 INFO - 1682360610826 Marionette DEBUG 62 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.827Z] 18:23:30 INFO - 1682360610826 Marionette DEBUG 62 <- [1,16,null,{"value":null}]
[task 2023-04-24T18:23:30.827Z] 18:23:30 INFO - 1682360610827 Marionette DEBUG 62 -> [0,17,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:30.828Z] 18:23:30 INFO - 1682360610827 Marionette DEBUG 62 <- [1,17,null,{"value":"content"}]
[task 2023-04-24T18:23:30.828Z] 18:23:30 INFO - 1682360610828 Marionette DEBUG 62 -> [0,18,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.829Z] 18:23:30 INFO - 1682360610828 Marionette DEBUG 62 <- [1,18,null,{"value":null}]
[task 2023-04-24T18:23:30.829Z] 18:23:30 INFO - 1682360610829 Marionette DEBUG 62 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2023-04-24T18:23:30.830Z] 18:23:30 INFO - 1682360610830 Marionette DEBUG 62 <- [1,19,null,["f7885a6c-a92c-46e6-8e9c-6caa1ac4bf63"]]
[task 2023-04-24T18:23:30.830Z] 18:23:30 INFO - 1682360610830 Marionette DEBUG 62 -> [0,20,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.831Z] 18:23:30 INFO - 1682360610831 Marionette DEBUG 62 <- [1,20,null,{"value":null}]
[task 2023-04-24T18:23:30.831Z] 18:23:30 INFO - 1682360610831 Marionette DEBUG 62 -> [0,21,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:30.832Z] 18:23:30 INFO - 1682360610832 Marionette DEBUG 62 <- [1,21,null,{"value":"content"}]
[task 2023-04-24T18:23:30.832Z] 18:23:30 INFO - 1682360610833 Marionette DEBUG 62 -> [0,22,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.833Z] 18:23:30 INFO - 1682360610833 Marionette DEBUG 62 <- [1,22,null,{"value":null}]
[task 2023-04-24T18:23:30.834Z] 18:23:30 INFO - 1682360610834 Marionette DEBUG 62 -> [0,23,"WebDriver:GetWindowHandles",{}]
[task 2023-04-24T18:23:30.834Z] 18:23:30 INFO - 1682360610834 Marionette DEBUG 62 <- [1,23,null,["f7885a6c-a92c-46e6-8e9c-6caa1ac4bf63"]]
[task 2023-04-24T18:23:30.835Z] 18:23:30 INFO - 1682360610835 Marionette DEBUG 62 -> [0,24,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:30.835Z] 18:23:30 INFO - 1682360610835 Marionette DEBUG 62 <- [1,24,null,{"value":null}]
[task 2023-04-24T18:23:30.836Z] 18:23:30 INFO - 1682360610836 Marionette DEBUG 62 -> [0,25,"WebDriver:NewWindow",{"type":"tab","focus":false,"private":false}]
[task 2023-04-24T18:23:30.842Z] 18:23:30 INFO - 1682360610848 RemoteAgent TRACE Received DOM event TabOpen for [object XULElement]
[task 2023-04-24T18:23:30.886Z] 18:23:30 INFO - 1682360610887 RemoteAgent TRACE Received DOM event TabSelect for [object XULElement]
[task 2023-04-24T18:23:30.900Z] 18:23:30 INFO - 1682360610899 RemoteAgent TRACE [91] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=3200 waitForExplicitStart=false
[task 2023-04-24T18:23:30.900Z] 18:23:30 INFO - 1682360610899 RemoteAgent TRACE [91] ProgressListener Document already loading about:blank
[task 2023-04-24T18:23:30.901Z] 18:23:30 INFO - 1682360610899 RemoteAgent TRACE [91] ProgressListener Check loading state: isStart=true isStop=false
[task 2023-04-24T18:23:30.901Z] 18:23:30 INFO - 1682360610899 RemoteAgent TRACE [91] ProgressListener state=start: about:blank
[task 2023-04-24T18:23:30.996Z] 18:23:30 INFO - 1682360611001 RemoteAgent TRACE [91] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-04-24T18:23:31.002Z] 18:23:31 INFO - 1682360611001 RemoteAgent TRACE [91] ProgressListener state=stop: about:blank
[task 2023-04-24T18:23:31.002Z] 18:23:31 INFO - 1682360611001 RemoteAgent TRACE [91] ProgressListener Stop: has error=false
[task 2023-04-24T18:23:31.003Z] 18:23:31 INFO - 1682360611002 Marionette DEBUG 62 <- [1,25,null,{"handle":"a7139685-76a1-48dd-9086-7a2ceb126217","type":"tab"}]
[task 2023-04-24T18:23:31.004Z] 18:23:31 INFO - 1682360611004 Marionette DEBUG 62 -> [0,26,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:31.004Z] 18:23:31 INFO - 1682360611004 Marionette DEBUG 62 <- [1,26,null,{"value":"content"}]
[task 2023-04-24T18:23:31.005Z] 18:23:31 INFO - 1682360611005 Marionette DEBUG 62 -> [0,27,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:31.005Z] 18:23:31 INFO - 1682360611005 Marionette DEBUG 62 <- [1,27,null,{"value":null}]
[task 2023-04-24T18:23:31.006Z] 18:23:31 INFO - 1682360611006 Marionette DEBUG 62 -> [0,28,"WebDriver:GetWindowHandles",{}]
[task 2023-04-24T18:23:31.007Z] 18:23:31 INFO - 1682360611006 Marionette DEBUG 62 <- [1,28,null,["f7885a6c-a92c-46e6-8e9c-6caa1ac4bf63","a7139685-76a1-48dd-9086-7a2ceb126217"]]
[task 2023-04-24T18:23:31.007Z] 18:23:31 INFO - 1682360611007 Marionette DEBUG 62 -> [0,29,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:31.008Z] 18:23:31 INFO - 1682360611008 Marionette DEBUG 62 <- [1,29,null,{"value":null}]
[task 2023-04-24T18:23:31.008Z] 18:23:31 INFO - 1682360611008 Marionette DEBUG 62 -> [0,30,"Marionette:GetContext",{}]
[task 2023-04-24T18:23:31.009Z] 18:23:31 INFO - 1682360611009 Marionette DEBUG 62 <- [1,30,null,{"value":"content"}]
[task 2023-04-24T18:23:31.009Z] 18:23:31 INFO - 1682360611009 Marionette DEBUG 62 -> [0,31,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:31.010Z] 18:23:31 INFO - 1682360611010 Marionette DEBUG 62 <- [1,31,null,{"value":null}]
[task 2023-04-24T18:23:31.010Z] 18:23:31 INFO - 1682360611010 Marionette DEBUG 62 -> [0,32,"WebDriver:GetWindowHandles",{}]
[task 2023-04-24T18:23:31.011Z] 18:23:31 INFO - 1682360611011 Marionette DEBUG 62 <- [1,32,null,["f7885a6c-a92c-46e6-8e9c-6caa1ac4bf63","a7139685-76a1-48dd-9086-7a2ceb126217"]]
[task 2023-04-24T18:23:31.012Z] 18:23:31 INFO - 1682360611012 Marionette DEBUG 62 -> [0,33,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:23:31.012Z] 18:23:31 INFO - 1682360611012 Marionette DEBUG 62 <- [1,33,null,{"value":null}]
[task 2023-04-24T18:23:31.013Z] 18:23:31 INFO - 1682360611013 Marionette DEBUG 62 -> [0,34,"WebDriver:SwitchToWindow",{"handle":"a7139685-76a1-48dd-9086-7a2ceb126217","focus":true}]
[task 2023-04-24T18:23:31.027Z] 18:23:31 INFO - 1682360611031 RemoteAgent TRACE Received DOM event TabSelect for [object XULElement]
[task 2023-04-24T18:23:31.043Z] 18:23:31 INFO - 1682360611043 Marionette DEBUG 62 <- [1,34,null,{"value":null}]
[task 2023-04-24T18:23:31.050Z] 18:23:31 INFO - 1682360611049 Marionette DEBUG 62 -> [0,35,"WebDriver:Navigate",{"url":"http://127.0.0.1:65198/clicks.html"}]
[task 2023-04-24T18:23:31.054Z] 18:23:31 INFO - 1682360611054 Marionette TRACE [91] Received event beforeunload for about:blank
[task 2023-04-24T18:23:31.061Z] 18:23:31 INFO - 1682360611063 Marionette TRACE Remoteness change detected. Set new top-level browsing context to 91
[task 2023-04-24T18:23:31.090Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d843900 for phase xpcom-will-shutdown
[task 2023-04-24T18:23:31.095Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d843900 for phase profile-before-change
[task 2023-04-24T18:23:31.097Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d843900 for phase quit-application-granted
[task 2023-04-24T18:23:31.100Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d844d00 for phase xpcom-will-shutdown
[task 2023-04-24T18:23:31.100Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d844d00 for phase profile-before-change
[task 2023-04-24T18:23:31.101Z] 18:23:31 INFO - DEBUG: Adding blocker ContentParent: id=1f93d844d00 for phase quit-application-granted
[task 2023-04-24T18:23:31.102Z] 18:23:31 INFO - 1682360611102 Marionette TRACE [91] Received event beforeunload for about:blank
[task 2023-04-24T18:23:31.113Z] 18:23:31 INFO - 1682360611113 Marionette TRACE [91] Received event pagehide for about:blank
[task 2023-04-24T18:23:31.202Z] 18:23:31 INFO - DEBUG: Adding blocker Transaction (14) for phase protections.sqlite#0: waiting for clients
[task 2023-04-24T18:23:31.203Z] 18:23:31 INFO - DEBUG: Completed blocker Transaction (14) for phase protections.sqlite#0: waiting for clients
[task 2023-04-24T18:23:31.212Z] 18:23:31 INFO - 1682360611211 Marionette TRACE [91] Received event DOMContentLoaded for http://127.0.0.1:65198/clicks.html
[task 2023-04-24T18:23:31.212Z] 18:23:31 INFO - 1682360611212 Marionette TRACE [91] Received event pageshow for http://127.0.0.1:65198/clicks.html
[task 2023-04-24T18:23:31.213Z] 18:23:31 INFO - 1682360611213 Marionette DEBUG 62 <- [1,35,null,{"value":null}]
[task 2023-04-24T18:23:31.215Z] 18:23:31 INFO - 1682360611215 Marionette DEBUG 62 -> [0,36,"WebDriver:FindElement",{"value":"links","using":"id"}]
[task 2023-04-24T18:23:31.232Z] 18:23:31 INFO - 1682360611231 RemoteAgent TRACE WebDriverProcessData actor created for PID 4404
[task 2023-04-24T18:23:31.237Z] 18:23:31 INFO - 1682360611237 Marionette TRACE [91] MarionetteCommands actor created for window id 109521666049
[task 2023-04-24T18:23:31.261Z] 18:23:31 INFO - 1682360611260 Marionette DEBUG 62 <- [1,36,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1adbccee-b604-4cc1-8152-5d9667f2fd31"}}]
[task 2023-04-24T18:23:31.262Z] 18:23:31 INFO - 1682360611262 Marionette DEBUG 62 -> [0,37,"WebDriver:ElementClick",{"id":"1adbccee-b604-4cc1-8152-5d9667f2fd31"}]
[task 2023-04-24T18:23:31.309Z] 18:23:31 INFO - 1682360611317 Marionette TRACE Received DOM event click for [object HTMLParagraphElement]
[task 2023-04-24T18:23:31.355Z] 18:23:31 INFO - DEBUG: Adding blocker UserInteractionTimer 1 for document 1f41f262200 for phase xpcom-will-shutdown
[task 2023-04-24T18:23:31.371Z] 18:23:31 INFO - 1682360611383 Marionette TRACE [91] Received event pagehide for about:blank
[task 2023-04-24T18:23:36.377Z] 18:23:36 INFO - DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-04-24T18:23:36.386Z] 18:23:36 INFO - DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-04-24T18:23:37.193Z] 18:23:37 INFO - DEBUG: Adding blocker places.sqlite#1: Interactions.jsm::updateDatabase (22) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:23:37.200Z] 18:23:37 INFO - DEBUG: Completed blocker places.sqlite#1: Interactions.jsm::updateDatabase (22) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:23:51.391Z] 18:23:51 INFO - DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-04-24T18:23:51.401Z] 18:23:51 INFO - DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-04-24T18:24:37.860Z] 18:24:37 INFO - DEBUG: Adding blocker places.sqlite#1: Interactions.jsm::updateDatabase (23) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:24:37.876Z] 18:24:37 INFO - DEBUG: Completed blocker places.sqlite#1: Interactions.jsm::updateDatabase (23) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:25:03.614Z] 18:25:03 INFO - DEBUG: Adding blocker Transaction (24) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:25:03.626Z] 18:25:03 INFO - DEBUG: Completed blocker Transaction (24) for phase places.sqlite#1: waiting for clients
[task 2023-04-24T18:28:31.280Z] 18:28:31 INFO - 1682360911282 Marionette DEBUG 62 <- [1,37,{"error":"timeout","message":"Navigation timed out after 300000 ms","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... or@chrome://remote/content/shared/webdriver/Errors.sys.mjs:503:5\nbail@chrome://remote/content/marionette/sync.sys.mjs:213:19\n"},null]
[task 2023-04-24T18:28:31.287Z] 18:28:31 INFO - 1682360911286 Marionette DEBUG 62 -> [0,38,"Marionette:GetContext",{}]
[task 2023-04-24T18:28:31.287Z] 18:28:31 INFO - 1682360911287 Marionette DEBUG 62 <- [1,38,null,{"value":"content"}]
[task 2023-04-24T18:28:31.289Z] 18:28:31 INFO - 1682360911289 Marionette DEBUG 62 -> [0,39,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-24T18:28:31.289Z] 18:28:31 INFO - 1682360911289 Marionette DEBUG 62 <- [1,39,null,{"value":null}]
[task 2023-04-24T18:28:31.290Z] 18:28:31 INFO - 1682360911290 Marionette DEBUG 62 -> [0,40,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2023-04-24T18:28:31.294Z] 18:28:31 INFO - 1682360911294 RemoteAgent TRACE WebDriverProcessData actor created for PID 3624
[task 2023-04-24T18:28:31.294Z] 18:28:31 INFO - 1682360911294 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2023-04-24T18:28:31.377Z] 18:28:31 INFO - 1682360911385 Marionette DEBUG 62 <- [1,40,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPQCAYAAABpCtbiAAAgAElEQVR4XuzdCZgU1bn/8XfYl2EVURQBBRUUt0QBRdB4dcyN+0VRkBiiXmRcQxQSuYkhJgEFjXEL4 ... ECBAgQIAAAQIECBAgQIAAAQIEroAD8BIJECBAgAABAgQIECBAgAABAgQIEOgKOAC722lOgAABAgQIECBAgAABAgQIECBA4AoMaz5qa1A3DUkAAAAASUVORK5CYII="}]
[task 2023-04-24T18:28:31.389Z] 18:28:31 INFO - 1682360911389 Marionette DEBUG 62 -> [0,41,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:28:31.390Z] 18:28:31 INFO - 1682360911390 Marionette DEBUG 62 <- [1,41,null,{"value":null}]
[task 2023-04-24T18:28:31.392Z] 18:28:31 INFO - 1682360911392 Marionette DEBUG 62 -> [0,42,"Marionette:GetContext",{}]
[task 2023-04-24T18:28:31.392Z] 18:28:31 INFO - 1682360911392 Marionette DEBUG 62 <- [1,42,null,{"value":"content"}]
[task 2023-04-24T18:28:31.394Z] 18:28:31 INFO - 1682360911393 Marionette DEBUG 62 -> [0,43,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:28:31.394Z] 18:28:31 INFO - 1682360911394 Marionette DEBUG 62 <- [1,43,null,{"value":null}]
[task 2023-04-24T18:28:31.395Z] 18:28:31 INFO - 1682360911395 Marionette DEBUG 62 -> [0,44,"WebDriver:GetPageSource",{}]
[task 2023-04-24T18:28:31.398Z] 18:28:31 INFO - 1682360911398 Marionette DEBUG 62 <- [1,44,null,{"value":"<html><head>\n <!-- This Source Code Form is subject to the terms of the Mozilla Public\n - License, v. 2.0. If a copy of ... dth: 5em;\">\n <a id=\"overflowLink\" href=\"test.html\">looooooooooong short looooooooooong</a>\n </p>\n</div>\n\n\n\n</body></html>"}]
[task 2023-04-24T18:28:31.399Z] 18:28:31 INFO - 1682360911399 Marionette DEBUG 62 -> [0,45,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-24T18:28:31.400Z] 18:28:31 INFO - 1682360911400 Marionette DEBUG 62 <- [1,45,null,{"value":null}]
[task 2023-04-24T18:28:31.404Z] 18:28:31 ERROR - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_click.py TestClickNavigation.test_click_no_link | marionette_driver.errors.TimeoutException: Navigation timed out after 300000 ms
[task 2023-04-24T18:28:31.404Z] 18:28:31 INFO - stacktrace:
[task 2023-04-24T18:28:31.405Z] 18:28:31 INFO - RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
[task 2023-04-24T18:28:31.405Z] 18:28:31 INFO - WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:183:5
[task 2023-04-24T18:28:31.405Z] 18:28:31 INFO - TimeoutError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:503:5
[task 2023-04-24T18:28:31.405Z] 18:28:31 INFO - bail@chrome://remote/content/marionette/sync.sys.mjs:213:19
[task 2023-04-24T18:28:31.406Z] 18:28:31 INFO - Traceback (most recent call last):
[task 2023-04-24T18:28:31.406Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 198, in run
[task 2023-04-24T18:28:31.406Z] 18:28:31 INFO - testMethod()
[task 2023-04-24T18:28:31.406Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_click.py", line 547, in test_click_no_link
[task 2023-04-24T18:28:31.407Z] 18:28:31 INFO - self.marionette.find_element(By.ID, "links").click()
[task 2023-04-24T18:28:31.407Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\venv\lib\site-packages\marionette_driver\marionette.py", line 256, in click
[task 2023-04-24T18:28:31.407Z] 18:28:31 INFO - self.marionette._send_message("WebDriver:ElementClick", {"id": self.id})
[task 2023-04-24T18:28:31.407Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\venv\lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2023-04-24T18:28:31.408Z] 18:28:31 INFO - return func(*args, **kwargs)
[task 2023-04-24T18:28:31.409Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\venv\lib\site-packages\marionette_driver\marionette.py", line 703, in _send_message
[task 2023-04-24T18:28:31.409Z] 18:28:31 INFO - self._handle_error(err)
[task 2023-04-24T18:28:31.409Z] 18:28:31 INFO - File "Z:\task_168235980160176\build\venv\lib\site-packages\marionette_driver\marionette.py", line 715, in _handle_error
[task 2023-04-24T18:28:31.409Z] 18:28:31 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2023-04-24T18:28:31.410Z] 18:28:31 INFO - TEST-INFO took 300641ms
|
||
Updated•2 years ago
|
You need to log in
before you can comment on or make changes to this bug.
Description
•