Closed
Bug 1854954
Opened 2 years ago
Closed 1 year ago
Intermittent Wd navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js) | expected PASS
Categories
(Remote Protocol :: WebDriver BiDi, defect, P5)
Remote Protocol
WebDriver BiDi
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=430212090&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Z_ntbw23RjKcMgOyfgdbTg/runs/0/artifacts/public/logs/live_backing.log
[task 2023-09-25T13:02:20.317Z] TEST-START | navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js)
[task 2023-09-25T13:02:20.318Z] PID 730 | ["test-start",{"title":"should navigate to empty page with domcontentloaded","fullTitle":"navigation Page.goto should navigate to empty page with domcontentloaded","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js","currentRetry":0}]
[task 2023-09-25T13:02:20.318Z] PID 730 | 1695646940317 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 -> {"id":6923,"method":"browsingContext.close","params":{"context":"c924e622-9684-4046-9e98-b2d97b7147df"}}
[task 2023-09-25T13:02:20.318Z] PID 730 | 1695646940317 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.318Z] PID 730 | 1695646940317 RemoteAgent TRACE Received command browsingContext.close for destination ROOT
[task 2023-09-25T13:02:20.318Z] PID 730 | 1695646940318 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.324Z] PID 730 | 1695646940324 RemoteAgent TRACE MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473 is being destroyed
[task 2023-09-25T13:02:20.325Z] PID 730 | 1695646940324 RemoteAgent TRACE Unregistered MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.326Z] PID 730 | 1695646940324 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"success","id":6923,"result":{}}
[task 2023-09-25T13:02:20.329Z] PID 730 | 1695646940329 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmDestroyed","params":{"realm":"8c43a791-03bb-4cee-a74d-4877925bb0f5"}}
[task 2023-09-25T13:02:20.330Z] PID 730 | 1695646940329 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 -> {"id":6924,"method":"browsingContext.create","params":{"type":"tab"}}
[task 2023-09-25T13:02:20.330Z] PID 730 | 1695646940329 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.330Z] PID 730 | 1695646940329 RemoteAgent TRACE Received command browsingContext.create for destination ROOT
[task 2023-09-25T13:02:20.330Z] PID 730 | 1695646940329 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.331Z] PID 730 | 1695646940331 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.contextCreated","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","url":"about:blank","children":null,"parent":null}}
[task 2023-09-25T13:02:20.335Z] PID 730 | 1695646940335 RemoteAgent TRACE Received DOM event TabSelect for [object XULElement]
[task 2023-09-25T13:02:20.336Z] PID 730 | 1695646940336 RemoteAgent TRACE [288] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2023-09-25T13:02:20.336Z] PID 730 | 1695646940336 RemoteAgent TRACE [288] ProgressListener Setting unload timer (5000ms)
[task 2023-09-25T13:02:20.343Z] PID 730 | 1695646940343 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.343Z] PID 730 | 1695646940343 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.343Z] PID 730 | 1695646940343 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.343Z] PID 730 | 1695646940343 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.344Z] PID 730 | 1695646940344 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.345Z] PID 730 | 1695646940344 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmCreated","params":{"realm":"72c49822-a4ce-49b1-8e98-a575f85230c2","origin":"null","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","type":"window"}}
[task 2023-09-25T13:02:20.345Z] PID 730 | 1695646940345 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.346Z] PID 730 | 1695646940345 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.346Z] PID 730 | 1695646940345 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.346Z] PID 730 | 1695646940345 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.347Z] PID 730 | 1695646940346 RemoteAgent TRACE Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.347Z] PID 730 | 1695646940346 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.347Z] PID 730 | 1695646940346 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.348Z] PID 730 | 1695646940347 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.348Z] PID 730 | 1695646940348 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.348Z] PID 730 | 1695646940348 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.349Z] PID 730 | 1695646940348 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.349Z] PID 730 | 1695646940348 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.349Z] PID 730 | 1695646940348 RemoteAgent TRACE Created MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.350Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-09-25T13:02:20.350Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.350Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] ProgressListener state=start: about:blank
[task 2023-09-25T13:02:20.351Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] ProgressListener Cleared the unload timer
[task 2023-09-25T13:02:20.351Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 131088, status: 2152398850, isStart: false, isStop: true, isNetwork: false, isBindingAborted: true, targetURI: about:blank
[task 2023-09-25T13:02:20.352Z] PID 730 | 1695646940350 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.352Z] PID 730 | 1695646940351 RemoteAgent TRACE [eb14f84a-0e8a-48d4-97ad-4141df6afee5] Navigation started for url: about:blank (a1704628-b484-4f3f-b619-964954f2f2ed)
[task 2023-09-25T13:02:20.352Z] PID 730 | 1695646940352 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.navigationStarted","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","navigation":"a1704628-b484-4f3f-b619-964954f2f2ed","timestamp":1695646940351,"url":"about:blank"}}
[task 2023-09-25T13:02:20.353Z] PID 730 | 1695646940352 RemoteAgent TRACE [288] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-09-25T13:02:20.353Z] PID 730 | 1695646940352 RemoteAgent TRACE [288] ProgressListener Ignore aborted navigation error to the initial document, real document will be loaded.
[task 2023-09-25T13:02:20.354Z] PID 730 | 1695646940353 RemoteAgent TRACE [288] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-09-25T13:02:20.354Z] PID 730 | 1695646940353 RemoteAgent TRACE [eb14f84a-0e8a-48d4-97ad-4141df6afee5] Skipping already tracked navigation, navigationId: a1704628-b484-4f3f-b619-964954f2f2ed
[task 2023-09-25T13:02:20.355Z] PID 730 | 1695646940354 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.357Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.358Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.358Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.358Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.358Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.358Z] PID 730 | 1695646940357 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.359Z] PID 730 | 1695646940358 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.359Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.359Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.359Z] PID 730 | 1695646940358 RemoteAgent TRACE Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.359Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.360Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.360Z] PID 730 | 1695646940358 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.360Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.361Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.361Z] PID 730 | 1695646940358 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.361Z] PID 730 | 1695646940358 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.361Z] PID 730 | 1695646940359 RemoteAgent TRACE Created MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.361Z] PID 730 | 1695646940360 RemoteAgent TRACE WebDriverProcessData actor created for PID 13067
[task 2023-09-25T13:02:20.362Z] PID 730 | 1695646940360 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmDestroyed","params":{"realm":"72c49822-a4ce-49b1-8e98-a575f85230c2"}}
[task 2023-09-25T13:02:20.362Z] PID 730 | 1695646940361 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmCreated","params":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3","origin":"null","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","type":"window"}}
[task 2023-09-25T13:02:20.365Z] PID 730 | 1695646940365 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.365Z] PID 730 | 1695646940365 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.365Z] PID 730 | 1695646940365 RemoteAgent TRACE MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473 is being destroyed
[task 2023-09-25T13:02:20.365Z] PID 730 | 1695646940365 RemoteAgent TRACE Unregistered MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.366Z] PID 730 | 1695646940365 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"log.entryAdded","params":{"type":"javascript","level":"error","source":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5"},"text":"Error: Permission denied to access property \"length\"","timestamp":1695646940363}}
[task 2023-09-25T13:02:20.366Z] PID 730 | 1695646940366 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"log.entryAdded","params":{"type":"javascript","level":"error","source":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5"},"text":"Error: Permission denied to access property \"length\"","timestamp":1695646940364}}
[task 2023-09-25T13:02:20.368Z] PID 730 | 1695646940367 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"log.entryAdded","params":{"type":"javascript","level":"error","source":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5"},"text":"Error: Permission denied to access property \"length\"","timestamp":1695646940364}}
[task 2023-09-25T13:02:20.368Z] PID 730 | 1695646940368 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"log.entryAdded","params":{"type":"javascript","level":"error","source":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5"},"text":"Error: Permission denied to access property \"length\"","timestamp":1695646940364}}
[task 2023-09-25T13:02:20.369Z] PID 730 | 1695646940368 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","timestamp":1695646940367,"url":"about:blank","navigation":"a1704628-b484-4f3f-b619-964954f2f2ed"}}
[task 2023-09-25T13:02:20.370Z] PID 730 | 1695646940370 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: about:blank
[task 2023-09-25T13:02:20.371Z] PID 730 | 1695646940370 RemoteAgent TRACE [eb14f84a-0e8a-48d4-97ad-4141df6afee5] Navigation finished for url: about:blank (a1704628-b484-4f3f-b619-964954f2f2ed)
[task 2023-09-25T13:02:20.371Z] PID 730 | 1695646940370 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.load","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","timestamp":1695646940370,"url":"about:blank","navigation":"a1704628-b484-4f3f-b619-964954f2f2ed"}}
[task 2023-09-25T13:02:20.374Z] PID 730 | 1695646940371 RemoteAgent TRACE [288] ProgressListener Check loading state: isStart=0 isStop=16
[task 2023-09-25T13:02:20.374Z] PID 730 | 1695646940372 RemoteAgent TRACE [288] ProgressListener state=stop: about:blank
[task 2023-09-25T13:02:20.374Z] PID 730 | 1695646940372 RemoteAgent TRACE [288] ProgressListener Stop: has error=false
[task 2023-09-25T13:02:20.374Z] PID 730 | 1695646940372 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"success","id":6924,"result":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5"}}
[task 2023-09-25T13:02:20.375Z] PID 730 | 1695646940374 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 -> {"id":6925,"method":"browsingContext.setViewport","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","viewport":{"width":800,"height":600}}}
[task 2023-09-25T13:02:20.375Z] PID 730 | 1695646940374 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.379Z] PID 730 | 1695646940374 RemoteAgent TRACE Received command browsingContext.setViewport for destination ROOT
[task 2023-09-25T13:02:20.379Z] PID 730 | 1695646940374 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.379Z] PID 730 | 1695646940374 RemoteAgent TRACE Received command browsingContext._awaitViewportDimensions for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.379Z] PID 730 | 1695646940375 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.407Z] PID 730 | 1695646940406 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"success","id":6925,"result":{}}
[task 2023-09-25T13:02:20.408Z] PID 730 | 1695646940408 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 -> {"id":6926,"method":"browsingContext.navigate","params":{"url":"http://localhost:33075/empty.html","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","wait":"interactive"}}
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940408 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940408 RemoteAgent TRACE Received command browsingContext.navigate for destination ROOT
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940408 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940409 RemoteAgent TRACE Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940409 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.409Z] PID 730 | 1695646940409 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.410Z] PID 730 | 1695646940409 RemoteAgent TRACE Received command browsingContext._getBaseURL for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.410Z] PID 730 | 1695646940409 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.413Z] PID 730 | 1695646940413 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.414Z] PID 730 | 1695646940413 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.414Z] PID 730 | 1695646940413 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.414Z] PID 730 | 1695646940413 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.414Z] PID 730 | 1695646940414 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.415Z] PID 730 | 1695646940414 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.415Z] PID 730 | 1695646940415 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.415Z] PID 730 | 1695646940414 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.416Z] PID 730 | 1695646940415 RemoteAgent TRACE Received command browsingContext._applySessionData for destination ROOT
[task 2023-09-25T13:02:20.416Z] PID 730 | 1695646940415 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.416Z] PID 730 | 1695646940416 RemoteAgent TRACE [288] ProgressListener Start: expectNavigation=true resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=true
[task 2023-09-25T13:02:20.417Z] PID 730 | 1695646940417 RemoteAgent TRACE [288] ProgressListener Skip setting the unload timer
[task 2023-09-25T13:02:20.418Z] PID 730 | 1695646940418 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: http://localhost:33075/empty.html
[task 2023-09-25T13:02:20.420Z] PID 730 | 1695646940419 RemoteAgent TRACE [eb14f84a-0e8a-48d4-97ad-4141df6afee5] Navigation started for url: http://localhost:33075/empty.html (2a916565-4cc8-4e20-8f8e-0485153cd2e8)
[task 2023-09-25T13:02:20.420Z] PID 730 | 1695646940420 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.navigationStarted","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8","timestamp":1695646940419,"url":"http://localhost:33075/empty.html"}}
[task 2023-09-25T13:02:20.422Z] PID 730 | 1695646940421 RemoteAgent TRACE [288] ProgressListener Check loading state: isStart=1 isStop=0
[task 2023-09-25T13:02:20.422Z] PID 730 | 1695646940422 RemoteAgent TRACE [288] ProgressListener state=start: http://localhost:33075/empty.html
[task 2023-09-25T13:02:20.424Z] PID 730 | 1695646940424 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"network.beforeRequestSent","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","isBlocked":false,"navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:33075"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Upgrade-Insecure-Requests","value":{"type":"string","value":"1"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"document"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"navigate"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"none"}},{"name":"Sec-Fetch-User","value":{"type":"string","value":"?1"}}],"headersSize":447,"method":"GET","request":"324","timings":{"timeOrigin":0,"requestTime":1695646940417436,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1695646940419379,"responseStart":1695646940420312,"responseEnd":1695646940420379},"url":"http://localhost:33075/empty.html"},"timestamp":1695646940423,"initiator":{"type":"other"}}}
[task 2023-09-25T13:02:20.425Z] PID 730 | 1695646940425 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"network.responseStarted","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","isBlocked":false,"navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"localhost:33075"}},{"name":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (X11; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/119.0"}},{"name":"Accept","value":{"type":"string","value":"text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,*/*;q=0.8"}},{"name":"Accept-Language","value":{"type":"string","value":"en-US,en;q=0.5"}},{"name":"Accept-Encoding","value":{"type":"string","value":"gzip, deflate, br"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Upgrade-Insecure-Requests","value":{"type":"string","value":"1"}},{"name":"Sec-Fetch-Dest","value":{"type":"string","value":"document"}},{"name":"Sec-Fetch-Mode","value":{"type":"string","value":"navigate"}},{"name":"Sec-Fetch-Site","value":{"type":"string","value":"none"}},{"name":"Sec-Fetch-User","value":{"type":"string","value":"?1"}}],"headersSize":447,"method":"GET","request":"324","timings":{"timeOrigin":0,"requestTime":1695646940417436,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1695646940419379,"responseStart":1695646940420312,"responseEnd":1695646940420379},"url":"http://localhost:33075/empty.html"},"timestamp":1695646940425,"response":{"bodySize":0,"content":{"size":null},"headersSize":197,"url":"http://localhost:33075/empty.html","bytesReceived":197,"fromCache":false,"headers":[{"name":"Cache-Control","value":{"type":"string","value":"no-cache, no-store"}},{"name":"Content-Type","value":{"type":"string","value":"text/html; charset=utf-8"}},{"name":"Date","value":{"type":"string","value":"Mon, 25 Sep 2023 13:02:20 GMT"}},{"name":"Connection","value":{"type":"string","value":"keep-alive"}},{"name":"Keep-Alive","value":{"type":"string","value":"timeout=5"}},{"name":"Content-Length","value":{"type":"string","value":"0"}}],"mimeType":"text/html;charset=utf-8","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2023-09-25T13:02:20.428Z] PID 730 | 1695646940428 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:33075/empty.html
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940432 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940432 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Received command log._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/log.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Received command script._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.434Z] PID 730 | 1695646940433 RemoteAgent TRACE Created MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.437Z] PID 730 | 1695646940436 RemoteAgent TRACE MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473 is being destroyed
[task 2023-09-25T13:02:20.437Z] PID 730 | 1695646940437 RemoteAgent TRACE Unregistered MessageHandler WINDOW_GLOBAL for session 66ceb2ba-520a-443d-9f45-789453375473
[task 2023-09-25T13:02:20.439Z] PID 730 | 1695646940437 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmDestroyed","params":{"realm":"cff7c31d-58aa-4a2c-8a3f-ceaa16746da3"}}
[task 2023-09-25T13:02:20.440Z] PID 730 | 1695646940439 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"script.realmCreated","params":{"realm":"0406e8b2-8c20-4afd-8b4b-a938b8758060","origin":"http://localhost:33075","context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","type":"window"}}
[task 2023-09-25T13:02:20.448Z] PID 730 | 1695646940446 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:33075/empty.html
[task 2023-09-25T13:02:20.455Z] PID 730 | 1695646940455 RemoteAgent TRACE [288] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: http://localhost:33075/empty.html
[task 2023-09-25T13:02:20.464Z] PID 730 | 1695646940463 RemoteAgent TRACE [288] ProgressListener Stop if started: seenStartFlag=true
[task 2023-09-25T13:02:20.464Z] PID 730 | 1695646940463 RemoteAgent TRACE [288] ProgressListener Stop: has error=false
[task 2023-09-25T13:02:20.465Z] PID 730 | 1695646940464 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.465Z] PID 730 | 1695646940464 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.465Z] PID 730 | 1695646940464 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.466Z] PID 730 | 1695646940465 RemoteAgent TRACE Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.466Z] PID 730 | 1695646940465 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.466Z] PID 730 | 1695646940465 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.466Z] PID 730 | 1695646940465 RemoteAgent TRACE Received command browsingContext._applySessionData for destination ROOT
[task 2023-09-25T13:02:20.467Z] PID 730 | 1695646940465 RemoteAgent TRACE Module root/browsingContext.sys.mjs found for ROOT
[task 2023-09-25T13:02:20.467Z] PID 730 | 1695646940465 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"success","id":6926,"result":{"navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8","url":"http://localhost:33075/empty.html"}}
[task 2023-09-25T13:02:20.468Z] TEST-UNEXPECTED-FAIL | navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js) | expected PASS
[task 2023-09-25T13:02:20.468Z] TEST-INFO took 151ms
[task 2023-09-25T13:02:20.470Z] PID 730 | ["fail",{"title":"should navigate to empty page with domcontentloaded","fullTitle":"navigation Page.goto should navigate to empty page with domcontentloaded","file":"/builds/worker/checkouts/gecko/remote/test/puppeteer/test/build/navigation.spec.js","duration":150,"currentRetry":0,"err":"Cannot read properties of null (reading 'status')","stack":"TypeError: Cannot read properties of null (reading 'status')\n at Context.<anonymous> (/builds/worker/checkouts/gecko/remote/test/puppeteer/test/src/navigation.spec.ts:106:24)\n at runMicrotasks (<anonymous>)\n at processTicksAndRejections (node:internal/process/task_queues:96:5)"}]
[task 2023-09-25T13:02:20.470Z] PID 730 | 1695646940465 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.domContentLoaded","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","timestamp":1695646940454,"url":"http://localhost:33075/empty.html","navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8"}}
[task 2023-09-25T13:02:20.470Z] PID 730 | 1695646940466 RemoteAgent TRACE [eb14f84a-0e8a-48d4-97ad-4141df6afee5] Navigation finished for url: http://localhost:33075/empty.html (2a916565-4cc8-4e20-8f8e-0485153cd2e8)
[task 2023-09-25T13:02:20.470Z] PID 730 | 1695646940466 RemoteAgent DEBUG WebDriverBiDiConnection d6a6135b-25ef-4b2e-b984-51ceabcc6992 <- {"type":"event","method":"browsingContext.load","params":{"context":"eb14f84a-0e8a-48d4-97ad-4141df6afee5","timestamp":1695646940455,"url":"http://localhost:33075/empty.html","navigation":"2a916565-4cc8-4e20-8f8e-0485153cd2e8"}}
[task 2023-09-25T13:02:20.470Z] PID 730 | 1695646940466 RemoteAgent TRACE Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-09-25T13:02:20.470Z] PID 730 | 1695646940466 RemoteAgent TRACE Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-09-25T13:02:20.470Z] TEST-START | navigation Page.goto should work when page calls history API in beforeunload (navigation.spec.js)
|
||
Updated•2 years ago
|
Component: Debugger → Agent
Product: DevTools → Remote Protocol
The problem here is Cannot read properties of null (reading 'status')" and happens in this line:
https://searchfox.org/mozilla-central/rev/699a7704521354cac1e6a0679029c89ca899e25c/remote/test/puppeteer/test/src/navigation.spec.ts#106
The Navigation basically waits for domContentLoaded before it returns and the network.responseStarted event contains the necessary status of 200. So not sure what's broken here. Lets see how often it fails.
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•2 years ago
|
||
I think this one is Bug 1852891. From the logs we get responseStarted to the html page, but not responseCompleted.
So the issue is not really about the status, but about the missing responseCompleted event.
Depends on: 1852891
|
||
Comment 4•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 5•2 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=438289726&repo=try
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 8•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 2 years ago → 2 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 9•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=448725820&repo=try
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Summary: Intermittent navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js) | expected PASS → Intermittent Wd navigation Page.goto should navigate to empty page with domcontentloaded (navigation.spec.js) | expected PASS
Component: Agent → WebDriver BiDi
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 11•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 2 years ago → 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•