[INFO] cloning repository https://github.com/jwodder/elapsing [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jwodder/elapsing" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjwodder%2Felapsing", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjwodder%2Felapsing'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 3735a4fe8bcba07aec4218a45628dffc93fcb095 [INFO] testing jwodder/elapsing against try#db823df02fd0c2cf67b43025ac3fef3f2d743245 for pr-150681 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjwodder%2Felapsing" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/jwodder/elapsing [INFO] finished tweaking git repo https://github.com/jwodder/elapsing [INFO] tweaked toml for git repo https://github.com/jwodder/elapsing written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/jwodder/elapsing on toolchain db823df02fd0c2cf67b43025ac3fef3f2d743245 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/jwodder/elapsing already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] e227af361ffd4acbbdf8af03d3fb3d5767bff260c944ff4f650d05fb33ae64d2 [INFO] running `Command { std: "docker" "start" "-a" "e227af361ffd4acbbdf8af03d3fb3d5767bff260c944ff4f650d05fb33ae64d2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "e227af361ffd4acbbdf8af03d3fb3d5767bff260c944ff4f650d05fb33ae64d2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e227af361ffd4acbbdf8af03d3fb3d5767bff260c944ff4f650d05fb33ae64d2", kill_on_drop: false }` [INFO] [stdout] e227af361ffd4acbbdf8af03d3fb3d5767bff260c944ff4f650d05fb33ae64d2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6bb399e0eca955d4310d88935f14d3932e06669d539f8b92a3405199c1f37c42 [INFO] running `Command { std: "docker" "start" "-a" "6bb399e0eca955d4310d88935f14d3932e06669d539f8b92a3405199c1f37c42", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.104 [INFO] [stderr] Compiling libc v0.2.178 [INFO] [stderr] Compiling unicode-ident v1.0.22 [INFO] [stderr] Compiling rustix v1.1.3 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling bytes v1.11.0 [INFO] [stderr] Compiling lexopt v0.3.1 [INFO] [stderr] Compiling quote v1.0.42 [INFO] [stderr] Compiling syn v2.0.112 [INFO] [stderr] Compiling errno v0.3.14 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling socket2 v0.6.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.8 [INFO] [stderr] Compiling terminal_size v0.4.3 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling pty-process v0.5.3 [INFO] [stderr] Compiling elapsed-cmd v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.01s [INFO] running `Command { std: "docker" "inspect" "6bb399e0eca955d4310d88935f14d3932e06669d539f8b92a3405199c1f37c42", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6bb399e0eca955d4310d88935f14d3932e06669d539f8b92a3405199c1f37c42", kill_on_drop: false }` [INFO] [stdout] 6bb399e0eca955d4310d88935f14d3932e06669d539f8b92a3405199c1f37c42 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d6ae94937b65aabd8075fb1d7e034fa763e0264dc0588ce34675c46a89fc4538 [INFO] running `Command { std: "docker" "start" "-a" "d6ae94937b65aabd8075fb1d7e034fa763e0264dc0588ce34675c46a89fc4538", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.178 [INFO] [stderr] Compiling syn v2.0.112 [INFO] [stderr] Compiling getrandom v0.3.4 [INFO] [stderr] Compiling glob v0.3.3 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling rustc_version v0.4.1 [INFO] [stderr] Compiling nix v0.30.1 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling relative-path v1.9.3 [INFO] [stderr] Compiling itoa v1.0.17 [INFO] [stderr] Compiling unicode-width v0.2.2 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling assert_matches v1.5.0 [INFO] [stderr] Compiling rstest_macros v0.26.1 [INFO] [stderr] Compiling vte v0.15.0 [INFO] [stderr] Compiling vt100 v0.16.2 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling errno v0.3.14 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling socket2 v0.6.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.8 [INFO] [stderr] Compiling tempfile v3.24.0 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling async-stream-impl v0.3.6 [INFO] [stderr] Compiling async-stream v0.3.6 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling rstest v0.26.1 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling pty-process v0.5.3 [INFO] [stderr] Compiling tokio-test v0.4.4 [INFO] [stderr] Compiling elapsed-cmd v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 21.79s [INFO] running `Command { std: "docker" "inspect" "d6ae94937b65aabd8075fb1d7e034fa763e0264dc0588ce34675c46a89fc4538", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d6ae94937b65aabd8075fb1d7e034fa763e0264dc0588ce34675c46a89fc4538", kill_on_drop: false }` [INFO] [stdout] d6ae94937b65aabd8075fb1d7e034fa763e0264dc0588ce34675c46a89fc4538 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:845e597a41426bbf2703be69acdb67d10b6de511142d05cba7bbe119c898b2c7" "/opt/rustwide/cargo-home/bin/cargo" "+db823df02fd0c2cf67b43025ac3fef3f2d743245" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1fc93c35c93615c03eb669aacf959f2879527289527418ca9bbbbcdb7af1f77b [INFO] running `Command { std: "docker" "start" "-a" "1fc93c35c93615c03eb669aacf959f2879527289527418ca9bbbbcdb7af1f77b", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/elapsed-b3ac09bd5349f43f) [INFO] [stdout] [INFO] [stdout] running 37 tests [INFO] [stdout] test format::tests::display::case_03 ... ok [INFO] [stdout] test format::tests::display::case_06 ... ok [INFO] [stdout] test format::tests::display::case_07 ... ok [INFO] [stdout] test format::tests::display::case_05 ... ok [INFO] [stdout] test format::tests::display::case_08 ... ok [INFO] [stdout] test format::tests::display::case_09 ... ok [INFO] [stdout] test format::tests::display::case_10 ... ok [INFO] [stdout] test format::tests::display::case_11 ... ok [INFO] [stdout] test format::tests::display::case_12 ... ok [INFO] [stdout] test format::tests::display::case_13 ... ok [INFO] [stdout] test format::tests::parse_default ... ok [INFO] [stdout] test format::tests::parse_err::case_2 ... ok [INFO] [stdout] test format::tests::parse_err::case_1 ... ok [INFO] [stdout] test format::tests::newlines ... ok [INFO] [stdout] test format::tests::parse_err::case_3 ... ok [INFO] [stdout] test format::tests::display::case_01 ... ok [INFO] [stdout] test format::tests::parse_err::case_4 ... ok [INFO] [stdout] test tests::byte_lines::many_short_lines_no_final_newline ... ok [INFO] [stdout] test format::tests::display::case_02 ... ok [INFO] [stdout] test format::tests::parse_err::case_6 ... ok [INFO] [stdout] test tests::byte_lines::non_utf8 ... ok [INFO] [stdout] test tests::byte_lines::split_line ... ok [INFO] [stdout] test tests::byte_lines::strip_cr ... ok [INFO] [stdout] test tests::parse_args::command_only ... ok [INFO] [stdout] test tests::parse_args::command_double_dash ... ok [INFO] [stdout] test tests::parse_args::command_with_my_opt ... ok [INFO] [stdout] test tests::parse_args::help ... ok [INFO] [stdout] test tests::parse_args::help_and_command ... ok [INFO] [stdout] test tests::parse_args::total ... ok [INFO] [stdout] test tests::parse_args::version ... ok [INFO] [stdout] test tests::parse_args::command_with_opt ... ok [INFO] [stdout] test tests::parse_args::double_dash_opt ... ok [INFO] [stdout] test tests::parse_args::command_with_arg ... ok [INFO] [stdout] test format::tests::display::case_04 ... ok [INFO] [stdout] test tests::byte_lines::many_short_lines ... ok [INFO] [stdout] test tests::parse_args::double_dash_command ... ok [INFO] [stdout] test format::tests::parse_err::case_5 ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running tests/cli.rs (/opt/rustwide/target/debug/deps/cli-ed6edd6e209036bc) [INFO] [stdout] [INFO] [stdout] running 18 tests [INFO] [stdout] test custom_refresh ... ok [INFO] [stdout] test failure ... ok [INFO] [stdout] test redir_stderr ... ok [INFO] [stdout] test redir_stderr_tty_split ... ok [INFO] [stdout] test closer ... ok [INFO] [stdout] test redir_stderr_tty ... ok [INFO] [stdout] test read_stdin ... FAILED [INFO] [stdout] test ctrl_c_total ... ok [INFO] [stdout] test read_stdin_tty ... ok [INFO] [stdout] test write_stderr ... ok [INFO] [stdout] test kill_sleepy ... ok [INFO] [stdout] test kill_sleepy_total ... ok [INFO] [stdout] test write_stderr_tty ... ok [INFO] [stdout] test ctrl_c ... ok [INFO] [stdout] test multiline_format ... ok [INFO] [stdout] test sleepy_tty ... ok [INFO] [stdout] test sleepy ... ok [INFO] [stdout] test sleepy_total ... ok [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test cli` [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- read_stdin stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'read_stdin' (72) panicked at tests/cli.rs:106:21: [INFO] [stdout] Timed out while waiting for screen contents "Line 1: Apple\nLine 2: Banana\nElapsed: 00:00:02"; final content = "Line 1: Apple\nLine 2: Banana\nLine 3: Coconut\nElapsed: 00:00:02" [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5fcd2478e3a2 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5fcd2478e3a2 - std[3f03f8b59f91bef1]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5fcd2478e3a2 - std[3f03f8b59f91bef1]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x5fcd2478e3a2 - <::print::DisplayBacktrace as core[585f66e14d78f9ba]::fmt::Display>::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x5fcd247a37ea - ::fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x5fcd247a37ea - core[585f66e14d78f9ba]::fmt::write [INFO] [stdout] 6: 0x5fcd247934a6 - std[3f03f8b59f91bef1]::io::default_write_fmt::> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5fcd247934a6 - as std[3f03f8b59f91bef1]::io::Write>::write_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x5fcd2476aeaf - ::print [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x5fcd2476aeaf - std[3f03f8b59f91bef1]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x5fcd24785bd9 - std[3f03f8b59f91bef1]::panicking::default_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x5fcd246ca90e - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x5fcd246ca90e - test[30224e0cb089acd2]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5fcd24785e42 - core[585f66e14d78f9ba]::ops::function::Fn<(&'a std[3f03f8b59f91bef1]::panic::PanicHookInfo<'b>,), Output = ()> + core[585f66e14d78f9ba]::marker::Sync + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::Fn<(&std[3f03f8b59f91bef1]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x5fcd24785e42 - std[3f03f8b59f91bef1]::panicking::panic_with_hook [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x5fcd2476af68 - std[3f03f8b59f91bef1]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x5fcd2475ff79 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x5fcd2476bdbd - __rustc[caa99d26c7bae192]::rust_begin_unwind [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x5fcd247a3f2c - core[585f66e14d78f9ba]::panicking::panic_fmt [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x5fcd24666c89 - ::wait_for_contents::<&str>::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/cli.rs:106:21 [INFO] [stdout] 20: 0x5fcd24667bed - cli[16dcb6b2bb1c243f]::read_stdin::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/cli.rs:305:10 [INFO] [stdout] 21: 0x5fcd2468b782 - > as core[585f66e14d78f9ba]::future::future::Future>::poll [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/future/future.rs:133:9 [INFO] [stdout] 22: 0x5fcd2468b7fd - >> as core[585f66e14d78f9ba]::future::future::Future>::poll [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/future/future.rs:133:9 [INFO] [stdout] 23: 0x5fcd246831fd - ::block_on::>>>::{closure#0}::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:70 [INFO] [stdout] 24: 0x5fcd246830fb - tokio[d2fe9cd05c073546]::task::coop::with_budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:167:5 [INFO] [stdout] 25: 0x5fcd246830fb - tokio[d2fe9cd05c073546]::task::coop::budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:133:5 [INFO] [stdout] 26: 0x5fcd246830fb - ::block_on::>>>::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:25 [INFO] [stdout] 27: 0x5fcd2467e7e0 - ::enter::, ::block_on>>>::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 28: 0x5fcd2468235d - ::block_on::>>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:741:44 [INFO] [stdout] 29: 0x5fcd24681654 - ::enter::<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:68 [INFO] [stdout] 30: 0x5fcd2467e0ab - >::set::<::enter<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0}, (alloc[15e7b27aba85d2e2]::boxed::Box, core[585f66e14d78f9ba]::option::Option<()>)> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 31: 0x5fcd24682c29 - tokio[d2fe9cd05c073546]::runtime::context::set_scheduler::<(alloc[15e7b27aba85d2e2]::boxed::Box, core[585f66e14d78f9ba]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0}>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:38 [INFO] [stdout] 32: 0x5fcd2467f662 - >::try_with::, core[585f66e14d78f9ba]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[15e7b27aba85d2e2]::boxed::Box, core[585f66e14d78f9ba]::option::Option<()>)> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/local.rs:513:12 [INFO] [stdout] 33: 0x5fcd2467f2ee - >::with::, core[585f66e14d78f9ba]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[15e7b27aba85d2e2]::boxed::Box, core[585f66e14d78f9ba]::option::Option<()>)> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/local.rs:477:20 [INFO] [stdout] 34: 0x5fcd24680c4d - tokio[d2fe9cd05c073546]::runtime::context::set_scheduler::<(alloc[15e7b27aba85d2e2]::boxed::Box, core[585f66e14d78f9ba]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>>::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:17 [INFO] [stdout] 35: 0x5fcd24680540 - ::enter::<::block_on>>>::{closure#0}, core[585f66e14d78f9ba]::option::Option<()>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:27 [INFO] [stdout] 36: 0x5fcd24680837 - ::block_on::>>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:729:24 [INFO] [stdout] 37: 0x5fcd24681266 - ::block_on::>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 38: 0x5fcd24688895 - tokio[d2fe9cd05c073546]::runtime::context::runtime::enter_runtime::<::block_on>>::{closure#0}, ()> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 39: 0x5fcd2467e281 - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 40: 0x5fcd246872c4 - ::block_on_inner::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:368:52 [INFO] [stdout] 41: 0x5fcd2468747c - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:342:18 [INFO] [stdout] 42: 0x5fcd2467b43f - cli[16dcb6b2bb1c243f]::read_stdin [INFO] [stdout] at /opt/rustwide/workdir/tests/cli.rs:319:6 [INFO] [stdout] 43: 0x5fcd24666cd7 - cli[16dcb6b2bb1c243f]::read_stdin::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/cli.rs:284:22 [INFO] [stdout] 44: 0x5fcd24686ac6 - >::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 45: 0x5fcd246becbb - core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 46: 0x5fcd246becbb - test[30224e0cb089acd2]::__rust_begin_short_backtrace::, fn() -> core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:663:18 [INFO] [stdout] 47: 0x5fcd246cb51a - test[30224e0cb089acd2]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:74 [INFO] [stdout] 48: 0x5fcd246cb51a - as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 49: 0x5fcd246cb51a - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 50: 0x5fcd246cb51a - std[3f03f8b59f91bef1]::panicking::catch_unwind::, core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 51: 0x5fcd246cb51a - std[3f03f8b59f91bef1]::panic::catch_unwind::, core[585f66e14d78f9ba]::result::Result<(), alloc[15e7b27aba85d2e2]::string::String>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 52: 0x5fcd246cb51a - test[30224e0cb089acd2]::run_test_in_process [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:686:27 [INFO] [stdout] 53: 0x5fcd246cb51a - test[30224e0cb089acd2]::run_test::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:607:43 [INFO] [stdout] 54: 0x5fcd246c59e4 - test[30224e0cb089acd2]::run_test::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/test/src/lib.rs:637:41 [INFO] [stdout] 55: 0x5fcd246c59e4 - std[3f03f8b59f91bef1]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 56: 0x5fcd246ce012 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 57: 0x5fcd246ce012 - ::{closure#1}::{closure#0}> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 58: 0x5fcd246ce012 - std[3f03f8b59f91bef1]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:581:40 [INFO] [stdout] 59: 0x5fcd246ce012 - std[3f03f8b59f91bef1]::panicking::catch_unwind::<(), core[585f66e14d78f9ba]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panicking.rs:544:19 [INFO] [stdout] 60: 0x5fcd246ce012 - std[3f03f8b59f91bef1]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/panic.rs:359:14 [INFO] [stdout] 61: 0x5fcd246ce012 - std[3f03f8b59f91bef1]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 62: 0x5fcd246ce012 - ::{closure#1} as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 63: 0x5fcd2478d8df - + core[585f66e14d78f9ba]::marker::Send> as core[585f66e14d78f9ba]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 64: 0x5fcd2478d8df - ::new::thread_start [INFO] [stdout] at /rustc/db823df02fd0c2cf67b43025ac3fef3f2d743245/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 65: 0x77a625301aa4 - [INFO] [stdout] 66: 0x77a62538ea64 - clone [INFO] [stdout] 67: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] read_stdin [INFO] [stdout] [INFO] [stdout] test result: FAILED. 17 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.18s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "1fc93c35c93615c03eb669aacf959f2879527289527418ca9bbbbcdb7af1f77b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1fc93c35c93615c03eb669aacf959f2879527289527418ca9bbbbcdb7af1f77b", kill_on_drop: false }` [INFO] [stdout] 1fc93c35c93615c03eb669aacf959f2879527289527418ca9bbbbcdb7af1f77b