Closed
Bug 1876982
Opened 1 year ago
Closed 1 year ago
Intermittent TEST-UNEXPECTED-TIMEOUT /webdriver/tests/bidi/network/continue_with_auth/invalid.py | single tracking bug
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, intermittent-testcase)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=444907184&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FPa5puo5QGqf1KQZU3btmA/runs/0/artifacts/public/logs/live_backing.log
[task 2024-01-28T10:21:18.132Z] 10:21:18 INFO - TEST-START | /webdriver/tests/bidi/network/continue_with_auth/invalid.py
[task 2024-01-28T10:21:18.251Z] 10:21:18 INFO - STDERR: Task was destroyed but it is pending!
[task 2024-01-28T10:21:18.251Z] 10:21:18 INFO - task: <Task pending name='Task-31' coro=<fetch.<locals>.fetch() running at Z:\task_170643622005554\build\tests\web-platform\tests\webdriver\tests\bidi\network\conftest.py:62> wait_for=<Future pending cb=[<TaskWakeupMethWrapper object at 0x0000021AFA51AF10>()]>>
[task 2024-01-28T10:21:18.298Z] 10:21:18 INFO - STDOUT: ============================= test session starts =============================
[task 2024-01-28T10:21:18.304Z] 10:21:18 INFO - STDOUT: platform win32 -- Python 3.9.10, pytest-7.0.1, pluggy-unknown -- Z:\task_170643622005554\build\venv\Scripts\python.exe
[task 2024-01-28T10:21:18.305Z] 10:21:18 INFO - STDOUT: rootdir: Z:\task_170643622005554\build
[task 2024-01-28T10:21:18.306Z] 10:21:18 INFO - STDOUT: plugins: asyncio-0.19.0
[task 2024-01-28T10:21:18.307Z] 10:21:18 INFO - STDOUT: asyncio: mode=strict
[task 2024-01-28T10:21:18.307Z] 10:21:18 INFO - STDOUT: collecting ...
[task 2024-01-28T10:21:18.345Z] 10:21:18 INFO - STDOUT: collected 41 items
[task 2024-01-28T10:21:18.351Z] 10:21:18 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/bidi/network/continue_with_auth/invalid.py::test_params_request_invalid_phase[beforeRequestSent]
[task 2024-01-28T10:21:18.354Z] 10:21:18 INFO - PID 4732 | 1706437278352 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_170643622005 ... Users\\task_170643622005554\\AppData\\Local\\Temp\\tmpr4z77dzw.mozrunner\\runtests_leaks_2744.log"}}, "webSocketUrl": true}}}
[task 2024-01-28T10:21:18.355Z] 10:21:18 INFO - PID 4732 | 1706437278352 geckodriver::capabilities DEBUG Trying to read firefox version from ini files
[task 2024-01-28T10:21:18.355Z] 10:21:18 INFO - PID 4732 | 1706437278353 geckodriver::capabilities DEBUG Found version 124.0a1
[task 2024-01-28T10:21:18.356Z] 10:21:18 INFO - PID 4732 | 1706437278354 geckodriver::browser DEBUG Backing up prefs to "C:\\Users\\task_170643622005554\\AppData\\Local\\Temp\\tmpr4z77dzw.mozrunner\\user.geckodriver_backup"
[task 2024-01-28T10:21:18.368Z] 10:21:18 INFO - PID 4732 | 1706437278370 mozrunner::runner INFO Running command: "Z:\\task_170643622005554\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\ ... AppData\\Local\\Temp\\tmpr4z77dzw.mozrunner" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-no-remote"
[task 2024-01-28T10:21:18.374Z] 10:21:18 INFO - PID 4732 | 1706437278373 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1
[task 2024-01-28T10:21:18.375Z] 10:21:18 INFO - PID 4732 | 1706437278373 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.376Z] 10:21:18 INFO - PID 4732 | 1706437278373 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.473Z] 10:21:18 INFO - PID 4732 | 1706437278473 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.475Z] 10:21:18 INFO - PID 4732 | 1706437278473 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.573Z] 10:21:18 INFO - PID 4732 | 1706437278574 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.575Z] 10:21:18 INFO - PID 4732 | 1706437278574 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.594Z] 10:21:18 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744.log
[task 2024-01-28T10:21:18.674Z] 10:21:18 INFO - PID 4732 | 1706437278674 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.676Z] 10:21:18 INFO - PID 4732 | 1706437278674 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.704Z] 10:21:18 INFO - PID 4732 | [Parent 3968, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:58
[task 2024-01-28T10:21:18.774Z] 10:21:18 INFO - PID 4732 | 1706437278774 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.777Z] 10:21:18 INFO - PID 4732 | 1706437278774 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.799Z] 10:21:18 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744_gpu_pid8808.log
[task 2024-01-28T10:21:18.875Z] 10:21:18 INFO - PID 4732 | 1706437278875 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.877Z] 10:21:18 INFO - PID 4732 | 1706437278875 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:18.940Z] 10:21:18 INFO - PID 4732 | 1706437278945 RemoteAgent DEBUG WebDriver BiDi enabled
[task 2024-01-28T10:21:18.947Z] 10:21:18 INFO - PID 4732 | 1706437278946 RemoteAgent DEBUG CDP enabled
[task 2024-01-28T10:21:18.947Z] 10:21:18 INFO - PID 4732 | 1706437278946 Marionette INFO Marionette enabled
[task 2024-01-28T10:21:18.949Z] 10:21:18 INFO - PID 4732 | 1706437278948 RemoteAgent DEBUG Setting recommended pref browser.contentblocking.introCount to 99
[task 2024-01-28T10:21:18.953Z] 10:21:18 INFO - PID 4732 | 1706437278952 RemoteAgent DEBUG Setting recommended pref browser.search.update to false
[task 2024-01-28T10:21:18.953Z] 10:21:18 INFO - PID 4732 | 1706437278952 RemoteAgent DEBUG Setting recommended pref browser.tabs.closeWindowWithLastTab to false
[task 2024-01-28T10:21:18.954Z] 10:21:18 INFO - PID 4732 | 1706437278952 RemoteAgent DEBUG Setting recommended pref browser.tabs.warnOnClose to false
[task 2024-01-28T10:21:18.955Z] 10:21:18 INFO - PID 4732 | 1706437278952 RemoteAgent DEBUG Setting recommended pref browser.tabs.warnOnCloseOtherTabs to false
[task 2024-01-28T10:21:18.955Z] 10:21:18 INFO - PID 4732 | 1706437278953 RemoteAgent DEBUG Setting recommended pref browser.tabs.warnOnOpen to false
[task 2024-01-28T10:21:18.956Z] 10:21:18 INFO - PID 4732 | 1706437278953 RemoteAgent DEBUG Setting recommended pref browser.usedOnWindows10.introURL to
[task 2024-01-28T10:21:18.957Z] 10:21:18 INFO - PID 4732 | 1706437278953 RemoteAgent DEBUG Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
[task 2024-01-28T10:21:18.958Z] 10:21:18 INFO - PID 4732 | 1706437278954 RemoteAgent DEBUG Setting recommended pref dom.navigation.locationChangeRateLimit.count to 0
[task 2024-01-28T10:21:18.958Z] 10:21:18 INFO - PID 4732 | 1706437278954 RemoteAgent DEBUG Setting recommended pref dom.screenorientation.allow-lock to true
[task 2024-01-28T10:21:18.959Z] 10:21:18 INFO - PID 4732 | 1706437278954 RemoteAgent DEBUG Setting recommended pref network.connectivity-service.enabled to false
[task 2024-01-28T10:21:18.960Z] 10:21:18 INFO - PID 4732 | 1706437278954 RemoteAgent DEBUG Setting recommended pref privacy.trackingprotection.enabled to false
[task 2024-01-28T10:21:18.960Z] 10:21:18 INFO - PID 4732 | 1706437278954 RemoteAgent DEBUG Setting recommended pref security.fileuri.strict_origin_policy to false
[task 2024-01-28T10:21:18.961Z] 10:21:18 INFO - PID 4732 | 1706437278955 RemoteAgent DEBUG Setting recommended pref signon.autofillForms to false
[task 2024-01-28T10:21:18.961Z] 10:21:18 INFO - PID 4732 | 1706437278955 RemoteAgent DEBUG Setting recommended pref widget.windows.window_occlusion_tracking.enabled to false
[task 2024-01-28T10:21:18.962Z] 10:21:18 INFO - PID 4732 | 1706437278957 Marionette TRACE Received observer notification final-ui-startup
[task 2024-01-28T10:21:18.962Z] 10:21:18 INFO - PID 4732 | 1706437278959 RemoteAgent TRACE Received observer notification final-ui-startup
[task 2024-01-28T10:21:18.975Z] 10:21:18 INFO - PID 4732 | 1706437278975 geckodriver::browser TRACE Failed to open C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\MarionetteActivePort
[task 2024-01-28T10:21:18.977Z] 10:21:18 INFO - PID 4732 | 1706437278975 geckodriver::marionette TRACE Retrying in 100ms
[task 2024-01-28T10:21:19.001Z] 10:21:19 INFO - PID 4732 | 1706437279010 Marionette INFO Listening on port 54283
[task 2024-01-28T10:21:19.012Z] 10:21:19 INFO - PID 4732 | 1706437279011 Marionette DEBUG Marionette is listening
[task 2024-01-28T10:21:19.023Z] 10:21:19 INFO - PID 4732 | 1706437279029 RemoteAgent TRACE Available local IP addresses: 127.0.0.1, [::1]
[task 2024-01-28T10:21:19.036Z] 10:21:19 INFO - PID 4732 | WebDriver BiDi listening on ws://127.0.0.1:9222
[task 2024-01-28T10:21:19.037Z] 10:21:19 INFO - PID 4732 | 1706437279035 RemoteAgent DEBUG Setting recommended pref browser.contentblocking.features.standard to -tp,tpPrivate,cookieBehavior0,-cm,-fp
[task 2024-01-28T10:21:19.038Z] 10:21:19 INFO - PID 4732 | 1706437279036 RemoteAgent DEBUG Setting recommended pref network.cookie.cookieBehavior to 0
[task 2024-01-28T10:21:19.039Z] 10:21:19 INFO - PID 4732 | 1706437279037 CDP DEBUG Waiting for initial application window
[task 2024-01-28T10:21:19.063Z] 10:21:19 INFO - PID 4732 | Initializing context 7065000 surface 53803a0 on display 7041c00
[task 2024-01-28T10:21:19.066Z] 10:21:19 INFO - PID 4732 | [GPU 8808, Renderer] WARNING: Failed to make GL context current!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:463
[task 2024-01-28T10:21:19.067Z] 10:21:19 INFO - PID 4732 | EGL Error: 0x3009
[task 2024-01-28T10:21:19.068Z] 10:21:19 INFO - PID 4732 | [GPU 8808, Renderer] WARNING: GLContext::InitWithPrefix failed!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:337
[task 2024-01-28T10:21:19.069Z] 10:21:19 INFO - PID 4732 | Destroying context 7065000 surface 53803a0 on display 7041c00
[task 2024-01-28T10:21:19.069Z] 10:21:19 INFO - PID 4732 | [GPU 8808, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1193
[task 2024-01-28T10:21:19.070Z] 10:21:19 INFO - PID 4732 | Initializing context 7078000 surface 53803a0 on display 7041c00
[task 2024-01-28T10:21:19.071Z] 10:21:19 INFO - PID 4732 | GL_VENDOR: Google Inc. (Microsoft)
[task 2024-01-28T10:21:19.071Z] 10:21:19 INFO - PID 4732 | mVendor: Unknown
[task 2024-01-28T10:21:19.072Z] 10:21:19 INFO - PID 4732 | GL_RENDERER: ANGLE (Microsoft, Microsoft Basic Render Driver Direct3D11 vs_5_0 ps_5_0, D3D11-10.0.22621.1194)
[task 2024-01-28T10:21:19.073Z] 10:21:19 INFO - PID 4732 | mRenderer: Microsoft Basic Render Driver
[task 2024-01-28T10:21:19.073Z] 10:21:19 INFO - PID 4732 | mIsMesa: 0
[task 2024-01-28T10:21:19.074Z] 10:21:19 INFO - PID 4732 | [GPU 8808, Renderer] WARNING: framebuffer_multisample marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:638
[task 2024-01-28T10:21:19.077Z] 10:21:19 INFO - PID 4732 | Read port: 54283
[task 2024-01-28T10:21:19.142Z] 10:21:19 INFO - PID 4732 | 1706437279155 Marionette DEBUG Accepted connection 0 from 127.0.0.1:54284
[task 2024-01-28T10:21:19.267Z] 10:21:19 INFO - PID 4732 | 1706437279270 geckodriver::marionette DEBUG Connection to Marionette established on 127.0.0.1:54283.
[task 2024-01-28T10:21:19.361Z] 10:21:19 INFO - PID 4732 | 1706437279363 Marionette DEBUG 0 -> [0,1,"WebDriver:NewSession",{"webSocketUrl":true}]
[task 2024-01-28T10:21:19.369Z] 10:21:19 INFO - PID 4732 | 1706437279368 WebDriver BiDi DEBUG Waiting for initial application window
[task 2024-01-28T10:21:19.548Z] 10:21:19 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744_tab_pid2268.log
[task 2024-01-28T10:21:19.751Z] 10:21:19 INFO - PID 4732 | [Parent 3968, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1618
[task 2024-01-28T10:21:20.567Z] 10:21:20 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744_tab_pid2776.log
[task 2024-01-28T10:21:20.599Z] 10:21:20 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744_tab_pid1892.log
[task 2024-01-28T10:21:20.646Z] 10:21:20 INFO - PID 4732 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170643622005554\AppData\Local\Temp\tmpr4z77dzw.mozrunner\runtests_leaks_2744_tab_pid8492.log
[task 2024-01-28T10:21:20.772Z] 10:21:20 INFO - PID 4732 | [WARN webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
<...>
[task 2024-01-28T10:21:25.500Z] 10:21:25 INFO - PID 4732 | 1706437285456 RemoteAgent TRACE [11] ProgressListener Skip setting the unload timer
[task 2024-01-28T10:21:25.501Z] 10:21:25 INFO - PID 4732 | 1706437285459 RemoteAgent TRACE [11] NavigationListener onStateChange, stateFlags: 983041, status: 0, isStart: true, isStop: false, isNetwork: true, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.502Z] 10:21:25 INFO - PID 4732 | 1706437285461 RemoteAgent TRACE [2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67] Navigation started for url: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html (d8618a84-7c50-4c58-80fe-e4dc04d104ac)
[task 2024-01-28T10:21:25.503Z] 10:21:25 INFO - PID 4732 | 1706437285462 RemoteAgent TRACE [2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67] Skipping already tracked navigation, navigationId: d8618a84-7c50-4c58-80fe-e4dc04d104ac
[task 2024-01-28T10:21:25.503Z] 10:21:25 INFO - PID 4732 | 1706437285464 RemoteAgent TRACE [11] ProgressListener Check loading state: isStart=1 isStop=0
[task 2024-01-28T10:21:25.504Z] 10:21:25 INFO - PID 4732 | 1706437285464 RemoteAgent TRACE [11] ProgressListener state=start: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.556Z] 10:21:25 INFO - PID 4732 | 1706437285554 RemoteAgent TRACE [11] NavigationListener onStateChange, stateFlags: 196610, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.573Z] 10:21:25 INFO - PID 4732 | 1706437285565 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"event","method":"network.responseCompleted","params":{"context":"2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67","isBlocked":false,"navigation":"d8618a84-7c50-4c58-80fe-e4dc04d104ac","redirectCount":0,"request":{"bodySize":null,"cookies":[],"headers":[{"name":"Host","value":{"type":"string","value":"web-platform.test:8443"}},{"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":"User-Agent","value":{"type":"string","value":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0"}},{"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":501,"method":"GET","request":"16","timings":{"timeOrigin":0,"requestTime":1706437285456000,"redirectStart":0,"redirectEnd":0,"fetchStart":0,"dnsStart":0,"dnsEnd":0,"connectStart":0,"connectEnd":0,"tlsStart":0,"tlsEnd":0,"requestStart":1706437285458209,"responseStart":1706437285459403,"responseEnd":1706437285459907},"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"},"timestamp":1706437285564,"response":{"bodySize":30,"content":{"size":30},"headersSize":117,"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html","bytesReceived":147,"fromCache":false,"headers":[{"name":"Content-Type","value":{"type":"string","value":"text/html"}},{"name":"Server","value":{"type":"string","value":"BaseHTTP/0.6 Python/3.9.10"}},{"name":"Date","value":{"type":"string","value":"Sun, 28 Jan 2024 10:21:25 GMT"}}],"mimeType":"text/html","protocol":"http/1.1","status":200,"statusText":"OK"}}}
[task 2024-01-28T10:21:25.575Z] 10:21:25 INFO - PID 4732 | 1706437285567 RemoteAgent DEBUG WebDriverBiDiConnection e67a804b-2beb-4e71-957b-ead230db0779 <- {"type":"error","id":68,"error":"unknown error","message":"AbortError: Actor 'MessageHandlerFrame' destroyed before query 'MessageHandlerFrameParent:sendCommand' was resolved","stacktrace":""}
[task 2024-01-28T10:21:25.576Z] 10:21:25 INFO - PID 4732 | 1706437285567 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.576Z] 10:21:25 INFO - PID 4732 | 1706437285567 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.577Z] 10:21:25 INFO - PID 4732 | 1706437285567 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.577Z] 10:21:25 INFO - PID 4732 | 1706437285567 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.579Z] 10:21:25 INFO - PID 4732 | 1706437285568 RemoteAgent TRACE Created MessageHandler WINDOW_GLOBAL for session 6d051f40-1836-42f1-80f1-f718dbb2606d
[task 2024-01-28T10:21:25.580Z] 10:21:25 INFO - PID 4732 | 1706437285569 RemoteAgent TRACE MessageHandler WINDOW_GLOBAL for session 6d051f40-1836-42f1-80f1-f718dbb2606d is being destroyed
[task 2024-01-28T10:21:25.581Z] 10:21:25 INFO - PID 4732 | 1706437285569 RemoteAgent TRACE Unregistered MessageHandler WINDOW_GLOBAL for session 6d051f40-1836-42f1-80f1-f718dbb2606d
[task 2024-01-28T10:21:25.581Z] 10:21:25 INFO - PID 4732 | 1706437285569 Marionette TRACE [11] MarionetteCommands actor destroyed for window id 6442450950
[task 2024-01-28T10:21:25.582Z] 10:21:25 INFO - PID 4732 | 1706437285570 RemoteAgent TRACE [11] NavigationListener onStateChange, stateFlags: 196612, status: 0, isStart: false, isStop: false, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.583Z] 10:21:25 INFO - PID 4732 | 1706437285579 RemoteAgent TRACE [11] NavigationListener onStateChange, stateFlags: 131088, status: 0, isStart: false, isStop: true, isNetwork: false, isBindingAborted: false, targetURI: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.587Z] 10:21:25 INFO - PID 4732 | 1706437285586 RemoteAgent TRACE [2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67] Navigation finished for url: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html (d8618a84-7c50-4c58-80fe-e4dc04d104ac)
[task 2024-01-28T10:21:25.594Z] 10:21:25 INFO - PID 4732 | 1706437285593 RemoteAgent TRACE [11] ProgressListener Check loading state: isStart=0 isStop=16
[task 2024-01-28T10:21:25.595Z] 10:21:25 INFO - PID 4732 | 1706437285593 RemoteAgent TRACE [11] ProgressListener state=stop: https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html
[task 2024-01-28T10:21:25.595Z] 10:21:25 INFO - PID 4732 | 1706437285593 RemoteAgent TRACE [11] ProgressListener Stop: has error=false
[task 2024-01-28T10:21:25.597Z] 10:21:25 INFO - PID 4732 | 1706437285596 RemoteAgent TRACE Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.597Z] 10:21:25 INFO - PID 4732 | 1706437285596 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.599Z] 10:21:25 INFO - PID 4732 | 1706437285596 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.599Z] 10:21:25 INFO - PID 4732 | 1706437285596 RemoteAgent TRACE Received command network._applySessionData for destination ROOT
[task 2024-01-28T10:21:25.600Z] 10:21:25 INFO - PID 4732 | 1706437285596 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.601Z] 10:21:25 INFO - PID 4732 | 1706437285598 RemoteAgent TRACE Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.602Z] 10:21:25 INFO - PID 4732 | 1706437285598 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.603Z] 10:21:25 INFO - PID 4732 | 1706437285598 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.603Z] 10:21:25 INFO - PID 4732 | 1706437285598 RemoteAgent TRACE Received command network._applySessionData for destination ROOT
[task 2024-01-28T10:21:25.604Z] 10:21:25 INFO - PID 4732 | 1706437285598 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.604Z] 10:21:25 INFO - PID 4732 | 1706437285600 RemoteAgent TRACE Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.606Z] 10:21:25 INFO - PID 4732 | 1706437285600 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.607Z] 10:21:25 INFO - PID 4732 | 1706437285600 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.607Z] 10:21:25 INFO - PID 4732 | 1706437285600 RemoteAgent TRACE Received command network._applySessionData for destination ROOT
[task 2024-01-28T10:21:25.608Z] 10:21:25 INFO - PID 4732 | 1706437285600 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.610Z] 10:21:25 INFO - PID 4732 | 1706437285601 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"success","id":74,"result":{"navigation":"d8618a84-7c50-4c58-80fe-e4dc04d104ac","url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"}}
[task 2024-01-28T10:21:25.610Z] 10:21:25 INFO - PID 4732 | 1706437285603 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 -> {"id":75,"method":"session.unsubscribe","params":{"events":["network.responseCompleted"],"contexts":["2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67"]}}
[task 2024-01-28T10:21:25.611Z] 10:21:25 INFO - PID 4732 | 1706437285603 RemoteAgent TRACE Module root/session.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.612Z] 10:21:25 INFO - PID 4732 | 1706437285603 RemoteAgent TRACE Received command session.unsubscribe for destination ROOT
[task 2024-01-28T10:21:25.613Z] 10:21:25 INFO - PID 4732 | 1706437285603 RemoteAgent TRACE Module root/session.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.614Z] 10:21:25 INFO - PID 4732 | 1706437285603 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.614Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent TRACE Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.616Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.616Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.617Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent TRACE Received command network._applySessionData for destination ROOT
[task 2024-01-28T10:21:25.618Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.619Z] 10:21:25 INFO - PID 4732 | 1706437285605 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"success","id":75,"result":{}}
[task 2024-01-28T10:21:25.620Z] 10:21:25 INFO - PID 4732 | 1706437285606 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 -> {"id":76,"method":"session.subscribe","params":{"events":["network.authRequired"]}}
[task 2024-01-28T10:21:25.621Z] 10:21:25 INFO - PID 4732 | 1706437285606 RemoteAgent TRACE Module root/session.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.622Z] 10:21:25 INFO - PID 4732 | 1706437285606 RemoteAgent TRACE Received command session.subscribe for destination ROOT
[task 2024-01-28T10:21:25.623Z] 10:21:25 INFO - PID 4732 | 1706437285606 RemoteAgent TRACE Module root/session.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.623Z] 10:21:25 INFO - PID 4732 | 1706437285606 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.624Z] 10:21:25 INFO - PID 4732 | 1706437285608 RemoteAgent TRACE Module windowglobal-in-root/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.625Z] 10:21:25 INFO - PID 4732 | 1706437285608 RemoteAgent TRACE Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.626Z] 10:21:25 INFO - PID 4732 | 1706437285608 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.626Z] 10:21:25 INFO - PID 4732 | 1706437285608 RemoteAgent TRACE Received command network._applySessionData for destination ROOT
[task 2024-01-28T10:21:25.628Z] 10:21:25 INFO - PID 4732 | 1706437285608 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.628Z] 10:21:25 INFO - PID 4732 | 1706437285609 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"success","id":76,"result":{}}
[task 2024-01-28T10:21:25.630Z] 10:21:25 INFO - PID 4732 | 1706437285610 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 -> {"id":77,"method":"network.addIntercept","params":{"phases":["authRequired"],"urlPatterns":[{"type":"string","pattern":"https://web-platform.test:8443/webdriver/tests/support/http_handlers/authentication.py?username=user&password=password&realm=test"}]}}
[task 2024-01-28T10:21:25.630Z] 10:21:25 INFO - PID 4732 | 1706437285610 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.631Z] 10:21:25 INFO - PID 4732 | 1706437285610 RemoteAgent TRACE Received command network.addIntercept for destination ROOT
[task 2024-01-28T10:21:25.631Z] 10:21:25 INFO - PID 4732 | 1706437285610 RemoteAgent TRACE Module root/network.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.632Z] 10:21:25 INFO - PID 4732 | 1706437285611 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"success","id":77,"result":{"intercept":"7124d3a0-a362-45c9-b08d-bd58e09ea399"}}
[task 2024-01-28T10:21:25.633Z] 10:21:25 INFO - PID 4732 | 1706437285612 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 -> {"id":78,"method":"script.evaluate","params":{"expression":"\n {\n const controller = new AbortController();\n setTimeout(() => controll ... \n signal: controller.signal\n }).then(response => response.text());\n }","target":{"context":"2a2c568d-c9a8-4da5-b5c8-5a8aefa4af67"},"awaitPromise":true}}
[task 2024-01-28T10:21:25.634Z] 10:21:25 INFO - PID 4732 | 1706437285612 RemoteAgent TRACE Module root/script.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.634Z] 10:21:25 INFO - PID 4732 | 1706437285612 RemoteAgent TRACE Received command script.evaluate for destination ROOT
[task 2024-01-28T10:21:25.635Z] 10:21:25 INFO - PID 4732 | 1706437285612 RemoteAgent TRACE Module root/script.sys.mjs found for ROOT
[task 2024-01-28T10:21:25.636Z] 10:21:25 INFO - PID 4732 | 1706437285613 RemoteAgent TRACE Received command script.evaluateExpression for destination WINDOW_GLOBAL
[task 2024-01-28T10:21:25.636Z] 10:21:25 INFO - PID 4732 | 1706437285613 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-28T10:21:25.637Z] 10:21:25 INFO - PID 4732 | 1706437285613 RemoteAgent TRACE Module windowglobal/script.sys.mjs found for WINDOW_GLOBAL
[task 2024-01-28T10:21:29.041Z] 10:21:29 INFO - PID 4732 | [Child 2776, Main Thread] WARNING: IPC message 'PContent::Msg_InsertNewFocusActionId' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-28T10:21:29.041Z] 10:21:29 INFO - PID 4732 | [Child 2776, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-28T10:21:38.852Z] 10:21:38 INFO - PID 4732 | 1706437298850 RemoteAgent DEBUG WebDriverBiDiConnection ae3d8691-5411-4687-bf8a-fc6c41a09ec3 <- {"type":"success","id":78,"result":{"realm":"739a82b6-8fca-4a14-983f-4941307b0f54","type":"exception","exceptionDetails":{"columnNumber":22,"exception":{"type":"error"},"lineNumber":8,"stackTrace":{"callFrames":[{"columnNumber":22,"functionName":"","lineNumber":8,"url":"https://web-platform.test:8443/webdriver/tests/bidi/network/support/empty.html"}]},"text":"AbortError: The operation was aborted. "}}}
[task 2024-01-28T10:23:03.136Z] 10:23:03 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/network/continue_with_auth/invalid.py | expected OK
[task 2024-01-28T10:23:03.137Z] 10:23:03 INFO - TEST-INFO took 105005ms
[task 2024-01-28T10:23:03.138Z] 10:23:03 INFO - Restarting browser for new test group
[task 2024-01-28T10:23:03.170Z] 10:23:03 WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2024-01-28T10:23:13.173Z] 10:23:13 WARNING - Forcibly terminating runner process
[task 2024-01-28T10:23:13.392Z] 10:23:13 INFO - PID 1516 | 1706437393379 geckodriver INFO Listening on 127.0.0.1:54581
[task 2024-01-28T10:23:13.394Z] 10:23:13 INFO - Starting runner
[task 2024-01-28T10:23:14.018Z] 10:23:14 INFO - TEST-START | /webdriver/tests/bidi/network/provide_response/invalid.py
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
All of the classified failures actually are bug 1838145. I've re-classified.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•1 year ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=445448836&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
|
|
||
Comment 4•1 year ago
|
||
(In reply to Treeherder Bug Filer from comment #3)
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=445448836&repo=mozilla-central
This is bug 1838145 again.
Status: REOPENED → RESOLVED
Closed: 1 year ago → 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•