Dec 13 08:38:19.525 INFO testing nrc/s against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 08:38:19.525 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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:38:19.975 INFO [stdout] 2d91a64422d481320f8ce87092e251278dea0667555da2442e0f100218b664fc
Dec 13 08:38:19.983 INFO running `"docker" "start" "-a" "2d91a64422d481320f8ce87092e251278dea0667555da2442e0f100218b664fc"`
Dec 13 08:38:21.572 INFO [stderr] usermod: no changes
Dec 13 08:38:21.683 INFO [stderr]    Compiling s v0.0.1 (/source)
Dec 13 08:38:22.123 INFO [stderr] warning: unused macro definition
Dec 13 08:38:22.123 INFO [stderr]   --> src/parser.rs:87:1
Dec 13 08:38:22.123 INFO [stderr]    |
Dec 13 08:38:22.123 INFO [stderr] 87 | / macro_rules! program {
Dec 13 08:38:22.123 INFO [stderr] 88 | |     ($($ns: expr),*) => (::parser::Node::Program(vec![$($ns),*]))
Dec 13 08:38:22.123 INFO [stderr] 89 | | }
Dec 13 08:38:22.123 INFO [stderr]    | |_^
Dec 13 08:38:22.123 INFO [stderr]    |
Dec 13 08:38:22.123 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 13 08:38:22.123 INFO [stderr] 
Dec 13 08:38:22.123 INFO [stderr] warning: unused macro definition
Dec 13 08:38:22.123 INFO [stderr]   --> src/parser.rs:93:1
Dec 13 08:38:22.123 INFO [stderr]    |
Dec 13 08:38:22.123 INFO [stderr] 93 | / macro_rules! ident {
Dec 13 08:38:22.123 INFO [stderr] 94 | |     ($s: expr) => (::parser::Node::Ident(::lexer::Str::new($s)))
Dec 13 08:38:22.123 INFO [stderr] 95 | | }
Dec 13 08:38:22.123 INFO [stderr]    | |_^
Dec 13 08:38:22.123 INFO [stderr] 
Dec 13 08:38:22.123 INFO [stderr] warning: unused macro definition
Dec 13 08:38:22.123 INFO [stderr]    --> src/parser.rs:99:1
Dec 13 08:38:22.123 INFO [stderr]     |
Dec 13 08:38:22.123 INFO [stderr] 99  | / macro_rules! lit_str {
Dec 13 08:38:22.123 INFO [stderr] 100 | |     ($s: expr) => (::parser::Node::LitStr(::lexer::Str::new($s)))
Dec 13 08:38:22.123 INFO [stderr] 101 | | }
Dec 13 08:38:22.123 INFO [stderr]     | |_^
Dec 13 08:38:22.123 INFO [stderr] 
Dec 13 08:38:22.403 INFO [stderr] warning: method is never used: `new`
Dec 13 08:38:22.403 INFO [stderr]   --> src/lexer.rs:36:5
Dec 13 08:38:22.403 INFO [stderr]    |
Dec 13 08:38:22.403 INFO [stderr] 36 |     pub fn new(s: &str) -> Str {
Dec 13 08:38:22.403 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 08:38:22.403 INFO [stderr]    |
Dec 13 08:38:22.403 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 08:38:22.403 INFO [stderr] 
Dec 13 08:38:22.403 INFO [stderr] warning: method is never used: `is_keyword`
Dec 13 08:38:22.403 INFO [stderr]   --> src/parser.rs:30:5
Dec 13 08:38:22.403 INFO [stderr]    |
Dec 13 08:38:22.403 INFO [stderr] 30 |     pub fn is_keyword(&self) -> bool {
Dec 13 08:38:22.403 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 08:38:22.403 INFO [stderr] 
Dec 13 08:38:22.403 INFO [stderr] warning: struct is never constructed: `NoopFolder`
Dec 13 08:38:22.403 INFO [stderr]   --> src/expand.rs:39:1
Dec 13 08:38:22.403 INFO [stderr]    |
Dec 13 08:38:22.403 INFO [stderr] 39 | pub struct NoopFolder;
Dec 13 08:38:22.403 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^
Dec 13 08:38:22.403 INFO [stderr] 
Dec 13 08:38:22.403 INFO [stderr] warning: method is never used: `with_value`
Dec 13 08:38:22.403 INFO [stderr]   --> src/interpreter.rs:29:5
Dec 13 08:38:22.403 INFO [stderr]    |
Dec 13 08:38:22.403 INFO [stderr] 29 |     fn with_value(name: &str, value: Node) -> Envr {
Dec 13 08:38:22.403 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 08:38:22.403 INFO [stderr] 
Dec 13 08:38:24.403 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.75s
Dec 13 08:38:24.406 INFO [stderr] su: No module specific data is present
Dec 13 08:38:25.457 INFO running `"docker" "inspect" "2d91a64422d481320f8ce87092e251278dea0667555da2442e0f100218b664fc"`
Dec 13 08:38:25.671 INFO running `"docker" "rm" "-f" "2d91a64422d481320f8ce87092e251278dea0667555da2442e0f100218b664fc"`
Dec 13 08:38:25.962 INFO [stdout] 2d91a64422d481320f8ce87092e251278dea0667555da2442e0f100218b664fc
Dec 13 08:38:25.967 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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:38:26.384 INFO [stdout] 3242f0d7a065bfc372767d83cf8c123f38ca8c0d1347f6cd75127b14d974f510
Dec 13 08:38:26.391 INFO running `"docker" "start" "-a" "3242f0d7a065bfc372767d83cf8c123f38ca8c0d1347f6cd75127b14d974f510"`
Dec 13 08:38:27.335 INFO [stderr] usermod: no changes
Dec 13 08:38:27.380 INFO [stderr]    Compiling s v0.0.1 (/source)
Dec 13 08:38:28.243 INFO [stderr] warning: method is never used: `is_keyword`
Dec 13 08:38:28.243 INFO [stderr]   --> src/parser.rs:30:5
Dec 13 08:38:28.243 INFO [stderr]    |
Dec 13 08:38:28.243 INFO [stderr] 30 |     pub fn is_keyword(&self) -> bool {
Dec 13 08:38:28.243 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 08:38:28.243 INFO [stderr]    |
Dec 13 08:38:28.243 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 08:38:28.243 INFO [stderr] 
Dec 13 08:38:30.150 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.79s
Dec 13 08:38:30.152 INFO [stderr] su: No module specific data is present
Dec 13 08:38:30.733 INFO running `"docker" "inspect" "3242f0d7a065bfc372767d83cf8c123f38ca8c0d1347f6cd75127b14d974f510"`
Dec 13 08:38:31.015 INFO running `"docker" "rm" "-f" "3242f0d7a065bfc372767d83cf8c123f38ca8c0d1347f6cd75127b14d974f510"`
Dec 13 08:38:31.228 INFO [stdout] 3242f0d7a065bfc372767d83cf8c123f38ca8c0d1347f6cd75127b14d974f510
Dec 13 08:38:31.229 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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:38:31.811 INFO [stdout] e1ff62f34bcc6fcc9f3772a1d9035a67811c9400168a1b76efbc48ce701cc56f
Dec 13 08:38:31.816 INFO running `"docker" "start" "-a" "e1ff62f34bcc6fcc9f3772a1d9035a67811c9400168a1b76efbc48ce701cc56f"`
Dec 13 08:38:32.403 INFO [stderr] usermod: no changes
Dec 13 08:38:32.432 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 13 08:38:32.433 INFO [stderr]      Running /target/debug/deps/s-eb57e51d7ba7b4c8
Dec 13 08:38:32.437 INFO [stdout] 
Dec 13 08:38:32.437 INFO [stdout] running 30 tests
Dec 13 08:38:32.439 INFO [stdout] test interpreter::test::test_fn_let ... ok
Dec 13 08:38:32.439 INFO [stdout] test interpreter::test::test_empty ... ok
Dec 13 08:38:32.439 INFO [stdout] test interpreter::test::test_envr_push_pop ... ok
Dec 13 08:38:32.439 INFO [stdout] test expand::test::test_noop ... ok
Dec 13 08:38:32.439 INFO [stdout] test interpreter::test::test_ident ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_let ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_plus ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_fn ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_s_reduce ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_values ... ok
Dec 13 08:38:32.440 INFO [stdout] test interpreter::test::test_scoped_ident ... ok
Dec 13 08:38:32.440 INFO [stdout] test lexer::test::test_empty ... ok
Dec 13 08:38:32.440 INFO [stdout] test lexer::test::test_realistic ... ok
Dec 13 08:38:32.441 INFO [stdout] test lexer::test::test_single_ws ... ok
Dec 13 08:38:32.443 INFO [stdout] test interpreter::test::test_print ... ok
Dec 13 08:38:32.443 INFO [stdout] test lexer::test::test_two ... ok
Dec 13 08:38:32.447 INFO [stdout] test lexer::test::test_single ... ok
Dec 13 08:38:32.451 INFO [stdout] test lexer::test::test_two_ws ... ok
Dec 13 08:38:32.451 INFO [stdout] test parser::test::test_empty ... ok
Dec 13 08:38:32.478 INFO [stdout] test interpreter::test::test_ident_fail ... ok
Dec 13 08:38:32.478 INFO [stdout] test interpreter::test::test_fn_arg_mismatch ... ok
Dec 13 08:38:32.480 INFO [stdout] test interpreter::test::test_let_not_rec ... ok
Dec 13 08:38:32.480 INFO [stdout] test interpreter::test::test_ident_dup_fail ... ok
Dec 13 08:38:32.480 INFO [stdout] test parser::test::test_realistic ... ok
Dec 13 08:38:32.481 INFO [stdout] test parser::test::test_fail_too_closed ... ok
Dec 13 08:38:32.481 INFO [stdout] test interpreter::test::test_plus_fail ... ok
Dec 13 08:38:32.481 INFO [stdout] test parser::test::test_fail_unclosed ... ok
Dec 13 08:38:32.481 INFO [stdout] test parser::test::test_fail_too_closed2 ... ok
Dec 13 08:38:32.481 INFO [stdout] test parser::test::test_fail_unclosed2 ... ok
Dec 13 08:38:32.487 INFO [stdout] test parser::test::test_simple ... ok
Dec 13 08:38:32.487 INFO [stderr] su: No module specific data is present
Dec 13 08:38:32.487 INFO [stdout] 
Dec 13 08:38:32.487 INFO [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 08:38:32.487 INFO [stdout] 
Dec 13 08:38:32.931 INFO running `"docker" "inspect" "e1ff62f34bcc6fcc9f3772a1d9035a67811c9400168a1b76efbc48ce701cc56f"`
Dec 13 08:38:33.123 INFO running `"docker" "rm" "-f" "e1ff62f34bcc6fcc9f3772a1d9035a67811c9400168a1b76efbc48ce701cc56f"`
Dec 13 08:38:33.328 INFO [stdout] e1ff62f34bcc6fcc9f3772a1d9035a67811c9400168a1b76efbc48ce701cc56f