Dec 13 08:39:35.652 INFO testing npj/rust-scheme against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 08:39:35.652 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:39:36.135 INFO [stdout] 91962ea356ebd85e4a9b6a94b42abca5f34a56f81cc24f6fbe5bf7579a298622
Dec 13 08:39:36.182 INFO running `"docker" "start" "-a" "91962ea356ebd85e4a9b6a94b42abca5f34a56f81cc24f6fbe5bf7579a298622"`
Dec 13 08:39:37.878 INFO [stderr] usermod: no changes
Dec 13 08:39:37.911 INFO [stderr]    Compiling scheme v0.1.0 (/source)
Dec 13 08:39:39.357 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.46s
Dec 13 08:39:39.361 INFO [stderr] su: No module specific data is present
Dec 13 08:39:40.700 INFO running `"docker" "inspect" "91962ea356ebd85e4a9b6a94b42abca5f34a56f81cc24f6fbe5bf7579a298622"`
Dec 13 08:39:41.006 INFO running `"docker" "rm" "-f" "91962ea356ebd85e4a9b6a94b42abca5f34a56f81cc24f6fbe5bf7579a298622"`
Dec 13 08:39:41.299 INFO [stdout] 91962ea356ebd85e4a9b6a94b42abca5f34a56f81cc24f6fbe5bf7579a298622
Dec 13 08:39:41.303 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:39:41.741 INFO [stdout] 6ba86ccc0eb527a5f2e3b9840f2c17659929717804a31b66dc573692b0429eca
Dec 13 08:39:41.749 INFO running `"docker" "start" "-a" "6ba86ccc0eb527a5f2e3b9840f2c17659929717804a31b66dc573692b0429eca"`
Dec 13 08:39:42.890 INFO [stderr] usermod: no changes
Dec 13 08:39:42.979 INFO [stderr]    Compiling scheme v0.1.0 (/source)
Dec 13 08:39:45.783 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.86s
Dec 13 08:39:45.786 INFO [stderr] su: No module specific data is present
Dec 13 08:39:46.384 INFO running `"docker" "inspect" "6ba86ccc0eb527a5f2e3b9840f2c17659929717804a31b66dc573692b0429eca"`
Dec 13 08:39:46.651 INFO running `"docker" "rm" "-f" "6ba86ccc0eb527a5f2e3b9840f2c17659929717804a31b66dc573692b0429eca"`
Dec 13 08:39:46.883 INFO [stdout] 6ba86ccc0eb527a5f2e3b9840f2c17659929717804a31b66dc573692b0429eca
Dec 13 08:39:46.891 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 13 08:39:47.271 INFO [stdout] 8fbe27a8a556e7b667801d9bcfb4bc6eea0144717a377a56b1f14a762f697d45
Dec 13 08:39:47.277 INFO running `"docker" "start" "-a" "8fbe27a8a556e7b667801d9bcfb4bc6eea0144717a377a56b1f14a762f697d45"`
Dec 13 08:39:48.595 INFO [stderr] usermod: no changes
Dec 13 08:39:48.667 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Dec 13 08:39:48.676 INFO [stderr]      Running /target/debug/deps/scheme-1226a0b2326fec23
Dec 13 08:39:48.688 INFO [stdout] 
Dec 13 08:39:48.688 INFO [stdout] running 38 tests
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::chr ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_end_nonempty ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_end_empty ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::set_line ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::set_chr ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::new ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::peek ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::get ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::io_lexer::tests::line ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_ident ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_invalid ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_unterminated ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::error_unterminated_multiline ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_all ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_comment ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_digits_dot_digits ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_dot ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_dot_digits ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_dot_digits_trailing_zero ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_dot_zero ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_float_negative_dot_digits ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_ident ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_integer ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_invalid_float_fractional ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_invalid_float_whole ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_invalid_integer ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_lpar ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_negative_float_dot_digits ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_negative_integer ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_rpar ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::lexer::tests::read_string ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::chr ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::get ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::line ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::new ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::peek ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::set_chr ... ok
Dec 13 08:39:48.695 INFO [stdout] test lexer::string_lexer::tests::set_line ... ok
Dec 13 08:39:48.695 INFO [stdout] 
Dec 13 08:39:48.695 INFO [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:39:48.695 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stderr]      Running /target/debug/deps/scheme-88b9df7bc9889d79
Dec 13 08:39:48.699 INFO [stdout] running 0 tests
Dec 13 08:39:48.699 INFO [stderr]      Running /target/debug/deps/lexer-3f1d1d920be4ab96
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stderr]    Doc-tests scheme
Dec 13 08:39:48.699 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stdout] running 0 tests
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:48.699 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:39:48.699 INFO [stdout] 
Dec 13 08:39:49.215 INFO [stdout] 
Dec 13 08:39:49.215 INFO [stdout] running 0 tests
Dec 13 08:39:49.215 INFO [stdout] 
Dec 13 08:39:49.215 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:39:49.215 INFO [stdout] 
Dec 13 08:39:49.222 INFO [stderr] su: No module specific data is present
Dec 13 08:39:49.811 INFO running `"docker" "inspect" "8fbe27a8a556e7b667801d9bcfb4bc6eea0144717a377a56b1f14a762f697d45"`
Dec 13 08:39:50.009 INFO running `"docker" "rm" "-f" "8fbe27a8a556e7b667801d9bcfb4bc6eea0144717a377a56b1f14a762f697d45"`
Dec 13 08:39:50.166 INFO [stdout] 8fbe27a8a556e7b667801d9bcfb4bc6eea0144717a377a56b1f14a762f697d45