[INFO] fetching crate nexus-logbuf 2.1.5...
[INFO] testing nexus-logbuf-2.1.5 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate nexus-logbuf 2.1.5 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate nexus-logbuf 2.1.5
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate nexus-logbuf 2.1.5
[INFO] tweaked toml for crates.io crate nexus-logbuf 2.1.5 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate nexus-logbuf 2.1.5 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 nexus-logbuf 2.1.5 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 hdrhistogram v7.5.4
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a5ed946e7636e74235202e3cf87e0f2fcad04c216e8299ec0e61df56bfb8be11
[INFO] running `Command { std: "docker" "start" "-a" "a5ed946e7636e74235202e3cf87e0f2fcad04c216e8299ec0e61df56bfb8be11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a5ed946e7636e74235202e3cf87e0f2fcad04c216e8299ec0e61df56bfb8be11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a5ed946e7636e74235202e3cf87e0f2fcad04c216e8299ec0e61df56bfb8be11", kill_on_drop: false }`
[INFO] [stdout] a5ed946e7636e74235202e3cf87e0f2fcad04c216e8299ec0e61df56bfb8be11
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 836b81e6965a1b0695032058b3c5cfd54574676718a3112cace1a9b66a9a49dc
[INFO] running `Command { std: "docker" "start" "-a" "836b81e6965a1b0695032058b3c5cfd54574676718a3112cace1a9b66a9a49dc", kill_on_drop: false }`
[INFO] [stderr]    Compiling nexus-logbuf v2.1.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.30s
[INFO] running `Command { std: "docker" "inspect" "836b81e6965a1b0695032058b3c5cfd54574676718a3112cace1a9b66a9a49dc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "836b81e6965a1b0695032058b3c5cfd54574676718a3112cace1a9b66a9a49dc", kill_on_drop: false }`
[INFO] [stdout] 836b81e6965a1b0695032058b3c5cfd54574676718a3112cace1a9b66a9a49dc
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 60170eef326b3d07de5545d25f61cdd1176d3c46c0f54f328219b366e687c9af
[INFO] running `Command { std: "docker" "start" "-a" "60170eef326b3d07de5545d25f61cdd1176d3c46c0f54f328219b366e687c9af", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling flate2 v1.1.5
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling nexus-logbuf v2.1.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.40s
[INFO] running `Command { std: "docker" "inspect" "60170eef326b3d07de5545d25f61cdd1176d3c46c0f54f328219b366e687c9af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "60170eef326b3d07de5545d25f61cdd1176d3c46c0f54f328219b366e687c9af", kill_on_drop: false }`
[INFO] [stdout] 60170eef326b3d07de5545d25f61cdd1176d3c46c0f54f328219b366e687c9af
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7d62c3cdb8670c386609b31d16748f922287cbb3f7ce7c6cfb567fd4f69b5604
[INFO] running `Command { std: "docker" "start" "-a" "7d62c3cdb8670c386609b31d16748f922287cbb3f7ce7c6cfb567fd4f69b5604", 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/nexus_logbuf-865b2df896a75fcb)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test channel::mpsc::tests::basic_send_recv ... ok
[INFO] [stdout] test channel::mpsc::tests::disconnection_receiver_dropped ... ok
[INFO] [stdout] test channel::mpsc::tests::sender_is_clone ... ok
[INFO] [stdout] test channel::mpsc::tests::disconnection_all_senders_dropped ... ok
[INFO] [stdout] test channel::mpsc::tests::multiple_senders ... ok
[INFO] [stdout] test channel::spsc::tests::basic_send_recv ... ok
[INFO] [stdout] test channel::mpsc::tests::zero_len_error ... ok
[INFO] [stdout] test channel::spsc::tests::disconnection_sender_dropped ... ok
[INFO] [stdout] test channel::spsc::tests::recv_timeout_with_data ... ok
[INFO] [stdout] test channel::spsc::tests::disconnection_receiver_dropped ... ok
[INFO] [stdout] test channel::spsc::tests::try_send_try_recv ... ok
[INFO] [stdout] test queue::mpsc::tests::aborted_claim_creates_skip ... ok
[INFO] [stdout] test queue::mpsc::tests::disconnection_detection ... ok
[INFO] [stdout] test channel::spsc::tests::try_send_returns_full ... ok
[INFO] [stdout] test channel::spsc::tests::zero_len_error ... ok
[INFO] [stdout] test queue::mpsc::tests::multiple_records ... ok
[INFO] [stdout] test channel::spsc::tests::cross_thread ... ok
[INFO] [stdout] test queue::mpsc::tests::basic_write_read ... ok
[INFO] [stdout] test queue::mpsc::tests::capacity_rounds_to_power_of_two ... ok
[INFO] [stdout] test queue::mpsc::tests::empty_returns_none ... ok
[INFO] [stdout] test queue::mpsc::tests::full_returns_error ... ok
[INFO] [stdout] test queue::mpsc::tests::producer_is_clone ... ok
[INFO] [stdout] test queue::mpsc::tests::wrap_around ... ok
[INFO] [stdout] test queue::spsc::tests::basic_write_read ... ok
[INFO] [stdout] test queue::spsc::tests::capacity_rounds_to_power_of_two ... ok
[INFO] [stdout] test queue::spsc::tests::full_returns_error ... ok
[INFO] [stdout] test queue::spsc::tests::empty_returns_none ... ok
[INFO] [stdout] test queue::spsc::tests::variable_length_records ... ok
[INFO] [stdout] test queue::spsc::tests::wrap_around ... ok
[INFO] [stdout] test queue::spsc::tests::multiple_records ... ok
[INFO] [stdout] test queue::spsc::tests::payload_is_word_aligned ... ok
[INFO] [stdout] test queue::mpsc::tests::zero_len_returns_error ... ok
[INFO] [stdout] test queue::spsc::tests::aborted_claim_creates_skip ... ok
[INFO] [stdout] test queue::spsc::tests::cross_thread ... ok
[INFO] [stdout] test channel::mpsc::tests::recv_timeout_works ... ok
[INFO] [stdout] test channel::mpsc::tests::stress_multiple_senders ... ok
[INFO] [stdout] test queue::spsc::tests::disconnection_detection ... ok
[INFO] [stdout] test queue::mpsc::tests::multiple_producers_single_consumer ... ok
[INFO] [stdout] test channel::spsc::tests::recv_timeout_works ... ok
[INFO] [stdout] test queue::spsc::tests::zero_len_returns_error ... ok
[INFO] [stdout] test tests::align8_works ... ok
[INFO] [stdout] test queue::mpsc::tests::tiny_capacity_panics - should panic ... ok
[INFO] [stdout] test queue::spsc::tests::tiny_capacity_panics - should panic ... ok
[INFO] [stdout] test queue::spsc::tests::stress_high_contention ... ok
[INFO] [stdout] test queue::mpsc::tests::stress_multiple_producers ... ok
[INFO] [stderr]      Running tests/miri_tests.rs (/opt/rustwide/target/debug/deps/miri_tests-7827250e80786b2c)
[INFO] [stdout] test queue::spsc::tests::stress_high_volume ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.60s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test mpsc_claim_write_commit_read ... ok
[INFO] [stdout] test spsc_claim_abort ... ok
[INFO] [stdout] test spsc_multiple_records ... ok
[INFO] [stdout] test spsc_claim_write_commit_read ... ok
[INFO] [stdout] test mpsc_two_producers ... 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]    Doc-tests nexus_logbuf
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test src/channel/mod.rs - channel (line 24) ... ignored
[INFO] [stdout] test src/channel/spsc.rs - channel::spsc (line 16) ... ok
[INFO] [stdout] test src/lib.rs - (line 30) ... ok
[INFO] [stdout] test src/channel/mpsc.rs - channel::mpsc (line 16) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.73s; merged doctests compilation took 0.71s
[INFO] running `Command { std: "docker" "inspect" "7d62c3cdb8670c386609b31d16748f922287cbb3f7ce7c6cfb567fd4f69b5604", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d62c3cdb8670c386609b31d16748f922287cbb3f7ce7c6cfb567fd4f69b5604", kill_on_drop: false }`
[INFO] [stdout] 7d62c3cdb8670c386609b31d16748f922287cbb3f7ce7c6cfb567fd4f69b5604