[INFO] fetching crate xi 0.1.1...
[INFO] testing xi-0.1.1 against beta-2022-04-10 for beta-1.61-1
[INFO] extracting crate xi 0.1.1 into /workspace/builds/worker-27/source
[INFO] validating manifest of crates.io crate xi 0.1.1 on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate xi 0.1.1
[INFO] finished tweaking crates.io crate xi 0.1.1
[INFO] tweaked toml for crates.io crate xi 0.1.1 written to /workspace/builds/worker-27/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "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-27/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7247c8f6e82b575f30ea1c9d07b1902adfa7d06a9e5c62efa532eb35ec312647
[INFO] running `Command { std: "docker" "start" "-a" "7247c8f6e82b575f30ea1c9d07b1902adfa7d06a9e5c62efa532eb35ec312647", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7247c8f6e82b575f30ea1c9d07b1902adfa7d06a9e5c62efa532eb35ec312647", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7247c8f6e82b575f30ea1c9d07b1902adfa7d06a9e5c62efa532eb35ec312647", kill_on_drop: false }`
[INFO] [stdout] 7247c8f6e82b575f30ea1c9d07b1902adfa7d06a9e5c62efa532eb35ec312647
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 76b420c84b0acc8122f785b3b78c1b5c84d65075c9d06e53fb973d57621ea334
[INFO] running `Command { std: "docker" "start" "-a" "76b420c84b0acc8122f785b3b78c1b5c84d65075c9d06e53fb973d57621ea334", kill_on_drop: false }`
[INFO] [stderr]    Compiling xi v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.94s
[INFO] running `Command { std: "docker" "inspect" "76b420c84b0acc8122f785b3b78c1b5c84d65075c9d06e53fb973d57621ea334", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "76b420c84b0acc8122f785b3b78c1b5c84d65075c9d06e53fb973d57621ea334", kill_on_drop: false }`
[INFO] [stdout] 76b420c84b0acc8122f785b3b78c1b5c84d65075c9d06e53fb973d57621ea334
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 47efaa4b3c2534abeea935b9b516c151fb3886b51ced30e4b7eab0ebe634ea67
[INFO] running `Command { std: "docker" "start" "-a" "47efaa4b3c2534abeea935b9b516c151fb3886b51ced30e4b7eab0ebe634ea67", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling semver v1.0.7
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling regex-automata v0.1.10
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling rand_xoshiro v0.1.0
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling rayon-core v1.9.2
[INFO] [stderr]    Compiling rustc_version v0.4.0
[INFO] [stderr]    Compiling rayon v1.5.2
[INFO] [stderr]    Compiling bstr v0.2.17
[INFO] [stderr]    Compiling csv-core v0.1.10
[INFO] [stderr]    Compiling cast v0.2.7
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling criterion-plot v0.3.1
[INFO] [stderr]    Compiling criterion v0.2.11
[INFO] [stderr]    Compiling xi v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.29s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/xi-32d209bc89e4baec)
[INFO] running `Command { std: "docker" "inspect" "47efaa4b3c2534abeea935b9b516c151fb3886b51ced30e4b7eab0ebe634ea67", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "47efaa4b3c2534abeea935b9b516c151fb3886b51ced30e4b7eab0ebe634ea67", kill_on_drop: false }`
[INFO] [stdout] 47efaa4b3c2534abeea935b9b516c151fb3886b51ced30e4b7eab0ebe634ea67
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-27/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6b88163d13b8aaf811aa70dc3bfde6969171828cf8dcf85b21b5afc15d7eaacc
[INFO] running `Command { std: "docker" "start" "-a" "6b88163d13b8aaf811aa70dc3bfde6969171828cf8dcf85b21b5afc15d7eaacc", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/xi-32d209bc89e4baec)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test peg::test::test_peg_keep_mode ... ok
[INFO] [stdout] test peg::test::test_peg_no_keep ... ok
[INFO] [stdout] test test::test_combine ... ok
[INFO] [stdout] test test::test_chained_maps ... ok
[INFO] [stdout] test test::test_imitate ... ok
[INFO] [stdout] test test::test_fold_and_remember ... ok
[INFO] [stdout] test test::test_fold_and_last ... ok
[INFO] [stdout] test test::test_imitate_cycle ... ok
[INFO] [stdout] test test::test_sink_auto_traits ... ok
[INFO] [stdout] test test::test_of ... ok
[INFO] [stdout] test test::test_stream_auto_traits ... ok
[INFO] [stdout] test test::test_subscription_auto_traits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests xi
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test src/imit.rs - imit::Imitator (line 24) ... ok
[INFO] [stdout] test src/lib.rs - (line 21) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::sink (line 129) ... ok
[INFO] [stdout] test src/sub.rs - sub::Subscription (line 53) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::never (line 172) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::start_with (line 831) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::dedupe_by (line 334) ... ok
[INFO] [stdout] test src/sub.rs - sub::Subscription (line 72) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::dedupe (line 310) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::has_memory (line 197) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::filter (line 502) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::wait (line 918) ... ok
[INFO] [stdout] test src/lib.rs - Stream<Stream<T>>::flatten (line 957) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::subscribe (line 230) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::map_to (line 676) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::remember (line 745) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::drop_while (line 424) ... ok
[INFO] [stdout] test src/lib.rs - Sink<T>::update (line 1107) ... ok
[INFO] [stdout] test src/lib.rs - Stream<Stream<T>>::flatten_concurrently (line 1010) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::of (line 148) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::merge (line 698) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::collect (line 270) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::drop (line 395) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::take_while (line 881) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::fold (line 542) ... ok
[INFO] [stdout] test src/lib.rs - Sink<T>::stream (line 1077) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::take (line 856) ... ok
[INFO] [stdout] test src/imit.rs - imit::Imitator<T>::stream (line 113) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::end_when (line 465) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::last (line 606) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::sample_combine (line 784) ... ok
[INFO] [stdout] test src/lib.rs - Stream<T>::map (line 642) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.69s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6b88163d13b8aaf811aa70dc3bfde6969171828cf8dcf85b21b5afc15d7eaacc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6b88163d13b8aaf811aa70dc3bfde6969171828cf8dcf85b21b5afc15d7eaacc", kill_on_drop: false }`
[INFO] [stdout] 6b88163d13b8aaf811aa70dc3bfde6969171828cf8dcf85b21b5afc15d7eaacc