Closed Bug 1587679 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/state.https.html | Service Worker state property and "statechange" event - Test timed out | expected OK

Categories

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

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

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=270523651&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/XknRujwGTsyEEVUBYbvMSg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-10-09T19:02:00.048Z] 19:02:00 INFO - TEST-START | /service-workers/service-worker/state.https.html
[task 2019-10-09T19:02:00.055Z] 19:02:00 INFO - Closing window 329
[task 2019-10-09T19:02:00.110Z] 19:02:00 INFO - PID 2632 | ++DOCSHELL 00D65400 == 1 [pid = 4688] [id = {51368678-db6e-4a6b-b9cc-a628b6dd0791}]
[task 2019-10-09T19:02:00.110Z] 19:02:00 INFO - PID 2632 | [Child 4688, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/widget/windows/nsLookAndFeel.cpp, line 853
[task 2019-10-09T19:02:00.111Z] 19:02:00 INFO - PID 2632 | ++DOMWINDOW == 1 (00DB3160) [pid = 4688] [serial = 1] [outer = 00000000]
[task 2019-10-09T19:02:00.111Z] 19:02:00 INFO - PID 2632 | ++DOMWINDOW == 2 (00D6D400) [pid = 4688] [serial = 2] [outer = 00DB3160]
[task 2019-10-09T19:02:00.131Z] 19:02:00 INFO - PID 2632 | [Child 4688, Main Thread] WARNING: 'NS_FAILED(GetAccentColor(unused))', file z:/build/build/src/widget/windows/nsLookAndFeel.cpp, line 481
[task 2019-10-09T19:02:00.166Z] 19:02:00 INFO - PID 2632 | [Child 6124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.166Z] 19:02:00 INFO - PID 2632 | [Child 6124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.169Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.177Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.177Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.178Z] 19:02:00 INFO - PID 2632 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-09T19:02:00.185Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.185Z] 19:02:00 INFO - PID 2632 | [Parent 5708, G[Child 6124, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHeckoadOwnerWindow || mOwnerW_indow) IfaileOThread] WAd:R file z:/build/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-10-09T19:02:00.185Z] 19:02:00 INFO - PID 2632 | NING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.185Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:00.252Z] 19:02:00 INFO - PID 2632 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1570640706\appdata\local\temp\tmp1neni_.mozrunner\runtests_leaks_3832_tab_pid5312.log
[task 2019-10-09T19:02:00.279Z] 19:02:00 INFO - PID 2632 | --DOCSHELL 07C87800 == 1 [pid = 6124] [id = {5c8472be-8c61-42ee-9bef-64142ddecb78}] [url = https://web-platform.test:8443/service-workers/service-worker/resources/blank.html?skip-waiting]
[task 2019-10-09T19:02:00.280Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 5 (089BC800) [pid = 6124] [serial = 3] [outer = 00000000] [url = about:blank]
[task 2019-10-09T19:02:00.280Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 4 (00B6D400) [pid = 6124] [serial = 2] [outer = 00000000] [url = about:blank]
[task 2019-10-09T19:02:00.280Z] 19:02:00 INFO - PID 2632 | --DOCSHELL 00B65400 == 0 [pid = 6124] [id = {8d64d57b-46f0-4618-8568-6b13f7e25f5e}] [url = https://web-platform.test:8443/service-workers/service-worker/skip-waiting.https.html]
[task 2019-10-09T19:02:00.288Z] 19:02:00 INFO - PID 2632 | [Child 5312, Main Thread] WARNING: No CID found when attempting to map contract ID: file z:/build/build/src/xpcom/components/nsComponentManager.cpp, line 732
[task 2019-10-09T19:02:00.297Z] 19:02:00 INFO - PID 2632 | ++DOMWINDOW == 3 (088C2800) [pid = 4688] [serial = 3] [outer = 00DB3160]
[task 2019-10-09T19:02:00.312Z] 19:02:00 INFO - PID 2632 | [Child 4688, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file z:/build/build/src/dom/base/ThirdPartyUtil.cpp, line 217
[task 2019-10-09T19:02:00.332Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 3 (00BAF940) [pid = 6124] [serial = 5] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/resources/blank.html?skip-waiting]
[task 2019-10-09T19:02:00.332Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 2 (00BAF160) [pid = 6124] [serial = 1] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/skip-waiting.https.html]
[task 2019-10-09T19:02:00.332Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 1 (07C89400) [pid = 6124] [serial = 6] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/resources/blank.html?skip-waiting]
[task 2019-10-09T19:02:00.332Z] 19:02:00 INFO - PID 2632 | --DOMWINDOW == 0 (07C81C00) [pid = 6124] [serial = 4] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/skip-waiting.https.html]
[task 2019-10-09T19:02:00.371Z] 19:02:00 INFO - PID 2632 | nsStringStats
[task 2019-10-09T19:02:00.371Z] 19:02:00 INFO - PID 2632 | => mAllocCount: 7771
[task 2019-10-09T19:02:00.371Z] 19:02:00 INFO - PID 2632 | => mReallocCount: 0
[task 2019-10-09T19:02:00.372Z] 19:02:00 INFO - PID 2632 | => mFreeCount: 7771
[task 2019-10-09T19:02:00.372Z] 19:02:00 INFO - PID 2632 | => mShareCount: 9257
[task 2019-10-09T19:02:00.372Z] 19:02:00 INFO - PID 2632 | => mAdoptCount: 583
[task 2019-10-09T19:02:00.372Z] 19:02:00 INFO - PID 2632 | => mAdoptFreeCount: 597
[task 2019-10-09T19:02:00.372Z] 19:02:00 INFO - PID 2632 | => Process ID: 6124, Thread ID: 5536
[task 2019-10-09T19:02:00.373Z] 19:02:00 INFO - PID 2632 | ++DOMWINDOW == 4 (0BD82C00) [pid = 4688] [serial = 4] [outer = 00DB3160]
[task 2019-10-09T19:02:00.480Z] 19:02:00 INFO - PID 2632 | [Parent 5708, Main Thread] WARNING: '!window', file z:/build/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-10-09T19:02:01.200Z] 19:02:01 INFO - PID 2632 | --DOMWINDOW == 1 (0C056000) [pid = 1728] [serial = 6] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/resources/respond-with-body-accessed-response-iframe.html]
[task 2019-10-09T19:02:01.200Z] 19:02:01 INFO - PID 2632 | --DOMWINDOW == 0 (0C05E800) [pid = 1728] [serial = 4] [outer = 00000000] [url = https://web-platform.test:8443/service-workers/service-worker/respond-with-body-accessed-response.https.html]
[task 2019-10-09T19:02:14.475Z] 19:02:14 INFO - PID 2632 | --DOMWINDOW == 3 (00D6D400) [pid = 4688] [serial = 2] [outer = 00000000] [url = about:blank]
[task 2019-10-09T19:02:14.475Z] 19:02:14 INFO - PID 2632 | --DOMWINDOW == 2 (088C2800) [pid = 4688] [serial = 3] [outer = 00000000] [url = about:blank]
[task 2019-10-09T19:02:17.615Z] 19:02:17 INFO - PID 2632 | [Parent 5708, IPDL Background] WARNING: 'aResult.IsReject()', file z:/build/build/src/dom/workers/remoteworkers/RemoteWorkerController.cpp, line 444
[task 2019-10-09T19:02:17.615Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.615Z] 19:02:17 INFO - PID 2632 | [Parent 5708, IPDL Background] WARNING: 'aResult.IsReject()', file z:/build/build/src/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp, line 108
[task 2019-10-09T19:02:17.618Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.619Z] 19:02:17 INFO - PID 2632 | [Child 1728, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.619Z] 19:02:17 INFO - PID 2632 | [Child 1728, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.634Z] 19:02:17 INFO - PID 2632 | [Child 1728, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file z:/build/build/src/dom/workers/WorkerPrivate.cpp, line 1418
[task 2019-10-09T19:02:17.635Z] 19:02:17 INFO - PID 2632 | [Child 1728, Main Thread] WARNING: Failed to dispatch offline status change event!: file z:/build/build/src/dom/workers/WorkerPrivate.cpp, line 1895
[task 2019-10-09T19:02:17.635Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.635Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.636Z] 19:02:17 INFO - PID 2632 | [P
[task 2019-10-09T19:02:17.636Z] 19:02:17 INFO - PID 2632 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-09T19:02:17.636Z] 19:02:17 INFO - PID 2632 | arent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.636Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.637Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.637Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.637Z] 19:02:17 INFO - PID 2632 | [Parent 5708, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-10-09T19:02:17.698Z] 19:02:17 INFO - PID 2632 | nsStringStats
[task 2019-10-09T19:02:17.699Z] 19:02:17 INFO - PID 2632 | => mAllocCount: 14733
[task 2019-10-09T19:02:17.699Z] 19:02:17 INFO - PID 2632 | => mReallocCount: 0
[task 2019-10-09T19:02:17.700Z] 19:02:17 INFO - PID 2632 | => mFreeCount: 14733
[task 2019-10-09T19:02:17.701Z] 19:02:17 INFO - PID 2632 | => mShareCount: 30301
[task 2019-10-09T19:02:17.701Z] 19:02:17 INFO - PID 2632 | => mAdoptCount: 707
[task 2019-10-09T19:02:17.701Z] 19:02:17 INFO - PID 2632 | => mAdoptFreeCount: 745
[task 2019-10-09T19:02:17.701Z] 19:02:17 INFO - PID 2632 | => Process ID: 1728, Thread ID: 4164
[task 2019-10-09T19:02:30.508Z] 19:02:30 INFO -
[task 2019-10-09T19:02:30.508Z] 19:02:30 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/state.https.html | Service Worker state property and "statechange" event - Test timed out
[task 2019-10-09T19:02:30.508Z] 19:02:30 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/state.https.html | expected OK
[task 2019-10-09T19:02:30.508Z] 19:02:30 INFO - TEST-INFO took 30460ms
[task 2019-10-09T19:02:30.537Z] 19:02:30 INFO - PID 2632 | 1570647750525 Marionette INFO Stopped listening on port 49433
...

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.