Dec 07 19:59:04.574 INFO testing config-0.9.1 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 19:59:04.574 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:59:04.864 INFO [stdout] af58fffd325b857a16cc30c8b121f7258585033380d2cc35882e99f1b251e1bd
Dec 07 19:59:04.872 INFO running `"docker" "start" "-a" "af58fffd325b857a16cc30c8b121f7258585033380d2cc35882e99f1b251e1bd"`
Dec 07 19:59:05.468 INFO [stderr] usermod: no changes
Dec 07 19:59:05.570 INFO [stderr]    Compiling config v0.9.1 (/source)
Dec 07 19:59:09.106 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.61s
Dec 07 19:59:09.112 INFO [stderr] su: No module specific data is present
Dec 07 19:59:09.389 INFO running `"docker" "inspect" "af58fffd325b857a16cc30c8b121f7258585033380d2cc35882e99f1b251e1bd"`
Dec 07 19:59:09.510 INFO running `"docker" "rm" "-f" "af58fffd325b857a16cc30c8b121f7258585033380d2cc35882e99f1b251e1bd"`
Dec 07 19:59:09.599 INFO [stdout] af58fffd325b857a16cc30c8b121f7258585033380d2cc35882e99f1b251e1bd
Dec 07 19:59:09.601 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:59:09.771 INFO [stdout] bb5d39fb31ecdf7a178303bc64a337bf260546c6c9938c445165b5d2abe007d5
Dec 07 19:59:09.773 INFO running `"docker" "start" "-a" "bb5d39fb31ecdf7a178303bc64a337bf260546c6c9938c445165b5d2abe007d5"`
Dec 07 19:59:10.312 INFO [stderr] usermod: no changes
Dec 07 19:59:10.470 INFO [stderr]    Compiling config v0.9.1 (/source)
Dec 07 19:59:33.828 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.48s
Dec 07 19:59:33.836 INFO [stderr] su: No module specific data is present
Dec 07 19:59:34.440 INFO running `"docker" "inspect" "bb5d39fb31ecdf7a178303bc64a337bf260546c6c9938c445165b5d2abe007d5"`
Dec 07 19:59:34.790 INFO running `"docker" "rm" "-f" "bb5d39fb31ecdf7a178303bc64a337bf260546c6c9938c445165b5d2abe007d5"`
Dec 07 19:59:35.052 INFO [stdout] bb5d39fb31ecdf7a178303bc64a337bf260546c6c9938c445165b5d2abe007d5
Dec 07 19:59:35.058 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 19:59:35.425 INFO [stdout] accaa4cbe915c5f97465b67f68059c47b5b72e5e11af951898ad6d2354fb331f
Dec 07 19:59:35.427 INFO running `"docker" "start" "-a" "accaa4cbe915c5f97465b67f68059c47b5b72e5e11af951898ad6d2354fb331f"`
Dec 07 19:59:36.574 INFO [stderr] usermod: no changes
Dec 07 19:59:36.664 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 07 19:59:36.684 INFO [stdout] 
Dec 07 19:59:36.684 INFO [stdout] running 6 tests
Dec 07 19:59:36.684 INFO [stderr]      Running /target/debug/deps/config-a41c66b2b2001cfe
Dec 07 19:59:36.688 INFO [stdout] test path::parser::test::test_id_dash ... ok
Dec 07 19:59:36.688 INFO [stderr]      Running /target/debug/deps/datetime-c618e1e1717e2a48
Dec 07 19:59:36.688 INFO [stdout] test path::parser::test::test_id ... ok
Dec 07 19:59:36.688 INFO [stdout] test path::parser::test::test_child ... ok
Dec 07 19:59:36.688 INFO [stdout] test path::parser::test::test_subscript ... ok
Dec 07 19:59:36.688 INFO [stdout] test ser::test::test_struct ... ok
Dec 07 19:59:36.688 INFO [stdout] test path::parser::test::test_subscript_neg ... ok
Dec 07 19:59:36.688 INFO [stdout] 
Dec 07 19:59:36.688 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.688 INFO [stdout] 
Dec 07 19:59:36.692 INFO [stdout] 
Dec 07 19:59:36.692 INFO [stdout] running 2 tests
Dec 07 19:59:36.700 INFO [stdout] test test_datetime_string ... ok
Dec 07 19:59:36.707 INFO [stdout] test test_datetime ... ok
Dec 07 19:59:36.707 INFO [stderr]      Running /target/debug/deps/env-c8869f36f2dcf05f
Dec 07 19:59:36.707 INFO [stdout] 
Dec 07 19:59:36.707 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.707 INFO [stdout] 
Dec 07 19:59:36.732 INFO [stdout] 
Dec 07 19:59:36.732 INFO [stdout] running 5 tests
Dec 07 19:59:36.732 INFO [stdout] test test_prefix_with_variant_forms_of_spelling ... ok
Dec 07 19:59:36.732 INFO [stdout] test test_default ... ok
Dec 07 19:59:36.732 INFO [stdout] test test_separator_behavior ... ok
Dec 07 19:59:36.732 INFO [stdout] test test_empty_value_is_ignored ... ok
Dec 07 19:59:36.732 INFO [stdout] test test_prefix_is_removed_from_key ... ok
Dec 07 19:59:36.732 INFO [stdout] 
Dec 07 19:59:36.732 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.732 INFO [stdout] 
Dec 07 19:59:36.732 INFO [stderr]      Running /target/debug/deps/errors-e6debe320d54c875
Dec 07 19:59:36.736 INFO [stdout] 
Dec 07 19:59:36.736 INFO [stdout] running 3 tests
Dec 07 19:59:36.740 INFO [stdout] test test_error_type_detached ... ok
Dec 07 19:59:36.740 INFO [stdout] test test_error_parse ... ok
Dec 07 19:59:36.740 INFO [stdout] test test_error_type ... ok
Dec 07 19:59:36.740 INFO [stdout] 
Dec 07 19:59:36.740 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.740 INFO [stdout] 
Dec 07 19:59:36.740 INFO [stderr]      Running /target/debug/deps/file-19e57f3ddbb89e8f
Dec 07 19:59:36.755 INFO [stdout] 
Dec 07 19:59:36.756 INFO [stdout] running 5 tests
Dec 07 19:59:36.756 INFO [stdout] test test_file_required_not_found ... ok
Dec 07 19:59:36.756 INFO [stdout] test test_file_auto_not_found ... ok
Dec 07 19:59:36.756 INFO [stdout] test test_file_not_required ... ok
Dec 07 19:59:36.764 INFO [stdout] test test_file_ext ... ok
Dec 07 19:59:36.764 INFO [stdout] test test_file_auto ... ok
Dec 07 19:59:36.764 INFO [stdout] 
Dec 07 19:59:36.764 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.764 INFO [stdout] 
Dec 07 19:59:36.764 INFO [stderr]      Running /target/debug/deps/file_hjson-9a81924fb74098bb
Dec 07 19:59:36.774 INFO [stdout] 
Dec 07 19:59:36.774 INFO [stdout] running 2 tests
Dec 07 19:59:36.802 INFO [stdout] test test_file ... ok
Dec 07 19:59:36.840 INFO [stdout] test test_error_parse ... FAILED
Dec 07 19:59:36.840 INFO [stdout] 
Dec 07 19:59:36.840 INFO [stdout] failures:
Dec 07 19:59:36.840 INFO [stdout] 
Dec 07 19:59:36.840 INFO [stdout] ---- test_error_parse stdout ----
Dec 07 19:59:36.840 INFO [stdout] thread 'test_error_parse' panicked at 'assertion failed: `(left == right)`
Dec 07 19:59:36.840 INFO [stdout]   left: `"Found a punctuator where a key name was expected (check your syntax or use quotes if the key name includes {}[],: or whitespace) at line 4 column 1 in tests/Settings-invalid.hjson"`,
Dec 07 19:59:36.840 INFO [stdout]  right: `"Found a punctuator where a key name was expected (check your syntax or use quotes if the key name includes {}[],: or whitespace) at line 1 column 1 in tests/Settings-invalid.hjson"`', tests/file_hjson.rs:71:5
Dec 07 19:59:36.840 INFO [stdout] stack backtrace:
Dec 07 19:59:36.840 INFO [stdout]    0:     0x561e987d32b3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 07 19:59:36.840 INFO [stdout]    1:     0x561e987cd968 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 07 19:59:36.840 INFO [stdout]    2:     0x561e987d2222 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/panicking.rs:211
Dec 07 19:59:36.840 INFO [stdout]    3:     0x561e987d1f0c - std::panicking::default_hook::h6c8d8e1c12a669e5
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/panicking.rs:221
Dec 07 19:59:36.840 INFO [stdout]    4:     0x561e987d2930 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/panicking.rs:491
Dec 07 19:59:36.840 INFO [stdout]    5:     0x561e987d24b1 - std::panicking::continue_panic_fmt::hf3c774aec6124caa
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/panicking.rs:398
Dec 07 19:59:36.840 INFO [stdout]    6:     0x561e987d23fe - std::panicking::begin_panic_fmt::h3605d603150d0c9a
Dec 07 19:59:36.840 INFO [stdout]                                at src/libstd/panicking.rs:353
Dec 07 19:59:36.840 INFO [stdout]    7:     0x561e985a74e7 - file_hjson::test_error_parse::h84ed0efaef56be38
Dec 07 19:59:36.840 INFO [stdout]                                at tests/file_hjson.rs:71
Dec 07 19:59:36.840 INFO [stdout]    8:     0x561e985a7169 - file_hjson::test_error_parse::{{closure}}::h6f0c080011556e64
Dec 07 19:59:36.840 INFO [stdout]                                at tests/file_hjson.rs:66
Dec 07 19:59:36.840 INFO [stdout]    9:     0x561e985b8ddd - core::ops::function::FnOnce::call_once::hf71b87dca5fe1605
Dec 07 19:59:36.840 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 07 19:59:36.840 INFO [stdout]   10:     0x561e985c2aee - <F as alloc::boxed::FnBox<A>>::call_box::h9ed1658cdce7f33f
Dec 07 19:59:36.840 INFO [stdout]                                at src/libtest/lib.rs:1471
Dec 07 19:59:36.840 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238
Dec 07 19:59:36.840 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 07 19:59:36.840 INFO [stdout]   11:     0x561e987e0ef9 - __rust_maybe_catch_panic
Dec 07 19:59:36.840 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 07 19:59:36.840 INFO [stdout]   12:     0x561e985ba9c3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5
Dec 07 19:59:36.840 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 07 19:59:36.840 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 07 19:59:36.840 INFO [stdout]                                at src/libtest/lib.rs:1426
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136
Dec 07 19:59:36.841 INFO [stdout]   13:     0x561e985bb954 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310
Dec 07 19:59:36.841 INFO [stdout]   14:     0x561e987e0ef9 - __rust_maybe_catch_panic
Dec 07 19:59:36.841 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 07 19:59:36.841 INFO [stdout]   15:     0x561e985c29cc - <F as alloc::boxed::FnBox<A>>::call_box::h6465cc998309e287
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673
Dec 07 19:59:36.841 INFO [stdout]   16:     0x561e987d4ebd - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5
Dec 07 19:59:36.841 INFO [stdout]                                at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683
Dec 07 19:59:36.841 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 07 19:59:36.841 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:90
Dec 07 19:59:36.841 INFO [stdout]   17:     0x7f7a0e46e6b9 - start_thread
Dec 07 19:59:36.841 INFO [stdout]   18:     0x7f7a0df8e41c - clone
Dec 07 19:59:36.841 INFO [stdout]   19:                0x0 - <unknown>
Dec 07 19:59:36.841 INFO [stdout] 
Dec 07 19:59:36.841 INFO [stdout] 
Dec 07 19:59:36.841 INFO [stdout] failures:
Dec 07 19:59:36.841 INFO [stdout]     test_error_parse
Dec 07 19:59:36.841 INFO [stdout] 
Dec 07 19:59:36.841 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 19:59:36.841 INFO [stdout] 
Dec 07 19:59:36.844 INFO [stderr] error: test failed, to rerun pass '--test file_hjson'
Dec 07 19:59:36.844 INFO [stderr] su: No module specific data is present
Dec 07 19:59:37.193 INFO running `"docker" "inspect" "accaa4cbe915c5f97465b67f68059c47b5b72e5e11af951898ad6d2354fb331f"`
Dec 07 19:59:37.278 INFO running `"docker" "rm" "-f" "accaa4cbe915c5f97465b67f68059c47b5b72e5e11af951898ad6d2354fb331f"`
Dec 07 19:59:37.408 INFO [stdout] accaa4cbe915c5f97465b67f68059c47b5b72e5e11af951898ad6d2354fb331f