Closed Bug 1757620 Opened 4 years ago Closed 3 years ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/broken-chunked-encoding.https.html | FetchEvent#preloadResponse resolves even if the body is sent with broken chunked encoding. - Test timed out

Categories

(Core :: DOM: Service Workers, defect, P5)

Product:
Core
Component:
DOM: Service Workers
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1810515

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=369589751&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TlV2J30QR_OigD_5jBqacQ/runs/1/artifacts/public/logs/live_backing.log

[task 2022-03-01T18:20:48.784Z] 18:20:48     INFO - TEST-START | /service-workers/service-worker/navigation-preload/broken-chunked-encoding.https.html
[task 2022-03-01T18:20:48.801Z] 18:20:48     INFO - Setting pref javascript.options.streams to true
[task 2022-03-01T18:20:48.811Z] 18:20:48     INFO - Setting pref dom.serviceWorkers.mitigations.bypass_on_fault to false
[task 2022-03-01T18:20:48.819Z] 18:20:48     INFO - Setting pref dom.serviceWorkers.enabled to true
[task 2022-03-01T18:20:51.507Z] 18:20:51     INFO - 
[task 2022-03-01T18:20:51.507Z] 18:20:51     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/broken-chunked-encoding.https.html | FetchEvent#preloadResponse resolves even if the body is sent with broken chunked encoding. - Test timed out
[task 2022-03-01T18:20:51.514Z] 18:20:51     INFO - 
[task 2022-03-01T18:20:51.514Z] 18:20:51     INFO - TEST-UNEXPECTED-NOTRUN | /service-workers/service-worker/navigation-preload/broken-chunked-encoding.https.html | FetchEvent#preloadResponse resolves even if the body is sent with broken chunked encoding with some delays - expected PASS
[task 2022-03-01T18:20:51.516Z] 18:20:51     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/broken-chunked-encoding.https.html | expected OK
[task 2022-03-01T18:20:51.516Z] 18:20:51     INFO - TEST-INFO took 2723ms
[task 2022-03-01T18:20:51.518Z] 18:20:51     INFO - PID 5884 | 1646158851514	Marionette	INFO	Stopped listening on port 61501
[task 2022-03-01T18:20:51.723Z] 18:20:51     INFO - PID 5884 | [Parent 2696, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-03-01T18:20:53.883Z] 18:20:53     INFO - Browser exited with return code 0
[task 2022-03-01T18:20:53.887Z] 18:20:53     INFO - Closing logging queue
[task 2022-03-01T18:20:53.888Z] 18:20:53     INFO - queue closed
[task 2022-03-01T18:20:54.505Z] 18:20:54     INFO - Application command: Z:\task_164615551298936\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_164615551298936\AppData\Local\Temp\tmpp_gszq86
[task 2022-03-01T18:20:54.516Z] 18:20:54     INFO - PID 5976 | 1646158848116	Marionette	INFO	Marionette enabled
[task 2022-03-01T18:20:54.517Z] 18:20:54     INFO - PID 5976 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_164615551298936\\AppData\\Local\\Temp\\tmpbwwlnelt\\search.json.mozlz4", (void 0)))
[task 2022-03-01T18:20:54.518Z] 18:20:54     INFO - PID 5976 | 1646158854431	Marionette	INFO	Listening on port 61707
[task 2022-03-01T18:20:54.519Z] 18:20:54     INFO - Starting runner
[task 2022-03-01T18:20:55.647Z] 18:20:55     INFO - TEST-START | /service-workers/service-worker/navigation-preload/chunked-encoding.https.html
[task 2022-03-01T18:20:55.664Z] 18:20:55     INFO - Setting pref javascript.options.streams to true
[task 2022-03-01T18:20:55.673Z] 18:20:55     INFO - Setting pref dom.serviceWorkers.mitigations.bypass_on_fault to false
[task 2022-03-01T18:20:55.681Z] 18:20:55     INFO - Setting pref dom.serviceWorkers.enabled to true
[task 2022-03-01T18:20:58.355Z] 18:20:58     INFO - 
[task 2022-03-01T18:20:58.355Z] 18:20:58     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/chunked-encoding.https.html | Navigation Preload must work with chunked encoding. - Test timed out
[task 2022-03-01T18:20:58.363Z] 18:20:58     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/chunked-encoding.https.html | expected OK
[task 2022-03-01T18:20:58.363Z] 18:20:58     INFO - TEST-INFO took 2708ms
[task 2022-03-01T18:20:58.365Z] 18:20:58     INFO - PID 5976 | 1646158858363	Marionette	INFO	Stopped listening on port 61707
[task 2022-03-01T18:20:58.378Z] 18:20:58     INFO - PID 5976 | JavaScript error: resource://activity-stream/lib/ActivityStreamPrefs.jsm, line 27: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIPrefBranch.removeObserver]
[task 2022-03-01T18:20:58.509Z] 18:20:58     INFO - PID 5976 | [Parent 3516, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-03-01T18:20:59.457Z] 18:20:59     INFO - PID 5976 | JavaScript error: resource://activity-stream/lib/PlacesFeed.jsm, line 178: NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsINavBookmarksService.addObserver]
[task 2022-03-01T18:21:00.444Z] 18:21:00     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.035Z] 18:21:03     INFO - PID 5976 | JavaScript error: resource://gre/modules/JSONFile.jsm, line 186: Error: Data is not ready.
[task 2022-03-01T18:21:03.706Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.716Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.719Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.721Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.724Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.726Z] 18:21:03     INFO - PID 5976 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:03.739Z] 18:21:03     INFO - PID 5976 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "DoHController: clear state and remove observers"
[task 2022-03-01T18:21:05.411Z] 18:21:05     INFO - PID 5976 | ###!!! [Parent][PGPUParent] Error: RunMessage(msgname=PGPU::Msg_ShutdownVR) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-01T18:21:05.475Z] 18:21:05     INFO - Browser exited with return code 0
[task 2022-03-01T18:21:05.479Z] 18:21:05     INFO - Closing logging queue
[task 2022-03-01T18:21:05.479Z] 18:21:05     INFO - queue closed
[task 2022-03-01T18:21:06.075Z] 18:21:06     INFO - Application command: Z:\task_164615551298936\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_164615551298936\AppData\Local\Temp\tmpyo88ojlt
[task 2022-03-01T18:21:06.097Z] 18:21:06     INFO - PID 6100 | 1646158854934	Marionette	INFO	Marionette enabled
[task 2022-03-01T18:21:06.098Z] 18:21:06     INFO - PID 6100 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_164615551298936\\AppData\\Local\\Temp\\tmpp_gszq86\\search.json.mozlz4", (void 0)))
[task 2022-03-01T18:21:06.099Z] 18:21:06     INFO - PID 6100 | 1646158859060	Marionette	INFO	Listening on port 61733
[task 2022-03-01T18:21:06.099Z] 18:21:06     INFO - Starting runner
[task 2022-03-01T18:21:07.147Z] 18:21:07     INFO - TEST-START | /service-workers/service-worker/navigation-preload/empty-preload-response-body.https.html
[task 2022-03-01T18:21:07.158Z] 18:21:07     INFO - Setting pref javascript.options.streams to true
[task 2022-03-01T18:21:07.167Z] 18:21:07     INFO - Setting pref dom.serviceWorkers.mitigations.bypass_on_fault to false
[task 2022-03-01T18:21:07.176Z] 18:21:07     INFO - Setting pref dom.serviceWorkers.enabled to true
[task 2022-03-01T18:21:09.870Z] 18:21:09     INFO - 
[task 2022-03-01T18:21:09.870Z] 18:21:09     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/empty-preload-response-body.https.html | Navigation Preload empty response body. - Test timed out
[task 2022-03-01T18:21:09.878Z] 18:21:09     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/empty-preload-response-body.https.html | expected OK
[task 2022-03-01T18:21:09.878Z] 18:21:09     INFO - TEST-INFO took 2723ms
[task 2022-03-01T18:21:09.880Z] 18:21:09     INFO - PID 6100 | 1646158869878	Marionette	INFO	Stopped listening on port 61733
[task 2022-03-01T18:21:10.239Z] 18:21:10     INFO - PID 6100 | JavaScript error: resource://gre/modules/JSONFile.jsm, line 186: Error: Data is not ready.
[task 2022-03-01T18:21:10.246Z] 18:21:10     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.389Z] 18:21:11     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.406Z] 18:21:11     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.409Z] 18:21:11     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.412Z] 18:21:11     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.414Z] 18:21:11     INFO - PID 6100 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:11.422Z] 18:21:11     INFO - PID 6100 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "DoHController: clear state and remove observers"
[task 2022-03-01T18:21:13.011Z] 18:21:13     INFO - Browser exited with return code 0
[task 2022-03-01T18:21:13.015Z] 18:21:13     INFO - Closing logging queue
[task 2022-03-01T18:21:13.015Z] 18:21:13     INFO - queue closed
[task 2022-03-01T18:21:13.412Z] 18:21:13     INFO - Application command: Z:\task_164615551298936\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_164615551298936\AppData\Local\Temp\tmp2__4novf
[task 2022-03-01T18:21:13.427Z] 18:21:13     INFO - PID 8296 | 1646158866788	Marionette	INFO	Marionette enabled
[task 2022-03-01T18:21:13.428Z] 18:21:13     INFO - PID 8296 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_164615551298936\\AppData\\Local\\Temp\\tmpyo88ojlt\\search.json.mozlz4", (void 0)))
[task 2022-03-01T18:21:13.429Z] 18:21:13     INFO - PID 8296 | 1646158872979	Marionette	INFO	Listening on port 61763
[task 2022-03-01T18:21:13.429Z] 18:21:13     INFO - Starting runner
[task 2022-03-01T18:21:14.516Z] 18:21:14     INFO - TEST-START | /service-workers/service-worker/navigation-preload/get-state.https.html
[task 2022-03-01T18:21:14.533Z] 18:21:14     INFO - Setting pref javascript.options.streams to true
[task 2022-03-01T18:21:14.543Z] 18:21:14     INFO - Setting pref dom.serviceWorkers.mitigations.bypass_on_fault to false
[task 2022-03-01T18:21:14.557Z] 18:21:14     INFO - Setting pref dom.serviceWorkers.enabled to true
[task 2022-03-01T18:21:17.260Z] 18:21:17     INFO - 
[task 2022-03-01T18:21:17.267Z] 18:21:17     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/get-state.https.html | getState - Test timed out
[task 2022-03-01T18:21:17.267Z] 18:21:17     INFO - 
[task 2022-03-01T18:21:17.267Z] 18:21:17     INFO - TEST-UNEXPECTED-NOTRUN | /service-workers/service-worker/navigation-preload/get-state.https.html | getState from a worker - expected PASS
[task 2022-03-01T18:21:17.269Z] 18:21:17     INFO - 
[task 2022-03-01T18:21:17.269Z] 18:21:17     INFO - TEST-UNEXPECTED-NOTRUN | /service-workers/service-worker/navigation-preload/get-state.https.html | no active worker - expected PASS
[task 2022-03-01T18:21:17.270Z] 18:21:17     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/navigation-preload/get-state.https.html | expected OK
[task 2022-03-01T18:21:17.270Z] 18:21:17     INFO - TEST-INFO took 2744ms
[task 2022-03-01T18:21:17.272Z] 18:21:17     INFO - PID 8296 | 1646158877269	Marionette	INFO	Stopped listening on port 61763
[task 2022-03-01T18:21:17.284Z] 18:21:17     INFO - PID 8296 | JavaScript error: resource://activity-stream/lib/ActivityStreamPrefs.jsm, line 27: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIPrefBranch.removeObserver]
[task 2022-03-01T18:21:17.391Z] 18:21:17     INFO - PID 8296 | [Parent 7356, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-03-01T18:21:18.277Z] 18:21:18     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:19.749Z] 18:21:19     INFO - PID 8296 | JavaScript error: resource://gre/modules/JSONFile.jsm, line 186: Error: Data is not ready.
[task 2022-03-01T18:21:21.100Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.114Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.118Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.120Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.122Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.125Z] 18:21:21     INFO - PID 8296 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2022-03-01T18:21:21.137Z] 18:21:21     INFO - PID 8296 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "DoHController: clear state and remove observers"
[task 2022-03-01T18:21:22.920Z] 18:21:22     INFO - PID 8296 | ###!!! [Parent][PGPUParent] Error: RunMessage(msgname=PGPU::Msg_ShutdownVR) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-01T18:21:22.989Z] 18:21:22     INFO - Browser exited with return code 0
[task 2022-03-01T18:21:22.993Z] 18:21:22     INFO - Closing logging queue
[task 2022-03-01T18:21:22.993Z] 18:21:22     INFO - queue closed
[task 2022-03-01T18:21:23.636Z] 18:21:23     INFO - Application command: Z:\task_164615551298936\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_164615551298936\AppData\Local\Temp\tmpprl0lhvt
[task 2022-03-01T18:21:23.644Z] 18:21:23     INFO - PID 8128 | 1646158873802	Marionette	INFO	Marionette enabled
[task 2022-03-01T18:21:23.646Z] 18:21:23     INFO - PID 8128 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_164615551298936\\AppData\\Local\\Temp\\tmp2__4novf\\search.json.mozlz4", (void 0)))
[task 2022-03-01T18:21:23.646Z] 18:21:23     INFO - PID 8128 | 1646158877756	Marionette	INFO	Listening on port 61791
[task 2022-03-01T18:21:23.647Z] 18:21:23     INFO - Starting runner
[task 2022-03-01T18:21:24.728Z] 18:21:24     INFO - TEST-START | /service-workers/service-worker/navigation-preload/navigationPreload.https.html

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=382083868&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
Duplicate of bug: 1810515
Resolution: INCOMPLETE → DUPLICATE
You need to log in before you can comment on or make changes to this bug.