Closed Bug 1830450 Opened 3 years ago Closed 2 years ago

Intermittent TCw Exception: Failure while resetting counters: {"name": "NotFoundError", "message": "Could not move source file(C:\\Users\\task_168263848083979\\AppData\\Local\\Temp\\tmpxvo_c5gc) because it does not exist", "code": 8, "result": 2152923144,

Categories

(Core :: Networking: HTTP, defect, P5)

Product:
Core
Component:
Networking: HTTP
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2023-04-27T23:51:45.873Z] 23:51:45     INFO - TEST-START | /webtransport/streams-echo.https.any.serviceworker.html
[task 2023-04-27T23:51:45.889Z] 23:51:45     INFO - Setting pref network.webtransport.enabled to true
[task 2023-04-27T23:51:45.904Z] 23:51:45     INFO - Setting pref network.webtransport.datagrams.enabled to true
[task 2023-04-27T23:51:46.092Z] 23:51:46     INFO - PID 5368 | [CodeCoverage] Requested flush for 5640.
[task 2023-04-27T23:51:47.316Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:47.583Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:47.598Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] Requested flush for 7756.
[task 2023-04-27T23:51:47.599Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] Requested flush for 8108.
[task 2023-04-27T23:51:47.599Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] Requested flush for 7844.
[task 2023-04-27T23:51:47.600Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] Requested flush for 9084.
[task 2023-04-27T23:51:47.601Z] 23:51:47     INFO - PID 5368 | [CodeCoverage] Requested flush for 3652.
[task 2023-04-27T23:51:48.588Z] 23:51:48     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:48.611Z] 23:51:48     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:50.988Z] 23:51:50     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:51.004Z] 23:51:51     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:53.296Z] 23:51:53     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:53.546Z] 23:51:53     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:55.524Z] 23:51:55     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:55.538Z] 23:51:55     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:57.989Z] 23:51:57     INFO - PID 5368 | [CodeCoverage] flush completed.
[task 2023-04-27T23:51:57.996Z] 23:51:57     INFO - PID 5368 | [CodeCoverage] JS flush completed.
[task 2023-04-27T23:51:59.070Z] 23:51:59  WARNING - Traceback (most recent call last):
[task 2023-04-27T23:51:59.070Z] 23:51:59  WARNING -   File "Z:\task_168263848083979\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 848, in run_func
[task 2023-04-27T23:51:59.070Z] 23:51:59  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2023-04-27T23:51:59.070Z] 23:51:59  WARNING -   File "Z:\task_168263848083979\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 955, in do_testharness
[task 2023-04-27T23:51:59.071Z] 23:51:59  WARNING -     self.protocol.coverage.reset()
[task 2023-04-27T23:51:59.071Z] 23:51:59  WARNING -   File "Z:\task_168263848083979\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 553, in reset
[task 2023-04-27T23:51:59.071Z] 23:51:59  WARNING -     raise Exception('Failure while resetting counters: %s' % json.dumps(error))
[task 2023-04-27T23:51:59.071Z] 23:51:59  WARNING - Exception: Failure while resetting counters: {"name": "NotFoundError", "message": "Could not move source file(C:\\Users\\task_168263848083979\\AppData\\Local\\Temp\\tmpxvo_c5gc) because it does not exist", "code": 8, "result": 2152923144, "filename": "", "lineNumber": 0, "columnNumber": 0, "data": null, "stack": "", "INDEX_SIZE_ERR": 1, "DOMSTRING_SIZE_ERR": 2, "HIERARCHY_REQUEST_ERR": 3, "WRONG_DOCUMENT_ERR": 4, "INVALID_CHARACTER_ERR": 5, "NO_DATA_ALLOWED_ERR": 6, "NO_MODIFICATION_ALLOWED_ERR": 7, "NOT_FOUND_ERR": 8, "NOT_SUPPORTED_ERR": 9, "INUSE_ATTRIBUTE_ERR": 10, "INVALID_STATE_ERR": 11, "SYNTAX_ERR": 12, "INVALID_MODIFICATION_ERR": 13, "NAMESPACE_ERR": 14, "INVALID_ACCESS_ERR": 15, "VALIDATION_ERR": 16, "TYPE_MISMATCH_ERR": 17, "SECURITY_ERR": 18, "NETWORK_ERR": 19, "ABORT_ERR": 20, "URL_MISMATCH_ERR": 21, "QUOTA_EXCEEDED_ERR": 22, "TIMEOUT_ERR": 23, "INVALID_NODE_TYPE_ERR": 24, "DATA_CLONE_ERR": 25, "location": null}
[task 2023-04-27T23:51:59.071Z] 23:51:59  WARNING - 
[task 2023-04-27T23:51:59.074Z] 23:51:59     INFO - TEST-KNOWN-INTERMITTENT-ERROR | /webtransport/streams-echo.https.any.serviceworker.html | took 13197ms
[task 2023-04-27T23:51:59.076Z] 23:51:59     INFO - PID 5368 | 1682639519073	Marionette	INFO	Stopped listening on port 60136
[task 2023-04-27T23:51:59.116Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.116Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.116Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.125Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.127Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.132Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.141Z] 23:51:59     INFO - PID 5368 | [Child 7756, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:51:59.744Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-3652-0.info'.
[task 2023-04-27T23:51:59.767Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-8108-1.info'.
[task 2023-04-27T23:51:59.769Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-3652-1.info'.
[task 2023-04-27T23:51:59.769Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-3652-2.info'.
[task 2023-04-27T23:51:59.769Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-8108-2.info'.
[task 2023-04-27T23:51:59.807Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7844-0.info'.
[task 2023-04-27T23:51:59.807Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-8580-0.info'.
[task 2023-04-27T23:51:59.816Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-1976-0.info'.
[task 2023-04-27T23:51:59.817Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-1.info'.
[task 2023-04-27T23:51:59.817Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-2.info'.
[task 2023-04-27T23:51:59.823Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-3.info'.
[task 2023-04-27T23:51:59.824Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-4.info'.
[task 2023-04-27T23:51:59.825Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-5.info'.
[task 2023-04-27T23:51:59.826Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-6.info'.
[task 2023-04-27T23:51:59.827Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-7.info'.
[task 2023-04-27T23:51:59.834Z] 23:51:59     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639519-7756-8.info'.
[task 2023-04-27T23:51:59.870Z] 23:51:59     INFO - PID 5368 | *** You are running in background task mode. ***
[task 2023-04-27T23:51:59.870Z] 23:51:59     INFO - PID 5368 | *** You are running in headless mode.
[task 2023-04-27T23:52:00.027Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-6.info'.
[task 2023-04-27T23:52:00.027Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-7.info'.
[task 2023-04-27T23:52:00.074Z] 23:52:00     INFO - PID 5368 | console.error: "C:\\Users\\task_168263848083979\\AppData\\Local\\Temp\\tmpotpkdr_3\\storage" "to-be-removed" 0 "" ""
[task 2023-04-27T23:52:00.080Z] 23:52:00     INFO - PID 5368 | console.error: "started removing C:\\Users\\task_168263848083979\\AppData\\Local\\Temp\\tmpotpkdr_3\\storage\\to-be-removed"
[task 2023-04-27T23:52:00.080Z] 23:52:00     INFO - PID 5368 | console.error: "done removing C:\\Users\\task_168263848083979\\AppData\\Local\\Temp\\tmpotpkdr_3\\storage\\to-be-removed. removed 1 entries."
[task 2023-04-27T23:52:00.080Z] 23:52:00     INFO - PID 5368 | console.warn: "Skipping Glean as no metrics id is passed"
[task 2023-04-27T23:52:00.122Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-3816-0.info'.
[task 2023-04-27T23:52:00.216Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-8.info'.
[task 2023-04-27T23:52:00.499Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-9.info'.
[task 2023-04-27T23:52:00.499Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-10.info'.
[task 2023-04-27T23:52:00.499Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-11.info'.
[task 2023-04-27T23:52:00.499Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-12.info'.
[task 2023-04-27T23:52:00.499Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-13.info'.
[task 2023-04-27T23:52:00.515Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-14.info'.
[task 2023-04-27T23:52:00.515Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-15.info'.
[task 2023-04-27T23:52:00.515Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-16.info'.
[task 2023-04-27T23:52:00.515Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-17.info'.
[task 2023-04-27T23:52:00.594Z] 23:52:00     INFO - PID 5368 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639520-5640-18.info'.
[task 2023-04-27T23:52:00.672Z] 23:52:00     INFO - PID 5368 | [Parent 5640, IPC I/O Parent] WARNING: Process 7756 may be hanging at shutdown; will wait for up to 80000ms: file Z:/task_168263423962506/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-04-27T23:52:04.268Z] 23:52:04     INFO - PID 5368 | [Parent 5640, IPC I/O Parent] WARNING: Process 8108 may be hanging at shutdown; will wait for up to 76390ms: file Z:/task_168263423962506/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-04-27T23:52:05.237Z] 23:52:05     INFO - Browser exited with return code 0
[task 2023-04-27T23:52:05.241Z] 23:52:05     INFO - Closing logging queue
[task 2023-04-27T23:52:05.242Z] 23:52:05     INFO - queue closed
[task 2023-04-27T23:52:05.269Z] 23:52:05     INFO - Application command: Z:\task_168263848083979\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_168263848083979\AppData\Local\Temp\tmpetktujnz
[task 2023-04-27T23:52:05.286Z] 23:52:05     INFO - Starting runner
[task 2023-04-27T23:52:06.207Z] 23:52:06     INFO - PID 2428 | 1682639526210	Marionette	INFO	Marionette enabled
[task 2023-04-27T23:52:06.213Z] 23:52:06     INFO - PID 2428 | 1682639526216	Marionette	INFO	Listening on port 60150
[task 2023-04-27T23:52:07.554Z] 23:52:07     INFO - PID 2428 | JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2023-04-27T23:52:07.695Z] 23:52:07     INFO - PID 2428 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-04-27T23:52:08.684Z] 23:52:08     INFO - PID 2428 | [Child 7532, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:52:08.695Z] 23:52:08     INFO - PID 2428 | [Child 7532, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file Z:/task_168263423962506/build/src/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-27T23:52:08.888Z] 23:52:08     INFO - PID 2428 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639528-4432-0.info'.
[task 2023-04-27T23:52:08.903Z] 23:52:08     INFO - PID 2428 | Warning: LCovRuntime::init: Cannot open file named 'C:\Users\task_168263848083979\AppData\Local\Temp\tmp2hjk9vx4/1682639528-4432-1.info'.
[task 2023-04-27T23:52:09.813Z] 23:52:09     INFO - PID 2428 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-04-27T23:52:09.827Z] 23:52:09     INFO - PID 2428 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-04-27T23:52:09.827Z] 23:52:09     INFO - PID 2428 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-04-27T23:52:10.300Z] 23:52:10     INFO - TEST-START | /webtransport/streams-echo.https.any.sharedworker.html

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
You need to log in before you can comment on or make changes to this bug.