[INFO] cloning repository https://github.com/TimTheBig/villain.rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/TimTheBig/villain.rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTimTheBig%2Fvillain.rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTimTheBig%2Fvillain.rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 66f316335cb40497631f19fb7af2f4d6cd284ba2
[INFO] testing TimTheBig/villain.rs against try#16fbf98f22730e073c09e7b03f0eafb87a295545 for pr-145628
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FTimTheBig%2Fvillain.rs" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/TimTheBig/villain.rs
[INFO] finished tweaking git repo https://github.com/TimTheBig/villain.rs
[INFO] tweaked toml for git repo https://github.com/TimTheBig/villain.rs written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/TimTheBig/villain.rs on toolchain 16fbf98f22730e073c09e7b03f0eafb87a295545
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/TimTheBig/villain.rs already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 6acabaea7804cbdcdc4d55dd1145191fc50a6e31dfee7e2269a6c3b0732e37fc
[INFO] running `Command { std: "docker" "start" "-a" "6acabaea7804cbdcdc4d55dd1145191fc50a6e31dfee7e2269a6c3b0732e37fc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6acabaea7804cbdcdc4d55dd1145191fc50a6e31dfee7e2269a6c3b0732e37fc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6acabaea7804cbdcdc4d55dd1145191fc50a6e31dfee7e2269a6c3b0732e37fc", kill_on_drop: false }`
[INFO] [stdout] 6acabaea7804cbdcdc4d55dd1145191fc50a6e31dfee7e2269a6c3b0732e37fc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "RUSTDOCFLAGS=--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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6f23fe41ee0707ca5833334d31c50b740d3a667fe6b3122a608b4a6c1dfee6b6
[INFO] running `Command { std: "docker" "start" "-a" "6f23fe41ee0707ca5833334d31c50b740d3a667fe6b3122a608b4a6c1dfee6b6", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.75
[INFO] [stderr]    Compiling thiserror v1.0.63
[INFO] [stderr]    Compiling html-sys v0.4.3
[INFO] [stderr]    Compiling thiserror-impl v1.0.63
[INFO] [stderr]    Compiling html v0.6.3
[INFO] [stderr]    Compiling villain v0.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 50.26s
[INFO] running `Command { std: "docker" "inspect" "6f23fe41ee0707ca5833334d31c50b740d3a667fe6b3122a608b4a6c1dfee6b6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6f23fe41ee0707ca5833334d31c50b740d3a667fe6b3122a608b4a6c1dfee6b6", kill_on_drop: false }`
[INFO] [stdout] 6f23fe41ee0707ca5833334d31c50b740d3a667fe6b3122a608b4a6c1dfee6b6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "RUSTDOCFLAGS=--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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2c583639631b98a0186f22eb90f311c3f28b4fde958df80a3f0296bf311b692f
[INFO] running `Command { std: "docker" "start" "-a" "2c583639631b98a0186f22eb90f311c3f28b4fde958df80a3f0296bf311b692f", kill_on_drop: false }`
[INFO] [stderr]    Compiling villain v0.0.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.67s
[INFO] running `Command { std: "docker" "inspect" "2c583639631b98a0186f22eb90f311c3f28b4fde958df80a3f0296bf311b692f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2c583639631b98a0186f22eb90f311c3f28b4fde958df80a3f0296bf311b692f", kill_on_drop: false }`
[INFO] [stdout] 2c583639631b98a0186f22eb90f311c3f28b4fde958df80a3f0296bf311b692f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "RUSTDOCFLAGS=--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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+16fbf98f22730e073c09e7b03f0eafb87a295545" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] af865a3b64cc6511870686cf42b6512bc0c7c89bbfa774e08263690194a5b948
[INFO] running `Command { std: "docker" "start" "-a" "af865a3b64cc6511870686cf42b6512bc0c7c89bbfa774e08263690194a5b948", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/villain-933929b4b6040960)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test parser::parser::tests::test_parse_attribute ... ok
[INFO] [stdout] test parser::parser::tests::test_parse_nested_tag_with_attributes ... ok
[INFO] [stdout] test parser::parser::tests::test_parse_tag ... ok
[INFO] [stdout] test parser::parser::tests::test_parse_text_node ... ok
[INFO] [stdout] test parser::scanner::tests::test_gracefully_handles_tag_in_unclosed_tag ... ok
[INFO] [stdout] test parser::parser::tests::test_parses_vue ... ok
[INFO] [stdout] test parser::scanner::tests::test_gracefully_handles_unclosed_tag ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_nested_tag ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_empty_tag ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_short_tag ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_template_and_interpolation_and_attrs ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_complex_interpolation ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_short_tag_with_attribute ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_interpolation ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_empty_tag_with_spaces ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_attributes ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_super_complex_interpolation ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_vattributes ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_text_with_interpolation ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_text ... ok
[INFO] [stdout] test parser::scanner::tests::test_scans_tag_with_only_super_complex_interpolation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests villain
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - create_entypoint (line 31) ... FAILED
[INFO] [stdout] test src/lib.rs - create_component (line 18) ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/lib.rs - create_entypoint (line 31) stdout ----
[INFO] [stdout] error: proc macro panicked
[INFO] [stdout]  --> src/lib.rs:36:21
[INFO] [stdout]   |
[INFO] [stdout] 7 |    println!("{:?}", create_entypoint!("src/App.vue"));
[INFO] [stdout]   |                     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = help: message: Could not read template file: src/App.vue
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/lib.rs - create_entypoint (line 31)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.50s
[INFO] [stdout] 
[INFO] [stderr] error: doctest failed, to rerun pass `--doc`
[INFO] running `Command { std: "docker" "inspect" "af865a3b64cc6511870686cf42b6512bc0c7c89bbfa774e08263690194a5b948", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "af865a3b64cc6511870686cf42b6512bc0c7c89bbfa774e08263690194a5b948", kill_on_drop: false }`
[INFO] [stdout] af865a3b64cc6511870686cf42b6512bc0c7c89bbfa774e08263690194a5b948