[INFO] fetching crate sonnerie 0.6.4... [INFO] testing sonnerie-0.6.4 against 1.59.0 for beta-1.60-1 [INFO] extracting crate sonnerie 0.6.4 into /workspace/builds/worker-8/source [INFO] validating manifest of crates.io crate sonnerie 0.6.4 on toolchain 1.59.0 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate sonnerie 0.6.4 [INFO] finished tweaking crates.io crate sonnerie 0.6.4 [INFO] tweaked toml for crates.io crate sonnerie 0.6.4 written to /workspace/builds/worker-8/source/Cargo.toml [INFO] crate crates.io crate sonnerie 0.6.4 already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 7b5f3ba0729c109fd49d97aee56801216c8ca7c8c507112979de395a1ed578cc [INFO] running `Command { std: "docker" "start" "-a" "7b5f3ba0729c109fd49d97aee56801216c8ca7c8c507112979de395a1ed578cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "7b5f3ba0729c109fd49d97aee56801216c8ca7c8c507112979de395a1ed578cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7b5f3ba0729c109fd49d97aee56801216c8ca7c8c507112979de395a1ed578cc", kill_on_drop: false }` [INFO] [stdout] 7b5f3ba0729c109fd49d97aee56801216c8ca7c8c507112979de395a1ed578cc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 661bea174636a94d774b7bc989c081f50eb41d1ffa1276e7f3fbf2a7878b968c [INFO] running `Command { std: "docker" "start" "-a" "661bea174636a94d774b7bc989c081f50eb41d1ffa1276e7f3fbf2a7878b968c", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.118 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling cfg_aliases v0.1.1 [INFO] [stderr] Compiling anyhow v1.0.53 [INFO] [stderr] Compiling crossbeam-queue v0.3.4 [INFO] [stderr] Compiling httpdate v1.0.2 [INFO] [stderr] Compiling min-max-heap v1.3.0 [INFO] [stderr] Compiling escape_string v0.1.1 [INFO] [stderr] Compiling lz4-sys v1.9.2 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling tracing v0.1.31 [INFO] [stderr] Compiling http-body v0.4.4 [INFO] [stderr] Compiling futures-channel v0.3.21 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling tokio-macros v1.7.0 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling static_init_macro v1.0.2 [INFO] [stderr] Compiling static_init v1.0.2 [INFO] [stderr] Compiling crossbeam v0.8.1 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling getrandom v0.2.4 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling tempfile v3.3.0 [INFO] [stderr] Compiling memmap v0.7.0 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling rayon-core v1.9.1 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling rayon v1.5.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tempfile-fast v0.3.4 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling lz4 v1.23.2 [INFO] [stderr] Compiling shardio v0.8.2 [INFO] [stderr] Compiling lines_from_request v0.3.1 [INFO] [stderr] Compiling sonnerie v0.6.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 17.48s [INFO] running `Command { std: "docker" "inspect" "661bea174636a94d774b7bc989c081f50eb41d1ffa1276e7f3fbf2a7878b968c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "661bea174636a94d774b7bc989c081f50eb41d1ffa1276e7f3fbf2a7878b968c", kill_on_drop: false }` [INFO] [stdout] 661bea174636a94d774b7bc989c081f50eb41d1ffa1276e7f3fbf2a7878b968c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 469a6902b48a7bf79a0a7f5ee39946bf38e129a4f11eba3ad9683523bcecd399 [INFO] running `Command { std: "docker" "start" "-a" "469a6902b48a7bf79a0a7f5ee39946bf38e129a4f11eba3ad9683523bcecd399", kill_on_drop: false }` [INFO] [stderr] Compiling sonnerie v0.6.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 3.61s [INFO] running `Command { std: "docker" "inspect" "469a6902b48a7bf79a0a7f5ee39946bf38e129a4f11eba3ad9683523bcecd399", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "469a6902b48a7bf79a0a7f5ee39946bf38e129a4f11eba3ad9683523bcecd399", kill_on_drop: false }` [INFO] [stdout] 469a6902b48a7bf79a0a7f5ee39946bf38e129a4f11eba3ad9683523bcecd399 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 279205c4c2ec1554a21c1174716265b63f84d6c4dfd2d81cd31363372e213639 [INFO] running `Command { std: "docker" "start" "-a" "279205c4c2ec1554a21c1174716265b63f84d6c4dfd2d81cd31363372e213639", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/sonnerie-05371f76a8f4427e) [INFO] [stdout] [INFO] [stdout] running 28 tests [INFO] [stdout] test merge::tests::merge_count_owns ... ok [INFO] [stdout] test merge::tests::merge_last_reader ... ok [INFO] [stdout] test merge::tests::merge1 ... ok [INFO] [stdout] test merge::tests::merge_str ... ok [INFO] [stdout] test merge::tests::merge_with_key ... ok [INFO] [stderr] first_key="a", last_key="a", offset=0, len=43, prev_sz=0, this_key_prev=0 [INFO] [stdout] test tests::basic3 ... ok [INFO] [stdout] test tests::write ... ok [INFO] [stdout] test tests::basic1 ... ok [INFO] [stdout] test tests::store_string1 ... ok [INFO] [stdout] test tests::escape_invocation ... ok [INFO] [stdout] test tests::multicolumn ... ok [INFO] [stdout] test tests::homogenic_types ... ok [INFO] [stdout] test tests::high_level_reader ... ok [INFO] [stdout] test tests::range_before ... ok [INFO] [stdout] test tests::multicolumn_string ... ok [INFO] [stdout] test merge::tests::merge_check_sorting - should panic ... ok [INFO] [stdout] test tests::correct_size - should panic ... ok [INFO] [stdout] test tests::violate_time_order - should panic ... ok [INFO] [stdout] test write::near_boundary ... FAILED [INFO] [stderr] first_key="aa", last_key="aa", offset=0, len=490037, prev_sz=0, this_key_prev=0 [INFO] [stderr] first_key="aabq", last_key="n", offset=490064, len=419790, prev_sz=490064, this_key_prev=0 [INFO] [stdout] test tests::database_merge1 ... ok [INFO] [stdout] test tests::database_merge_last ... ok [INFO] [stdout] test tests::basic2 ... ok [INFO] [stdout] test tests::parallel_split1 ... ok [INFO] [stdout] test tests::basic_huge ... ok [INFO] [stdout] test tests::parallel_split2 ... ok [INFO] [stdout] test tests::keys_split has been running for over 60 seconds [INFO] [stdout] test tests::parallel_split3 has been running for over 60 seconds [INFO] [stdout] test tests::parallel_very_slow has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "279205c4c2ec1554a21c1174716265b63f84d6c4dfd2d81cd31363372e213639", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "279205c4c2ec1554a21c1174716265b63f84d6c4dfd2d81cd31363372e213639", kill_on_drop: false }` [INFO] [stdout] 279205c4c2ec1554a21c1174716265b63f84d6c4dfd2d81cd31363372e213639