[INFO] fetching crate filmreel 0.7.0...
[INFO] testing filmreel-0.7.0 against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] extracting crate filmreel 0.7.0 into /workspace/builds/worker-79/source
[INFO] validating manifest of crates.io crate filmreel 0.7.0 on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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-79/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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-79/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] beeee3aa3d9b4f24e109b8c684f4914c2424f6d5f0a71fdb87b8bd795c6d3782
[INFO] running `Command { std: "docker" "start" "-a" "beeee3aa3d9b4f24e109b8c684f4914c2424f6d5f0a71fdb87b8bd795c6d3782", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "beeee3aa3d9b4f24e109b8c684f4914c2424f6d5f0a71fdb87b8bd795c6d3782", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "beeee3aa3d9b4f24e109b8c684f4914c2424f6d5f0a71fdb87b8bd795c6d3782", kill_on_drop: false }`
[INFO] [stdout] beeee3aa3d9b4f24e109b8c684f4914c2424f6d5f0a71fdb87b8bd795c6d3782
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 730deff818247f3ef2c47ace2e5ae711e5b5db1b44cc56573b710ed0265de7e8
[INFO] running `Command { std: "docker" "start" "-a" "730deff818247f3ef2c47ace2e5ae711e5b5db1b44cc56573b710ed0265de7e8", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.108
[INFO] [stderr]    Compiling ucd-trie v0.1.3
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling paste v1.0.6
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling syn v1.0.81
[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 pest_meta v2.1.3
[INFO] [stderr]    Compiling colored v2.0.0
[INFO] [stderr]    Compiling ordered-float v2.8.0
[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.71
[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 29.32s
[INFO] running `Command { std: "docker" "inspect" "730deff818247f3ef2c47ace2e5ae711e5b5db1b44cc56573b710ed0265de7e8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "730deff818247f3ef2c47ace2e5ae711e5b5db1b44cc56573b710ed0265de7e8", kill_on_drop: false }`
[INFO] [stdout] 730deff818247f3ef2c47ace2e5ae711e5b5db1b44cc56573b710ed0265de7e8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 594b79988d9a2902cbcc7f65aa864b1338aaf9d301db6c138ef99d10e7fc3f9e
[INFO] running `Command { std: "docker" "start" "-a" "594b79988d9a2902cbcc7f65aa864b1338aaf9d301db6c138ef99d10e7fc3f9e", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver v1.0.4
[INFO] [stderr]    Compiling rustc_version v0.4.0
[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 14.96s
[INFO] running `Command { std: "docker" "inspect" "594b79988d9a2902cbcc7f65aa864b1338aaf9d301db6c138ef99d10e7fc3f9e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "594b79988d9a2902cbcc7f65aa864b1338aaf9d301db6c138ef99d10e7fc3f9e", kill_on_drop: false }`
[INFO] [stdout] 594b79988d9a2902cbcc7f65aa864b1338aaf9d301db6c138ef99d10e7fc3f9e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-79/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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 25d3f05f31d9404f5dc15d38f64b262afa1a03853e7be3d510c54ceb961f473d
[INFO] running `Command { std: "docker" "start" "-a" "25d3f05f31d9404f5dc15d38f64b262afa1a03853e7be3d510c54ceb961f473d", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/filmreel-076e0827801c9845)
[INFO] [stdout] 
[INFO] [stdout] running 92 tests
[INFO] [stdout] test cut::serde_tests::register_ser ... ok
[INFO] [stdout] test cut::tests::test_iter ... ok
[INFO] [stdout] test cut::tests::test_write_op ... ok
[INFO] [stdout] test cut::serde_tests::register_de ... ok
[INFO] [stdout] test frame::tests::test_instruction_set_validate ... ok
[INFO] [stdout] test cut::tests::test_write_op_err ... 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_2 ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_2 ... 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_validate ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::case_4 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_4 ... 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 cut::tests::test_destructive_merge::case_3 ... 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_write_match::case_3 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_02 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_5 ... 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 cut::tests::test_read_op::case_4 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_2 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_1 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_05 ... 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 cut::tests::test_read_match_err::case_1 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_3 ... ok
[INFO] [stdout] test frame::tests::test_hydrate ... 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_match_payload_response ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_12 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_11 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_14 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_13 ... 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 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_04 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_03 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_02 ... 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_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_08 ... ok
[INFO] [stdout] test response::tests::test_unordered_validation::case_12 ... ok
[INFO] [stdout] test serde_tests::frame_ser ... ok
[INFO] [stdout] test serde_tests::instruction_set_de ... ok
[INFO] [stdout] test serde_tests::frame_de ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_de ... ok
[INFO] [stdout] test serde_tests::instruction_set_ser ... ok
[INFO] [stdout] test serde_tests::protocol_grpc_ser ... 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::response_etc_de ... ok
[INFO] [stdout] test serde_tests::response_etc_ser ... ok
[INFO] [stdout] test serde_tests::response_ser ... 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_1 ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_2 ... ok
[INFO] [stdout] test utils::tests::test_obj_selection::case_6 ... 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::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_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. 92 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 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 2.33s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "25d3f05f31d9404f5dc15d38f64b262afa1a03853e7be3d510c54ceb961f473d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "25d3f05f31d9404f5dc15d38f64b262afa1a03853e7be3d510c54ceb961f473d", kill_on_drop: false }`
[INFO] [stdout] 25d3f05f31d9404f5dc15d38f64b262afa1a03853e7be3d510c54ceb961f473d