[INFO] fetching crate filmreel 0.6.2...
[INFO] testing filmreel-0.6.2 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate filmreel 0.6.2 into /workspace/builds/worker-63/source
[INFO] validating manifest of crates.io crate filmreel 0.6.2 on toolchain beta-2021-09-08
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "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-63/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "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-09-08" "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-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e6bca1ffdea229ab2c5f479aec32834ecb887ee9b765de1b1d0d3aa181128eba
[INFO] running `Command { std: "docker" "start" "-a" "e6bca1ffdea229ab2c5f479aec32834ecb887ee9b765de1b1d0d3aa181128eba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e6bca1ffdea229ab2c5f479aec32834ecb887ee9b765de1b1d0d3aa181128eba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e6bca1ffdea229ab2c5f479aec32834ecb887ee9b765de1b1d0d3aa181128eba", kill_on_drop: false }`
[INFO] [stdout] e6bca1ffdea229ab2c5f479aec32834ecb887ee9b765de1b1d0d3aa181128eba
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 170bc1b98f87a2de045e6c45a73705aa1bb1ebcddcd2181ea8a6b63add4c275b
[INFO] running `Command { std: "docker" "start" "-a" "170bc1b98f87a2de045e6c45a73705aa1bb1ebcddcd2181ea8a6b63add4c275b", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.76
[INFO] [stderr]    Compiling ucd-trie v0.1.3
[INFO] [stderr]    Compiling libc v0.2.101
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling paste v1.0.5
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling ordered-float v2.8.0
[INFO] [stderr]    Compiling pest v2.1.3
[INFO] [stderr]    Compiling pretty_assertions v0.7.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling colored v2.0.0
[INFO] [stderr]    Compiling pest_meta v2.1.3
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling pest_generator v2.1.3
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling pest_derive v2.1.0
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling serde_json v1.0.67
[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 43.03s
[INFO] running `Command { std: "docker" "inspect" "170bc1b98f87a2de045e6c45a73705aa1bb1ebcddcd2181ea8a6b63add4c275b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "170bc1b98f87a2de045e6c45a73705aa1bb1ebcddcd2181ea8a6b63add4c275b", kill_on_drop: false }`
[INFO] [stdout] 170bc1b98f87a2de045e6c45a73705aa1bb1ebcddcd2181ea8a6b63add4c275b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 862b56803393550f2ebcac6aa01460570e8e2f79ffd68a96be8931ab2472feb1
[INFO] running `Command { std: "docker" "start" "-a" "862b56803393550f2ebcac6aa01460570e8e2f79ffd68a96be8931ab2472feb1", 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 14.72s
[INFO] running `Command { std: "docker" "inspect" "862b56803393550f2ebcac6aa01460570e8e2f79ffd68a96be8931ab2472feb1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "862b56803393550f2ebcac6aa01460570e8e2f79ffd68a96be8931ab2472feb1", kill_on_drop: false }`
[INFO] [stdout] 862b56803393550f2ebcac6aa01460570e8e2f79ffd68a96be8931ab2472feb1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-63/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7f251c91088dc85e5aa4360cb951285fefd6466c38ec2ab2488124f88d1c5ff3
[INFO] running `Command { std: "docker" "start" "-a" "7f251c91088dc85e5aa4360cb951285fefd6466c38ec2ab2488124f88d1c5ff3", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/filmreel-43505b0632083c86)
[INFO] [stdout] 
[INFO] [stdout] running 91 tests
[INFO] [stdout] test cut::serde_tests::register_de ... ok
[INFO] [stdout] test cut::tests::test_iter ... ok
[INFO] [stdout] test cut::serde_tests::register_ser ... ok
[INFO] [stdout] test cut::tests::test_destructive_merge::case_2 ... 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_read_match_err::case_1 ... 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_op::case_5 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_1 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_3 ... ok
[INFO] [stdout] test cut::tests::test_write_op ... ok
[INFO] [stdout] test frame::tests::test_instruction_set_validate ... ok
[INFO] [stdout] test frame::tests::test_hydrate ... ok
[INFO] [stdout] test reel::tests::test_metaframe_try_from ... ok
[INFO] [stdout] test cut::tests::test_write_op_err ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_2 ... 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_3 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_4 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_3 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_4 ... ok
[INFO] [stdout] test reel::tests::test_validate ... ok
[INFO] [stdout] test reel::tests::test_validate_err ... 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_2 ... 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_06 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_05 ... 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_09 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_10 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_11 ... 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_unordered_validation::case_12 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_1 ... 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_6 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_02 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_7 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_01 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_03 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_4 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_2 ... 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_match_payload_response ... 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_11 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_10 ... 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::frame_ser ... ok
[INFO] [stdout] test serde_tests::instruction_set_ser ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_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::protocol_http_de ... ok
[INFO] [stdout] test serde_tests::request_de ... ok
[INFO] [stdout] test serde_tests::request_etc_de ... ok
[INFO] [stdout] test serde_tests::request_ser ... ok
[INFO] [stdout] test serde_tests::response_de ... ok
[INFO] [stdout] test serde_tests::request_etc_ser ... ok
[INFO] [stdout] test serde_tests::response_etc_de ... ok
[INFO] [stdout] test serde_tests::response_etc_ser ... ok
[INFO] [stdout] test serde_tests::simple_frame_de ... 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_2 ... 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 serde_tests::simple_frame_ser ... ok
[INFO] [stdout] test vreel::tests::pathbuf_vframe_ser ... ok
[INFO] [stdout] test vreel::tests::pathbuf_vframe_de ... 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_4 - 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.10s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests filmreel
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/frame.rs - frame::to (line 285) ... ok
[INFO] [stdout] test src/frame.rs - frame::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.48s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7f251c91088dc85e5aa4360cb951285fefd6466c38ec2ab2488124f88d1c5ff3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7f251c91088dc85e5aa4360cb951285fefd6466c38ec2ab2488124f88d1c5ff3", kill_on_drop: false }`
[INFO] [stdout] 7f251c91088dc85e5aa4360cb951285fefd6466c38ec2ab2488124f88d1c5ff3