[INFO] fetching crate fountain 0.1.12...
[INFO] testing fountain-0.1.12 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate fountain 0.1.12 into /workspace/builds/worker-60/source
[INFO] validating manifest of crates.io crate fountain 0.1.12 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 fountain 0.1.12
[INFO] finished tweaking crates.io crate fountain 0.1.12
[INFO] tweaked toml for crates.io crate fountain 0.1.12 written to /workspace/builds/worker-60/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-60/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/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] b503ed217588da33398b63eec811d0d602efc1c74d1a9183a6f69a91dcb4b70d
[INFO] running `Command { std: "docker" "start" "-a" "b503ed217588da33398b63eec811d0d602efc1c74d1a9183a6f69a91dcb4b70d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b503ed217588da33398b63eec811d0d602efc1c74d1a9183a6f69a91dcb4b70d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b503ed217588da33398b63eec811d0d602efc1c74d1a9183a6f69a91dcb4b70d", kill_on_drop: false }`
[INFO] [stdout] b503ed217588da33398b63eec811d0d602efc1c74d1a9183a6f69a91dcb4b70d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/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] cbef0fbfcec8fe8df17a7e2795cbd4391855efe3bdc599559f4e5b1891475366
[INFO] running `Command { std: "docker" "start" "-a" "cbef0fbfcec8fe8df17a7e2795cbd4391855efe3bdc599559f4e5b1891475366", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling lexical-core v0.7.6
[INFO] [stderr]    Compiling bitflags v1.3.2
[INFO] [stderr]    Compiling arrayvec v0.5.2
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling nom v5.1.2
[INFO] [stderr]    Compiling fountain v0.1.12 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.72s
[INFO] running `Command { std: "docker" "inspect" "cbef0fbfcec8fe8df17a7e2795cbd4391855efe3bdc599559f4e5b1891475366", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cbef0fbfcec8fe8df17a7e2795cbd4391855efe3bdc599559f4e5b1891475366", kill_on_drop: false }`
[INFO] [stdout] cbef0fbfcec8fe8df17a7e2795cbd4391855efe3bdc599559f4e5b1891475366
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/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] 9663b989a28c6397d9f20ba5b70005f5b1e709c27c33cb2a918ad505df1acef2
[INFO] running `Command { std: "docker" "start" "-a" "9663b989a28c6397d9f20ba5b70005f5b1e709c27c33cb2a918ad505df1acef2", kill_on_drop: false }`
[INFO] [stderr]    Compiling fountain v0.1.12 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.98s
[INFO] running `Command { std: "docker" "inspect" "9663b989a28c6397d9f20ba5b70005f5b1e709c27c33cb2a918ad505df1acef2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9663b989a28c6397d9f20ba5b70005f5b1e709c27c33cb2a918ad505df1acef2", kill_on_drop: false }`
[INFO] [stdout] 9663b989a28c6397d9f20ba5b70005f5b1e709c27c33cb2a918ad505df1acef2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-60/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] 0ba3361e5a37b0b694a386e63980e56af11d626a418667a2223c69f5d2641987
[INFO] running `Command { std: "docker" "start" "-a" "0ba3361e5a37b0b694a386e63980e56af11d626a418667a2223c69f5d2641987", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fountain-3ab60d4df078e8c1)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test parse::tests::test_action ... ok
[INFO] [stdout] test parse::tests::test_alien ... ok
[INFO] [stdout] test parse::tests::test_document_small ... ok
[INFO] [stdout] test parse::tests::test_document_tiny ... ok
[INFO] [stdout] test parse::tests::test_document ... ok
[INFO] [stdout] test parse::tests::test_ext_scene ... ok
[INFO] [stdout] test parse::tests::test_in_parens ... ok
[INFO] [stdout] test parse::tests::test_forced_transition ... ok
[INFO] [stdout] test parse::tests::test_int_scene ... ok
[INFO] [stdout] test parse::tests::test_lyric ... ok
[INFO] [stdout] test parse::tests::test_no_lower ... ok
[INFO] [stdout] test parse::tests::test_parenthetical ... ok
[INFO] [stdout] test parse::tests::test_sd_block ... ok
[INFO] [stdout] test parse::tests::test_some_line ... ok
[INFO] [stdout] test parse::tests::test_spd_block ... ok
[INFO] [stdout] test parse::tests::test_strip_suffix ... ok
[INFO] [stdout] test parse::tests::test_speaker ... ok
[INFO] [stdout] test parse::tests::test_transition ... ok
[INFO] [stdout] test parse::tests::test_titlepage ... ok
[INFO] [stdout] test utils::tests::test_position_after ... ok
[INFO] [stdout] test utils::tests::test_position_before ... 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 fountain
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/lib.rs - (line 8) - compile ... ok
[INFO] [stdout] test src/data.rs - data::TitlePage (line 95) ... ok
[INFO] [stdout] test src/parse.rs - parse::document (line 137) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.58s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "0ba3361e5a37b0b694a386e63980e56af11d626a418667a2223c69f5d2641987", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0ba3361e5a37b0b694a386e63980e56af11d626a418667a2223c69f5d2641987", kill_on_drop: false }`
[INFO] [stdout] 0ba3361e5a37b0b694a386e63980e56af11d626a418667a2223c69f5d2641987