[INFO] cloning repository https://github.com/danielparks/cron-wrapper [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/danielparks/cron-wrapper" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielparks%2Fcron-wrapper", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielparks%2Fcron-wrapper'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] f6b303adfe759d504588c6f01b2a4697211ba5bc [INFO] testing danielparks/cron-wrapper against 1.79.0 for beta-1.80-4 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdanielparks%2Fcron-wrapper" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/danielparks/cron-wrapper on toolchain 1.79.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.79.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/danielparks/cron-wrapper [INFO] finished tweaking git repo https://github.com/danielparks/cron-wrapper [INFO] tweaked toml for git repo https://github.com/danielparks/cron-wrapper written to /workspace/builds/worker-6-tc1/source/Cargo.toml [INFO] crate git repo https://github.com/danielparks/cron-wrapper 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" "+1.79.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded fd-lock v4.0.2 [INFO] [stderr] Downloaded simplelog v0.12.2 [INFO] [stderr] Downloaded predicates v3.1.0 [INFO] [stderr] Downloaded assert2-macros v0.3.14 [INFO] [stderr] Downloaded assert_cmd v2.0.14 [INFO] [stderr] Downloaded assert2 v0.3.14 [INFO] [stderr] Downloaded rust_decimal v1.34.3 [INFO] [stderr] Downloaded roundable v0.1.0 [INFO] [stderr] Downloaded duration-str v0.5.1 [INFO] [stderr] Downloaded popol v3.0.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.79.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 476f8b1f920c6e11d6463f3bdfc64d35acdb8f731d9199c29f47ae46688bc696 [INFO] running `Command { std: "docker" "start" "-a" "476f8b1f920c6e11d6463f3bdfc64d35acdb8f731d9199c29f47ae46688bc696", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "476f8b1f920c6e11d6463f3bdfc64d35acdb8f731d9199c29f47ae46688bc696", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "476f8b1f920c6e11d6463f3bdfc64d35acdb8f731d9199c29f47ae46688bc696", kill_on_drop: false }` [INFO] [stdout] 476f8b1f920c6e11d6463f3bdfc64d35acdb8f731d9199c29f47ae46688bc696 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.79.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 00b3d0b3f10c168e37a9131f2f18ec56febea367b18855a9000b4ffaea367a3d [INFO] running `Command { std: "docker" "start" "-a" "00b3d0b3f10c168e37a9131f2f18ec56febea367b18855a9000b4ffaea367a3d", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.153 [INFO] [stderr] Compiling thiserror v1.0.57 [INFO] [stderr] Compiling num-traits v0.2.18 [INFO] [stderr] Compiling rustix v0.38.31 [INFO] [stderr] Compiling memchr v2.7.1 [INFO] [stderr] Compiling arrayvec v0.7.4 [INFO] [stderr] Compiling syn v2.0.52 [INFO] [stderr] Compiling rust_decimal v1.34.3 [INFO] [stderr] Compiling cc v1.0.90 [INFO] [stderr] Compiling anstream v0.6.13 [INFO] [stderr] Compiling time-macros v0.2.17 [INFO] [stderr] Compiling linux-raw-sys v0.4.13 [INFO] [stderr] Compiling bitflags v2.4.2 [INFO] [stderr] Compiling nom v7.1.3 [INFO] [stderr] Compiling clap_builder v4.5.2 [INFO] [stderr] Compiling blake3 v1.5.0 [INFO] [stderr] Compiling log v0.4.21 [INFO] [stderr] Compiling termcolor v1.4.1 [INFO] [stderr] Compiling time v0.3.34 [INFO] [stderr] Compiling arrayref v0.3.7 [INFO] [stderr] Compiling constant_time_eq v0.3.0 [INFO] [stderr] Compiling nix v0.26.4 [INFO] [stderr] Compiling fd-lock v4.0.2 [INFO] [stderr] Compiling os_pipe v1.1.5 [INFO] [stderr] Compiling is-terminal v0.4.12 [INFO] [stderr] Compiling popol v3.0.0 [INFO] [stderr] Compiling bstr v1.9.1 [INFO] [stderr] Compiling roundable v0.1.0 [INFO] [stderr] Compiling home v0.5.9 [INFO] [stderr] Compiling simplelog v0.12.2 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling thiserror-impl v1.0.57 [INFO] [stderr] Compiling clap_derive v4.5.0 [INFO] [stderr] Compiling duration-str v0.5.1 [INFO] [stderr] Compiling clap v4.5.2 [INFO] [stderr] Compiling cron-wrapper v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 40.74s [INFO] running `Command { std: "docker" "inspect" "00b3d0b3f10c168e37a9131f2f18ec56febea367b18855a9000b4ffaea367a3d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "00b3d0b3f10c168e37a9131f2f18ec56febea367b18855a9000b4ffaea367a3d", kill_on_drop: false }` [INFO] [stdout] 00b3d0b3f10c168e37a9131f2f18ec56febea367b18855a9000b4ffaea367a3d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.79.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8904752e0acb207d6b467d2260d94b9ef3befbed16b0275bcbcad9bc56e2b958 [INFO] running `Command { std: "docker" "start" "-a" "8904752e0acb207d6b467d2260d94b9ef3befbed16b0275bcbcad9bc56e2b958", kill_on_drop: false }` [INFO] [stderr] Compiling semver v1.0.22 [INFO] [stderr] Compiling regex-syntax v0.8.2 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling predicates-core v1.0.6 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling difflib v0.4.0 [INFO] [stderr] Compiling assert_cmd v2.0.14 [INFO] [stderr] Compiling termtree v0.4.1 [INFO] [stderr] Compiling wait-timeout v0.2.0 [INFO] [stderr] Compiling predicates-tree v1.0.9 [INFO] [stderr] Compiling diff v0.1.13 [INFO] [stderr] Compiling predicates v3.1.0 [INFO] [stderr] Compiling fastrand v2.0.1 [INFO] [stderr] Compiling yansi v0.5.1 [INFO] [stderr] Compiling tempfile v3.10.1 [INFO] [stderr] Compiling rustc_version v0.4.0 [INFO] [stderr] Compiling assert2-macros v0.3.14 [INFO] [stderr] Compiling regex-automata v0.4.6 [INFO] [stderr] Compiling bstr v1.9.1 [INFO] [stderr] Compiling regex v1.10.3 [INFO] [stderr] Compiling cron-wrapper v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Compiling assert2 v0.3.14 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 39.48s [INFO] running `Command { std: "docker" "inspect" "8904752e0acb207d6b467d2260d94b9ef3befbed16b0275bcbcad9bc56e2b958", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8904752e0acb207d6b467d2260d94b9ef3befbed16b0275bcbcad9bc56e2b958", kill_on_drop: false }` [INFO] [stdout] 8904752e0acb207d6b467d2260d94b9ef3befbed16b0275bcbcad9bc56e2b958 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.79.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4ca10450183e155c38fa57effd8fa2b44f457198db516aa52f6a76939e54d5ab [INFO] running `Command { std: "docker" "start" "-a" "4ca10450183e155c38fa57effd8fa2b44f457198db516aa52f6a76939e54d5ab", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cron_wrapper-7bcdf57db0e9fd9a) [INFO] [stdout] [INFO] [stdout] running 55 tests [INFO] [stdout] test job_logger::logger::tests::directory_and_stream_loggers ... ok [INFO] [stdout] test command::tests::killed ... ok [INFO] [stdout] test command::tests::echo_ok ... ok [INFO] [stdout] test job_logger::logger::tests::directory_logger_no_metadata ... ok [INFO] [stdout] test command::tests::wait_run_timeout ... ok [INFO] [stdout] test job_logger::logger::tests::escape_value_escapes ... ok [INFO] [stdout] test job_logger::logger::tests::escape_value_newline_explicit ... ok [INFO] [stdout] test command::tests::wait_no_timeout ... FAILED [INFO] [stdout] test command::tests::wait_idle_timeout ... FAILED [INFO] [stdout] test job_logger::logger::tests::directory_logger_with_child ... ok [INFO] [stdout] test job_logger::logger::tests::directory_logger_with_command ... ok [INFO] [stdout] test job_logger::logger::tests::escape_value_newline_implicit ... ok [INFO] [stdout] test job_logger::logger::tests::none_logger ... ok [INFO] [stdout] test job_logger::logger::tests::stream_logger_recoverable_error ... ok [INFO] [stdout] test job_logger::logger::tests::stream_logger_escapes ... ok [INFO] [stdout] test job_logger::logger::tests::stream_logger_continuation ... ok [INFO] [stdout] test job_logger::logger::tests::stream_logger_continuation_recoverable_error ... ok [INFO] [stdout] test job_logger::parser::tests::bstr_to_duration_milliseconds ... ok [INFO] [stdout] test job_logger::parser::tests::bstr_to_duration_max ... ok [INFO] [stdout] test job_logger::parser::tests::bstr_to_duration_nanoseconds ... ok [INFO] [stdout] test job_logger::parser::tests::bstr_to_u64_extremes ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_blank_no_metadata ... ok [INFO] [stdout] test job_logger::parser::tests::bstr_to_duration_zero ... ok [INFO] [stdout] test job_logger::logger::tests::stream_logger ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_just_blank ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_empty ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_no_blank_no_records ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_simple ... ok [INFO] [stdout] test job_logger::parser::tests::metadata_line_parser_ok ... ok [INFO] [stdout] test job_logger::parser::tests::metadata_line_parser_ok_multiline ... ok [INFO] [stdout] test job_logger::parser::tests::record_parser_err ... ok [INFO] [stdout] test job_logger::parser::tests::record_parser_ok ... ok [INFO] [stdout] test job_logger::parser::tests::record_parser_ok_multiline ... ok [INFO] [stdout] test job_logger::parser::tests::unescape ... ok [INFO] [stdout] test job_logger::parser::tests::unescape_invalid_utf8 ... ok [INFO] [stdout] test job_logger::parser::tests::unescape_line_ending_output ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_no_blank_no_metadata ... ok [INFO] [stdout] test job_logger::parser::tests::log_parser_blank_no_records ... ok [INFO] [stdout] test job_logger::parser::tests::metadata_line_parser_err ... ok [INFO] [stdout] test timeout::tests::check_expired_timeout_expired ... ok [INFO] [stdout] test timeout::tests::check_expired_timeout_never ... ok [INFO] [stdout] test lock::tests::lock_basic ... ok [INFO] [stdout] test timeout::tests::check_expired_within_timeout_pending ... ok [INFO] [stdout] test timeout::tests::check_expired_timeout_pending ... ok [INFO] [stdout] test timeout::tests::compare_timeout_expired ... ok [INFO] [stdout] test timeout::tests::compare_timeout_future ... ok [INFO] [stdout] test lock::tests::lock_standard_basic ... ok [INFO] [stdout] test timeout::tests::compare_timeout_never ... ok [INFO] [stdout] test job_logger::parser::tests::unescape_line_ending_non_output ... ok [INFO] [stdout] test timeout::tests::check_expired_timeout_future ... ok [INFO] [stdout] test timeout::tests::compare_timeout_pending ... ok [INFO] [stdout] test timeout::tests::compare_timeout_pending_overtime ... ok [INFO] [stdout] test job_logger::logger::tests::directory_logger_conflicting_file_name ... ok [INFO] [stdout] test command::tests::run_timeout ... ok [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] test command::tests::idle_timeout ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- command::tests::wait_no_timeout stdout ---- [INFO] [stdout] Assertion failed at src/command.rs:1252:9: [INFO] [stdout] check!( start.elapsed() < Duration::from_millis(19) ) [INFO] [stdout] with expansion: [INFO] [stdout] 31.693958ms < 19ms [INFO] [stdout] [INFO] [stdout] thread 'command::tests::wait_no_timeout' panicked at src/command.rs:1252:9: [INFO] [stdout] check failed [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55dda6d10905 - std::backtrace_rs::backtrace::libunwind::trace::h1a07e5dba0da0cd2 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/../../backtrace/src/backtrace/libunwind.rs:105:5 [INFO] [stdout] 1: 0x55dda6d10905 - std::backtrace_rs::backtrace::trace_unsynchronized::h61b9b8394328c0bc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55dda6d10905 - std::sys_common::backtrace::_print_fmt::h1c5e18b460934cff [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55dda6d10905 - ::fmt::h1e1a1972118942ad [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55dda6d3896b - core::fmt::rt::Argument::fmt::h07af2b4071d536cd [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/fmt/rt.rs:165:63 [INFO] [stdout] 5: 0x55dda6d3896b - core::fmt::write::hc090a2ffd6b28c4a [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/fmt/mod.rs:1157:21 [INFO] [stdout] 6: 0x55dda6d0dcbf - std::io::Write::write_fmt::h9443d787e8277bd2 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/io/mod.rs:1832:15 [INFO] [stdout] 7: 0x55dda6d106de - std::sys_common::backtrace::_print::h4e80c5803d4ee35b [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55dda6d106de - std::sys_common::backtrace::print::ha96650907276675e [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55dda6d12059 - std::panicking::default_hook::{{closure}}::h215c2a0a8346e0e0 [INFO] [stdout] 10: 0x55dda6d11d1e - std::panicking::default_hook::h207342be97478370 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:295:9 [INFO] [stdout] 11: 0x55dda6b6d5ea - as core::ops::function::Fn>::call::haebd875eeb75949f [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2036:9 [INFO] [stdout] 12: 0x55dda6b6d5ea - test::test_main::{{closure}}::h9108cb0ebacdb458 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55dda6d12626 - as core::ops::function::Fn>::call::ha9c3bc81d312fd83 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2036:9 [INFO] [stdout] 14: 0x55dda6d12626 - std::panicking::rust_panic_with_hook::hac8bdceee1e4fe2c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:799:13 [INFO] [stdout] 15: 0x55dda6d1239b - std::panicking::begin_panic_handler::{{closure}}::h00d785e82757ce3c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:656:13 [INFO] [stdout] 16: 0x55dda6d10dc9 - std::sys_common::backtrace::__rust_end_short_backtrace::h1628d957bcd06996 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55dda6d12107 - rust_begin_unwind [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:652:5 [INFO] [stdout] 18: 0x55dda6a71273 - core::panicking::panic_fmt::hdc63834ffaaefae5 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55dda6b197e9 - cron_wrapper::command::tests::wait_no_timeout::{{closure}}::hecef1e1eaad9dd38 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/assert2-0.3.14/src/lib.rs:247:47 [INFO] [stdout] 20: 0x55dda6b364c1 - as core::ops::drop::Drop>::drop::hdfbf030eb41792e9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/assert2-0.3.14/src/__assert2_impl/mod.rs:16:4 [INFO] [stdout] 21: 0x55dda6affe8a - core::ptr::drop_in_place>::hb7f74326a099d4a3 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ptr/mod.rs:514:1 [INFO] [stdout] 22: 0x55dda6b0106e - core::ptr::drop_in_place>>::h60d28cd9c88b6ff0 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ptr/mod.rs:514:1 [INFO] [stdout] 23: 0x55dda6b193b0 - cron_wrapper::command::tests::wait_no_timeout::hf791723c645173f7 [INFO] [stdout] at /opt/rustwide/workdir/src/command.rs:1253:5 [INFO] [stdout] 24: 0x55dda6b18c67 - cron_wrapper::command::tests::wait_no_timeout::{{closure}}::h52d96f6bf849fa16 [INFO] [stdout] at /opt/rustwide/workdir/src/command.rs:1242:25 [INFO] [stdout] 25: 0x55dda6afea56 - core::ops::function::FnOnce::call_once::h05a70680cd5546a5 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 26: 0x55dda6b71fcb - core::ops::function::FnOnce::call_once::hb194ee8411fb46c7 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 27: 0x55dda6b71fcb - test::__rust_begin_short_backtrace::h871a93aaa56c76c5 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:623:18 [INFO] [stdout] 28: 0x55dda6b716d1 - test::run_test_in_process::{{closure}}::h6d9e6b0161cb50c6 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:646:60 [INFO] [stdout] 29: 0x55dda6b716d1 - as core::ops::function::FnOnce<()>>::call_once::hb9f3c63b5d0e0baa [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 30: 0x55dda6b716d1 - std::panicking::try::do_call::h83eb4c9b7c70ebcc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:559:40 [INFO] [stdout] 31: 0x55dda6b716d1 - std::panicking::try::h5ffd56bf671a89b3 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:523:19 [INFO] [stdout] 32: 0x55dda6b716d1 - std::panic::catch_unwind::h044e758d6cbf65b7 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panic.rs:149:14 [INFO] [stdout] 33: 0x55dda6b716d1 - test::run_test_in_process::hf7ca2db08c9223b8 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:646:27 [INFO] [stdout] 34: 0x55dda6b716d1 - test::run_test::{{closure}}::h9657c20462181b87 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:569:43 [INFO] [stdout] 35: 0x55dda6b3a154 - test::run_test::{{closure}}::h80c3aef427ce62cc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:597:41 [INFO] [stdout] 36: 0x55dda6b3a154 - std::sys_common::backtrace::__rust_begin_short_backtrace::h43274eec75e29097 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 37: 0x55dda6b3ebc2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hea1335728d2a7953 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/thread/mod.rs:542:17 [INFO] [stdout] 38: 0x55dda6b3ebc2 - as core::ops::function::FnOnce<()>>::call_once::hb16d5d5fd985753c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 39: 0x55dda6b3ebc2 - std::panicking::try::do_call::h65de05e16c44e167 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:559:40 [INFO] [stdout] 40: 0x55dda6b3ebc2 - std::panicking::try::h187f01afe14eb3bf [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:523:19 [INFO] [stdout] 41: 0x55dda6b3ebc2 - std::panic::catch_unwind::h1b55347b2c8efb63 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panic.rs:149:14 [INFO] [stdout] 42: 0x55dda6b3ebc2 - std::thread::Builder::spawn_unchecked_::{{closure}}::hea39e0adaff5b52d [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/thread/mod.rs:541:30 [INFO] [stdout] 43: 0x55dda6b3ebc2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9b61aba01c3c1f32 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 44: 0x55dda6d173db - as core::ops::function::FnOnce>::call_once::h09e5a4c541afa800 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2022:9 [INFO] [stdout] 45: 0x55dda6d173db - as core::ops::function::FnOnce>::call_once::h9c8b03c22f4e7026 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2022:9 [INFO] [stdout] 46: 0x55dda6d173db - std::sys::pal::unix::thread::Thread::new::thread_start::h522bc89a54da820a [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 47: 0x7f29a6e30ac3 - [INFO] [stdout] 48: 0x7f29a6ec1a04 - __clone [INFO] [stdout] 49: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- command::tests::wait_idle_timeout stdout ---- [INFO] [stdout] Assertion failed at src/command.rs:1267:9: [INFO] [stdout] let_assert!( Ok(status) = child.wait() ) [INFO] [stdout] with expansion: [INFO] [stdout] Err( [INFO] [stdout] IdleTimeout { [INFO] [stdout] timeout: Expired { [INFO] [stdout] requested: 5ms, [INFO] [stdout] actual: 22.845318ms, [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] ) [INFO] [stdout] [INFO] [stdout] thread 'command::tests::wait_idle_timeout' panicked at src/command.rs:1267:9: [INFO] [stdout] assertion failed [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55dda6d10905 - std::backtrace_rs::backtrace::libunwind::trace::h1a07e5dba0da0cd2 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/../../backtrace/src/backtrace/libunwind.rs:105:5 [INFO] [stdout] 1: 0x55dda6d10905 - std::backtrace_rs::backtrace::trace_unsynchronized::h61b9b8394328c0bc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55dda6d10905 - std::sys_common::backtrace::_print_fmt::h1c5e18b460934cff [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55dda6d10905 - ::fmt::h1e1a1972118942ad [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55dda6d3896b - core::fmt::rt::Argument::fmt::h07af2b4071d536cd [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/fmt/rt.rs:165:63 [INFO] [stdout] 5: 0x55dda6d3896b - core::fmt::write::hc090a2ffd6b28c4a [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/fmt/mod.rs:1157:21 [INFO] [stdout] 6: 0x55dda6d0dcbf - std::io::Write::write_fmt::h9443d787e8277bd2 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/io/mod.rs:1832:15 [INFO] [stdout] 7: 0x55dda6d106de - std::sys_common::backtrace::_print::h4e80c5803d4ee35b [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55dda6d106de - std::sys_common::backtrace::print::ha96650907276675e [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55dda6d12059 - std::panicking::default_hook::{{closure}}::h215c2a0a8346e0e0 [INFO] [stdout] 10: 0x55dda6d11d1e - std::panicking::default_hook::h207342be97478370 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:295:9 [INFO] [stdout] 11: 0x55dda6b6d5ea - as core::ops::function::Fn>::call::haebd875eeb75949f [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2036:9 [INFO] [stdout] 12: 0x55dda6b6d5ea - test::test_main::{{closure}}::h9108cb0ebacdb458 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55dda6d12626 - as core::ops::function::Fn>::call::ha9c3bc81d312fd83 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2036:9 [INFO] [stdout] 14: 0x55dda6d12626 - std::panicking::rust_panic_with_hook::hac8bdceee1e4fe2c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:799:13 [INFO] [stdout] 15: 0x55dda6d1239b - std::panicking::begin_panic_handler::{{closure}}::h00d785e82757ce3c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:656:13 [INFO] [stdout] 16: 0x55dda6d10dc9 - std::sys_common::backtrace::__rust_end_short_backtrace::h1628d957bcd06996 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55dda6d12107 - rust_begin_unwind [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:652:5 [INFO] [stdout] 18: 0x55dda6a71273 - core::panicking::panic_fmt::hdc63834ffaaefae5 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55dda6b1a0ee - cron_wrapper::command::tests::wait_idle_timeout::h5b4671a8416181a6 [INFO] [stdout] at /opt/rustwide/workdir/src/command.rs:1267:9 [INFO] [stdout] 20: 0x55dda6b19807 - cron_wrapper::command::tests::wait_idle_timeout::{{closure}}::h884cfbf9b732f936 [INFO] [stdout] at /opt/rustwide/workdir/src/command.rs:1256:27 [INFO] [stdout] 21: 0x55dda6aff226 - core::ops::function::FnOnce::call_once::ha33bdaf14b6734ca [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55dda6b71fcb - core::ops::function::FnOnce::call_once::hb194ee8411fb46c7 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55dda6b71fcb - test::__rust_begin_short_backtrace::h871a93aaa56c76c5 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:623:18 [INFO] [stdout] 24: 0x55dda6b716d1 - test::run_test_in_process::{{closure}}::h6d9e6b0161cb50c6 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:646:60 [INFO] [stdout] 25: 0x55dda6b716d1 - as core::ops::function::FnOnce<()>>::call_once::hb9f3c63b5d0e0baa [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x55dda6b716d1 - std::panicking::try::do_call::h83eb4c9b7c70ebcc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:559:40 [INFO] [stdout] 27: 0x55dda6b716d1 - std::panicking::try::h5ffd56bf671a89b3 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:523:19 [INFO] [stdout] 28: 0x55dda6b716d1 - std::panic::catch_unwind::h044e758d6cbf65b7 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panic.rs:149:14 [INFO] [stdout] 29: 0x55dda6b716d1 - test::run_test_in_process::hf7ca2db08c9223b8 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:646:27 [INFO] [stdout] 30: 0x55dda6b716d1 - test::run_test::{{closure}}::h9657c20462181b87 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:569:43 [INFO] [stdout] 31: 0x55dda6b3a154 - test::run_test::{{closure}}::h80c3aef427ce62cc [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/test/src/lib.rs:597:41 [INFO] [stdout] 32: 0x55dda6b3a154 - std::sys_common::backtrace::__rust_begin_short_backtrace::h43274eec75e29097 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 33: 0x55dda6b3ebc2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hea1335728d2a7953 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/thread/mod.rs:542:17 [INFO] [stdout] 34: 0x55dda6b3ebc2 - as core::ops::function::FnOnce<()>>::call_once::hb16d5d5fd985753c [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x55dda6b3ebc2 - std::panicking::try::do_call::h65de05e16c44e167 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:559:40 [INFO] [stdout] 36: 0x55dda6b3ebc2 - std::panicking::try::h187f01afe14eb3bf [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panicking.rs:523:19 [INFO] [stdout] 37: 0x55dda6b3ebc2 - std::panic::catch_unwind::h1b55347b2c8efb63 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/panic.rs:149:14 [INFO] [stdout] 38: 0x55dda6b3ebc2 - std::thread::Builder::spawn_unchecked_::{{closure}}::hea39e0adaff5b52d [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/thread/mod.rs:541:30 [INFO] [stdout] 39: 0x55dda6b3ebc2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9b61aba01c3c1f32 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x55dda6d173db - as core::ops::function::FnOnce>::call_once::h09e5a4c541afa800 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2022:9 [INFO] [stdout] 41: 0x55dda6d173db - as core::ops::function::FnOnce>::call_once::h9c8b03c22f4e7026 [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/alloc/src/boxed.rs:2022:9 [INFO] [stdout] 42: 0x55dda6d173db - std::sys::pal::unix::thread::Thread::new::thread_start::h522bc89a54da820a [INFO] [stdout] at /rustc/129f3b9964af4d4a709d1383930ade12dfe7c081/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 43: 0x7f29a6e30ac3 - [INFO] [stdout] 44: 0x7f29a6ec1a04 - __clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] command::tests::wait_idle_timeout [INFO] [stdout] command::tests::wait_no_timeout [INFO] [stdout] [INFO] [stdout] test result: FAILED. 53 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.02s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "4ca10450183e155c38fa57effd8fa2b44f457198db516aa52f6a76939e54d5ab", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4ca10450183e155c38fa57effd8fa2b44f457198db516aa52f6a76939e54d5ab", kill_on_drop: false }` [INFO] [stdout] 4ca10450183e155c38fa57effd8fa2b44f457198db516aa52f6a76939e54d5ab