[INFO] fetching crate slow_function_warning 0.6.0... [INFO] testing slow_function_warning-0.6.0 against master#2fd6efc32704647e64d3d646d21c4c68eae100e4 for pr-149852-1 [INFO] extracting crate slow_function_warning 0.6.0 into /workspace/builds/worker-7-tc1/source [INFO] started tweaking crates.io crate slow_function_warning 0.6.0 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate slow_function_warning 0.6.0 [INFO] tweaked toml for crates.io crate slow_function_warning 0.6.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate slow_function_warning 0.6.0 on toolchain 2fd6efc32704647e64d3d646d21c4c68eae100e4 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 17 packages to latest compatible versions [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 779b1ecb9809941bbed195fe70a0ba984c70987e95a39ed9ab4b2bc548df9ab6 [INFO] running `Command { std: "docker" "start" "-a" "779b1ecb9809941bbed195fe70a0ba984c70987e95a39ed9ab4b2bc548df9ab6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "779b1ecb9809941bbed195fe70a0ba984c70987e95a39ed9ab4b2bc548df9ab6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "779b1ecb9809941bbed195fe70a0ba984c70987e95a39ed9ab4b2bc548df9ab6", kill_on_drop: false }` [INFO] [stdout] 779b1ecb9809941bbed195fe70a0ba984c70987e95a39ed9ab4b2bc548df9ab6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 9437ed697172272ea418b2c853a720c6efa32e4e779217cd6cc2edf1a63b6849 [INFO] running `Command { std: "docker" "start" "-a" "9437ed697172272ea418b2c853a720c6efa32e4e779217cd6cc2edf1a63b6849", kill_on_drop: false }` [INFO] [stderr] Compiling slow_function_warning v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.53s [INFO] running `Command { std: "docker" "inspect" "9437ed697172272ea418b2c853a720c6efa32e4e779217cd6cc2edf1a63b6849", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9437ed697172272ea418b2c853a720c6efa32e4e779217cd6cc2edf1a63b6849", kill_on_drop: false }` [INFO] [stdout] 9437ed697172272ea418b2c853a720c6efa32e4e779217cd6cc2edf1a63b6849 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d761a90e589aa7e4296c98a4642efa1f3091afc28740fa1496959082c0590f7f [INFO] running `Command { std: "docker" "start" "-a" "d761a90e589aa7e4296c98a4642efa1f3091afc28740fa1496959082c0590f7f", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.105 [INFO] [stderr] Compiling quote v1.0.43 [INFO] [stderr] Compiling syn v2.0.114 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling slow_function_warning v0.6.0 (/opt/rustwide/workdir) [INFO] [stderr] Compiling tokio v1.49.0 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 9.29s [INFO] running `Command { std: "docker" "inspect" "d761a90e589aa7e4296c98a4642efa1f3091afc28740fa1496959082c0590f7f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d761a90e589aa7e4296c98a4642efa1f3091afc28740fa1496959082c0590f7f", kill_on_drop: false }` [INFO] [stdout] d761a90e589aa7e4296c98a4642efa1f3091afc28740fa1496959082c0590f7f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+2fd6efc32704647e64d3d646d21c4c68eae100e4" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d5371075d0c01632c16fb7a545965a0439f164deebafb57fdda6d21a97b9f6ea [INFO] running `Command { std: "docker" "start" "-a" "d5371075d0c01632c16fb7a545965a0439f164deebafb57fdda6d21a97b9f6ea", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/slow_function_warning-e0a24d1d7d504cce) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/compiles.rs (/opt/rustwide/target/debug/deps/compiles-973733af07a9ffc1) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test default_compiles ... ok [INFO] [stdout] test default_warning_compiles ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/slow_function_warning.rs (/opt/rustwide/target/debug/deps/slow_function_warning-1bf7bd6a33a6162f) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test no_warn_impl ... ok [INFO] [stdout] test no_warn_using_params ... ok [INFO] [stdout] test warn ... ok [INFO] [stdout] test warn_using_params ... ok [INFO] [stdout] test no_warn_async ... ok [INFO] [stdout] test warn_impl ... ok [INFO] [stdout] test no_warn ... ok [INFO] [stdout] test warn_async ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/variables.rs (/opt/rustwide/target/debug/deps/variables-8341e4fd257e960a) [INFO] [stdout] [INFO] [stdout] running 44 tests [INFO] [stdout] test elapsed_d ... ok [INFO] [stdout] test elapsed_days ... ok [INFO] [stdout] test elapsed ... ok [INFO] [stdout] test elapsed_m ... ok [INFO] [stdout] test elapsed_h ... ok [INFO] [stdout] test elapsed_millis ... ok [INFO] [stdout] test elapsed_minutes ... ok [INFO] [stdout] test elapsed_milliseconds ... ok [INFO] [stdout] test elapsed_nanos ... ok [INFO] [stdout] test elapsed_min ... ok [INFO] [stdout] test elapsed_nanoseconds ... ok [INFO] [stdout] test elapsed_s ... ok [INFO] [stdout] test elapsed_seconds ... ok [INFO] [stdout] test elapsed_microseconds ... ok [INFO] [stdout] test elapsed_us ... ok [INFO] [stdout] test function ... ok [INFO] [stdout] test limit ... ok [INFO] [stdout] test limit_d ... ok [INFO] [stdout] test limit_days ... ok [INFO] [stdout] test limit_h ... ok [INFO] [stdout] test limit_hours ... ok [INFO] [stdout] test limit_m ... ok [INFO] [stdout] test limit_micros ... ok [INFO] [stdout] test elapsed_secs ... ok [INFO] [stdout] test elapsed_hours ... ok [INFO] [stdout] test limit_microseconds ... ok [INFO] [stdout] test limit_milliseconds ... ok [INFO] [stdout] test limit_min ... ok [INFO] [stdout] test limit_minutes ... ok [INFO] [stdout] test limit_ms ... ok [INFO] [stdout] test limit_millis ... ok [INFO] [stdout] test limit_nanoseconds ... ok [INFO] [stdout] test limit_ns ... ok [INFO] [stdout] test limit_s ... ok [INFO] [stdout] test limit_secs ... ok [INFO] [stdout] test limit_str ... ok [INFO] [stdout] test module ... ok [INFO] [stdout] test limit_seconds ... ok [INFO] [stdout] test limit_nanos ... ok [INFO] [stdout] test elapsed_micros ... ok [INFO] [stdout] test elapsed_ns ... ok [INFO] [stdout] test limit_us ... ok [INFO] [stdout] test elapsed_ms ... FAILED [INFO] [stderr] error: test failed, to rerun pass `--test variables` [INFO] [stdout] test elapsed_str ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- elapsed_ms stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'elapsed_ms' (50) panicked at tests/variables.rs:145:5: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: 12 [INFO] [stdout] right: 2 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x566a4592a4c2 - <::print::DisplayBacktrace as core[c5ed12ab89cc536a]::fmt::Display>::fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x566a4593f34a - ::fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x566a4593f34a - core[c5ed12ab89cc536a]::fmt::write [INFO] [stdout] 6: 0x566a4592f2f6 - std[716c9a7a72e5c14e]::io::default_write_fmt::> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x566a4592f2f6 - as std[716c9a7a72e5c14e]::io::Write>::write_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x566a459088bf - ::print [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x566a459088bf - std[716c9a7a72e5c14e]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x566a459226d9 - std[716c9a7a72e5c14e]::panicking::default_hook [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x566a458bdfde - core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x566a458bdfde - test[64760d2bdea328cc]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x566a45922892 - core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x566a45922892 - std[716c9a7a72e5c14e]::panicking::panic_with_hook [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x566a45908978 - std[716c9a7a72e5c14e]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x566a45900349 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x566a459097cd - __rustc[4f0b026143eab78e]::rust_begin_unwind [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x566a4593f9bc - core[c5ed12ab89cc536a]::panicking::panic_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x566a4593f8d3 - core[c5ed12ab89cc536a]::panicking::assert_failed_inner [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:439:17 [INFO] [stdout] 20: 0x566a4589c08e - core[c5ed12ab89cc536a]::panicking::assert_failed:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:394:5 [INFO] [stdout] 21: 0x566a4589d080 - variables[56cf2d05f71cd537]::elapsed_ms [INFO] [stdout] at /opt/rustwide/workdir/tests/variables.rs:145:5 [INFO] [stdout] 22: 0x566a4589c1e7 - variables[56cf2d05f71cd537]::elapsed_ms::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/variables.rs:136:16 [INFO] [stdout] 23: 0x566a458b17f6 - >::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x566a458b238b - core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x566a458b238b - test[64760d2bdea328cc]::__rust_begin_short_backtrace::, fn() -> core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:663:18 [INFO] [stdout] 26: 0x566a458bebea - test[64760d2bdea328cc]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:74 [INFO] [stdout] 27: 0x566a458bebea - as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 28: 0x566a458bebea - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40 [INFO] [stdout] 29: 0x566a458bebea - std[716c9a7a72e5c14e]::panicking::catch_unwind::, core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19 [INFO] [stdout] 30: 0x566a458bebea - std[716c9a7a72e5c14e]::panic::catch_unwind::, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x566a458bebea - test[64760d2bdea328cc]::run_test_in_process [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:27 [INFO] [stdout] 32: 0x566a458bebea - test[64760d2bdea328cc]::run_test::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:607:43 [INFO] [stdout] 33: 0x566a458b90b4 - test[64760d2bdea328cc]::run_test::{closure#1} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:637:41 [INFO] [stdout] 34: 0x566a458b90b4 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 35: 0x566a458c16e2 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 36: 0x566a458c16e2 - ::{closure#1}::{closure#0}> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 37: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40 [INFO] [stdout] 38: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panicking::catch_unwind::<(), core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19 [INFO] [stdout] 39: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x566a458c16e2 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 41: 0x566a458c16e2 - ::{closure#1} as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x566a45929d6f - + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 43: 0x566a45929d6f - ::new::thread_start [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 44: 0x7dc2b967baa4 - [INFO] [stdout] 45: 0x7dc2b9708a64 - clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- elapsed_str stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'elapsed_str' (57) panicked at tests/variables.rs:54:5: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: "3ms" [INFO] [stdout] right: "2ms" [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x566a4592a4c2 - std[716c9a7a72e5c14e]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x566a4592a4c2 - <::print::DisplayBacktrace as core[c5ed12ab89cc536a]::fmt::Display>::fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x566a4593f34a - ::fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x566a4593f34a - core[c5ed12ab89cc536a]::fmt::write [INFO] [stdout] 6: 0x566a4592f2f6 - std[716c9a7a72e5c14e]::io::default_write_fmt::> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x566a4592f2f6 - as std[716c9a7a72e5c14e]::io::Write>::write_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x566a459088bf - ::print [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x566a459088bf - std[716c9a7a72e5c14e]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x566a459226d9 - std[716c9a7a72e5c14e]::panicking::default_hook [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x566a458bdfde - core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x566a458bdfde - test[64760d2bdea328cc]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x566a45922892 - core[c5ed12ab89cc536a]::ops::function::Fn<(&'a std[716c9a7a72e5c14e]::panic::PanicHookInfo<'b>,), Output = ()> + core[c5ed12ab89cc536a]::marker::Sync + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::Fn<(&std[716c9a7a72e5c14e]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x566a45922892 - std[716c9a7a72e5c14e]::panicking::panic_with_hook [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x566a45908978 - std[716c9a7a72e5c14e]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x566a45900349 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x566a459097cd - __rustc[4f0b026143eab78e]::rust_begin_unwind [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x566a4593f9bc - core[c5ed12ab89cc536a]::panicking::panic_fmt [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x566a4593f8d3 - core[c5ed12ab89cc536a]::panicking::assert_failed_inner [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:439:17 [INFO] [stdout] 20: 0x566a4589c03d - core[c5ed12ab89cc536a]::panicking::assert_failed:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panicking.rs:394:5 [INFO] [stdout] 21: 0x566a4589d335 - variables[56cf2d05f71cd537]::elapsed_str [INFO] [stdout] at /opt/rustwide/workdir/tests/variables.rs:54:5 [INFO] [stdout] 22: 0x566a4589c307 - variables[56cf2d05f71cd537]::elapsed_str::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/variables.rs:45:17 [INFO] [stdout] 23: 0x566a458b1976 - >::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x566a458b238b - core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x566a458b238b - test[64760d2bdea328cc]::__rust_begin_short_backtrace::, fn() -> core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:663:18 [INFO] [stdout] 26: 0x566a458bebea - test[64760d2bdea328cc]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:74 [INFO] [stdout] 27: 0x566a458bebea - as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 28: 0x566a458bebea - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40 [INFO] [stdout] 29: 0x566a458bebea - std[716c9a7a72e5c14e]::panicking::catch_unwind::, core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19 [INFO] [stdout] 30: 0x566a458bebea - std[716c9a7a72e5c14e]::panic::catch_unwind::, core[c5ed12ab89cc536a]::result::Result<(), alloc[9c68fdf4f4f29218]::string::String>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x566a458bebea - test[64760d2bdea328cc]::run_test_in_process [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:686:27 [INFO] [stdout] 32: 0x566a458bebea - test[64760d2bdea328cc]::run_test::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:607:43 [INFO] [stdout] 33: 0x566a458b90b4 - test[64760d2bdea328cc]::run_test::{closure#1} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/test/src/lib.rs:637:41 [INFO] [stdout] 34: 0x566a458b90b4 - std[716c9a7a72e5c14e]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 35: 0x566a458c16e2 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 36: 0x566a458c16e2 - ::{closure#1}::{closure#0}> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 37: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:581:40 [INFO] [stdout] 38: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panicking::catch_unwind::<(), core[c5ed12ab89cc536a]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panicking.rs:544:19 [INFO] [stdout] 39: 0x566a458c16e2 - std[716c9a7a72e5c14e]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x566a458c16e2 - std[716c9a7a72e5c14e]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 41: 0x566a458c16e2 - ::{closure#1} as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x566a45929d6f - + core[c5ed12ab89cc536a]::marker::Send> as core[c5ed12ab89cc536a]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 43: 0x566a45929d6f - ::new::thread_start [INFO] [stdout] at /rustc/2fd6efc32704647e64d3d646d21c4c68eae100e4/library/std/src/sys/thread/unix.rs:119:17 [INFO] [stdout] 44: 0x7dc2b967baa4 - [INFO] [stdout] 45: 0x7dc2b9708a64 - clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] elapsed_ms [INFO] [stdout] elapsed_str [INFO] [stdout] [INFO] [stdout] test result: FAILED. 42 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "d5371075d0c01632c16fb7a545965a0439f164deebafb57fdda6d21a97b9f6ea", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d5371075d0c01632c16fb7a545965a0439f164deebafb57fdda6d21a97b9f6ea", kill_on_drop: false }` [INFO] [stdout] d5371075d0c01632c16fb7a545965a0439f164deebafb57fdda6d21a97b9f6ea