[INFO] fetching crate q-debug 0.1.1... [INFO] testing q-debug-0.1.1 against master#d7bd9cd469ff6871420007f091ef52fc32d2ca99 for pr-121417 [INFO] extracting crate q-debug 0.1.1 into /workspace/builds/worker-2-tc1/source [INFO] validating manifest of crates.io crate q-debug 0.1.1 on toolchain d7bd9cd469ff6871420007f091ef52fc32d2ca99 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate q-debug 0.1.1 [INFO] finished tweaking crates.io crate q-debug 0.1.1 [INFO] tweaked toml for crates.io crate q-debug 0.1.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml [INFO] crate crates.io crate q-debug 0.1.1 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" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] aedd78079b7275832523da0b91dbc6e7b732749ae4f631dd6120eb4d8cd3b42c [INFO] running `Command { std: "docker" "start" "-a" "aedd78079b7275832523da0b91dbc6e7b732749ae4f631dd6120eb4d8cd3b42c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "aedd78079b7275832523da0b91dbc6e7b732749ae4f631dd6120eb4d8cd3b42c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "aedd78079b7275832523da0b91dbc6e7b732749ae4f631dd6120eb4d8cd3b42c", kill_on_drop: false }` [INFO] [stdout] aedd78079b7275832523da0b91dbc6e7b732749ae4f631dd6120eb4d8cd3b42c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 70f2a16a0b1f0be68b9a88d7d684ed88a5897939426904db4732fdb5b2e906a7 [INFO] running `Command { std: "docker" "start" "-a" "70f2a16a0b1f0be68b9a88d7d684ed88a5897939426904db4732fdb5b2e906a7", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.0.0 [INFO] [stderr] Compiling libc v0.2.71 [INFO] [stderr] Compiling num-traits v0.2.12 [INFO] [stderr] Compiling num-integer v0.1.43 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling chrono v0.4.11 [INFO] [stderr] Compiling q-debug v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.36s [INFO] running `Command { std: "docker" "inspect" "70f2a16a0b1f0be68b9a88d7d684ed88a5897939426904db4732fdb5b2e906a7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "70f2a16a0b1f0be68b9a88d7d684ed88a5897939426904db4732fdb5b2e906a7", kill_on_drop: false }` [INFO] [stdout] 70f2a16a0b1f0be68b9a88d7d684ed88a5897939426904db4732fdb5b2e906a7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] e62b0f5c35af0a9e9a78aabd5072d9402826b438603d0db5128ee15b95006077 [INFO] running `Command { std: "docker" "start" "-a" "e62b0f5c35af0a9e9a78aabd5072d9402826b438603d0db5128ee15b95006077", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.3.3 [INFO] [stderr] Compiling regex-syntax v0.6.18 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling aho-corasick v0.7.10 [INFO] [stderr] Compiling regex v1.3.9 [INFO] [stderr] Compiling q-debug v0.1.1 (/opt/rustwide/workdir) [INFO] [stdout] warning: the item `chrono` is imported redundantly [INFO] [stdout] --> tests/smoke.rs:7:5 [INFO] [stdout] | [INFO] [stdout] 7 | use chrono; [INFO] [stdout] | ^^^^^^ the item `chrono` is already defined here [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.84s [INFO] running `Command { std: "docker" "inspect" "e62b0f5c35af0a9e9a78aabd5072d9402826b438603d0db5128ee15b95006077", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e62b0f5c35af0a9e9a78aabd5072d9402826b438603d0db5128ee15b95006077", kill_on_drop: false }` [INFO] [stdout] e62b0f5c35af0a9e9a78aabd5072d9402826b438603d0db5128ee15b95006077 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+d7bd9cd469ff6871420007f091ef52fc32d2ca99" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] a019ccb601c34ec27c9ff3b9412011e63bde41235ac4f4816a2ba7da685fd129 [INFO] running `Command { std: "docker" "start" "-a" "a019ccb601c34ec27c9ff3b9412011e63bde41235ac4f4816a2ba7da685fd129", kill_on_drop: false }` [INFO] [stderr] warning: the item `chrono` is imported redundantly [INFO] [stderr] --> tests/smoke.rs:7:5 [INFO] [stderr] | [INFO] [stderr] 7 | use chrono; [INFO] [stderr] | ^^^^^^ the item `chrono` is already defined here [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: `q-debug` (test "smoke") generated 1 warning [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/q_debug-1984d7a73a6c6e2a) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test fmt::tests::test_q ... ok [INFO] [stdout] test fmt::tests::test_header ... ok [INFO] [stdout] test fmt::tests::test_q_expr ... ok [INFO] [stdout] test fmt::tests::test_q_literal ... ok [INFO] [stdout] test logging::tests::test_header_returns_none_if_header_interval_not_elapsed_and_prev_module_and_function_same ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_header_interval_elapsed ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_not_logged_previously ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_prev_function_differs ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_prev_module_differs ... ok [INFO] [stdout] test macros::tests::test_function ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/smoke.rs (/opt/rustwide/target/debug/deps/smoke-c2ffe990ae0605bf) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test test_expr ... ok [INFO] [stdout] test test_ident ... ok [INFO] [stdout] test test_literal ... ok [INFO] [stdout] test test_header_on_function_or_module_change ... ok [INFO] [stdout] test test_empty_call ... ok [INFO] [stdout] test test_header_interval ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_header_interval stdout ---- [INFO] [stdout] thread 'test_header_interval' panicked at tests/smoke.rs:144:5: [INFO] [stdout] assertion failed: !HEADER_RE.is_match(&output_2) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5573a008f386 - std::backtrace_rs::backtrace::libunwind::trace::hf12510db56099dfb [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x5573a008f386 - std::backtrace_rs::backtrace::trace_unsynchronized::h94bf70956aef5b0b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5573a008f386 - std::sys_common::backtrace::_print_fmt::ha7d7986858ac8c0a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x5573a008f386 - ::fmt::h372c1ec7a663a1ab [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x5573a00b6cdc - core::fmt::rt::Argument::fmt::hecbea7268359d058 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x5573a00b6cdc - core::fmt::write::h4912438cc1e3f359 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x5573a008cabf - std::io::Write::write_fmt::hf3498c39593ae16b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/io/mod.rs:1846:15 [INFO] [stdout] 7: 0x5573a008f134 - std::sys_common::backtrace::_print::h37e973c7e1a1b050 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x5573a008f134 - std::sys_common::backtrace::print::h07b06dbedbb17b31 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x5573a0090e2b - std::panicking::default_hook::{{closure}}::he72022d4e9537bcf [INFO] [stdout] 10: 0x5573a0090afd - std::panicking::default_hook::h04cdc89992c8479b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x55739fec18a7 - as core::ops::function::Fn>::call::h815f3e5bf035c1a1 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 12: 0x55739fec18a7 - test::test_main::{{closure}}::hc6d17bd58ba26ad4 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x5573a0091460 - as core::ops::function::Fn>::call::hec33fe851e726d9b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 14: 0x5573a0091460 - std::panicking::rust_panic_with_hook::hf07491bf03f90571 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:786:13 [INFO] [stdout] 15: 0x5573a0091169 - std::panicking::begin_panic_handler::{{closure}}::h92e879536c12f298 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:652:13 [INFO] [stdout] 16: 0x5573a008f866 - std::sys_common::backtrace::__rust_end_short_backtrace::h3800ba1351aa8eea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x5573a0090ed4 - rust_begin_unwind [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:648:5 [INFO] [stdout] 18: 0x55739fe7bd95 - core::panicking::panic_fmt::h83446605a04b4380 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55739fe7be53 - core::panicking::panic::h6673d5ef89efcb4d [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panicking.rs:144:5 [INFO] [stdout] 20: 0x55739fe85f36 - smoke::test_header_interval::h323702b7bafdcdba [INFO] [stdout] at /opt/rustwide/workdir/tests/smoke.rs:144:5 [INFO] [stdout] 21: 0x55739fe83b27 - smoke::test_header_interval::{{closure}}::hed2fb78423c57908 [INFO] [stdout] at /opt/rustwide/workdir/tests/smoke.rs:127:26 [INFO] [stdout] 22: 0x55739fe8c436 - core::ops::function::FnOnce::call_once::h65bffab121b803ba [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x55739fec704f - core::ops::function::FnOnce::call_once::h2088a64ea35e9f55 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x55739fec704f - test::__rust_begin_short_backtrace::h3e3262ca517e0f6a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:620:18 [INFO] [stdout] 25: 0x55739fec5c20 - test::run_test_in_process::{{closure}}::h6b4258191636288b [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:60 [INFO] [stdout] 26: 0x55739fec5c20 - as core::ops::function::FnOnce<()>>::call_once::h017d06cfb8bed9ea [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x55739fec5c20 - std::panicking::try::do_call::hd5a0dc4bb374d709 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 28: 0x55739fec5c20 - std::panicking::try::h3168a4ce5bf4579d [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 29: 0x55739fec5c20 - std::panic::catch_unwind::hb64c62facfdc3e4a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 30: 0x55739fec5c20 - test::run_test_in_process::h127b8bcabd50528a [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:643:27 [INFO] [stdout] 31: 0x55739fec5c20 - test::run_test::{{closure}}::h0fdd7a3e6b40f361 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:566:43 [INFO] [stdout] 32: 0x55739fe8dfb3 - test::run_test::{{closure}}::h48b321fe4dddd4b5 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/test/src/lib.rs:594:41 [INFO] [stdout] 33: 0x55739fe8dfb3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hc21ce17eb804db15 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 34: 0x55739fe92cdf - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h09231cb1ff02341f [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:528:17 [INFO] [stdout] 35: 0x55739fe92cdf - as core::ops::function::FnOnce<()>>::call_once::h39609956c133d940 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x55739fe92cdf - std::panicking::try::do_call::hc39de059392fc838 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:555:40 [INFO] [stdout] 37: 0x55739fe92cdf - std::panicking::try::h8e296b7f146034ae [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panicking.rs:519:19 [INFO] [stdout] 38: 0x55739fe92cdf - std::panic::catch_unwind::hbd1bff6c3e457dcd [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/panic.rs:142:14 [INFO] [stdout] 39: 0x55739fe92cdf - std::thread::Builder::spawn_unchecked_::{{closure}}::h879be7a53fe7d1d6 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/thread/mod.rs:527:30 [INFO] [stdout] 40: 0x55739fe92cdf - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0fa08594624076ec [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x5573a00956c5 - as core::ops::function::FnOnce>::call_once::hce6bcef84364f9de [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x5573a00956c5 - as core::ops::function::FnOnce>::call_once::h5656bb08f6ae0836 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 43: 0x5573a00956c5 - std::sys::pal::unix::thread::Thread::new::thread_start::h42aad656803ef398 [INFO] [stdout] at /rustc/d7bd9cd469ff6871420007f091ef52fc32d2ca99/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 44: 0x7ff702e91ac3 - [INFO] [stdout] 45: 0x7ff702f22bf4 - __clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_header_interval [INFO] [stdout] [INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.26s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test smoke` [INFO] running `Command { std: "docker" "inspect" "a019ccb601c34ec27c9ff3b9412011e63bde41235ac4f4816a2ba7da685fd129", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a019ccb601c34ec27c9ff3b9412011e63bde41235ac4f4816a2ba7da685fd129", kill_on_drop: false }` [INFO] [stdout] a019ccb601c34ec27c9ff3b9412011e63bde41235ac4f4816a2ba7da685fd129 [INFO] testing q-debug-0.1.1 against try#393ef12c970fbc7f294cd96c35cb76f9591bc1d6 for pr-121417 [INFO] extracting crate q-debug 0.1.1 into /workspace/builds/worker-2-tc2/source [INFO] validating manifest of crates.io crate q-debug 0.1.1 on toolchain 393ef12c970fbc7f294cd96c35cb76f9591bc1d6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate q-debug 0.1.1 [INFO] finished tweaking crates.io crate q-debug 0.1.1 [INFO] tweaked toml for crates.io crate q-debug 0.1.1 written to /workspace/builds/worker-2-tc2/source/Cargo.toml [INFO] crate crates.io crate q-debug 0.1.1 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" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2bcbb037b03efe810a83bb9260fb5287e25c5b210f4a81621071384eec070193 [INFO] running `Command { std: "docker" "start" "-a" "2bcbb037b03efe810a83bb9260fb5287e25c5b210f4a81621071384eec070193", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2bcbb037b03efe810a83bb9260fb5287e25c5b210f4a81621071384eec070193", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2bcbb037b03efe810a83bb9260fb5287e25c5b210f4a81621071384eec070193", kill_on_drop: false }` [INFO] [stdout] 2bcbb037b03efe810a83bb9260fb5287e25c5b210f4a81621071384eec070193 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 21160436bbdfb4f6f172d4b90049b9fbed59cf78053a5eaa48646ba644e27d13 [INFO] running `Command { std: "docker" "start" "-a" "21160436bbdfb4f6f172d4b90049b9fbed59cf78053a5eaa48646ba644e27d13", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.0.0 [INFO] [stderr] Compiling libc v0.2.71 [INFO] [stderr] Compiling num-traits v0.2.12 [INFO] [stderr] Compiling num-integer v0.1.43 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling chrono v0.4.11 [INFO] [stderr] Compiling q-debug v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.31s [INFO] running `Command { std: "docker" "inspect" "21160436bbdfb4f6f172d4b90049b9fbed59cf78053a5eaa48646ba644e27d13", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "21160436bbdfb4f6f172d4b90049b9fbed59cf78053a5eaa48646ba644e27d13", kill_on_drop: false }` [INFO] [stdout] 21160436bbdfb4f6f172d4b90049b9fbed59cf78053a5eaa48646ba644e27d13 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] e893856e06e8a827c3acf23932e62f8531c4a7c1687d65be81fb80522d9bf46e [INFO] running `Command { std: "docker" "start" "-a" "e893856e06e8a827c3acf23932e62f8531c4a7c1687d65be81fb80522d9bf46e", kill_on_drop: false }` [INFO] [stderr] Compiling memchr v2.3.3 [INFO] [stderr] Compiling regex-syntax v0.6.18 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling aho-corasick v0.7.10 [INFO] [stderr] Compiling regex v1.3.9 [INFO] [stderr] Compiling q-debug v0.1.1 (/opt/rustwide/workdir) [INFO] [stdout] warning: the item `chrono` is imported redundantly [INFO] [stdout] --> tests/smoke.rs:7:5 [INFO] [stdout] | [INFO] [stdout] 7 | use chrono; [INFO] [stdout] | ^^^^^^ the item `chrono` is already defined here [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 1 warning emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.78s [INFO] running `Command { std: "docker" "inspect" "e893856e06e8a827c3acf23932e62f8531c4a7c1687d65be81fb80522d9bf46e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e893856e06e8a827c3acf23932e62f8531c4a7c1687d65be81fb80522d9bf46e", kill_on_drop: false }` [INFO] [stdout] e893856e06e8a827c3acf23932e62f8531c4a7c1687d65be81fb80522d9bf46e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+393ef12c970fbc7f294cd96c35cb76f9591bc1d6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 74ce3f4df780c35b0a923d656e44c4ef1bc8f2174b77ce6a1f6d423f1704065c [INFO] running `Command { std: "docker" "start" "-a" "74ce3f4df780c35b0a923d656e44c4ef1bc8f2174b77ce6a1f6d423f1704065c", kill_on_drop: false }` [INFO] [stderr] warning: the item `chrono` is imported redundantly [INFO] [stderr] --> tests/smoke.rs:7:5 [INFO] [stderr] | [INFO] [stderr] 7 | use chrono; [INFO] [stderr] | ^^^^^^ the item `chrono` is already defined here [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: `q-debug` (test "smoke") generated 1 warning [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/q_debug-1984d7a73a6c6e2a) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test fmt::tests::test_header ... ok [INFO] [stdout] test fmt::tests::test_q_expr ... ok [INFO] [stdout] test fmt::tests::test_q_literal ... ok [INFO] [stdout] test fmt::tests::test_q ... ok [INFO] [stdout] test logging::tests::test_header_returns_none_if_header_interval_not_elapsed_and_prev_module_and_function_same ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_header_interval_elapsed ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_not_logged_previously ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_prev_function_differs ... ok [INFO] [stdout] test logging::tests::test_header_returns_some_if_prev_module_differs ... ok [INFO] [stdout] test macros::tests::test_function ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/smoke.rs (/opt/rustwide/target/debug/deps/smoke-c2ffe990ae0605bf) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test test_literal ... ok [INFO] [stdout] test test_ident ... ok [INFO] [stdout] test test_expr ... ok [INFO] [stdout] test test_header_on_function_or_module_change ... ok [INFO] [stdout] test test_empty_call ... ok [INFO] [stdout] test test_header_interval ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s [INFO] [stdout] [INFO] [stderr] Doc-tests q-debug [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test src/fmt.rs - fmt::Formatter::q_literal (line 28) ... ok [INFO] [stdout] test src/fmt.rs - fmt::Formatter::q_expr (line 50) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "74ce3f4df780c35b0a923d656e44c4ef1bc8f2174b77ce6a1f6d423f1704065c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "74ce3f4df780c35b0a923d656e44c4ef1bc8f2174b77ce6a1f6d423f1704065c", kill_on_drop: false }` [INFO] [stdout] 74ce3f4df780c35b0a923d656e44c4ef1bc8f2174b77ce6a1f6d423f1704065c