[INFO] fetching crate time-lib 0.2.1... [INFO] testing time-lib-0.2.1 against master#9e297bf54d31eb3b30067208ff9af4416945a2ed for pr-125507 [INFO] extracting crate time-lib 0.2.1 into /workspace/builds/worker-2-tc1/source [INFO] validating manifest of crates.io crate time-lib 0.2.1 on toolchain 9e297bf54d31eb3b30067208ff9af4416945a2ed [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate time-lib 0.2.1 [INFO] finished tweaking crates.io crate time-lib 0.2.1 [INFO] tweaked toml for crates.io crate time-lib 0.2.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml [INFO] crate crates.io crate time-lib 0.2.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" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded async-trait v0.1.76 [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 3fe8e6c8ab9f7748de49f19c8a470ff087a9603277bbedc07ec8e6decec0483a [INFO] running `Command { std: "docker" "start" "-a" "3fe8e6c8ab9f7748de49f19c8a470ff087a9603277bbedc07ec8e6decec0483a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "3fe8e6c8ab9f7748de49f19c8a470ff087a9603277bbedc07ec8e6decec0483a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3fe8e6c8ab9f7748de49f19c8a470ff087a9603277bbedc07ec8e6decec0483a", kill_on_drop: false }` [INFO] [stdout] 3fe8e6c8ab9f7748de49f19c8a470ff087a9603277bbedc07ec8e6decec0483a [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 1a477e225ec434ee78aa1a957a133bc6b0bf8ddc480d9476b313a12c74b09e34 [INFO] running `Command { std: "docker" "start" "-a" "1a477e225ec434ee78aa1a957a133bc6b0bf8ddc480d9476b313a12c74b09e34", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.72 [INFO] [stderr] Compiling libc v0.2.151 [INFO] [stderr] Compiling serde v1.0.193 [INFO] [stderr] Compiling async-trait v0.1.76 [INFO] [stderr] Compiling serde_json v1.0.108 [INFO] [stderr] Compiling pin-project-lite v0.2.13 [INFO] [stderr] Compiling itoa v1.0.10 [INFO] [stderr] Compiling ryu v1.0.16 [INFO] [stderr] Compiling bytes v1.5.0 [INFO] [stderr] Compiling socket2 v0.5.5 [INFO] [stderr] Compiling mio v0.8.10 [INFO] [stderr] Compiling quote v1.0.33 [INFO] [stderr] Compiling syn v2.0.43 [INFO] [stderr] Compiling tokio v1.35.1 [INFO] [stderr] Compiling serde_derive v1.0.193 [INFO] [stderr] Compiling time-lib v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.85s [INFO] running `Command { std: "docker" "inspect" "1a477e225ec434ee78aa1a957a133bc6b0bf8ddc480d9476b313a12c74b09e34", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1a477e225ec434ee78aa1a957a133bc6b0bf8ddc480d9476b313a12c74b09e34", kill_on_drop: false }` [INFO] [stdout] 1a477e225ec434ee78aa1a957a133bc6b0bf8ddc480d9476b313a12c74b09e34 [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 432d8c245396ebb98ba14a14202c649d722c4a63fd01d66dbe491f52d0fc32ad [INFO] running `Command { std: "docker" "start" "-a" "432d8c245396ebb98ba14a14202c649d722c4a63fd01d66dbe491f52d0fc32ad", kill_on_drop: false }` [INFO] [stderr] Compiling parking_lot_core v0.9.9 [INFO] [stderr] Compiling memchr v2.7.1 [INFO] [stderr] Compiling rustix v0.38.28 [INFO] [stderr] Compiling smallvec v1.11.2 [INFO] [stderr] Compiling regex-syntax v0.8.2 [INFO] [stderr] Compiling linux-raw-sys v0.4.12 [INFO] [stderr] Compiling bitflags v2.4.1 [INFO] [stderr] Compiling lock_api v0.4.11 [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling signal-hook-registry v1.4.1 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling termcolor v1.4.0 [INFO] [stderr] Compiling mock_instant v0.3.1 [INFO] [stderr] Compiling tokio-macros v2.2.0 [INFO] [stderr] Compiling parking_lot v0.12.1 [INFO] [stderr] Compiling tokio v1.35.1 [INFO] [stderr] Compiling aho-corasick v1.1.2 [INFO] [stderr] Compiling is-terminal v0.4.10 [INFO] [stderr] Compiling regex-automata v0.4.3 [INFO] [stderr] Compiling regex v1.10.2 [INFO] [stderr] Compiling env_logger v0.10.1 [INFO] [stderr] Compiling time-lib v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 6.43s [INFO] running `Command { std: "docker" "inspect" "432d8c245396ebb98ba14a14202c649d722c4a63fd01d66dbe491f52d0fc32ad", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "432d8c245396ebb98ba14a14202c649d722c4a63fd01d66dbe491f52d0fc32ad", kill_on_drop: false }` [INFO] [stdout] 432d8c245396ebb98ba14a14202c649d722c4a63fd01d66dbe491f52d0fc32ad [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] f0c8250a8afd36642b30a430584f7c0b09e315d6adbec7cb640f13c78c9871ba [INFO] running `Command { std: "docker" "start" "-a" "f0c8250a8afd36642b30a430584f7c0b09e315d6adbec7cb640f13c78c9871ba", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/time-ccad946c7b11455d) [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test timer::tests::paused_timer_not_impacted_by_iterator ... ok [INFO] [stdout] test timer::tests::thread_safe_timer ... ok [INFO] [stdout] test timer::tests::stopped_timer_not_impacted_by_iterator ... ok [INFO] [stdout] test timer::tests::running_infinite_timer ... ok [INFO] [stderr] Running tests/multiple-tcp-clients.rs (/opt/rustwide/target/debug/deps/multiple_tcp_clients-c4aab86e2905212e) [INFO] [stdout] test timer::tests::running_timer_events ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test multiple_tcp_clients ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- multiple_tcp_clients stdout ---- [INFO] [stdout] server event: Started [INFO] [stdout] timer event: Started [INFO] [stdout] timer event: Began(TimerCycle { name: "Work", duration: 3 }) [INFO] [stdout] timer event: Running(TimerCycle { name: "Work", duration: 3 }) [INFO] [stdout] timer event: Running(TimerCycle { name: "Work", duration: 2 }) [INFO] [stdout] timer event: Paused(TimerCycle { name: "Work", duration: 1 }) [INFO] [stdout] timer event: Resumed(TimerCycle { name: "Work", duration: 1 }) [INFO] [stdout] timer event: Running(TimerCycle { name: "Work", duration: 1 }) [INFO] [stdout] timer event: Running(TimerCycle { name: "Work", duration: 1 }) [INFO] [stdout] timer event: Ended(TimerCycle { name: "Work", duration: 0 }) [INFO] [stdout] timer event: Began(TimerCycle { name: "Break", duration: 5 }) [INFO] [stdout] thread 'multiple_tcp_clients' panicked at tests/multiple-tcp-clients.rs:66:13: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: {"state":"Running","cycle":{"name":"Break","duration":5},"cycles_count":"Infinite","elapsed":2} [INFO] [stdout] right: {"state":"Running","cycle":{"name":"Break","duration":4},"cycles_count":"Infinite","elapsed":2} [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55e4f7a87635 - std::backtrace_rs::backtrace::libunwind::trace::h01616864447d7b30 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/../../backtrace/src/backtrace/libunwind.rs:105:5 [INFO] [stdout] 1: 0x55e4f7a87635 - std::backtrace_rs::backtrace::trace_unsynchronized::haf2505f1cce46362 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55e4f7a87635 - std::sys_common::backtrace::_print_fmt::h194ca41cc100de5c [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x55e4f7a87635 - ::fmt::h0cdf3b9de85168c4 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55e4f7ab140b - core::fmt::rt::Argument::fmt::h4873b1048d9b82db [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/fmt/rt.rs:165:63 [INFO] [stdout] 5: 0x55e4f7ab140b - core::fmt::write::ha4c74c323a0d086c [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/fmt/mod.rs:1172:21 [INFO] [stdout] 6: 0x55e4f7a83f8f - std::io::Write::write_fmt::hb6b43a5c88baad33 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/io/mod.rs:1835:15 [INFO] [stdout] 7: 0x55e4f7a8740e - std::sys_common::backtrace::_print::h667dd447a835cd2b [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x55e4f7a8740e - std::sys_common::backtrace::print::hd059cf329bebd7b7 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x55e4f7a88e69 - std::panicking::default_hook::{{closure}}::hcda624442cb26ab2 [INFO] [stdout] 10: 0x55e4f7a88ad6 - std::panicking::default_hook::h1433a4b0280b10c1 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:295:9 [INFO] [stdout] 11: 0x55e4f794d68a - as core::ops::function::Fn>::call::h1d330c91badb41c3 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/alloc/src/boxed.rs:2077:9 [INFO] [stdout] 12: 0x55e4f794d68a - test::test_main::{{closure}}::h51ee890341c1da25 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:137:21 [INFO] [stdout] 13: 0x55e4f7a894db - as core::ops::function::Fn>::call::h7bfd229c9579d378 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/alloc/src/boxed.rs:2077:9 [INFO] [stdout] 14: 0x55e4f7a894db - std::panicking::rust_panic_with_hook::hbbff73039be21b12 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:799:13 [INFO] [stdout] 15: 0x55e4f7a89254 - std::panicking::begin_panic_handler::{{closure}}::hf2d595805aea6208 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:664:13 [INFO] [stdout] 16: 0x55e4f7a87af9 - std::sys_common::backtrace::__rust_end_short_backtrace::h043134ab1b7436ad [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x55e4f7a88f87 - rust_begin_unwind [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:652:5 [INFO] [stdout] 18: 0x55e4f7aaf903 - core::panicking::panic_fmt::h5e9d189302ee3459 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x55e4f7aafd9e - core::panicking::assert_failed_inner::hbb2128a8bf12649f [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/panicking.rs:408:17 [INFO] [stdout] 20: 0x55e4f764b79e - core::panicking::assert_failed::hd6e5842283f24761 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/panicking.rs:363:5 [INFO] [stdout] 21: 0x55e4f765cc6b - multiple_tcp_clients::multiple_tcp_clients::{{closure}}::{{closure}}::{{closure}}::h87207a8208a7821a [INFO] [stdout] at /opt/rustwide/workdir/tests/multiple-tcp-clients.rs:66:13 [INFO] [stdout] 22: 0x55e4f763ed31 - time::server::Server::bind_with::{{closure}}::hc0247636610bf029 [INFO] [stdout] at /opt/rustwide/workdir/src/server/mod.rs:257:16 [INFO] [stdout] 23: 0x55e4f765aaa5 - multiple_tcp_clients::multiple_tcp_clients::{{closure}}::h155b4fca4b6f58ee [INFO] [stdout] at /opt/rustwide/workdir/tests/multiple-tcp-clients.rs:101:10 [INFO] [stdout] 24: 0x55e4f764a822 - as core::future::future::Future>::poll::ha7c282f40c2a9f88 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/future/future.rs:123:9 [INFO] [stdout] 25: 0x55e4f764edaf - tokio::runtime::park::CachedParkThread::block_on::{{closure}}::h0c6d1092d86881a9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/park.rs:282:63 [INFO] [stdout] 26: 0x55e4f764ec60 - tokio::runtime::coop::with_budget::hd7d72d3fc1335f48 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/coop.rs:107:5 [INFO] [stdout] 27: 0x55e4f764ec60 - tokio::runtime::coop::budget::h7f893321d0291e67 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/coop.rs:73:5 [INFO] [stdout] 28: 0x55e4f764ec60 - tokio::runtime::park::CachedParkThread::block_on::hc5b87429b02be2cf [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/park.rs:282:31 [INFO] [stdout] 29: 0x55e4f76492f4 - tokio::runtime::context::blocking::BlockingRegionGuard::block_on::h9e0f61b3942323c8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/context/blocking.rs:66:9 [INFO] [stdout] 30: 0x55e4f7649528 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}}::h55e3099d0d340d2c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/scheduler/multi_thread/mod.rs:87:13 [INFO] [stdout] 31: 0x55e4f764f6ec - tokio::runtime::context::runtime::enter_runtime::h3d88a14e9875066d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 32: 0x55e4f76494f4 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::h2f78fb013a1fc373 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/scheduler/multi_thread/mod.rs:86:9 [INFO] [stdout] 33: 0x55e4f7649467 - tokio::runtime::runtime::Runtime::block_on::h97b7d17079ff007b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/tokio-1.35.1/src/runtime/runtime.rs:350:45 [INFO] [stdout] 34: 0x55e4f765a5a1 - multiple_tcp_clients::multiple_tcp_clients::h340e497ef4e4e0dd [INFO] [stdout] at /opt/rustwide/workdir/tests/multiple-tcp-clients.rs:30:5 [INFO] [stdout] 35: 0x55e4f765a447 - multiple_tcp_clients::multiple_tcp_clients::{{closure}}::h04eb9f4a7a4affa4 [INFO] [stdout] at /opt/rustwide/workdir/tests/multiple-tcp-clients.rs:12:32 [INFO] [stdout] 36: 0x55e4f763ab66 - core::ops::function::FnOnce::call_once::h738f38958fa31340 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 37: 0x55e4f7951edb - core::ops::function::FnOnce::call_once::h142728b0f901c1d8 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 38: 0x55e4f7951edb - test::__rust_begin_short_backtrace::h78f66600c792516f [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:625:18 [INFO] [stdout] 39: 0x55e4f7951611 - test::run_test_in_process::{{closure}}::h36b63bf5ec069162 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:648:60 [INFO] [stdout] 40: 0x55e4f7951611 - as core::ops::function::FnOnce<()>>::call_once::hc0bc08664ac0fa12 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 41: 0x55e4f7951611 - std::panicking::try::do_call::haab6c4522a086511 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:559:40 [INFO] [stdout] 42: 0x55e4f7951611 - std::panicking::try::hf718cb1b5809fd88 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:523:19 [INFO] [stdout] 43: 0x55e4f7951611 - std::panic::catch_unwind::he2ad76393ba02fb8 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panic.rs:149:14 [INFO] [stdout] 44: 0x55e4f7951611 - test::run_test_in_process::hc186a56cb0b7c789 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:648:27 [INFO] [stdout] 45: 0x55e4f7951611 - test::run_test::{{closure}}::h904ff18418fddb43 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:569:43 [INFO] [stdout] 46: 0x55e4f7919364 - test::run_test::{{closure}}::hac26f57ad5c71290 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/test/src/lib.rs:599:41 [INFO] [stdout] 47: 0x55e4f7919364 - std::sys_common::backtrace::__rust_begin_short_backtrace::hfe28fbb6dc5c1a07 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 48: 0x55e4f791dc52 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h95bfe91ffe8f44f9 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/thread/mod.rs:542:17 [INFO] [stdout] 49: 0x55e4f791dc52 - as core::ops::function::FnOnce<()>>::call_once::hd46f137d10008ee5 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 50: 0x55e4f791dc52 - std::panicking::try::do_call::hb6e54244f5c0dd5a [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:559:40 [INFO] [stdout] 51: 0x55e4f791dc52 - std::panicking::try::h9bd598d7f06adceb [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panicking.rs:523:19 [INFO] [stdout] 52: 0x55e4f791dc52 - std::panic::catch_unwind::hd6bd9f72f43d406c [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/panic.rs:149:14 [INFO] [stdout] 53: 0x55e4f791dc52 - std::thread::Builder::spawn_unchecked_::{{closure}}::h656cd769863cb7e9 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/thread/mod.rs:541:30 [INFO] [stdout] 54: 0x55e4f791dc52 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h434a6c48d2f2306f [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 55: 0x55e4f7a8d93b - as core::ops::function::FnOnce>::call_once::h4e53d953a4689d25 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/alloc/src/boxed.rs:2063:9 [INFO] [stdout] 56: 0x55e4f7a8d93b - as core::ops::function::FnOnce>::call_once::hb1f69d21a251f1d9 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/alloc/src/boxed.rs:2063:9 [INFO] [stdout] 57: 0x55e4f7a8d93b - std::sys::pal::unix::thread::Thread::new::thread_start::h03f22bbd669825b9 [INFO] [stdout] at /rustc/9e297bf54d31eb3b30067208ff9af4416945a2ed/library/std/src/sys/pal/unix/thread.rs:108:17 [INFO] [stdout] 58: 0x7f3c1957fac3 - [INFO] [stdout] 59: 0x7f3c19610a04 - __clone [INFO] [stdout] 60: 0x0 - [INFO] [stdout] timer event: Running(TimerCycle { name: "Break", duration: 5 }) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] multiple_tcp_clients [INFO] [stdout] [INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.03s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test multiple-tcp-clients` [INFO] running `Command { std: "docker" "inspect" "f0c8250a8afd36642b30a430584f7c0b09e315d6adbec7cb640f13c78c9871ba", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f0c8250a8afd36642b30a430584f7c0b09e315d6adbec7cb640f13c78c9871ba", kill_on_drop: false }` [INFO] [stdout] f0c8250a8afd36642b30a430584f7c0b09e315d6adbec7cb640f13c78c9871ba [INFO] testing time-lib-0.2.1 against try#61a9ac64344e91d62f5496627ff363d177f9daab for pr-125507 [INFO] extracting crate time-lib 0.2.1 into /workspace/builds/worker-2-tc2/source [INFO] validating manifest of crates.io crate time-lib 0.2.1 on toolchain 61a9ac64344e91d62f5496627ff363d177f9daab [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate time-lib 0.2.1 [INFO] finished tweaking crates.io crate time-lib 0.2.1 [INFO] tweaked toml for crates.io crate time-lib 0.2.1 written to /workspace/builds/worker-2-tc2/source/Cargo.toml [INFO] crate crates.io crate time-lib 0.2.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" "+61a9ac64344e91d62f5496627ff363d177f9daab" "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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 53ec4fb09512ae47afa7a5c772e61892587e2d2a932edd95af9dc52ee92d8e71 [INFO] running `Command { std: "docker" "start" "-a" "53ec4fb09512ae47afa7a5c772e61892587e2d2a932edd95af9dc52ee92d8e71", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "53ec4fb09512ae47afa7a5c772e61892587e2d2a932edd95af9dc52ee92d8e71", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "53ec4fb09512ae47afa7a5c772e61892587e2d2a932edd95af9dc52ee92d8e71", kill_on_drop: false }` [INFO] [stdout] 53ec4fb09512ae47afa7a5c772e61892587e2d2a932edd95af9dc52ee92d8e71 [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b28d51347e7030ee1dfbf7ac01ede235caad8a9442f2e0f2253b423316209694 [INFO] running `Command { std: "docker" "start" "-a" "b28d51347e7030ee1dfbf7ac01ede235caad8a9442f2e0f2253b423316209694", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.72 [INFO] [stderr] Compiling libc v0.2.151 [INFO] [stderr] Compiling serde v1.0.193 [INFO] [stderr] Compiling async-trait v0.1.76 [INFO] [stderr] Compiling serde_json v1.0.108 [INFO] [stderr] Compiling pin-project-lite v0.2.13 [INFO] [stderr] Compiling bytes v1.5.0 [INFO] [stderr] Compiling ryu v1.0.16 [INFO] [stderr] Compiling itoa v1.0.10 [INFO] [stderr] Compiling quote v1.0.33 [INFO] [stderr] Compiling mio v0.8.10 [INFO] [stderr] Compiling socket2 v0.5.5 [INFO] [stderr] Compiling syn v2.0.43 [INFO] [stderr] Compiling tokio v1.35.1 [INFO] [stderr] Compiling serde_derive v1.0.193 [INFO] [stderr] Compiling time-lib v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.96s [INFO] running `Command { std: "docker" "inspect" "b28d51347e7030ee1dfbf7ac01ede235caad8a9442f2e0f2253b423316209694", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b28d51347e7030ee1dfbf7ac01ede235caad8a9442f2e0f2253b423316209694", kill_on_drop: false }` [INFO] [stdout] b28d51347e7030ee1dfbf7ac01ede235caad8a9442f2e0f2253b423316209694 [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6e6c8005b8eb913ed1e6bc15abb2210297c7e7b908c98ce907abca7dbc2b0639 [INFO] running `Command { std: "docker" "start" "-a" "6e6c8005b8eb913ed1e6bc15abb2210297c7e7b908c98ce907abca7dbc2b0639", kill_on_drop: false }` [INFO] [stderr] Compiling parking_lot_core v0.9.9 [INFO] [stderr] Compiling memchr v2.7.1 [INFO] [stderr] Compiling rustix v0.38.28 [INFO] [stderr] Compiling smallvec v1.11.2 [INFO] [stderr] Compiling bitflags v2.4.1 [INFO] [stderr] Compiling linux-raw-sys v0.4.12 [INFO] [stderr] Compiling regex-syntax v0.8.2 [INFO] [stderr] Compiling lock_api v0.4.11 [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling termcolor v1.4.0 [INFO] [stderr] Compiling mock_instant v0.3.1 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.1 [INFO] [stderr] Compiling tokio-macros v2.2.0 [INFO] [stderr] Compiling parking_lot v0.12.1 [INFO] [stderr] Compiling aho-corasick v1.1.2 [INFO] [stderr] Compiling tokio v1.35.1 [INFO] [stderr] Compiling is-terminal v0.4.10 [INFO] [stderr] Compiling regex-automata v0.4.3 [INFO] [stderr] Compiling regex v1.10.2 [INFO] [stderr] Compiling env_logger v0.10.1 [INFO] [stderr] Compiling time-lib v0.2.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 5.85s [INFO] running `Command { std: "docker" "inspect" "6e6c8005b8eb913ed1e6bc15abb2210297c7e7b908c98ce907abca7dbc2b0639", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6e6c8005b8eb913ed1e6bc15abb2210297c7e7b908c98ce907abca7dbc2b0639", kill_on_drop: false }` [INFO] [stdout] 6e6c8005b8eb913ed1e6bc15abb2210297c7e7b908c98ce907abca7dbc2b0639 [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:beeedbb442662e57d416641ecc3200b38e573c25b057896194468d05594dd835" "/opt/rustwide/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 9b15ead0a6d9ba5ebf9d2b4909d255d4a153846e728d5ee639bdcdc203fb3c0f [INFO] running `Command { std: "docker" "start" "-a" "9b15ead0a6d9ba5ebf9d2b4909d255d4a153846e728d5ee639bdcdc203fb3c0f", 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/time-ccad946c7b11455d) [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test timer::tests::running_timer_events ... ok [INFO] [stdout] test timer::tests::stopped_timer_not_impacted_by_iterator ... ok [INFO] [stdout] test timer::tests::running_infinite_timer ... ok [INFO] [stdout] test timer::tests::paused_timer_not_impacted_by_iterator ... ok [INFO] [stdout] test timer::tests::thread_safe_timer ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/multiple-tcp-clients.rs (/opt/rustwide/target/debug/deps/multiple_tcp_clients-c4aab86e2905212e) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test multiple_tcp_clients ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.01s [INFO] [stdout] [INFO] [stderr] Doc-tests time [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test src/lib.rs - (line 42) ... ignored [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "9b15ead0a6d9ba5ebf9d2b4909d255d4a153846e728d5ee639bdcdc203fb3c0f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9b15ead0a6d9ba5ebf9d2b4909d255d4a153846e728d5ee639bdcdc203fb3c0f", kill_on_drop: false }` [INFO] [stdout] 9b15ead0a6d9ba5ebf9d2b4909d255d4a153846e728d5ee639bdcdc203fb3c0f