[INFO] fetching crate filmreel 0.7.0...
[INFO] testing filmreel-0.7.0 against beta-2022-02-22 for beta-1.60-1
[INFO] extracting crate filmreel 0.7.0 into /workspace/builds/worker-26/source
[INFO] validating manifest of crates.io crate filmreel 0.7.0 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 filmreel 0.7.0
[INFO] finished tweaking crates.io crate filmreel 0.7.0
[INFO] tweaked toml for crates.io crate filmreel 0.7.0 written to /workspace/builds/worker-26/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[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]  Downloading crates ...
[INFO] [stderr]   Downloaded kv-log-macro v1.0.7
[INFO] [stderr]   Downloaded byte-tools v0.3.1
[INFO] [stderr]   Downloaded ordered-float v2.10.0
[INFO] [stderr]   Downloaded futures-lite v1.12.0
[INFO] [stderr]   Downloaded ucd-trie v0.1.3
[INFO] [stderr]   Downloaded async-process v1.3.0
[INFO] [stderr]   Downloaded signal-hook v0.3.13
[INFO] [stderr]   Downloaded async-channel v1.6.1
[INFO] [stderr]   Downloaded atomic-waker v1.0.0
[INFO] [stderr]   Downloaded semver v1.0.6
[INFO] [stderr]   Downloaded async-std v1.10.0
[INFO] [stderr]   Downloaded async-io v1.6.0
[INFO] [stderr]   Downloaded polling v2.2.0
[INFO] [stderr]   Downloaded async-global-executor v2.0.2
[INFO] [stderr]   Downloaded async-executor v1.4.1
[INFO] [stderr]   Downloaded cache-padded v1.2.0
[INFO] [stderr]   Downloaded pest_generator v2.1.3
[INFO] [stderr]   Downloaded pest_meta v2.1.3
[INFO] [stderr]   Downloaded anyhow v1.0.55
[INFO] [stderr]   Downloaded strsim v0.10.0
[INFO] [stderr]   Downloaded concurrent-queue v1.2.2
[INFO] [stderr]   Downloaded signal-hook-registry v1.4.0
[INFO] [stderr]   Downloaded os_str_bytes v6.0.0
[INFO] [stderr]   Downloaded textwrap v0.14.2
[INFO] [stderr]   Downloaded rayon-core v1.9.1
[INFO] [stderr]   Downloaded rayon v1.5.1
[INFO] [stderr]   Downloaded crossbeam-channel v0.5.2
[INFO] [stderr]   Downloaded event-listener v2.5.2
[INFO] [stderr]   Downloaded async-attributes v1.1.2
[INFO] [stderr]   Downloaded block-padding v0.1.5
[INFO] [stderr]   Downloaded socket2 v0.4.4
[INFO] [stderr]   Downloaded generic-array v0.12.4
[INFO] [stderr]   Downloaded waker-fn v1.1.0
[INFO] [stderr]   Downloaded rustc_version v0.4.0
[INFO] [stderr]   Downloaded rstest v0.11.0
[INFO] [stderr]   Downloaded parking v2.0.0
[INFO] [stderr]   Downloaded clap v3.1.2
[INFO] [stderr]   Downloaded paste v1.0.6
[INFO] [stderr]   Downloaded output_vt100 v0.1.3
[INFO] [stderr]   Downloaded pest v2.1.3
[INFO] [stderr]   Downloaded crossbeam-epoch v0.9.7
[INFO] [stderr]   Downloaded crossbeam-deque v0.8.1
[INFO] [stderr]   Downloaded maplit v1.0.2
[INFO] [stderr]   Downloaded block-buffer v0.7.3
[INFO] [stderr]   Downloaded digest v0.8.1
[INFO] [stderr]   Downloaded fake-simd v0.1.2
[INFO] [stderr]   Downloaded opaque-debug v0.2.3
[INFO] [stderr]   Downloaded sha-1 v0.8.2
[INFO] [stderr]   Downloaded pretty_assertions v0.7.2
[INFO] [stderr]   Downloaded ansi_term v0.12.1
[INFO] [stderr]   Downloaded pest_derive v2.1.0
[INFO] [stderr]   Downloaded diff v0.1.12
[INFO] [stderr]   Downloaded colored_json v2.1.0
[INFO] [stderr]   Downloaded async-mutex v1.4.0
[INFO] [stderr]   Downloaded async-lock v2.5.0
[INFO] [stderr]   Downloaded wepoll-ffi v0.1.2
[INFO] [stderr]   Downloaded async-task v4.1.0
[INFO] [stderr]   Downloaded gloo-timers v0.2.3
[INFO] [stderr]   Downloaded blocking v1.1.0
[INFO] [stderr]   Downloaded value-bag v1.0.0-alpha.8
[INFO] [stderr]   Downloaded serde-hashkey v0.4.0
[INFO] [stderr]   Downloaded jql v3.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/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] ef8147d373dbeed6b680ffa64044b269f75cdecb269192dbdff156929f8e0e4a
[INFO] running `Command { std: "docker" "start" "-a" "ef8147d373dbeed6b680ffa64044b269f75cdecb269192dbdff156929f8e0e4a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ef8147d373dbeed6b680ffa64044b269f75cdecb269192dbdff156929f8e0e4a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef8147d373dbeed6b680ffa64044b269f75cdecb269192dbdff156929f8e0e4a", kill_on_drop: false }`
[INFO] [stdout] ef8147d373dbeed6b680ffa64044b269f75cdecb269192dbdff156929f8e0e4a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/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] 6223ffa2991689a069de0c15ce405730001b120e45aa7a4999d2b3ad1fcbd84e
[INFO] running `Command { std: "docker" "start" "-a" "6223ffa2991689a069de0c15ce405730001b120e45aa7a4999d2b3ad1fcbd84e", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.119
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling paste v1.0.6
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling pest_generator v2.1.3
[INFO] [stderr]    Compiling ordered-float v2.10.0
[INFO] [stderr]    Compiling pretty_assertions v0.7.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling pest_derive v2.1.0
[INFO] [stderr]    Compiling colored v2.0.0
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling serde-hashkey v0.4.0
[INFO] [stderr]    Compiling filmreel v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.48s
[INFO] running `Command { std: "docker" "inspect" "6223ffa2991689a069de0c15ce405730001b120e45aa7a4999d2b3ad1fcbd84e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6223ffa2991689a069de0c15ce405730001b120e45aa7a4999d2b3ad1fcbd84e", kill_on_drop: false }`
[INFO] [stdout] 6223ffa2991689a069de0c15ce405730001b120e45aa7a4999d2b3ad1fcbd84e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/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] 7e873da3d06313ab97b443fd193cfd8a71397882545ce6224828245bd7bbf83f
[INFO] running `Command { std: "docker" "start" "-a" "7e873da3d06313ab97b443fd193cfd8a71397882545ce6224828245bd7bbf83f", 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]    Compiling rstest v0.11.0
[INFO] [stderr]    Compiling filmreel v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.95s
[INFO] running `Command { std: "docker" "inspect" "7e873da3d06313ab97b443fd193cfd8a71397882545ce6224828245bd7bbf83f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7e873da3d06313ab97b443fd193cfd8a71397882545ce6224828245bd7bbf83f", kill_on_drop: false }`
[INFO] [stdout] 7e873da3d06313ab97b443fd193cfd8a71397882545ce6224828245bd7bbf83f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-26/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] 239ca49dd80db6d4d2483bf9f8db524e06b9266a881733d223bb443204b0ed88
[INFO] running `Command { std: "docker" "start" "-a" "239ca49dd80db6d4d2483bf9f8db524e06b9266a881733d223bb443204b0ed88", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/filmreel-47edcb2ec31c9525)
[INFO] [stdout] 
[INFO] [stdout] running 92 tests
[INFO] [stdout] test cut::tests::test_write_op ... ok
[INFO] [stdout] test cut::tests::test_iter ... ok
[INFO] [stdout] test cut::serde_tests::register_de ... ok
[INFO] [stdout] test cut::tests::test_write_op_err ... ok
[INFO] [stdout] test cut::serde_tests::register_ser ... ok
[INFO] [stdout] test frame::tests::test_instruction_set_validate ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_1 ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_2 ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_3 ... ok
[INFO] [stdout] test reel::tests::test_metaframe_try_from ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_1 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_1 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_4 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_2 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_3 ... ok
[INFO] [stdout] test frame::tests::test_hydrate ... ok
[INFO] [stdout] test reel::tests::test_validate ... ok
[INFO] [stdout] test reel::tests::test_validate_err ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_1 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_02 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_4 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_2 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_01 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_5 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_1 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_04 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_4 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_3 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_05 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_03 ... ok
[INFO] [stdout] test frame::tests::test_key_hydrate ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_06 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_09 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_07 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_08 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_10 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_3 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_11 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_12 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_13 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_14 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_1 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_2 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_4 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_3 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_5 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_7 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_6 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_01 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_02 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_03 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_04 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_05 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_06 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_07 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_08 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_09 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_10 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_11 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_12 ... ok
[INFO] [stdout] test serde_tests::frame_de ... ok
[INFO] [stdout] test serde_tests::instruction_set_de ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_ser ... ok
[INFO] [stdout] test serde_tests::protocol_http_ser ... ok
[INFO] [stdout] test serde_tests::frame_ser ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_de ... ok
[INFO] [stdout] test serde_tests::request_de ... ok
[INFO] [stdout] test serde_tests::protocol_http_de ... ok
[INFO] [stdout] test serde_tests::instruction_set_ser ... ok
[INFO] [stdout] test serde_tests::response_de ... ok
[INFO] [stdout] test serde_tests::response_etc_de ... ok
[INFO] [stdout] test serde_tests::simple_frame_ser ... ok
[INFO] [stdout] test serde_tests::request_etc_ser ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_2 ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_1 ... ok
[INFO] [stdout] test serde_tests::request_ser ... ok
[INFO] [stdout] test serde_tests::request_etc_de ... ok
[INFO] [stdout] test serde_tests::response_ser ... ok
[INFO] [stdout] test serde_tests::response_etc_ser ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_6 ... ok
[INFO] [stdout] test response::tests::test_match_payload_response ... ok
[INFO] [stdout] test vreel::tests::pathbuf_vframe_de ... ok
[INFO] [stdout] test serde_tests::simple_frame_de ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_5 ... ok
[INFO] [stdout] test vreel::tests::pathbuf_vframe_ser ... ok
[INFO] [stdout] test vreel::tests::vframe_de ... ok
[INFO] [stdout] test vreel::tests::vframe_ser ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_3 - should panic ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_7 - should panic ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_4 - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 92 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests filmreel
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/frame.rs - frame::to (line 319) ... ok
[INFO] [stdout] test src/frame.rs - frame::from (line 343) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "239ca49dd80db6d4d2483bf9f8db524e06b9266a881733d223bb443204b0ed88", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "239ca49dd80db6d4d2483bf9f8db524e06b9266a881733d223bb443204b0ed88", kill_on_drop: false }`
[INFO] [stdout] 239ca49dd80db6d4d2483bf9f8db524e06b9266a881733d223bb443204b0ed88