[INFO] fetching crate filmreel 0.6.2...
[INFO] testing filmreel-0.6.2 against beta-2021-08-01 for beta-1.55-1
[INFO] extracting crate filmreel 0.6.2 into /workspace/builds/worker-9/source
[INFO] validating manifest of crates.io crate filmreel 0.6.2 on toolchain beta-2021-08-01
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-08-01" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate filmreel 0.6.2
[INFO] finished tweaking crates.io crate filmreel 0.6.2
[INFO] tweaked toml for crates.io crate filmreel 0.6.2 written to /workspace/builds/worker-9/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-08-01" "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-2021-08-01" "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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-08-01" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5628feb594c8166cdd23f5c5af5a7ff43dbe20e6d7e92313a8ed21b7b3301852
[INFO] running `Command { std: "docker" "start" "-a" "5628feb594c8166cdd23f5c5af5a7ff43dbe20e6d7e92313a8ed21b7b3301852", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5628feb594c8166cdd23f5c5af5a7ff43dbe20e6d7e92313a8ed21b7b3301852", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5628feb594c8166cdd23f5c5af5a7ff43dbe20e6d7e92313a8ed21b7b3301852", kill_on_drop: false }`
[INFO] [stdout] 5628feb594c8166cdd23f5c5af5a7ff43dbe20e6d7e92313a8ed21b7b3301852
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-08-01" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d42d1c4c39a2275658b35f2127ce60930a7960f2b4fed27cbd7289a0b74a8ba4
[INFO] running `Command { std: "docker" "start" "-a" "d42d1c4c39a2275658b35f2127ce60930a7960f2b4fed27cbd7289a0b74a8ba4", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling syn v1.0.74
[INFO] [stderr]    Compiling serde_derive v1.0.127
[INFO] [stderr]    Compiling serde v1.0.127
[INFO] [stderr]    Compiling itoa v0.4.7
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling colored v2.0.0
[INFO] [stderr]    Compiling pretty_assertions v0.7.2
[INFO] [stderr]    Compiling ordered-float v2.7.0
[INFO] [stderr]    Compiling pest_generator v2.1.3
[INFO] [stderr]    Compiling pest_derive v2.1.0
[INFO] [stderr]    Compiling serde_json v1.0.66
[INFO] [stderr]    Compiling serde-hashkey v0.4.0
[INFO] [stderr]    Compiling filmreel v0.6.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.55s
[INFO] running `Command { std: "docker" "inspect" "d42d1c4c39a2275658b35f2127ce60930a7960f2b4fed27cbd7289a0b74a8ba4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d42d1c4c39a2275658b35f2127ce60930a7960f2b4fed27cbd7289a0b74a8ba4", kill_on_drop: false }`
[INFO] [stdout] d42d1c4c39a2275658b35f2127ce60930a7960f2b4fed27cbd7289a0b74a8ba4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-08-01" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5e35e8d856cdb8630d855be11484d79fd33f4ba972a569907e4277f99d3aea06
[INFO] running `Command { std: "docker" "start" "-a" "5e35e8d856cdb8630d855be11484d79fd33f4ba972a569907e4277f99d3aea06", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver-parser v0.10.2
[INFO] [stderr]    Compiling semver v0.11.0
[INFO] [stderr]    Compiling rustc_version v0.3.3
[INFO] [stderr]    Compiling rstest v0.10.0
[INFO] [stderr]    Compiling filmreel v0.6.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 15.57s
[INFO] running `Command { std: "docker" "inspect" "5e35e8d856cdb8630d855be11484d79fd33f4ba972a569907e4277f99d3aea06", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5e35e8d856cdb8630d855be11484d79fd33f4ba972a569907e4277f99d3aea06", kill_on_drop: false }`
[INFO] [stdout] 5e35e8d856cdb8630d855be11484d79fd33f4ba972a569907e4277f99d3aea06
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-9/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-08-01" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 73c9b1e328c522fdf17b8077ce3369fd55e55ee37a899e0b917e9cf01fecc136
[INFO] running `Command { std: "docker" "start" "-a" "73c9b1e328c522fdf17b8077ce3369fd55e55ee37a899e0b917e9cf01fecc136", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/filmreel-58933eaecd15615e)
[INFO] [stdout] 
[INFO] [stdout] running 91 tests
[INFO] [stdout] test reel::tests::test_metaframe_try_from ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_1 ... ok
[INFO] [stdout] test frame::tests::test_instruction_set_validate ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_2 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_3 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_3 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_4 ... ok
[INFO] [stdout] test reel::tests::test_validate ... ok
[INFO] [stdout] test cut::serde_tests::register_ser ... ok
[INFO] [stdout] test reel::tests::test_validate_err ... ok
[INFO] [stdout] test cut::tests::test_write_op ... ok
[INFO] [stdout] test cut::serde_tests::register_de ... ok
[INFO] [stdout] test cut::tests::test_write_op_err ... ok
[INFO] [stdout] test cut::tests::test_iter ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_03 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_04 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_01 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_02 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_1 ... 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 response::tests::test_partial_unordered_validation::case_11 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_08 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_07 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_10 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_06 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_05 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_12 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_09 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_13 ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_1 ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_3 ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_2 ... 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_3 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_4 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_5 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_6 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_2 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_7 ... ok
[INFO] [stdout] test response::tests::test_match_payload_response ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_01 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_4 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_1 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_3 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_05 ... ok
[INFO] [stdout] test frame::tests::test_hydrate ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_06 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_1 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_04 ... 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_07 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_5 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_08 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_10 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_09 ... 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::instruction_set_de ... ok
[INFO] [stdout] test serde_tests::instruction_set_ser ... ok
[INFO] [stdout] test serde_tests::frame_ser ... ok
[INFO] [stdout] test serde_tests::frame_de ... ok
[INFO] [stdout] test serde_tests::protocol_http_de ... ok
[INFO] [stdout] test serde_tests::protocol_http_ser ... ok
[INFO] [stdout] test serde_tests::request_de ... ok
[INFO] [stdout] test serde_tests::request_etc_de ... ok
[INFO] [stdout] test serde_tests::request_etc_ser ... ok
[INFO] [stdout] test serde_tests::request_ser ... ok
[INFO] [stdout] test serde_tests::response_de ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_de ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_ser ... ok
[INFO] [stdout] test serde_tests::response_etc_ser ... ok
[INFO] [stdout] test serde_tests::response_etc_de ... ok
[INFO] [stdout] test serde_tests::simple_frame_de ... ok
[INFO] [stdout] test serde_tests::simple_frame_ser ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_2 ... ok
[INFO] [stdout] test serde_tests::response_ser ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_1 ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_5 ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_6 ... ok
[INFO] [stdout] test vreel::tests::pathbuf_vframe_de ... 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_4 - should panic ... 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] 
[INFO] [stdout] test result: ok. 91 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests filmreel
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/frame.rs - to (line 285) ... ok
[INFO] [stdout] test src/frame.rs - from (line 309) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.09s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "73c9b1e328c522fdf17b8077ce3369fd55e55ee37a899e0b917e9cf01fecc136", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73c9b1e328c522fdf17b8077ce3369fd55e55ee37a899e0b917e9cf01fecc136", kill_on_drop: false }`
[INFO] [stdout] 73c9b1e328c522fdf17b8077ce3369fd55e55ee37a899e0b917e9cf01fecc136
