[INFO] fetching crate clockworker 0.2.6... [INFO] testing clockworker-0.2.6 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210 [INFO] extracting crate clockworker 0.2.6 into /workspace/builds/worker-1-tc1/source [INFO] started tweaking crates.io crate clockworker 0.2.6 [INFO] finished tweaking crates.io crate clockworker 0.2.6 [INFO] tweaked toml for crates.io crate clockworker 0.2.6 written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate clockworker 0.2.6 on toolchain 562dee4820c458d823175268e41601d4c060588a [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate clockworker 0.2.6 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded tabled_derive v0.11.0 [INFO] [stderr] Downloaded core_affinity v0.8.3 [INFO] [stderr] Downloaded monoio-macros v0.1.0 [INFO] [stderr] Downloaded testing_table v0.3.0 [INFO] [stderr] Downloaded auto-const-array v0.2.2 [INFO] [stderr] Downloaded io-uring v0.6.4 [INFO] [stderr] Downloaded papergrid v0.17.0 [INFO] [stderr] Downloaded monoio v0.2.4 [INFO] [stderr] Downloaded tabled v0.20.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6cf0e06b78907fe7cee07c9f5e4c8bf227f6109142513c029311691c20b90151 [INFO] running `Command { std: "docker" "start" "-a" "6cf0e06b78907fe7cee07c9f5e4c8bf227f6109142513c029311691c20b90151", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6cf0e06b78907fe7cee07c9f5e4c8bf227f6109142513c029311691c20b90151", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6cf0e06b78907fe7cee07c9f5e4c8bf227f6109142513c029311691c20b90151", kill_on_drop: false }` [INFO] [stdout] 6cf0e06b78907fe7cee07c9f5e4c8bf227f6109142513c029311691c20b90151 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5040812a0fe62f380a35a074fe7c0341ddfb01b0f46ae5daa08af306c189e963 [INFO] running `Command { std: "docker" "start" "-a" "5040812a0fe62f380a35a074fe7c0341ddfb01b0f46ae5daa08af306c189e963", kill_on_drop: false }` [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling syn v2.0.114 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling clockworker v0.2.6 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.39s [INFO] running `Command { std: "docker" "inspect" "5040812a0fe62f380a35a074fe7c0341ddfb01b0f46ae5daa08af306c189e963", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5040812a0fe62f380a35a074fe7c0341ddfb01b0f46ae5daa08af306c189e963", kill_on_drop: false }` [INFO] [stdout] 5040812a0fe62f380a35a074fe7c0341ddfb01b0f46ae5daa08af306c189e963 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] fd7bd01065086dae89fb4700d357bdaf0bdc12c3765db1f628358a2f626c9b91 [INFO] running `Command { std: "docker" "start" "-a" "fd7bd01065086dae89fb4700d357bdaf0bdc12c3765db1f628358a2f626c9b91", kill_on_drop: false }` [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling syn v2.0.114 [INFO] [stderr] Compiling rustix v1.1.3 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling errno v0.3.14 [INFO] [stderr] Compiling zerocopy v0.8.33 [INFO] [stderr] Compiling async-io v2.6.0 [INFO] [stderr] Compiling memoffset v0.7.1 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling proc-macro-error-attr2 v2.0.0 [INFO] [stderr] Compiling io-uring v0.6.4 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling bytecount v0.6.9 [INFO] [stderr] Compiling mio v0.8.11 [INFO] [stderr] Compiling testing_table v0.3.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.8 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling nanorand v0.7.0 [INFO] [stderr] Compiling papergrid v0.17.0 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling parking_lot_core v0.9.12 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling num_cpus v1.17.0 [INFO] [stderr] Compiling nix v0.26.4 [INFO] [stderr] Compiling socket2 v0.6.1 [INFO] [stderr] Compiling concurrent-queue v2.5.0 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling core_affinity v0.8.3 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling parking_lot v0.12.5 [INFO] [stderr] Compiling event-listener v5.4.1 [INFO] [stderr] Compiling async-executor v1.13.3 [INFO] [stderr] Compiling event-listener-strategy v0.5.4 [INFO] [stderr] Compiling async-channel v2.5.0 [INFO] [stderr] Compiling async-lock v3.4.2 [INFO] [stderr] Compiling blocking v1.6.2 [INFO] [stderr] Compiling async-fs v2.2.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling proc-macro-error2 v2.0.1 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tabled_derive v0.11.0 [INFO] [stderr] Compiling monoio-macros v0.1.0 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling auto-const-array v0.2.2 [INFO] [stderr] Compiling tokio v1.49.0 [INFO] [stderr] Compiling monoio v0.2.4 [INFO] [stderr] Compiling tabled v0.20.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling polling v3.11.0 [INFO] [stderr] Compiling async-signal v0.2.13 [INFO] [stderr] Compiling async-net v2.0.0 [INFO] [stderr] Compiling async-process v2.5.0 [INFO] [stderr] Compiling smol v2.0.2 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling clockworker v0.2.6 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 26.81s [INFO] running `Command { std: "docker" "inspect" "fd7bd01065086dae89fb4700d357bdaf0bdc12c3765db1f628358a2f626c9b91", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "fd7bd01065086dae89fb4700d357bdaf0bdc12c3765db1f628358a2f626c9b91", kill_on_drop: false }` [INFO] [stdout] fd7bd01065086dae89fb4700d357bdaf0bdc12c3765db1f628358a2f626c9b91 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 3ba97cc4ad16e49070f3d8b6dee380a0734f695b2316348b85b5703cf2b310b8 [INFO] running `Command { std: "docker" "start" "-a" "3ba97cc4ad16e49070f3d8b6dee380a0734f695b2316348b85b5703cf2b310b8", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/clockworker-2dbf5dd60dcf0504) [INFO] [stdout] [INFO] [stdout] running 41 tests [INFO] [stdout] test executor::tests::test_smol_runtime ... ok [INFO] [stdout] test executor::tests::test_spawn_detached_runs_to_completion ... ok [INFO] [stdout] test executor::tests::test_join_handle_returns_result ... ok [INFO] [stdout] test executor::tests::test_basic_task_completion ... ok [INFO] [stdout] test executor::tests::test_multiple_tasks_same_class ... ok [INFO] [stdout] test executor::tests::test_spawn_detached_many_tasks ... ok [INFO] [stdout] test executor::tests::test_yield_maybe ... ok [INFO] [stdout] test join::tests::test_complete_cancelled ... ok [INFO] [stdout] test join::tests::test_complete_ok ... ok [INFO] [stdout] test join::tests::test_complete_panic ... ok [INFO] [stdout] test join::tests::test_only_one_completer_wins ... ok [INFO] [stdout] test join::tests::test_result_taken_on_second_take ... ok [INFO] [stdout] test executor::tests::test_vruntime_resets ... ok [INFO] [stdout] test mpsc::tests::test_drop_with_items ... ok [INFO] [stdout] test mpsc::tests::test_basic_push_pop ... ok [INFO] [stdout] test mpsc::tests::test_fifo_order ... ok [INFO] [stdout] test preempt::tests::test_preemption_mask_basic ... ok [INFO] [stdout] test preempt::tests::test_preemption_state_check_clear ... ok [INFO] [stdout] test executor::tests::test_bad_executor_creation ... ok [INFO] [stdout] test queue::tests::test_task_queue_enqueue_empty_lifo ... ok [INFO] [stdout] test queue::tests::test_task_queue_enqueue_non_empty_lifo ... ok [INFO] [stdout] test queue::tests::test_task_queue_is_empty ... ok [INFO] [stdout] test queue::tests::test_task_queue_length_accuracy ... ok [INFO] [stdout] test queue::tests::test_task_queue_concurrent_enqueue ... ok [INFO] [stdout] test executor::tests::test_join_handle_abort ... ok [INFO] [stdout] test queue::tests::test_task_queue_pop_lifo ... ok [INFO] [stdout] test executor::tests::test_preemption_mask_computed_correctly ... ok [INFO] [stdout] test executor::tests::test_monoio_runtime ... ok [INFO] [stdout] test executor::tests::test_abort_after_done ... ok [INFO] [stdout] test executor::tests::test_enum_queue_ids ... ok [INFO] [stdout] test executor::tests::test_abort_before_task_starts ... ok [INFO] [stdout] test queue::tests::test_task_queue_lifo_disabled ... ok [INFO] [stdout] test queue::tests::test_task_queue_pop_skip_interval ... ok [INFO] [stdout] test mpsc::tests::test_empty_queue ... ok [INFO] [stdout] test executor::tests::test_task_with_yield ... ok [INFO] [stdout] test mpsc::tests::test_concurrent_push ... ok [INFO] [stdout] test executor::tests::test_policy_fifo_ordering ... ok [INFO] [stdout] test executor::tests::test_panic_crashes_executor ... ok [INFO] [stdout] test executor::tests::test_panic_caught_when_configured ... ok [INFO] [stdout] test executor::tests::test_vruntime_scheduling ... FAILED [INFO] [stdout] test join::tests::test_race_condition_result_visible_when_done ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- executor::tests::test_vruntime_scheduling stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'executor::tests::test_vruntime_scheduling' (35) panicked at src/executor.rs:1165:17: [INFO] [stdout] High weight class should get significantly more CPU time. High: 800000, Low: 400000 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5884151be07a - std[31e582c13f4e914f]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5884151be07a - std[31e582c13f4e914f]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5884151be07a - std[31e582c13f4e914f]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x5884151be07a - <::print::DisplayBacktrace as core[fa0eadad68403074]::fmt::Display>::fmt [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x5884151d256a - ::fmt [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x5884151d256a - core[fa0eadad68403074]::fmt::write [INFO] [stdout] 6: 0x5884151c2ad2 - std[31e582c13f4e914f]::io::default_write_fmt::> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5884151c2ad2 - as std[31e582c13f4e914f]::io::Write>::write_fmt [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x58841519cccf - ::print [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x58841519cccf - std[31e582c13f4e914f]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x5884151b64d9 - std[31e582c13f4e914f]::panicking::default_hook [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x5884150c3eec - core[fa0eadad68403074]::ops::function::Fn<(&'a std[31e582c13f4e914f]::panic::PanicHookInfo<'b>,), Output = ()> + core[fa0eadad68403074]::marker::Sync + core[fa0eadad68403074]::marker::Send> as core[fa0eadad68403074]::ops::function::Fn<(&std[31e582c13f4e914f]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 12: 0x5884150c3eec - test[7530e63dc052480e]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5884151b6752 - core[fa0eadad68403074]::ops::function::Fn<(&'a std[31e582c13f4e914f]::panic::PanicHookInfo<'b>,), Output = ()> + core[fa0eadad68403074]::marker::Sync + core[fa0eadad68403074]::marker::Send> as core[fa0eadad68403074]::ops::function::Fn<(&std[31e582c13f4e914f]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 14: 0x5884151b6752 - std[31e582c13f4e914f]::panicking::panic_with_hook [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x58841519cd88 - std[31e582c13f4e914f]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x588415191f69 - std[31e582c13f4e914f]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x58841519da9d - __rustc[fc8e3a9f23f26cb]::rust_begin_unwind [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x5884151d2d2c - core[fa0eadad68403074]::panicking::panic_fmt [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x58841500d251 - clockworker[1300233e95d39aac]::executor::tests::test_vruntime_scheduling::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/executor.rs:1165:17 [INFO] [stdout] 20: 0x58841504d7cf - as core[fa0eadad68403074]::future::future::Future>::poll::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/local.rs:1076:44 [INFO] [stdout] 21: 0x588415049790 - ::with::, as core[fa0eadad68403074]::future::future::Future>::poll::{closure#0}>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/local.rs:827:13 [INFO] [stdout] 22: 0x58841505d285 - >::try_with::<::with, as core[fa0eadad68403074]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[fa0eadad68403074]::task::poll::Poll<()>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:462:12 [INFO] [stdout] 23: 0x58841505c5ee - >::with::<::with, as core[fa0eadad68403074]::future::future::Future>::poll::{closure#0}>::{closure#0}, core[fa0eadad68403074]::task::poll::Poll<()>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:426:20 [INFO] [stdout] 24: 0x588415044636 - ::with::, as core[fa0eadad68403074]::future::future::Future>::poll::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/local.rs:825:17 [INFO] [stdout] 25: 0x5884150508f6 - as core[fa0eadad68403074]::future::future::Future>::poll [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/local.rs:1066:22 [INFO] [stdout] 26: 0x58841504b025 - ::run_until::::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/local.rs:723:19 [INFO] [stdout] 27: 0x588415014557 - clockworker[1300233e95d39aac]::executor::tests::test_vruntime_scheduling::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/executor.rs:1172:14 [INFO] [stdout] 28: 0x588415060332 - > as core[fa0eadad68403074]::future::future::Future>::poll [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/future/future.rs:133:9 [INFO] [stdout] 29: 0x5884150603ad - >> as core[fa0eadad68403074]::future::future::Future>::poll [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/future/future.rs:133:9 [INFO] [stdout] 30: 0x5884150581cd - ::block_on::>>>::{closure#0}::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:70 [INFO] [stdout] 31: 0x5884150580cb - tokio[13ae18bd0d5b994b]::task::coop::with_budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:167:5 [INFO] [stdout] 32: 0x5884150580cb - tokio[13ae18bd0d5b994b]::task::coop::budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/task/coop/mod.rs:133:5 [INFO] [stdout] 33: 0x5884150580cb - ::block_on::>>>::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:753:25 [INFO] [stdout] 34: 0x588415051af0 - ::enter::, ::block_on>>>::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:442:19 [INFO] [stdout] 35: 0x58841505721d - ::block_on::>>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:752:44 [INFO] [stdout] 36: 0x588415056514 - ::enter::<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:68 [INFO] [stdout] 37: 0x58841506a30b - >::set::<::enter<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0}, (alloc[49b8922c36ca62de]::boxed::Box, core[fa0eadad68403074]::option::Option<()>)> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 38: 0x588415078a89 - tokio[13ae18bd0d5b994b]::runtime::context::set_scheduler::<(alloc[49b8922c36ca62de]::boxed::Box, core[fa0eadad68403074]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0}>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:38 [INFO] [stdout] 39: 0x58841505e372 - >::try_with::, core[fa0eadad68403074]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[49b8922c36ca62de]::boxed::Box, core[fa0eadad68403074]::option::Option<()>)> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:462:12 [INFO] [stdout] 40: 0x58841505defe - >::with::, core[fa0eadad68403074]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[49b8922c36ca62de]::boxed::Box, core[fa0eadad68403074]::option::Option<()>)> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/local.rs:426:20 [INFO] [stdout] 41: 0x588415074d4d - tokio[13ae18bd0d5b994b]::runtime::context::set_scheduler::<(alloc[49b8922c36ca62de]::boxed::Box, core[fa0eadad68403074]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>>::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context.rs:176:17 [INFO] [stdout] 42: 0x588415054150 - ::enter::<::block_on>>>::{closure#0}, core[fa0eadad68403074]::option::Option<()>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:840:27 [INFO] [stdout] 43: 0x588415054446 - ::block_on::>>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:740:24 [INFO] [stdout] 44: 0x588415056020 - ::block_on::>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 45: 0x5884150642a3 - tokio[13ae18bd0d5b994b]::runtime::context::runtime::enter_runtime::<::block_on>>::{closure#0}, ()> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 46: 0x588415051411 - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 47: 0x58841505b034 - ::block_on_inner::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:368:52 [INFO] [stdout] 48: 0x58841505b1ff - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.49.0/src/runtime/runtime.rs:342:18 [INFO] [stdout] 49: 0x588415016bc3 - clockworker[1300233e95d39aac]::executor::tests::test_vruntime_scheduling [INFO] [stdout] at /opt/rustwide/workdir/src/executor.rs:1172:19 [INFO] [stdout] 50: 0x588415012777 - clockworker[1300233e95d39aac]::executor::tests::test_vruntime_scheduling::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/src/executor.rs:1118:40 [INFO] [stdout] 51: 0x588414ffd146 - >::call_once [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 52: 0x5884150b7fcb - core[fa0eadad68403074]::result::Result<(), alloc[49b8922c36ca62de]::string::String> as core[fa0eadad68403074]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 53: 0x5884150b7fcb - test[7530e63dc052480e]::__rust_begin_short_backtrace::, fn() -> core[fa0eadad68403074]::result::Result<(), alloc[49b8922c36ca62de]::string::String>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:663:18 [INFO] [stdout] 54: 0x5884150c49bb - test[7530e63dc052480e]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:74 [INFO] [stdout] 55: 0x5884150c49bb - as core[fa0eadad68403074]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 56: 0x5884150c49bb - std[31e582c13f4e914f]::panicking::catch_unwind::do_call::, core[fa0eadad68403074]::result::Result<(), alloc[49b8922c36ca62de]::string::String>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40 [INFO] [stdout] 57: 0x5884150c49bb - std[31e582c13f4e914f]::panicking::catch_unwind::, core[fa0eadad68403074]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19 [INFO] [stdout] 58: 0x5884150c49bb - std[31e582c13f4e914f]::panic::catch_unwind::, core[fa0eadad68403074]::result::Result<(), alloc[49b8922c36ca62de]::string::String>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14 [INFO] [stdout] 59: 0x5884150c49bb - test[7530e63dc052480e]::run_test_in_process [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:686:27 [INFO] [stdout] 60: 0x5884150c49bb - test[7530e63dc052480e]::run_test::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:607:43 [INFO] [stdout] 61: 0x5884150bee14 - test[7530e63dc052480e]::run_test::{closure#1} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/test/src/lib.rs:637:41 [INFO] [stdout] 62: 0x5884150bee14 - std[31e582c13f4e914f]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 63: 0x5884150c75c2 - std[31e582c13f4e914f]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 64: 0x5884150c75c2 - ::{closure#1}::{closure#0}> as core[fa0eadad68403074]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 65: 0x5884150c75c2 - std[31e582c13f4e914f]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:581:40 [INFO] [stdout] 66: 0x5884150c75c2 - std[31e582c13f4e914f]::panicking::catch_unwind::<(), core[fa0eadad68403074]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panicking.rs:544:19 [INFO] [stdout] 67: 0x5884150c75c2 - std[31e582c13f4e914f]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/panic.rs:359:14 [INFO] [stdout] 68: 0x5884150c75c2 - std[31e582c13f4e914f]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 69: 0x5884150c75c2 - ::{closure#1} as core[fa0eadad68403074]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 70: 0x5884151bd97f - + core[fa0eadad68403074]::marker::Send> as core[fa0eadad68403074]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/alloc/src/boxed.rs:2240:9 [INFO] [stdout] 71: 0x5884151bd97f - ::new::thread_start [INFO] [stdout] at /rustc/562dee4820c458d823175268e41601d4c060588a/library/std/src/sys/thread/unix.rs:118:17 [INFO] [stdout] 72: 0x796328d2eaa4 - [INFO] [stdout] 73: 0x796328dbba64 - clone [INFO] [stdout] 74: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] executor::tests::test_vruntime_scheduling [INFO] [stdout] [INFO] [stdout] test result: FAILED. 40 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.06s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "3ba97cc4ad16e49070f3d8b6dee380a0734f695b2316348b85b5703cf2b310b8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3ba97cc4ad16e49070f3d8b6dee380a0734f695b2316348b85b5703cf2b310b8", kill_on_drop: false }` [INFO] [stdout] 3ba97cc4ad16e49070f3d8b6dee380a0734f695b2316348b85b5703cf2b310b8