[INFO] fetching crate sonnerie 0.6.4... [INFO] testing sonnerie-0.6.4 against beta-2022-02-22 for beta-1.60-1 [INFO] extracting crate sonnerie 0.6.4 into /workspace/builds/worker-29/source [INFO] validating manifest of crates.io crate sonnerie 0.6.4 on toolchain beta-2022-02-22 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "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-29/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" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded unsigned-varint v0.7.1 [INFO] [stderr] Downloaded static_init_macro v1.0.2 [INFO] [stderr] Downloaded static_init v1.0.2 [INFO] [stderr] Downloaded min-max-heap v1.3.0 [INFO] [stderr] Downloaded shardio v0.8.2 [INFO] [stderr] Downloaded libc v0.2.118 [INFO] [stderr] Downloaded lz4 v1.23.2 [INFO] [stderr] Downloaded hyper v0.14.17 [INFO] [stderr] Downloaded crossbeam-queue v0.3.4 [INFO] [stderr] Downloaded lines_from_request v0.3.1 [INFO] [stderr] Downloaded lz4-sys v1.9.2 [INFO] [stderr] Downloaded tempfile-fast v0.3.4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/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" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] f207d73d4dbcc601a551f7ad01799a9597e36de55ff52ebf16dbba8c93f2c4ee [INFO] running `Command { std: "docker" "start" "-a" "f207d73d4dbcc601a551f7ad01799a9597e36de55ff52ebf16dbba8c93f2c4ee", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "f207d73d4dbcc601a551f7ad01799a9597e36de55ff52ebf16dbba8c93f2c4ee", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f207d73d4dbcc601a551f7ad01799a9597e36de55ff52ebf16dbba8c93f2c4ee", kill_on_drop: false }` [INFO] [stdout] f207d73d4dbcc601a551f7ad01799a9597e36de55ff52ebf16dbba8c93f2c4ee [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/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" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] e192c00af74548ccee65d04dd8c86c79987cd8b6d162c3e100cc98e1696d319a [INFO] running `Command { std: "docker" "start" "-a" "e192c00af74548ccee65d04dd8c86c79987cd8b6d162c3e100cc98e1696d319a", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.118 [INFO] [stderr] Compiling crossbeam-epoch v0.9.7 [INFO] [stderr] Compiling cfg_aliases v0.1.1 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling rayon-core v1.9.1 [INFO] [stderr] Compiling crossbeam-queue v0.3.4 [INFO] [stderr] Compiling anyhow v1.0.53 [INFO] [stderr] Compiling min-max-heap v1.3.0 [INFO] [stderr] Compiling escape_string v0.1.1 [INFO] [stderr] Compiling memoffset v0.6.5 [INFO] [stderr] Compiling lz4-sys v1.9.2 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling rayon v1.5.1 [INFO] [stderr] Compiling futures-channel v0.3.21 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling static_init_macro v1.0.2 [INFO] [stderr] Compiling static_init v1.0.2 [INFO] [stderr] Compiling crossbeam-deque v0.8.1 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling getrandom v0.2.4 [INFO] [stderr] Compiling mio v0.8.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling tempfile v3.3.0 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling memmap v0.7.0 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling crossbeam v0.8.1 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling tokio v1.17.0 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling tempfile-fast v0.3.4 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling lines_from_request v0.3.1 [INFO] [stderr] Compiling lz4 v1.23.2 [INFO] [stderr] Compiling shardio v0.8.2 [INFO] [stderr] Compiling sonnerie v0.6.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 15.75s [INFO] running `Command { std: "docker" "inspect" "e192c00af74548ccee65d04dd8c86c79987cd8b6d162c3e100cc98e1696d319a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e192c00af74548ccee65d04dd8c86c79987cd8b6d162c3e100cc98e1696d319a", kill_on_drop: false }` [INFO] [stdout] e192c00af74548ccee65d04dd8c86c79987cd8b6d162c3e100cc98e1696d319a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/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" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0534a95219de98ffead8b9592ed87ce738e1fde9a5b848195af7f45f790594fa [INFO] running `Command { std: "docker" "start" "-a" "0534a95219de98ffead8b9592ed87ce738e1fde9a5b848195af7f45f790594fa", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling sonnerie v0.6.4 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 5.50s [INFO] running `Command { std: "docker" "inspect" "0534a95219de98ffead8b9592ed87ce738e1fde9a5b848195af7f45f790594fa", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0534a95219de98ffead8b9592ed87ce738e1fde9a5b848195af7f45f790594fa", kill_on_drop: false }` [INFO] [stdout] 0534a95219de98ffead8b9592ed87ce738e1fde9a5b848195af7f45f790594fa [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-29/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" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 58615d5fba2c1413a6cc9e4ea32d5c97d77fbcf68db7423f26cc32fe243b7108 [INFO] running `Command { std: "docker" "start" "-a" "58615d5fba2c1413a6cc9e4ea32d5c97d77fbcf68db7423f26cc32fe243b7108", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/sonnerie-80011a1e1d02affc) [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] [stdout] test tests::basic3 ... ok [INFO] [stdout] test tests::multicolumn ... ok [INFO] [stdout] test tests::basic1 ... ok [INFO] [stdout] test tests::homogenic_types ... ok [INFO] [stderr] first_key="a", last_key="a", offset=0, len=43, prev_sz=0, this_key_prev=0 [INFO] [stdout] test tests::escape_invocation ... ok [INFO] [stdout] test tests::write ... ok [INFO] [stdout] test tests::store_string1 ... ok [INFO] [stdout] test tests::multicolumn_string ... ok [INFO] [stdout] test tests::range_before ... ok [INFO] [stdout] test tests::high_level_reader ... 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] [stdout] test tests::database_merge_last ... ok [INFO] [stdout] test tests::database_merge1 ... ok [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::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" "58615d5fba2c1413a6cc9e4ea32d5c97d77fbcf68db7423f26cc32fe243b7108", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "58615d5fba2c1413a6cc9e4ea32d5c97d77fbcf68db7423f26cc32fe243b7108", kill_on_drop: false }` [INFO] [stdout] 58615d5fba2c1413a6cc9e4ea32d5c97d77fbcf68db7423f26cc32fe243b7108