[INFO] fetching crate criterion2 3.0.2... [INFO] testing criterion2-3.0.2 against master#7704328ba5ae8d6ce0ac303c9d5a1a1605906766 for pr-146989 [INFO] extracting crate criterion2 3.0.2 into /workspace/builds/worker-7-tc1/source [INFO] removed /workspace/builds/worker-7-tc1/source/rust-toolchain.toml [INFO] started tweaking crates.io crate criterion2 3.0.2 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate criterion2 3.0.2 [INFO] tweaked toml for crates.io crate criterion2 3.0.2 written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate criterion2 3.0.2 on toolchain 7704328ba5ae8d6ce0ac303c9d5a1a1605906766 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate criterion2 3.0.2 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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded winapi-util v0.1.9 [INFO] [stderr] Downloaded slab v0.4.10 [INFO] [stderr] Downloaded addr2line v0.24.2 [INFO] [stderr] Downloaded codspeed v3.0.4 [INFO] [stderr] Downloaded rustc-demangle v0.1.26 [INFO] [stderr] Downloaded backtrace v0.3.75 [INFO] [stderr] Downloaded async-global-executor v2.4.1 [INFO] [stderr] Downloaded gloo-timers v0.3.0 [INFO] [stderr] Downloaded glob v0.3.2 [INFO] [stderr] Downloaded oorandom v11.1.5 [INFO] [stderr] Downloaded wasm-bindgen-futures v0.4.50 [INFO] [stderr] Downloaded anes v0.2.1 [INFO] [stderr] Downloaded half v2.6.0 [INFO] [stderr] Downloaded anyhow v1.0.98 [INFO] [stderr] Downloaded polling v3.9.0 [INFO] [stderr] Downloaded zerocopy-derive v0.8.26 [INFO] [stderr] Downloaded async-io v2.5.0 [INFO] [stderr] Downloaded io-uring v0.7.9 [INFO] [stderr] Downloaded uuid v1.17.0 [INFO] [stderr] Downloaded statrs v0.18.0 [INFO] [stderr] Downloaded bpaf v0.9.20 [INFO] [stderr] Downloaded serde_json v1.0.141 [INFO] [stderr] Downloaded async-std v1.13.1 [INFO] [stderr] Downloaded zerocopy v0.8.26 [INFO] [stderr] Downloaded gimli v0.31.1 [INFO] [stderr] Downloaded smol v2.0.2 [INFO] [stderr] Downloaded litrs v0.4.2 [INFO] [stderr] Downloaded nix v0.29.0 [INFO] [stderr] Downloaded object v0.36.7 [INFO] [stderr] Downloaded async-lock v3.4.0 [INFO] [stderr] Downloaded event-listener v5.4.0 [INFO] [stderr] Downloaded futures-lite v2.6.0 [INFO] [stderr] Downloaded colored v2.2.0 [INFO] [stderr] Downloaded value-bag v1.11.1 [INFO] [stderr] Downloaded colored v3.0.0 [INFO] [stderr] Downloaded async-executor v1.13.2 [INFO] [stderr] Downloaded async-signal v0.2.12 [INFO] [stderr] Downloaded async-process v2.4.0 [INFO] [stderr] Downloaded kv-log-macro v1.0.7 [INFO] [stderr] Downloaded document-features v0.2.11 [INFO] [stderr] Downloaded quickcheck v1.0.3 [INFO] [stderr] Downloaded async-fs v2.1.3 [INFO] [stderr] Downloaded async-net v2.0.0 [INFO] [stderr] Downloaded signal-hook-registry v1.4.5 [INFO] [stderr] Downloaded web-sys v0.3.77 [INFO] [stderr] Downloaded tokio v1.47.0 [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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6068246a04acf5577912e44d10fdb49a63c1df5180d01535b544c127dc58ff05 [INFO] running `Command { std: "docker" "start" "-a" "6068246a04acf5577912e44d10fdb49a63c1df5180d01535b544c127dc58ff05", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6068246a04acf5577912e44d10fdb49a63c1df5180d01535b544c127dc58ff05", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6068246a04acf5577912e44d10fdb49a63c1df5180d01535b544c127dc58ff05", kill_on_drop: false }` [INFO] [stdout] 6068246a04acf5577912e44d10fdb49a63c1df5180d01535b544c127dc58ff05 [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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b4a4a1803c8eba2e4676dffc618939c0557edb5eb7ae98426a0b85d48c760e3a [INFO] running `Command { std: "docker" "start" "-a" "b4a4a1803c8eba2e4676dffc618939c0557edb5eb7ae98426a0b85d48c760e3a", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.95 [INFO] [stderr] Compiling unicode-ident v1.0.18 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling cfg-if v1.0.1 [INFO] [stderr] Compiling rayon-core v1.12.1 [INFO] [stderr] Compiling ciborium-io v0.2.2 [INFO] [stderr] Compiling serde_json v1.0.141 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling anes v0.2.1 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling bpaf v0.9.20 [INFO] [stderr] Compiling half v2.6.0 [INFO] [stderr] Compiling oorandom v11.1.5 [INFO] [stderr] Compiling walkdir v2.5.0 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling rayon v1.10.0 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling criterion2 v3.0.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.89s [INFO] running `Command { std: "docker" "inspect" "b4a4a1803c8eba2e4676dffc618939c0557edb5eb7ae98426a0b85d48c760e3a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b4a4a1803c8eba2e4676dffc618939c0557edb5eb7ae98426a0b85d48c760e3a", kill_on_drop: false }` [INFO] [stdout] b4a4a1803c8eba2e4676dffc618939c0557edb5eb7ae98426a0b85d48c760e3a [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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5e25899a9be63faa72490bbca7bee1d88c74fa4a427f851d41b75484c0c801cc [INFO] running `Command { std: "docker" "start" "-a" "5e25899a9be63faa72490bbca7bee1d88c74fa4a427f851d41b75484c0c801cc", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.174 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling zerocopy v0.8.26 [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling slab v0.4.10 [INFO] [stderr] Compiling rustix v1.0.8 [INFO] [stderr] Compiling bitflags v2.9.1 [INFO] [stderr] Compiling linux-raw-sys v0.9.4 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling approx v0.5.1 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling serde_json v1.0.141 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling criterion2 v3.0.2 (/opt/rustwide/workdir) [INFO] [stderr] Compiling quickcheck v1.0.3 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 12.92s [INFO] running `Command { std: "docker" "inspect" "5e25899a9be63faa72490bbca7bee1d88c74fa4a427f851d41b75484c0c801cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5e25899a9be63faa72490bbca7bee1d88c74fa4a427f851d41b75484c0c801cc", kill_on_drop: false }` [INFO] [stdout] 5e25899a9be63faa72490bbca7bee1d88c74fa4a427f851d41b75484c0c801cc [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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 10cf0aad0a41c6afb580db235e64e4193cff5d63271c00f44706cdcba5620f13 [INFO] running `Command { std: "docker" "start" "-a" "10cf0aad0a41c6afb580db235e64e4193cff5d63271c00f44706cdcba5620f13", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/criterion-b9ac633e9c137f88) [INFO] [stdout] [INFO] [stdout] running 29 tests [INFO] [stdout] test format::test::integer_thousands_sep ... ok [INFO] [stdout] test report::test::test_benchmark_id_make_directory_name_unique ... ok [INFO] [stdout] test format::test::signed_short_max_len ... ok [INFO] [stdout] test report::test::test_benchmark_id_make_long_directory_name_unique ... ok [INFO] [stdout] test format::test::short_max_len ... ok [INFO] [stdout] test cli::check_invariants ... ok [INFO] [stdout] test report::test::test_make_filename_safe_respects_character_boundaries ... ok [INFO] [stdout] test report::test::test_make_filename_safe_truncates_long_strings ... ok [INFO] [stdout] test report::test::test_make_filename_safe_replaces_characters ... ok [INFO] [stdout] test stats::univariate::kde::kernel::test::f64::gaussian::symmetric ... ok [INFO] [stdout] test stats::univariate::kde::kernel::test::f32::gaussian::symmetric ... ok [INFO] [stdout] test stats::univariate::kde::kernel::test::f64::gaussian::integral ... ok [INFO] [stdout] test stats::univariate::kde::kernel::test::f32::gaussian::integral ... ok [INFO] [stdout] test stats::univariate::resamples::test::different_subsets ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f32::two_sample ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f64::two_sample ... ok [INFO] [stdout] test stats::bivariate::bootstrap::test::f64::slope ... ok [INFO] [stdout] test stats::univariate::kde::test::f64::integral ... ok [INFO] [stdout] test stats::bivariate::bootstrap::test::f32::means ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f32::mixed_two_sample ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f64::mixed_two_sample ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f64::mean ... ok [INFO] [stdout] test stats::bivariate::bootstrap::test::f64::means ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f32::mean ... ok [INFO] [stdout] test stats::bivariate::bootstrap::test::f32::slope ... ok [INFO] [stdout] test stats::univariate::resamples::test::subset ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f64::mean_median ... ok [INFO] [stdout] test stats::univariate::bootstrap::test::f32::mean_median ... ok [INFO] [stdout] test stats::univariate::kde::test::f32::integral ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.04s [INFO] [stdout] [INFO] [stderr] Running tests/criterion_tests.rs (/opt/rustwide/target/debug/deps/criterion_tests-a8691f1e12c18cd6) [INFO] [stdout] [INFO] [stdout] running 19 tests [INFO] [stdout] test test_filtering ... ok [INFO] [stdout] test test_compare_baseline_strict_panics_when_missing_baseline - should panic ... ok [INFO] [stdout] test test_bench_with_no_iteration_panics - should panic ... ok [INFO] [stdout] test test_setup_wrapper_with_multiple_runner_calls_panics - should panic ... ok [INFO] [stdout] test test_setup_wrapper_with_no_runner_call_panics - should panic ... ok [INFO] [stdout] test test_criterion_doesnt_panic_if_measured_time_is_zero ... ok [INFO] [stdout] test test_profiler_called ... ok [INFO] [stdout] test test_compare_baseline_lenient_when_missing_baseline ... ok [INFO] [stdout] test test_measurement_time ... FAILED [INFO] [stdout] test test_sample_size ... ok [INFO] [stdout] test test_without_plots ... ok [INFO] [stdout] test test_creates_directory ... ok [INFO] [stdout] test test_bench_function ... ok [INFO] [stdout] test test_save_baseline ... ok [INFO] [stdout] test test_benchmark_group_without_input ... ok [INFO] [stdout] test test_retain_baseline ... ok [INFO] [stdout] test test_warmup_time ... ok [INFO] [stdout] test test_benchmark_group_with_input ... ok [INFO] [stdout] test test_timing_loops ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_measurement_time stdout ---- [INFO] [stdout] Benchmarking test_meas_time_1 [INFO] [stdout] Benchmarking test_meas_time_1: Warming up for 250.00 ms [INFO] [stdout] Benchmarking test_meas_time_1: Collecting 100 samples in estimated 100.11 ms (2.6M iterations) [INFO] [stdout] Benchmarking test_meas_time_1: Analyzing [INFO] [stdout] test_meas_time_1 time: [35.011 ns 35.153 ns 35.328 ns] [INFO] [stdout] Found 9 outliers among 100 measurements (9.00%) [INFO] [stdout] 5 (5.00%) high mild [INFO] [stdout] 4 (4.00%) high severe [INFO] [stdout] [INFO] [stdout] Benchmarking test_meas_time_2 [INFO] [stdout] Benchmarking test_meas_time_2: Warming up for 250.00 ms [INFO] [stdout] Benchmarking test_meas_time_2: Collecting 100 samples in estimated 2.0017 s (3.1M iterations) [INFO] [stdout] Benchmarking test_meas_time_2: Analyzing [INFO] [stdout] test_meas_time_2 time: [190.88 ns 272.09 ns 375.55 ns] [INFO] [stdout] Found 7 outliers among 100 measurements (7.00%) [INFO] [stdout] 2 (2.00%) high mild [INFO] [stdout] 5 (5.00%) high severe [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] thread 'test_measurement_time' (80) panicked at tests/criterion_tests.rs:228:5: [INFO] [stdout] assertion failed: counter1.read() < counter2.read() [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x58939cb39fc2 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x58939cb39fc2 - std[ed5b486fa4cdb954]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x58939cb39fc2 - std[ed5b486fa4cdb954]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x58939cb39fc2 - <::print::DisplayBacktrace as core[ab67b43d93431064]::fmt::Display>::fmt [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x58939cb5056a - ::fmt [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x58939cb5056a - core[ab67b43d93431064]::fmt::write [INFO] [stdout] 6: 0x58939cb3f4c6 - std[ed5b486fa4cdb954]::io::default_write_fmt::> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x58939cb3f4c6 - as std[ed5b486fa4cdb954]::io::Write>::write_fmt [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x58939cb1540f - ::print [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x58939cb1540f - std[ed5b486fa4cdb954]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x58939cb30839 - std[ed5b486fa4cdb954]::panicking::default_hook [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x58939c9cd65e - core[ab67b43d93431064]::ops::function::Fn<(&'a std[ed5b486fa4cdb954]::panic::PanicHookInfo<'b>,), Output = ()> + core[ab67b43d93431064]::marker::Send + core[ab67b43d93431064]::marker::Sync> as core[ab67b43d93431064]::ops::function::Fn<(&std[ed5b486fa4cdb954]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 12: 0x58939c9cd65e - test[f0ec78552bebb08e]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x58939cb30aa2 - core[ab67b43d93431064]::ops::function::Fn<(&'a std[ed5b486fa4cdb954]::panic::PanicHookInfo<'b>,), Output = ()> + core[ab67b43d93431064]::marker::Send + core[ab67b43d93431064]::marker::Sync> as core[ab67b43d93431064]::ops::function::Fn<(&std[ed5b486fa4cdb954]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/alloc/src/boxed.rs:2220:9 [INFO] [stdout] 14: 0x58939cb30aa2 - std[ed5b486fa4cdb954]::panicking::panic_with_hook [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x58939cb154fa - std[ed5b486fa4cdb954]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:691:13 [INFO] [stdout] 16: 0x58939cb0ccd9 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x58939cb164ed - __rustc[5067f77124470312]::rust_begin_unwind [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x58939cb50cac - core[ab67b43d93431064]::panicking::panic_fmt [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x58939cb50c72 - core[ab67b43d93431064]::panicking::panic [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panicking.rs:150:5 [INFO] [stdout] 20: 0x58939c99e8aa - criterion_tests[d5ac55e102bba28a]::test_measurement_time [INFO] [stdout] at /opt/rustwide/workdir/tests/criterion_tests.rs:228:5 [INFO] [stdout] 21: 0x58939c99ac47 - criterion_tests[d5ac55e102bba28a]::test_measurement_time::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/tests/criterion_tests.rs:213:27 [INFO] [stdout] 22: 0x58939c998aa6 - >::call_once [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x58939c9c1a0b - core[ab67b43d93431064]::result::Result<(), alloc[8787631d7fe8ec5a]::string::String> as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x58939c9c1a0b - test[f0ec78552bebb08e]::__rust_begin_short_backtrace::, fn() -> core[ab67b43d93431064]::result::Result<(), alloc[8787631d7fe8ec5a]::string::String>> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:663:18 [INFO] [stdout] 25: 0x58939c9ce26a - test[f0ec78552bebb08e]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:686:74 [INFO] [stdout] 26: 0x58939c9ce26a - as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 27: 0x58939c9ce26a - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::, core[ab67b43d93431064]::result::Result<(), alloc[8787631d7fe8ec5a]::string::String>> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40 [INFO] [stdout] 28: 0x58939c9ce26a - std[ed5b486fa4cdb954]::panicking::catch_unwind::, core[ab67b43d93431064]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19 [INFO] [stdout] 29: 0x58939c9ce26a - std[ed5b486fa4cdb954]::panic::catch_unwind::, core[ab67b43d93431064]::result::Result<(), alloc[8787631d7fe8ec5a]::string::String>> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14 [INFO] [stdout] 30: 0x58939c9ce26a - test[f0ec78552bebb08e]::run_test_in_process [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:686:27 [INFO] [stdout] 31: 0x58939c9ce26a - test[f0ec78552bebb08e]::run_test::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:607:43 [INFO] [stdout] 32: 0x58939c9c9814 - test[f0ec78552bebb08e]::run_test::{closure#1} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/test/src/lib.rs:637:41 [INFO] [stdout] 33: 0x58939c9c9814 - std[ed5b486fa4cdb954]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 34: 0x58939c9d0d62 - std[ed5b486fa4cdb954]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 35: 0x58939c9d0d62 - ::{closure#1}::{closure#0}> as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stdout] 36: 0x58939c9d0d62 - std[ed5b486fa4cdb954]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:581:40 [INFO] [stdout] 37: 0x58939c9d0d62 - std[ed5b486fa4cdb954]::panicking::catch_unwind::<(), core[ab67b43d93431064]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panicking.rs:544:19 [INFO] [stdout] 38: 0x58939c9d0d62 - std[ed5b486fa4cdb954]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/panic.rs:359:14 [INFO] [stdout] 39: 0x58939c9d0d62 - std[ed5b486fa4cdb954]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 40: 0x58939c9d0d62 - ::{closure#1} as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x58939cb3966f - + core[ab67b43d93431064]::marker::Send> as core[ab67b43d93431064]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/alloc/src/boxed.rs:2206:9 [INFO] [stdout] 42: 0x58939cb3966f - ::new::thread_start [INFO] [stdout] at /rustc/7704328ba5ae8d6ce0ac303c9d5a1a1605906766/library/std/src/sys/thread/unix.rs:127:17 [INFO] [stdout] 43: 0x7813474edaa4 - [INFO] [stdout] 44: 0x78134757aa64 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_measurement_time [INFO] [stdout] [INFO] [stdout] test result: FAILED. 18 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 14.21s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test criterion_tests` [INFO] running `Command { std: "docker" "inspect" "10cf0aad0a41c6afb580db235e64e4193cff5d63271c00f44706cdcba5620f13", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "10cf0aad0a41c6afb580db235e64e4193cff5d63271c00f44706cdcba5620f13", kill_on_drop: false }` [INFO] [stdout] 10cf0aad0a41c6afb580db235e64e4193cff5d63271c00f44706cdcba5620f13