[INFO] crate tokio_safe_block_on 0.2.0 is already in cache
[INFO] testing tokio_safe_block_on-0.2.0 against try#fc4ba03ef77ca5018fb78a097d3b924d30f15bfc+target=i686-unknown-linux-musl for musl_upgrade_1_2_take4
[INFO] extracting crate tokio_safe_block_on 0.2.0 into /workspace/builds/worker-5/source
[INFO] validating manifest of crates.io crate tokio_safe_block_on 0.2.0 on toolchain fc4ba03ef77ca5018fb78a097d3b924d30f15bfc
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tokio_safe_block_on 0.2.0
[INFO] finished tweaking crates.io crate tokio_safe_block_on 0.2.0
[INFO] tweaked toml for crates.io crate tokio_safe_block_on 0.2.0 written to /workspace/builds/worker-5/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bdbc252bfd842244193c7d8ee5e83fb9dc56a15b10d1086f2b51005209ca1808
[INFO] running `Command { std: "docker" "start" "-a" "bdbc252bfd842244193c7d8ee5e83fb9dc56a15b10d1086f2b51005209ca1808", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bdbc252bfd842244193c7d8ee5e83fb9dc56a15b10d1086f2b51005209ca1808", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bdbc252bfd842244193c7d8ee5e83fb9dc56a15b10d1086f2b51005209ca1808", kill_on_drop: false }`
[INFO] [stdout] bdbc252bfd842244193c7d8ee5e83fb9dc56a15b10d1086f2b51005209ca1808
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "build" "--frozen" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 7c290d28a3edd9aa824e8724a50c6c5989211b188e36a5617359a26f2ccb1683
[INFO] running `Command { std: "docker" "start" "-a" "7c290d28a3edd9aa824e8724a50c6c5989211b188e36a5617359a26f2ccb1683", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.47
[INFO] [stderr]    Compiling quote v1.0.21
[INFO] [stderr]    Compiling unicode-ident v1.0.5
[INFO] [stderr]    Compiling syn v1.0.105
[INFO] [stderr]    Compiling autocfg v1.1.0
[INFO] [stderr]    Compiling futures-core v0.3.25
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling futures-channel v0.3.25
[INFO] [stderr]    Compiling futures-task v0.3.25
[INFO] [stderr]    Compiling futures-sink v0.3.25
[INFO] [stderr]    Compiling futures-util v0.3.25
[INFO] [stderr]    Compiling futures-io v0.3.25
[INFO] [stderr]    Compiling pin-project-lite v0.2.9
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling slab v0.4.7
[INFO] [stderr]    Compiling num_cpus v1.14.0
[INFO] [stderr]    Compiling pin-project-lite v0.1.12
[INFO] [stderr]    Compiling bytes v0.5.6
[INFO] [stderr]    Compiling futures-macro v0.3.25
[INFO] [stderr]    Compiling tokio-macros v0.2.6
[INFO] [stderr]    Compiling tokio v0.2.25
[INFO] [stderr]    Compiling futures-executor v0.3.25
[INFO] [stderr]    Compiling futures v0.3.25
[INFO] [stderr]    Compiling tokio_safe_block_on v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 21.91s
[INFO] running `Command { std: "docker" "inspect" "7c290d28a3edd9aa824e8724a50c6c5989211b188e36a5617359a26f2ccb1683", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c290d28a3edd9aa824e8724a50c6c5989211b188e36a5617359a26f2ccb1683", kill_on_drop: false }`
[INFO] [stdout] 7c290d28a3edd9aa824e8724a50c6c5989211b188e36a5617359a26f2ccb1683
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "test" "--frozen" "--no-run" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] cbad716cb53818fcbd967c3cf12caeda631c58665d5ee8a5c59be63e20e26ad4
[INFO] running `Command { std: "docker" "start" "-a" "cbad716cb53818fcbd967c3cf12caeda631c58665d5ee8a5c59be63e20e26ad4", kill_on_drop: false }`
[INFO] [stderr]    Compiling assert_matches v1.5.0
[INFO] [stderr]    Compiling tokio_safe_block_on v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 3.01s
[INFO] running `Command { std: "docker" "inspect" "cbad716cb53818fcbd967c3cf12caeda631c58665d5ee8a5c59be63e20e26ad4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cbad716cb53818fcbd967c3cf12caeda631c58665d5ee8a5c59be63e20e26ad4", kill_on_drop: false }`
[INFO] [stdout] cbad716cb53818fcbd967c3cf12caeda631c58665d5ee8a5c59be63e20e26ad4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "test" "--frozen" "--target" "i686-unknown-linux-musl", kill_on_drop: false }`
[INFO] [stdout] 8f7e8aa6bf99f64fc956938a376015c3b52bacd42309e75a410fbb828589f9c0
[INFO] running `Command { std: "docker" "start" "-a" "8f7e8aa6bf99f64fc956938a376015c3b52bacd42309e75a410fbb828589f9c0", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/i686-unknown-linux-musl/debug/deps/tokio_safe_block_on-d0eaec312aebf2e4)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test tests::it_should_execute_async_from_sync_context ... ok
[INFO] [stdout] test tests::it_should_timeout_timed_async_from_sync_context ... ok
[INFO] [stdout] test tests::recursive_blocks_test ... ok
[INFO] [stdout] test tests::it_should_execute_async_from_sync_context_forever ... ok
[INFO] [stdout] test tests::it_should_execute_timed_async_from_sync_context has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "8f7e8aa6bf99f64fc956938a376015c3b52bacd42309e75a410fbb828589f9c0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8f7e8aa6bf99f64fc956938a376015c3b52bacd42309e75a410fbb828589f9c0", kill_on_drop: false }`
[INFO] [stdout] 8f7e8aa6bf99f64fc956938a376015c3b52bacd42309e75a410fbb828589f9c0