[INFO] fetching crate filmreel 0.7.0...
[INFO] testing filmreel-0.7.0 against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] extracting crate filmreel 0.7.0 into /workspace/builds/worker-92/source
[INFO] validating manifest of crates.io crate filmreel 0.7.0 on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "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-92/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded colored_json v2.1.0
[INFO] [stderr]   Downloaded jql v3.0.4
[INFO] [stderr]   Downloaded serde-hashkey v0.4.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/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" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d462839b3d3f659a950ba8fee90fd5ede84b5d31bb245f553affecd45612e89d
[INFO] running `Command { std: "docker" "start" "-a" "d462839b3d3f659a950ba8fee90fd5ede84b5d31bb245f553affecd45612e89d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d462839b3d3f659a950ba8fee90fd5ede84b5d31bb245f553affecd45612e89d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d462839b3d3f659a950ba8fee90fd5ede84b5d31bb245f553affecd45612e89d", kill_on_drop: false }`
[INFO] [stdout] d462839b3d3f659a950ba8fee90fd5ede84b5d31bb245f553affecd45612e89d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/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" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f8904f91fd06a528d7688e1e625333cf5dbdd3638669a979eb73f3db88411720
[INFO] running `Command { std: "docker" "start" "-a" "f8904f91fd06a528d7688e1e625333cf5dbdd3638669a979eb73f3db88411720", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.32
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.81
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling libc v0.2.108
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling serde_json v1.0.71
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling paste v1.0.6
[INFO] [stderr]    Compiling pest_meta v2.1.3
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling atty v0.2.14
[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 pest_derive v2.1.0
[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.11s
[INFO] running `Command { std: "docker" "inspect" "f8904f91fd06a528d7688e1e625333cf5dbdd3638669a979eb73f3db88411720", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f8904f91fd06a528d7688e1e625333cf5dbdd3638669a979eb73f3db88411720", kill_on_drop: false }`
[INFO] [stdout] f8904f91fd06a528d7688e1e625333cf5dbdd3638669a979eb73f3db88411720
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 68acf9916ba8977057c4bae8fbdb2b700f5cc36253cc210b5cdd3275fa75888a
[INFO] running `Command { std: "docker" "start" "-a" "68acf9916ba8977057c4bae8fbdb2b700f5cc36253cc210b5cdd3275fa75888a", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver v1.0.4
[INFO] [stderr]    Compiling cfg-if v1.0.0
[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 15.49s
[INFO] running `Command { std: "docker" "inspect" "68acf9916ba8977057c4bae8fbdb2b700f5cc36253cc210b5cdd3275fa75888a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68acf9916ba8977057c4bae8fbdb2b700f5cc36253cc210b5cdd3275fa75888a", kill_on_drop: false }`
[INFO] [stdout] 68acf9916ba8977057c4bae8fbdb2b700f5cc36253cc210b5cdd3275fa75888a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-92/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d703e1d081727f054d39c3bf33e455bc17cf59314b31ee99c6ac434694a9c0c4
[INFO] running `Command { std: "docker" "start" "-a" "d703e1d081727f054d39c3bf33e455bc17cf59314b31ee99c6ac434694a9c0c4", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.53s
[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_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_3 ... 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_read_op::case_5 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_1 ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_2 ... ok
[INFO] [stdout] test cut::tests::test_write_op_err ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_4 ... ok
[INFO] [stdout] test cut::tests::test_write_op ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_3 ... ok
[INFO] [stdout] test cut::tests::test_read_match_err::case_2 ... ok
[INFO] [stdout] test frame::tests::test_instruction_set_validate ... ok
[INFO] [stdout] test cut::tests::test_read_op::case_1 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_2 ... 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 reel::tests::test_parse_sequence::case_3 ... 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 cut::tests::test_write_match::case_3 ... ok
[INFO] [stdout] test frame::tests::test_hydrate ... ok
[INFO] [stdout] test frame::tests::test_key_hydrate ... ok
[INFO] [stdout] test reel::tests::test_parse_sequence::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 response::tests::test_partial_unordered_validation::case_03 ... ok
[INFO] [stdout] test cut::tests::test_write_match::case_4 ... 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_04 ... 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_12 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_11 ... ok
[INFO] [stdout] test response::tests::test_partial_validation::case_1 ... ok
[INFO] [stdout] test response::tests::test_partial_unordered_validation::case_10 ... 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_2 ... 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_4 ... 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_03 ... 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_partial_validation::case_7 ... 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_match_payload_response ... 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_09 ... 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::frame_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::instruction_set_de ... ok
[INFO] [stdout] test serde_tests::instruction_set_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_ser ... ok
[INFO] [stdout] test serde_tests::request_etc_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_ser ... ok
[INFO] [stdout] test serde_tests::simple_frame_de ... 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 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_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. 92 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[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.60s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "d703e1d081727f054d39c3bf33e455bc17cf59314b31ee99c6ac434694a9c0c4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d703e1d081727f054d39c3bf33e455bc17cf59314b31ee99c6ac434694a9c0c4", kill_on_drop: false }`
[INFO] [stdout] d703e1d081727f054d39c3bf33e455bc17cf59314b31ee99c6ac434694a9c0c4