[INFO] cloning repository https://github.com/Nilstrieb/vfpl
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Nilstrieb/vfpl" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNilstrieb%2Fvfpl", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNilstrieb%2Fvfpl'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] bb067f5466388f2842737559f241f329195f3d98
[INFO] testing Nilstrieb/vfpl against beta-2022-04-10 for beta-1.61-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNilstrieb%2Fvfpl" "/workspace/builds/worker-84/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-84/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/Nilstrieb/vfpl on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/Nilstrieb/vfpl
[INFO] finished tweaking git repo https://github.com/Nilstrieb/vfpl
[INFO] tweaked toml for git repo https://github.com/Nilstrieb/vfpl written to /workspace/builds/worker-84/source/Cargo.toml
[INFO] crate git repo https://github.com/Nilstrieb/vfpl already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "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-84/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ea609f401bb16eff14f19c8e56af9a6f382d44814c4c3126e78e1b9a09427fca
[INFO] running `Command { std: "docker" "start" "-a" "ea609f401bb16eff14f19c8e56af9a6f382d44814c4c3126e78e1b9a09427fca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ea609f401bb16eff14f19c8e56af9a6f382d44814c4c3126e78e1b9a09427fca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ea609f401bb16eff14f19c8e56af9a6f382d44814c4c3126e78e1b9a09427fca", kill_on_drop: false }`
[INFO] [stdout] ea609f401bb16eff14f19c8e56af9a6f382d44814c4c3126e78e1b9a09427fca
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1122aca5cf3a38b4cf1c5d9d8fb715f1ef51e5bb51b3393161a62a8342b84559
[INFO] running `Command { std: "docker" "start" "-a" "1122aca5cf3a38b4cf1c5d9d8fb715f1ef51e5bb51b3393161a62a8342b84559", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.109
[INFO] [stderr]    Compiling version_check v0.9.3
[INFO] [stderr]    Compiling once_cell v1.8.0
[INFO] [stderr]    Compiling fastrand v1.5.0
[INFO] [stderr]    Compiling peekmore v1.0.0
[INFO] [stderr]    Compiling ahash v0.7.6
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling lasso v0.6.0
[INFO] [stderr]    Compiling vfpl_global v0.1.0 (/opt/rustwide/workdir/vfpl_global)
[INFO] [stderr]    Compiling vfpl_error v0.0.0 (/opt/rustwide/workdir/vfpl_error)
[INFO] [stderr]    Compiling vfpl_ast v0.1.0 (/opt/rustwide/workdir/vfpl_ast)
[INFO] [stderr]    Compiling vfpl_lexer v0.0.0 (/opt/rustwide/workdir/vfpl_lexer)
[INFO] [stderr]    Compiling vfpl_parser v0.0.0 (/opt/rustwide/workdir/vfpl_parser)
[INFO] [stderr]    Compiling vfpl_ast_interpreter v0.0.0 (/opt/rustwide/workdir/vfpl_ast_interpreter)
[INFO] [stderr]    Compiling vfpl_driver v0.0.0 (/opt/rustwide/workdir/vfpl_driver)
[INFO] [stderr]    Compiling vfpl v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 45.36s
[INFO] running `Command { std: "docker" "inspect" "1122aca5cf3a38b4cf1c5d9d8fb715f1ef51e5bb51b3393161a62a8342b84559", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1122aca5cf3a38b4cf1c5d9d8fb715f1ef51e5bb51b3393161a62a8342b84559", kill_on_drop: false }`
[INFO] [stdout] 1122aca5cf3a38b4cf1c5d9d8fb715f1ef51e5bb51b3393161a62a8342b84559
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bc28393251e016c12243fadfcb4d677950d63184a2ad30d0d56d96048e3cea07
[INFO] running `Command { std: "docker" "start" "-a" "bc28393251e016c12243fadfcb4d677950d63184a2ad30d0d56d96048e3cea07", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.109
[INFO] [stderr]    Compiling proc-macro2 v1.0.33
[INFO] [stderr]    Compiling syn v1.0.82
[INFO] [stderr]    Compiling serde_derive v1.0.131
[INFO] [stderr]    Compiling serde v1.0.131
[INFO] [stderr]    Compiling ryu v1.0.6
[INFO] [stderr]    Compiling serde_json v1.0.72
[INFO] [stderr]    Compiling linked-hash-map v0.5.4
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling dtoa v0.4.8
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]    Compiling similar v1.3.0
[INFO] [stderr]    Compiling indexmap v1.7.0
[INFO] [stderr]    Compiling yaml-rust v0.4.5
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling terminal_size v0.1.17
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling ahash v0.7.6
[INFO] [stderr]    Compiling console v0.14.1
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling lasso v0.6.0
[INFO] [stderr]    Compiling vfpl_global v0.1.0 (/opt/rustwide/workdir/vfpl_global)
[INFO] [stderr]    Compiling vfpl_error v0.0.0 (/opt/rustwide/workdir/vfpl_error)
[INFO] [stderr]    Compiling vfpl_ast v0.1.0 (/opt/rustwide/workdir/vfpl_ast)
[INFO] [stderr]    Compiling vfpl_lexer v0.0.0 (/opt/rustwide/workdir/vfpl_lexer)
[INFO] [stderr]    Compiling vfpl_ast_interpreter v0.0.0 (/opt/rustwide/workdir/vfpl_ast_interpreter)
[INFO] [stderr]    Compiling vfpl_parser v0.0.0 (/opt/rustwide/workdir/vfpl_parser)
[INFO] [stderr]    Compiling vfpl_driver v0.0.0 (/opt/rustwide/workdir/vfpl_driver)
[INFO] [stderr]    Compiling vfpl v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling serde_yaml v0.8.21
[INFO] [stderr]    Compiling insta v1.8.0
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2m 18s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/vfpl-f415870fe536f069)
[INFO] [stderr]   Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/vfpl-2b94c1c2398beab1)
[INFO] [stderr]   Executable tests/common.rs (/opt/rustwide/target/debug/deps/common-8bc6f9cbd641f72e)
[INFO] [stderr]   Executable tests/programs.rs (/opt/rustwide/target/debug/deps/programs-affa59309db4d83f)
[INFO] [stderr]   Executable tests/statements.rs (/opt/rustwide/target/debug/deps/statements-ad08622d7e4a40c1)
[INFO] [stderr]   Executable tests/std.rs (/opt/rustwide/target/debug/deps/std-afb49713b0fad8b8)
[INFO] [stderr]   Executable tests/struct.rs (/opt/rustwide/target/debug/deps/struct-11492b62b883794c)
[INFO] [stderr]   Executable tests/unexpected_eof.rs (/opt/rustwide/target/debug/deps/unexpected_eof-d1607a4a34e38269)
[INFO] running `Command { std: "docker" "inspect" "bc28393251e016c12243fadfcb4d677950d63184a2ad30d0d56d96048e3cea07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bc28393251e016c12243fadfcb4d677950d63184a2ad30d0d56d96048e3cea07", kill_on_drop: false }`
[INFO] [stdout] bc28393251e016c12243fadfcb4d677950d63184a2ad30d0d56d96048e3cea07
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-84/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 390989962cf40930c50a7276f7559a9651d6a347cc9897c802875c025eb6ac41
[INFO] running `Command { std: "docker" "start" "-a" "390989962cf40930c50a7276f7559a9651d6a347cc9897c802875c025eb6ac41", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.91s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vfpl-f415870fe536f069)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/vfpl-2b94c1c2398beab1)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/common.rs (/opt/rustwide/target/debug/deps/common-8bc6f9cbd641f72e)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/programs.rs (/opt/rustwide/target/debug/deps/programs-affa59309db4d83f)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test fizzbuzz ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/statements.rs (/opt/rustwide/target/debug/deps/statements-ad08622d7e4a40c1)
[INFO] [stdout] 
[INFO] [stdout] running 29 tests
[INFO] [stderr]      Running tests/std.rs (/opt/rustwide/target/debug/deps/std-afb49713b0fad8b8)
[INFO] [stdout] test deny_keyword_as_as_ident_name ... ok
[INFO] [stderr]      Running tests/struct.rs (/opt/rustwide/target/debug/deps/struct-11492b62b883794c)
[INFO] [stdout] test deny_keyword_absent_as_ident_name ... ok
[INFO] [stderr]      Running tests/unexpected_eof.rs (/opt/rustwide/target/debug/deps/unexpected_eof-d1607a4a34e38269)
[INFO] [stdout] test deny_keyword_break_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_check_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_create_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_function_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_call_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_and_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_do_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_false_as_ident_name ... ok
[INFO] [stdout] test cond_keywords_in_ident_names ... ok
[INFO] [stdout] test deny_keyword_end_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_initialize_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_not_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_novalue_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_otherwise_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_please_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_null_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_or_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_return_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_this_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_then_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_repeat_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_undefined_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_true_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_variable_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_whether_as_ident_name ... ok
[INFO] [stdout] test deny_keyword_while_as_ident_name ... ok
[INFO] [stdout] test variable_init_number_name ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test time_without_error ... ok
[INFO] [stdout] test print_different_types ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test add_expr_no_rhs ... ok
[INFO] [stdout] test unterminated_string_literal ... ok
[INFO] [stdout] test statement_without_dot ... ok
[INFO] [stdout] test unclosed_check_stmt ... ok
[INFO] [stdout] test incomplete_check_close_stmt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vfpl
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "390989962cf40930c50a7276f7559a9651d6a347cc9897c802875c025eb6ac41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "390989962cf40930c50a7276f7559a9651d6a347cc9897c802875c025eb6ac41", kill_on_drop: false }`
[INFO] [stdout] 390989962cf40930c50a7276f7559a9651d6a347cc9897c802875c025eb6ac41