Closed Bug 1670389 Opened 4 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit/test_http3_alt_svc.js | Test timed out

Categories

(Core :: Networking, defect)

defect
Not set
normal

Tracking

()

RESOLVED FIXED
98 Branch
Tracking Status
firefox98 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: kershaw)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell needswork:owner])

Attachments

(1 file)

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=318249688&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/U2bT3R0wTaiC7EWQnLmKJw/runs/0/artifacts/public/logs/live_backing.log


[task 2020-10-10T10:06:15.246Z] 10:06:15     INFO -  TEST-START | netwerk/test/unit/test_http3_alt_svc.js
[task 2020-10-10T10:11:15.267Z] 10:11:15  WARNING -  TEST-UNEXPECTED-TIMEOUT | netwerk/test/unit/test_http3_alt_svc.js | Test timed out
[task 2020-10-10T10:11:15.267Z] 10:11:15     INFO -  TEST-INFO took 300032ms
[task 2020-10-10T10:11:15.267Z] 10:11:15     INFO -  >>>>>>>
[task 2020-10-10T10:11:15.267Z] 10:11:15     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-10-10T10:11:15.267Z] 10:11:15     INFO -  TEST-PASS | netwerk/test/unit/test_http3_alt_svc.js | run_test - [run_test : 26] "50112" != null```
[task 2020-10-10T10:16:17.255Z] 10:16:17     INFO -  TEST-PASS | netwerk/test/unit/test_http3.js | testOnStopRequest - [testOnStopRequest : 194] 0 == 0
[task 2020-10-10T10:16:17.255Z] 10:16:17     INFO -  PID 3941 | routed is NA
[task 2020-10-10T10:16:17.255Z] 10:16:17     INFO -  PID 3941 | poll later for alt svc mapping
[task 2020-10-10T10:16:17.255Z] 10:16:17     INFO -  (xpcshell/head.js) | test pending (2)
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  (xpcshell/head.js) | test finished (2)
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  <<<<<<<
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  xpcshell return code: None
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  netwerk/test/unit/test_http3.js | Process still running after test!
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  Failed to remove directory: /var/folders/zg/dhk4yplx405f07y_4gz9f7zc000017/T/xpc-plugins-9LSfNM. Waiting.
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  INFO | Result summary:
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  INFO | Passed: 971
[task 2020-10-10T10:16:17.256Z] 10:16:17  WARNING -  INFO | Failed: 2
[task 2020-10-10T10:16:17.256Z] 10:16:17  WARNING -  One or more unittests failed.
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  INFO | Todo: 0
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  INFO | Retried: 15
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  SUITE-END | took 1314s
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  Node moz-http2 server shutting down ...
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  Process stdout
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  forked process without handler sent: [object Object]
[task 2020-10-10T10:16:17.256Z] 10:16:17     INFO -  Process stderr
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  (node:1638) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  (node:1638) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  (node:1638) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  (node:1638) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 window_update listeners added. Use emitter.setMaxListeners() to increase limit
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  http3Server server shutting down ...
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  Http3 server http3Server already dead -6
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  Process stderr
[task 2020-10-10T10:16:17.257Z] 10:16:17    ERROR -  thread 'main' panicked at 'assertion failed: i.is_empty()', <::std::macros::panic macros>:2:4
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -  stack backtrace:
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     0:        0x1025e939f - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h43ad886765c40c91
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     1:        0x10260232e - core::fmt::write::he548ee6ce743d415
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     2:        0x1025e36f7 - std::io::Write::write_fmt::hf0a8cad0ca2251e3
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     3:        0x1025eb39a - std::panicking::default_hook::{{closure}}::h166f557a03fd111a
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     4:        0x1025eb0dc - std::panicking::default_hook::h16057d891db6b023
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     5:        0x1025eba08 - std::panicking::rust_panic_with_hook::h2db5049a14746a6d
[task 2020-10-10T10:16:17.257Z] 10:16:17     INFO -     6:        0x10260bc8d - std::panicking::begin_panic::h631a94f1030fc47e
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -     7:        0x10253d3dc - neqo_transport::server::Server::process_connection::h599e7ad607d47271
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -     8:        0x102543d33 - neqo_transport::server::Server::process::h6aef0305f17fba54
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -     9:        0x1024d9f93 - neqo_http3::server::Http3Server::process::hf1bea322e28e99ab
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -    10:        0x1024b7588 - http3server::process::h21ac26b2745af502
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -    11:        0x1024ba550 - http3server::main::h5ba659022cae8ba6
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -    12:        0x1024b1142 - std::rt::lang_start::{{closure}}::hf62faeb5991efdf0
[task 2020-10-10T10:16:17.258Z] 10:16:17     INFO -    13:        0x1025eb4d8 - std::panicking::try::do_call::h7c88c220bfff6b21
[task 2020-10-10T10:16:17.371Z] 10:16:17    ERROR - Return code: 1
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Just noticed that most of failed tests have this crash.

[task 2022-01-20T10:01:43.321Z] 10:01:43    ERROR -  thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: InvalidStreamId', netwerk/test/http3server/src/main.rs:97:48
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -  stack backtrace:
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -     0:     0x55ef9b6a42a7 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h84bf2ff690853d4e
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -     1:     0x55ef9b71889c - core::fmt::write::h5b83959fe7b7c86a
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -     2:     0x55ef9b68f235 - std::io::Write::write_fmt::hf67af37c32f95bbd
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -     3:     0x55ef9b698ac0 - std::panicking::default_hook::{{closure}}::hb0dc6392c0ef305b
[task 2022-01-20T10:01:43.321Z] 10:01:43     INFO -     4:     0x55ef9b6986c4 - std::panicking::default_hook::hc38eaa5c9f389c8a
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -     5:     0x55ef9b699073 - std::panicking::rust_panic_with_hook::h213176a09718247f
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -     6:     0x55ef9b6a4980 - std::panicking::begin_panic_handler::{{closure}}::h3cfe49433456fe03
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -     7:     0x55ef9b6a43f4 - std::sys_common::backtrace::__rust_end_short_backtrace::hbf0fc5e1af0506eb
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -     8:     0x55ef9b698bb2 - rust_begin_unwind
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -     9:     0x55ef9b0862d1 - core::panicking::panic_fmt::hcb79d2bd962905f6
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    10:     0x55ef9b086003 - core::result::unwrap_failed::h553888e414025bfc
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    11:     0x55ef9b1557d7 - <http3server::Http3TestServer as http3server::HttpServer>::process_events::h1ab69e77515dbe88
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    12:     0x55ef9b15dae0 - http3server::ServersRunner::process_datagrams_and_events::hbd21d8ff3068496d
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -                                 at /builds/worker/checkouts/gecko/netwerk/test/http3server/src/main.rs:646:17
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    13:     0x55ef9b162d9a - http3server::ServersRunner::run::h09f17c0dd01a55ed
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -                                 at /builds/worker/checkouts/gecko/netwerk/test/http3server/src/main.rs:698:21
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    14:     0x55ef9b162d9a - http3server::main::h50bd85bd2f258020
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -                                 at /builds/worker/checkouts/gecko/netwerk/test/http3server/src/main.rs:733:5
[task 2022-01-20T10:01:43.322Z] 10:01:43     INFO -    15:     0x55ef9b130916 - core::ops::function::FnOnce::call_once::h6ca31c456a2fc39d
[task 2022-01-20T10:01:43.323Z] 10:01:43     INFO -                                 at /builds/worker/fetches/rust/library/core/src/ops/function.rs:227:5
[task 2022-01-20T10:01:43.323Z] 10:01:43     INFO -    16:     0x55ef9b130916 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd70ba7ed3f2d0392
[task 2022-01-20T10:01:43.323Z] 10:01:43     INFO -  Failed to remove directory: /tmp/xpc-plugins-13t8dpmi. Waiting.
[task 2022-01-20T10:01:43.323Z] 10:01:43     INFO -                                 at /builds/worker/fetches/rust/library/std/src/sys_common/backtrace.rs:123:18
[task 2022-01-20T10:01:43.324Z] 10:01:43     INFO -    17:     0x55ef9b131848 - std::rt::lang_start::{{closure}}::h3ba7f2822c259d0f
[task 2022-01-20T10:01:43.324Z] 10:01:43     INFO -                                 at /builds/worker/fetches/rust/library/std/src/rt.rs:145:18
[task 2022-01-20T10:01:43.324Z] 10:01:43     INFO -    18:     0x55ef9b684b31 - std::rt::lang_start_internal::h61f65027e0eee157
[task 2022-01-20T10:01:43.325Z] 10:01:43     INFO -    19:     0x55ef9b165361 - main
[task 2022-01-20T10:01:43.325Z] 10:01:43     INFO -    20:     0x7efcddb0ab97 - __libc_start_main
Assignee: nobody → kershaw
Whiteboard: [stockwell needswork:owner][stockwell needswork:owner] → [stockwell needswork:owner]
Pushed by kjang@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/cf2839433da4
Check the return value of stream.send_data, r=necko-reviewers,dragana
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 98 Branch
Whiteboard: [stockwell needswork:owner][stockwell needswork:owner] → [stockwell needswork:owner]
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: