Closed Bug 1720778 Opened 5 years ago Closed 4 years ago

Intermittent TV netwerk/test/unit/test_http3.js | SlowReceiverListener.prototype.onStopRequest - [SlowReceiverListener.prototype.onStopRequest : 486] 2152398924 == 0

Categories

(Core :: Networking, defect, P5)

Product:
Core
Component:
Networking
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=345347574&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ALK-iDhwSRCfnrXwfHUpZw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ALK-iDhwSRCfnrXwfHUpZw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-07-15T19:48:48.451Z] 19:48:48     INFO -  TEST-PASS | netwerk/test/unit/test_http3.js | testOnStartRequest - [testOnStartRequest : 154] 0 == 0
[task 2021-07-15T19:48:48.451Z] 19:48:48     INFO -  TEST-PASS | netwerk/test/unit/test_http3.js | testOnStartRequest - [testOnStartRequest : 156] 200 == 200
[task 2021-07-15T19:48:48.451Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10250->10250
[task 2021-07-15T19:48:48.451Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10254->10252
[task 2021-07-15T19:48:48.452Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10256->10256
[task 2021-07-15T19:48:48.452Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10259->10258
[task 2021-07-15T19:48:48.452Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10262->10262
[task 2021-07-15T19:48:48.452Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10265->10264
[task 2021-07-15T19:48:48.453Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10267->10267
[task 2021-07-15T19:48:48.453Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10350->10350
[task 2021-07-15T19:48:48.453Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10353->10352
[task 2021-07-15T19:48:48.453Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10355->10355
[task 2021-07-15T19:48:48.454Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10358->10357
[task 2021-07-15T19:48:48.454Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10360->10360
[task 2021-07-15T19:48:48.454Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10363->10362
[task 2021-07-15T19:48:48.455Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10365->10365
[task 2021-07-15T19:48:48.455Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10368->10367
[task 2021-07-15T19:48:48.455Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10370->10370
[task 2021-07-15T19:48:48.455Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10373->10372
[task 2021-07-15T19:48:48.456Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10455->10455
[task 2021-07-15T19:48:48.456Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10458->10457
[task 2021-07-15T19:48:48.456Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10460->10460
[task 2021-07-15T19:48:48.456Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10463->10462
[task 2021-07-15T19:48:48.457Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10545->10545
[task 2021-07-15T19:48:48.457Z] 19:48:48     INFO -  PID 6304 | [2021-07-15T19:48:16Z WARN  neqo_transport::tracking] [Recvd-ap] Dropping unacknowledged ACK range: 10548->10547
[task 2021-07-15T19:48:48.457Z] 19:48:48  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http3.js | SlowReceiverListener.prototype.onStopRequest - [SlowReceiverListener.prototype.onStopRequest : 486] 2152398924 == 0
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  Z:/task_1626377937/build/tests/xpcshell/tests/netwerk/test/unit/test_http3.js:SlowReceiverListener.prototype.onStopRequest:486
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  Z:\task_1626377937\build\tests\xpcshell\head.js:_do_main:240
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  Z:\task_1626377937\build\tests\xpcshell\head.js:_execute_test:597
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  -e:null:1
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  exiting test
[task 2021-07-15T19:48:48.458Z] 19:48:48     INFO -  PID 6304 | JavaScript error: Z:\task_1626377937\build\tests\xpcshell\head.js, line 860: NS_ERROR_ABORT:
[task 2021-07-15T19:48:48.459Z] 19:48:48     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_ERROR_ABORT: " {file: "Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js" line: 860}]
[task 2021-07-15T19:48:48.459Z] 19:48:48     INFO -  _abort_failed_test@Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js:860:20
[task 2021-07-15T19:48:48.459Z] 19:48:48     INFO -  do_report_result@Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js:961:5
[task 2021-07-15T19:48:48.459Z] 19:48:48     INFO -  Assert<@Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js:75:21
[task 2021-07-15T19:48:48.460Z] 19:48:48     INFO -  proto.report@resource://testing-common/Assert.jsm:228:10
[task 2021-07-15T19:48:48.460Z] 19:48:48     INFO -  equal@resource://testing-common/Assert.jsm:270:8
[task 2021-07-15T19:48:48.460Z] 19:48:48     INFO -  SlowReceiverListener.prototype.onStopRequest@Z:/task_1626377937/build/tests/xpcshell/tests/netwerk/test/unit/test_http3.js:486:10
[task 2021-07-15T19:48:48.460Z] 19:48:48     INFO -  _do_main@Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js:240:6
[task 2021-07-15T19:48:48.461Z] 19:48:48     INFO -  _execute_test@Z:\\task_1626377937\\build\\tests\\xpcshell\\head.js:597:5
[task 2021-07-15T19:48:48.461Z] 19:48:48     INFO -  @-e:1:1
[task 2021-07-15T19:48:48.461Z] 19:48:48     INFO -  "
[task 2021-07-15T19:48:48.461Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-07-15T19:48:48.461Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-07-15T19:48:48.462Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-07-15T19:48:48.462Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-07-15T19:48:48.462Z] 19:48:48     INFO -  PID 6304 | !!! error running onStopped callback: TypeError: callback is not a function
[task 2021-07-15T19:48:48.463Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: failed to post proxy release event, leaking!: file /builds/worker/checkouts/gecko/xpcom/threads/nsProxyRelease.h:81
[task 2021-07-15T19:48:48.463Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: failed to post proxy release event, leaking!: file /builds/worker/checkouts/gecko/xpcom/threads/nsProxyRelease.h:81
[task 2021-07-15T19:48:48.463Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-07-15T19:48:48.464Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:3404
[task 2021-07-15T19:48:48.464Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-07-15T19:48:48.464Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:204
[task 2021-07-15T19:48:48.465Z] 19:48:48     INFO -  PID 6304 | [Parent 6304, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:204
[task 2021-07-15T19:48:48.465Z] 19:48:48     INFO -  <<<<<<<
[task 2021-07-15T19:48:48.473Z] 19:48:48     INFO -  TEST-START | netwerk/test/unit/test_http3.js
[task 2021-07-15T19:53:48.589Z] 19:53:48  WARNING -  TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit/test_http3.js | Test timed out
[task 2021-07-15T19:53:48.589Z] 19:53:48     INFO -  TEST-INFO took 300116ms
[task 2021-07-15T19:53:48.589Z] 19:53:48     INFO -  >>>>>>>
[task 2021-07-15T19:53:48.589Z] 19:53:48     INFO -  PID 4408 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-07-15T19:53:48.589Z] 19:53:48     INFO -  PID 4408 | [Parent 4408, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-15T19:53:48.590Z] 19:53:48     INFO -  PID 4408 | [Parent 4408, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-15T19:53:48.590Z] 19:53:48     INFO -  PID 4408 | [Parent 4408, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-15T19:53:48.590Z] 19:53:48     INFO -  PID 4408 | [Parent 4408, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-15T19:53:48.591Z] 19:53:48     INFO -  PID 4408 | [Parent 4408, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2948
[task 2021-07-15T19:53:48.591Z] 19:53:48     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-07-15T19:53:48.591Z] 19:53:48     INFO -  TEST-PASS | netwerk/test/unit/test_http3.js | run_test - [run_test : 63] "50312" != null

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

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=358495406&repo=autoland&lineNumber=2069

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 → 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.