[INFO] fetching crate pausable_clock 1.0.1...
[INFO] testing pausable_clock-1.0.1 against try#d5830e3d3b571fe52cdf4b7b34be99be74dc65ea+target=i686-unknown-linux-musl for musl_1_2_upgrade_i686_unknown_linux_musl
[INFO] extracting crate pausable_clock 1.0.1 into /workspace/builds/worker-6-tc1/source
[INFO] validating manifest of crates.io crate pausable_clock 1.0.1 on toolchain d5830e3d3b571fe52cdf4b7b34be99be74dc65ea
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate pausable_clock 1.0.1
[INFO] finished tweaking crates.io crate pausable_clock 1.0.1
[INFO] tweaked toml for crates.io crate pausable_clock 1.0.1 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" __CARGO_TEST_CHANNEL_OVERRIDE_DO_NOT_USE_THIS="nightly" "/workspace/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8cc92d544e9554abb4d0db6d0c3ffc3526063c7ffcdd50cadaf1cb2a434224f6
[INFO] running `Command { std: "docker" "start" "-a" "8cc92d544e9554abb4d0db6d0c3ffc3526063c7ffcdd50cadaf1cb2a434224f6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8cc92d544e9554abb4d0db6d0c3ffc3526063c7ffcdd50cadaf1cb2a434224f6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8cc92d544e9554abb4d0db6d0c3ffc3526063c7ffcdd50cadaf1cb2a434224f6", kill_on_drop: false }`
[INFO] [stdout] 8cc92d544e9554abb4d0db6d0c3ffc3526063c7ffcdd50cadaf1cb2a434224f6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "build" "--frozen" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 9ee426b4d7e1aa894413fac19f4445a3dea8f7fd8a6dff71e8c1a1b8aac55f16
[INFO] running `Command { std: "docker" "start" "-a" "9ee426b4d7e1aa894413fac19f4445a3dea8f7fd8a6dff71e8c1a1b8aac55f16", kill_on_drop: false }`
[INFO] [stderr]    Compiling pausable_clock v1.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
[INFO] running `Command { std: "docker" "inspect" "9ee426b4d7e1aa894413fac19f4445a3dea8f7fd8a6dff71e8c1a1b8aac55f16", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9ee426b4d7e1aa894413fac19f4445a3dea8f7fd8a6dff71e8c1a1b8aac55f16", kill_on_drop: false }`
[INFO] [stdout] 9ee426b4d7e1aa894413fac19f4445a3dea8f7fd8a6dff71e8c1a1b8aac55f16
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "test" "--frozen" "--no-run" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] d6d2fe37945595fde5c20394ab3bc31bcd27aa5b85efdbce14dec03c1b91a61b
[INFO] running `Command { std: "docker" "start" "-a" "d6d2fe37945595fde5c20394ab3bc31bcd27aa5b85efdbce14dec03c1b91a61b", kill_on_drop: false }`
[INFO] [stderr]    Compiling either v1.8.1
[INFO] [stderr]    Compiling plotters-backend v0.3.4
[INFO] [stderr]    Compiling unicode-width v0.1.10
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling bstr v0.2.17
[INFO] [stderr]    Compiling serde_json v1.0.91
[INFO] [stderr]    Compiling half v1.8.2
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling regex v1.7.1
[INFO] [stderr]    Compiling oorandom v11.1.3
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling rayon v1.6.1
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling plotters-svg v0.3.3
[INFO] [stderr]    Compiling serde_cbor v0.11.2
[INFO] [stderr]    Compiling plotters v0.3.4
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling criterion-plot v0.4.5
[INFO] [stderr]    Compiling criterion v0.3.6
[INFO] [stderr]    Compiling pausable_clock v1.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 10.63s
[INFO] running `Command { std: "docker" "inspect" "d6d2fe37945595fde5c20394ab3bc31bcd27aa5b85efdbce14dec03c1b91a61b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d6d2fe37945595fde5c20394ab3bc31bcd27aa5b85efdbce14dec03c1b91a61b", kill_on_drop: false }`
[INFO] [stdout] d6d2fe37945595fde5c20394ab3bc31bcd27aa5b85efdbce14dec03c1b91a61b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3d532c2baa59a8a48e45c3be7eb049f77ac5a58a7a6514a58a8e878df1c3d03d" "/opt/rustwide/cargo-home/bin/cargo" "+d5830e3d3b571fe52cdf4b7b34be99be74dc65ea" "test" "--frozen" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 2a4550a3275d2662a025eecea7a0867967f018003d0d8ffae234fc4285fa11c3
[INFO] running `Command { std: "docker" "start" "-a" "2a4550a3275d2662a025eecea7a0867967f018003d0d8ffae234fc4285fa11c3", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/i686-unknown-linux-musl/debug/deps/pausable_clock-d342a89f7322d0dc)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test pausable_instant::tests::test_checked_add ... ok
[INFO] [stdout] test pausable_instant::tests::test_checked_sub ... ok
[INFO] [stdout] test pausable_instant::tests::test_duration_since ... ok
[INFO] [stdout] test pause_state::tests::test_accurate_millis ... ok
[INFO] [stdout] test pausable_instant::tests::test_duration_since_fail - should panic ... ok
[INFO] [stdout] test pausable_instant::tests::test_elapsed_fail - should panic ... ok
[INFO] [stdout] test tests::test_unpausable_wont_run_while_paused ... ok
[INFO] [stdout] test tests::it_works ... ok
[INFO] [stdout] test tests::test_pause_blocks_until_unpausable_exits ... ok
[INFO] [stdout] test tests::test_resume_blocks_until_unresumable_exits ... ok
[INFO] [stdout] test pausable_instant::tests::test_elapsed ... ok
[INFO] [stdout] test tests::test_start_paused ... ok
[INFO] [stdout] test tests::test_multiple_pauses ... ok
[INFO] [stdout] test tests::test_time_max_when_paused ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.40s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "2a4550a3275d2662a025eecea7a0867967f018003d0d8ffae234fc4285fa11c3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2a4550a3275d2662a025eecea7a0867967f018003d0d8ffae234fc4285fa11c3", kill_on_drop: false }`
[INFO] [stdout] 2a4550a3275d2662a025eecea7a0867967f018003d0d8ffae234fc4285fa11c3