[INFO] fetching crate win-events 0.0.2...
[INFO] testing win-events-0.0.2 against master#9e297bf54d31eb3b30067208ff9af4416945a2ed for pr-125507
[INFO] extracting crate win-events 0.0.2 into /workspace/builds/worker-5-tc1/source
[INFO] validating manifest of crates.io crate win-events 0.0.2 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 win-events 0.0.2
[INFO] finished tweaking crates.io crate win-events 0.0.2
[INFO] tweaked toml for crates.io crate win-events 0.0.2 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e297bf54d31eb3b30067208ff9af4416945a2ed" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 456a1e23fb5755615fcfb7b5940fdfc3f04442372d879b7804df8e07fb448c20
[INFO] running `Command { std: "docker" "start" "-a" "456a1e23fb5755615fcfb7b5940fdfc3f04442372d879b7804df8e07fb448c20", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "456a1e23fb5755615fcfb7b5940fdfc3f04442372d879b7804df8e07fb448c20", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "456a1e23fb5755615fcfb7b5940fdfc3f04442372d879b7804df8e07fb448c20", kill_on_drop: false }`
[INFO] [stdout] 456a1e23fb5755615fcfb7b5940fdfc3f04442372d879b7804df8e07fb448c20
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 0427449d158acd19bcc2d4b946d6babbe1b126b74dbb03d3b9b8db03660637e5
[INFO] running `Command { std: "docker" "start" "-a" "0427449d158acd19bcc2d4b946d6babbe1b126b74dbb03d3b9b8db03660637e5", kill_on_drop: false }`
[INFO] [stderr]    Compiling win-events v0.0.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.24s
[INFO] running `Command { std: "docker" "inspect" "0427449d158acd19bcc2d4b946d6babbe1b126b74dbb03d3b9b8db03660637e5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0427449d158acd19bcc2d4b946d6babbe1b126b74dbb03d3b9b8db03660637e5", kill_on_drop: false }`
[INFO] [stdout] 0427449d158acd19bcc2d4b946d6babbe1b126b74dbb03d3b9b8db03660637e5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 86d810a764d78b8cd74568e90b9471e312def0abf1752c94ce120f8b48111150
[INFO] running `Command { std: "docker" "start" "-a" "86d810a764d78b8cd74568e90b9471e312def0abf1752c94ce120f8b48111150", kill_on_drop: false }`
[INFO] [stderr]    Compiling win-events v0.0.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.38s
[INFO] running `Command { std: "docker" "inspect" "86d810a764d78b8cd74568e90b9471e312def0abf1752c94ce120f8b48111150", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "86d810a764d78b8cd74568e90b9471e312def0abf1752c94ce120f8b48111150", kill_on_drop: false }`
[INFO] [stdout] 86d810a764d78b8cd74568e90b9471e312def0abf1752c94ce120f8b48111150
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] aec95998e8c857b4e3ebcdcca02d54ccc3c74fcd3cbe08ff3b2023e73a038eee
[INFO] running `Command { std: "docker" "start" "-a" "aec95998e8c857b4e3ebcdcca02d54ccc3c74fcd3cbe08ff3b2023e73a038eee", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.00s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/win_events-fcf2bdb06d007ce8)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/auto_reset_event_test.rs (/opt/rustwide/target/debug/deps/auto_reset_event_test-f79335dca13252da)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test ae_test_init_false ... ok
[INFO] [stdout] test ae_test_clear_event ... ok
[INFO] [stdout] test ae_test_init_true ... ok
[INFO] [stdout] test ae_test_set_event ... ok
[INFO] [stdout] test ae_test_wait_all_already_set ... ok
[INFO] [stdout] test ae_test_wait_already_set ... ok
[INFO] [stdout] test ae_test_wait_timeout ... ok
[INFO] [stdout] test ae_test_wait_first_already_set ... ok
[INFO] [stdout] test ae_test_wait_all_timeout ... ok
[INFO] [stdout] test ae_test_multiple_waiters_all ... ok
[INFO] [stdout] test ae_test_thread_wait ... ok
[INFO] [stdout] test ae_test_wait_all_toggled_initialized_timeout ... ok
[INFO] [stdout] test ae_test_multiple_waiters_one ... ok
[INFO] [stdout] test ae_test_wait_all_toggled_timeout ... ok
[INFO] [stdout] test ae_test_wait_first_toggled_timeout ... ok
[INFO] [stdout] test ae_test_wait_first_timeout ... ok
[INFO] [stdout] test ae_test_wait_all_partial_set ... ok
[INFO] [stdout] test ae_test_wait_all ... ok
[INFO] [stdout] test ae_test_wait_first ... ok
[INFO] [stdout] test ae_test_multiple_ordered_lock_all ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/event_test.rs (/opt/rustwide/target/debug/deps/event_test-667069e326463266)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_event_guard ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/manual_reset_event_test.rs (/opt/rustwide/target/debug/deps/manual_reset_event_test-3ef6ad27bb97db7c)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test me_test_clear_event ... ok
[INFO] [stdout] test me_test_init_true ... ok
[INFO] [stdout] test me_test_init_false ... ok
[INFO] [stdout] test me_test_set_event ... ok
[INFO] [stdout] test me_test_wait_all_cleared_timeout ... ok
[INFO] [stdout] test me_test_wait_all_already_set ... ok
[INFO] [stdout] test me_test_wait_all_timeout ... ok
[INFO] [stdout] test me_test_wait_already_set ... ok
[INFO] [stdout] test me_test_wait_first_already_set ... ok
[INFO] [stdout] test me_test_wait_timeout ... ok
[INFO] [stdout] test me_test_thread_wait ... ok
[INFO] [stdout] test me_test_wait_first_timeout ... ok
[INFO] [stdout] test me_test_wait_all ... ok
[INFO] [stdout] test me_test_wait_all_partial_set ... ok
[INFO] [stdout] test me_test_wait_first ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/manual_reset_event_test_pair.rs (/opt/rustwide/target/debug/deps/manual_reset_event_test_pair-a681b19d943745aa)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test mep_test_init_true ... ok
[INFO] [stdout] test mep_test_clear_event ... ok
[INFO] [stdout] test mep_test_set_event ... ok
[INFO] [stdout] test mep_test_init_false ... ok
[INFO] [stdout] test mep_test_wait_all_already_set ... ok
[INFO] [stdout] test mep_test_wait_all_cleared_timeout ... ok
[INFO] [stdout] test mep_test_wait_all_timeout ... ok
[INFO] [stdout] test mep_test_wait_already_set ... ok
[INFO] [stdout] test mep_test_wait_first_already_set ... ok
[INFO] [stdout] test mep_test_wait_timeout ... ok
[INFO] [stdout] test mep_test_thread_wait ... ok
[INFO] [stdout] test mep_test_wait_first_timeout ... ok
[INFO] [stdout] test mep_test_wait_all ... ok
[INFO] [stdout] test mep_test_wait_all_partial_set ... ok
[INFO] [stdout] test mep_test_wait_first ... ok
[INFO] [stdout] test mep_test_wait_not ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.50s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pulse_event_test.rs (/opt/rustwide/target/debug/deps/pulse_event_test-7012a81afb7a9efa)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test pe_test_init ... ok
[INFO] [stdout] test pe_test_set_event ... ok
[INFO] [stdout] test pe_test_clear_event ... ok
[INFO] [stdout] test pe_test_wait_all_timeout ... ok
[INFO] [stdout] test pe_test_wait_timeout ... ok
[INFO] [stdout] test pe_test_thread_wait ... ok
[INFO] [stdout] test pe_test_wait_already_set ... ok
[INFO] [stdout] test pe_test_wait_first_timeout ... ok
[INFO] [stdout] test pe_test_wait_all ... ok
[INFO] [stdout] test pe_test_wait_first ... ok
[INFO] [stdout] test pe_test_wait_all_partial_set ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests win_events
[INFO] [stdout] 
[INFO] [stdout] running 30 tests
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent::clear (line 160) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent::new (line 99) ... ok
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent::is_set (line 131) ... ok
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent::wait (line 174) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent::clear (line 144) ... ok
[INFO] [stdout] test src/events/event.rs - events::event::EventGuard (line 145) ... ok
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent::set (line 145) ... ok
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent::new (line 115) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::clear (line 334) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent::is_set (line 115) ... ok
[INFO] [stdout] test src/events/auto_reset.rs - events::auto_reset::AutoResetEvent (line 88) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent::set (line 129) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent (line 73) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::event_not_set (line 403) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair (line 259) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::new (line 287) ... ok
[INFO] [stdout] test src/events/manual_reset.rs - events::manual_reset::ManualResetEvent::wait (line 158) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::is_set (line 305) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::event_set (line 373) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent::clear (line 142) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent::new (line 96) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent::is_set (line 113) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::set (line 319) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent::set (line 127) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent (line 68) ... ok
[INFO] [stdout] test src/events/manual_reset_pair.rs - events::manual_reset_pair::ManualResetEventPair::wait (line 348) ... ok
[INFO] [stdout] test src/events/pulse.rs - events::pulse::PulseEvent::wait (line 156) ... ok
[INFO] [stdout] test src/waiters/wait_first.rs - waiters::wait_first::wait_first (line 41) ... ok
[INFO] [stdout] test src/waiters/wait_one.rs - waiters::wait_one::wait_one (line 41) ... ok
[INFO] [stdout] test src/waiters/wait_all.rs - waiters::wait_all::wait_all (line 70) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.54s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "aec95998e8c857b4e3ebcdcca02d54ccc3c74fcd3cbe08ff3b2023e73a038eee", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aec95998e8c857b4e3ebcdcca02d54ccc3c74fcd3cbe08ff3b2023e73a038eee", kill_on_drop: false }`
[INFO] [stdout] aec95998e8c857b4e3ebcdcca02d54ccc3c74fcd3cbe08ff3b2023e73a038eee
[INFO] testing win-events-0.0.2 against try#61a9ac64344e91d62f5496627ff363d177f9daab for pr-125507
[INFO] extracting crate win-events 0.0.2 into /workspace/builds/worker-5-tc2/source
[INFO] validating manifest of crates.io crate win-events 0.0.2 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 win-events 0.0.2
[INFO] finished tweaking crates.io crate win-events 0.0.2
[INFO] tweaked toml for crates.io crate win-events 0.0.2 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+61a9ac64344e91d62f5496627ff363d177f9daab" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 041632fd7d7fa4d23528ef6e511914dc6de3d25d83e4134807020913e277196b
[INFO] running `Command { std: "docker" "start" "-a" "041632fd7d7fa4d23528ef6e511914dc6de3d25d83e4134807020913e277196b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "041632fd7d7fa4d23528ef6e511914dc6de3d25d83e4134807020913e277196b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "041632fd7d7fa4d23528ef6e511914dc6de3d25d83e4134807020913e277196b", kill_on_drop: false }`
[INFO] [stdout] 041632fd7d7fa4d23528ef6e511914dc6de3d25d83e4134807020913e277196b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 8dfcd61c8ece64427d73d46d8bb78f01f4a47d1c289c4d23f9f55439ae1e8dd1
[INFO] running `Command { std: "docker" "start" "-a" "8dfcd61c8ece64427d73d46d8bb78f01f4a47d1c289c4d23f9f55439ae1e8dd1", kill_on_drop: false }`
[INFO] [stderr]    Compiling win-events v0.0.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.39s
[INFO] running `Command { std: "docker" "inspect" "8dfcd61c8ece64427d73d46d8bb78f01f4a47d1c289c4d23f9f55439ae1e8dd1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8dfcd61c8ece64427d73d46d8bb78f01f4a47d1c289c4d23f9f55439ae1e8dd1", kill_on_drop: false }`
[INFO] [stdout] 8dfcd61c8ece64427d73d46d8bb78f01f4a47d1c289c4d23f9f55439ae1e8dd1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 445215f8d11b863a6ae137447c09d068999f6ef2accf5c0bc2648a4f8667471a
[INFO] running `Command { std: "docker" "start" "-a" "445215f8d11b863a6ae137447c09d068999f6ef2accf5c0bc2648a4f8667471a", kill_on_drop: false }`
[INFO] [stderr]    Compiling win-events v0.0.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.89s
[INFO] running `Command { std: "docker" "inspect" "445215f8d11b863a6ae137447c09d068999f6ef2accf5c0bc2648a4f8667471a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "445215f8d11b863a6ae137447c09d068999f6ef2accf5c0bc2648a4f8667471a", kill_on_drop: false }`
[INFO] [stdout] 445215f8d11b863a6ae137447c09d068999f6ef2accf5c0bc2648a4f8667471a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] b9adcd24bb97651b3d59565b28a04714473da8cfec0fd7ddf3bff4a4ca7a4217
[INFO] running `Command { std: "docker" "start" "-a" "b9adcd24bb97651b3d59565b28a04714473da8cfec0fd7ddf3bff4a4ca7a4217", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/win_events-fcf2bdb06d007ce8)
[INFO] [stderr]      Running tests/auto_reset_event_test.rs (/opt/rustwide/target/debug/deps/auto_reset_event_test-f79335dca13252da)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test ae_test_set_event ... ok
[INFO] [stdout] test ae_test_clear_event ... ok
[INFO] [stdout] test ae_test_init_false ... ok
[INFO] [stdout] test ae_test_init_true ... ok
[INFO] [stdout] test ae_test_wait_all_toggled_initialized_timeout ... ok
[INFO] [stdout] test ae_test_wait_all_timeout ... ok
[INFO] [stdout] test ae_test_wait_all_toggled_timeout ... ok
[INFO] [stdout] test ae_test_wait_already_set ... ok
[INFO] [stdout] test ae_test_thread_wait ... ok
[INFO] [stdout] test ae_test_wait_all_already_set ... ok
[INFO] [stdout] test ae_test_wait_all_partial_set ... ok
[INFO] [stdout] test ae_test_wait_all ... ok
[INFO] [stdout] test ae_test_wait_timeout ... ok
[INFO] [stdout] test ae_test_wait_first ... ok
[INFO] [stdout] test ae_test_wait_first_timeout ... ok
[INFO] [stdout] test ae_test_wait_first_toggled_timeout ... ok
[INFO] [stdout] test ae_test_wait_first_already_set ... ok
[INFO] [stdout] test ae_test_multiple_waiters_all ... ok
[INFO] [stdout] test ae_test_multiple_waiters_one ... ok
[INFO] [stdout] test ae_test_multiple_ordered_lock_all has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "b9adcd24bb97651b3d59565b28a04714473da8cfec0fd7ddf3bff4a4ca7a4217", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b9adcd24bb97651b3d59565b28a04714473da8cfec0fd7ddf3bff4a4ca7a4217", kill_on_drop: false }`
[INFO] [stdout] b9adcd24bb97651b3d59565b28a04714473da8cfec0fd7ddf3bff4a4ca7a4217