Closed
Bug 1650685
Opened 6 years ago
Closed 6 years ago
Intermittent Tier 2 TEST-UNEXPECTED-TIMEOUT | /network-error-logging/no-report-on-unexpired-cached-response.https.html | expected OK
Categories
(Testing :: web-platform-tests, defect, P5)
Testing
web-platform-tests
Tracking
(Not tracked)
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=308633268&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B0PI2DXOTW-DJ1RSrW4cRg/runs/0/artifacts/public/logs/live_backing.log
[task 2020-07-05T22:33:58.180Z] 22:33:58 INFO - TEST-START | /network-error-logging/no-report-on-unexpired-cached-response.https.html
[task 2020-07-05T22:33:58.180Z] 22:33:58 INFO - Closing window 24
[task 2020-07-05T22:33:59.262Z] 22:33:59 INFO - ........
[task 2020-07-05T22:33:59.262Z] 22:33:59 INFO - TEST-TIMEOUT | /mixed-content/gen/sharedworker-module-data.meta/unset/xhr.https.html | took 16781ms
[task 2020-07-05T22:33:59.262Z] 22:33:59 INFO - Restarting browser for new test group
[task 2020-07-05T22:33:59.267Z] 22:33:59 INFO - PID 1957 | 1593988439263 Marionette INFO Stopped listening on port 53541
[task 2020-07-05T22:33:59.561Z] 22:33:59 INFO - PID 1957 | [Child 1989, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1432
[task 2020-07-05T22:33:59.565Z] 22:33:59 INFO - PID 1957 | [Child 1989, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp, line 1915
[task 2020-07-05T22:33:59.582Z] 22:33:59 INFO - PID 1957 | [Child 1959, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:33:59.582Z] 22:33:59 INFO - PID 1957 | [Child 1960, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:33:59.609Z] 22:33:59 INFO - PID 1957 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-05T22:33:59.609Z] 22:33:59 INFO - PID 1957 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-05T22:33:59.742Z] 22:33:59 INFO - PID 1957 | [2020-07-05T22:33:59Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-07-05T22:33:59.743Z] 22:33:59 INFO - PID 1957 | [2020-07-05T22:33:59Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-07-05T22:33:59.813Z] 22:33:59 INFO - PID 1957 | [Child 1960, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:33:59.843Z] 22:33:59 INFO - PID 1957 | [Child 1960, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:33:59.887Z] 22:33:59 INFO - PID 1957 | [Child 1959, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:33:59.894Z] 22:33:59 INFO - PID 1957 | [Child 1959, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:34:00.138Z] 22:34:00 INFO - PID 1957 | [Child 1989, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:34:00.138Z] 22:34:00 INFO - PID 1957 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-05T22:34:00.204Z] 22:34:00 INFO - PID 1957 | [Child 1961, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:34:00.204Z] 22:34:00 INFO - PID 1957 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-07-05T22:34:00.204Z] 22:34:00 INFO - PID 1957 | [Child 1994, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:34:00.238Z] 22:34:00 INFO - PID 1957 | [Child 1994, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:34:00.245Z] 22:34:00 INFO - PID 1957 | [Child 1994, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:34:00.252Z] 22:34:00 INFO - PID 1957 | [Child 1989, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:34:00.315Z] 22:34:00 INFO - PID 1957 | [Child 1989, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:34:00.423Z] 22:34:00 INFO - PID 1957 | [Child 1961, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:34:00.502Z] 22:34:00 INFO - PID 1957 | [Child 1961, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:34:00.546Z] 22:34:00 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1277
[task 2020-07-05T22:34:00.546Z] 22:34:00 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm, line 714
[task 2020-07-05T22:34:00.590Z] 22:34:00 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 227
[task 2020-07-05T22:34:00.590Z] 22:34:00 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 254
[task 2020-07-05T22:34:01.447Z] 22:34:01 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-07-05T22:34:01.487Z] 22:34:01 INFO - PID 1957 | [Parent 1957, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp, line 4383
[task 2020-07-05T22:34:01.604Z] 22:34:01 INFO - Browser exited with return code 0
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - PROCESS LEAKS /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605.log
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - leakcheck | Processing log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605.log for scope /mixed-content/gen/sharedworker-module-data.meta
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 2100 bytes
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2020-07-05T22:34:01.605Z] 22:34:01 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1963.log
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1963
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.609Z] 22:34:01 INFO - 0 |TOTAL | 28 0| 80172 0|
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 979 entries
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1962.log
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.623Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1962
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO - 0 |TOTAL | 21 0| 122463 0|
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.624Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 977 entries
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1989.log
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1989
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.625Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.626Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.626Z] 22:34:01 INFO - 0 |TOTAL | 16 0| 208043 0|
[task 2020-07-05T22:34:01.626Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.626Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 981 entries
[task 2020-07-05T22:34:01.626Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.635Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1960.log
[task 2020-07-05T22:34:01.635Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.635Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1960
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO - 0 |TOTAL | 11 0| 562573 0|
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 880 entries
[task 2020-07-05T22:34:01.636Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.637Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1961.log
[task 2020-07-05T22:34:01.637Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.637Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1961
[task 2020-07-05T22:34:01.637Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.637Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.638Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.638Z] 22:34:01 INFO - 0 |TOTAL | 22 0| 365899 0|
[task 2020-07-05T22:34:01.638Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.638Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 1005 entries
[task 2020-07-05T22:34:01.644Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.644Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1965.log
[task 2020-07-05T22:34:01.646Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1965
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - 0 |TOTAL | 27 0| 84113 0|
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 977 entries
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1959.log
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1959
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.647Z] 22:34:01 INFO - 0 |TOTAL | 13 0| 569720 0|
[task 2020-07-05T22:34:01.659Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.659Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 881 entries
[task 2020-07-05T22:34:01.659Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.660Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605_tab_pid1967.log
[task 2020-07-05T22:34:01.660Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.660Z] 22:34:01 INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 1967
[task 2020-07-05T22:34:01.660Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.660Z] 22:34:01 INFO - |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2020-07-05T22:34:01.661Z] 22:34:01 INFO - | | Per-Inst Leaked| Total Rem|
[task 2020-07-05T22:34:01.661Z] 22:34:01 INFO - 0 |TOTAL | 14 0| 275985 0|
[task 2020-07-05T22:34:01.661Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.661Z] 22:34:01 INFO -
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - TEST-UNEXPECTED-TIMEOUT | /network-error-logging/no-report-on-unexpired-cached-response.https.html |
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - Test that NEL reports are not sent for cached responses that don't hit the
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - network
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - - Test timed out
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - nsTraceRefcnt::DumpStatistics: 981 entries
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - leakcheck | Processing leak log file /var/folders/14/_kl_s0_s5733f_qksjtxbgf8000017/T/tmp0r_XdJ/runtests_leaks_1605.log
[task 2020-07-05T22:34:01.662Z] 22:34:01 INFO - TEST-UNEXPECTED-TIMEOUT | /network-error-logging/no-report-on-unexpired-cached-response.https.html | expected OK
[task 2020-07-05T22:34:01.663Z] 22:34:01 INFO - TEST-INFO took 3496ms
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•6 years ago
|
||
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.
Description
•