Nov 18 17:14:42.535 INFO testing config-0.9.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 17:14:42.536 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 17:14:42.855 INFO [stdout] 5b8e99368cffe4d4e5dcb2a309cfac7f6a93dd9edbb079db50534457a78dbbdb
Nov 18 17:14:42.856 INFO running `"docker" "start" "-a" "5b8e99368cffe4d4e5dcb2a309cfac7f6a93dd9edbb079db50534457a78dbbdb"`
Nov 18 17:14:43.538 INFO [stderr] usermod: no changes
Nov 18 17:14:43.659 INFO [stderr]    Compiling config v0.9.1 (/source)
Nov 18 17:14:46.566 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.00s
Nov 18 17:14:46.573 INFO [stderr] su: No module specific data is present
Nov 18 17:14:46.975 INFO running `"docker" "inspect" "5b8e99368cffe4d4e5dcb2a309cfac7f6a93dd9edbb079db50534457a78dbbdb"`
Nov 18 17:14:47.071 INFO running `"docker" "rm" "-f" "5b8e99368cffe4d4e5dcb2a309cfac7f6a93dd9edbb079db50534457a78dbbdb"`
Nov 18 17:14:47.172 INFO [stdout] 5b8e99368cffe4d4e5dcb2a309cfac7f6a93dd9edbb079db50534457a78dbbdb
Nov 18 17:14:47.173 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 17:14:47.403 INFO [stdout] 8d1d8cdc9c75407759356a2aca91a229f2620d509e5ff458892b14493952911d
Nov 18 17:14:47.406 INFO running `"docker" "start" "-a" "8d1d8cdc9c75407759356a2aca91a229f2620d509e5ff458892b14493952911d"`
Nov 18 17:14:47.860 INFO [stderr] usermod: no changes
Nov 18 17:14:47.973 INFO [stderr]    Compiling config v0.9.1 (/source)
Nov 18 17:15:06.567 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.68s
Nov 18 17:15:06.583 INFO [stderr] su: No module specific data is present
Nov 18 17:15:07.218 INFO running `"docker" "inspect" "8d1d8cdc9c75407759356a2aca91a229f2620d509e5ff458892b14493952911d"`
Nov 18 17:15:07.487 INFO running `"docker" "rm" "-f" "8d1d8cdc9c75407759356a2aca91a229f2620d509e5ff458892b14493952911d"`
Nov 18 17:15:07.935 INFO [stdout] 8d1d8cdc9c75407759356a2aca91a229f2620d509e5ff458892b14493952911d
Nov 18 17:15:07.941 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 17:15:08.308 INFO [stdout] e6ec8778ee39c41b93462ba0d0cd12a4ccb59db92b4fafce57997fec4f3f119d
Nov 18 17:15:08.312 INFO running `"docker" "start" "-a" "e6ec8778ee39c41b93462ba0d0cd12a4ccb59db92b4fafce57997fec4f3f119d"`
Nov 18 17:15:09.251 INFO [stderr] usermod: no changes
Nov 18 17:15:09.425 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Nov 18 17:15:09.452 INFO [stderr]      Running /target/debug/deps/config-bbdbd4b0b67b4e1e
Nov 18 17:15:09.463 INFO [stdout] 
Nov 18 17:15:09.463 INFO [stdout] running 6 tests
Nov 18 17:15:09.463 INFO [stdout] test path::parser::test::test_id ... ok
Nov 18 17:15:09.463 INFO [stdout] test path::parser::test::test_child ... ok
Nov 18 17:15:09.463 INFO [stdout] test path::parser::test::test_id_dash ... ok
Nov 18 17:15:09.463 INFO [stdout] test ser::test::test_struct ... ok
Nov 18 17:15:09.463 INFO [stdout] test path::parser::test::test_subscript_neg ... ok
Nov 18 17:15:09.463 INFO [stdout] test path::parser::test::test_subscript ... ok
Nov 18 17:15:09.463 INFO [stdout] 
Nov 18 17:15:09.463 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.463 INFO [stdout] 
Nov 18 17:15:09.471 INFO [stdout] 
Nov 18 17:15:09.471 INFO [stderr]      Running /target/debug/deps/datetime-5a5513209b951558
Nov 18 17:15:09.471 INFO [stdout] running 2 tests
Nov 18 17:15:09.499 INFO [stdout] test test_datetime ... ok
Nov 18 17:15:09.499 INFO [stdout] test test_datetime_string ... ok
Nov 18 17:15:09.499 INFO [stdout] 
Nov 18 17:15:09.499 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.499 INFO [stdout] 
Nov 18 17:15:09.507 INFO [stdout] 
Nov 18 17:15:09.507 INFO [stderr]      Running /target/debug/deps/env-7369b3ae6652286c
Nov 18 17:15:09.507 INFO [stdout] running 5 tests
Nov 18 17:15:09.507 INFO [stdout] test test_empty_value_is_ignored ... ok
Nov 18 17:15:09.507 INFO [stdout] test test_default ... ok
Nov 18 17:15:09.507 INFO [stdout] test test_prefix_is_removed_from_key ... ok
Nov 18 17:15:09.507 INFO [stdout] test test_separator_behavior ... ok
Nov 18 17:15:09.511 INFO [stdout] test test_prefix_with_variant_forms_of_spelling ... ok
Nov 18 17:15:09.511 INFO [stdout] 
Nov 18 17:15:09.511 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.511 INFO [stdout] 
Nov 18 17:15:09.515 INFO [stdout] 
Nov 18 17:15:09.515 INFO [stderr]      Running /target/debug/deps/errors-76074a4b3a6e5863
Nov 18 17:15:09.515 INFO [stdout] running 3 tests
Nov 18 17:15:09.515 INFO [stdout] test test_error_parse ... ok
Nov 18 17:15:09.524 INFO [stdout] test test_error_type_detached ... ok
Nov 18 17:15:09.524 INFO [stdout] test test_error_type ... ok
Nov 18 17:15:09.524 INFO [stdout] 
Nov 18 17:15:09.524 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.524 INFO [stdout] 
Nov 18 17:15:09.531 INFO [stdout] 
Nov 18 17:15:09.531 INFO [stderr]      Running /target/debug/deps/file-ed35c8b984bc8f5c
Nov 18 17:15:09.531 INFO [stdout] running 5 tests
Nov 18 17:15:09.539 INFO [stdout] test test_file_auto ... ok
Nov 18 17:15:09.539 INFO [stderr]      Running /target/debug/deps/file_hjson-ab0e401bbb0ce804
Nov 18 17:15:09.539 INFO [stdout] test test_file_required_not_found ... ok
Nov 18 17:15:09.539 INFO [stdout] test test_file_not_required ... ok
Nov 18 17:15:09.539 INFO [stdout] test test_file_auto_not_found ... ok
Nov 18 17:15:09.539 INFO [stdout] test test_file_ext ... ok
Nov 18 17:15:09.539 INFO [stdout] 
Nov 18 17:15:09.539 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.539 INFO [stdout] 
Nov 18 17:15:09.543 INFO [stdout] 
Nov 18 17:15:09.543 INFO [stdout] running 2 tests
Nov 18 17:15:09.543 INFO [stdout] test test_file ... ok
Nov 18 17:15:09.656 INFO [stdout] test test_error_parse ... FAILED
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.656 INFO [stdout] failures:
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.656 INFO [stdout] ---- test_error_parse stdout ----
Nov 18 17:15:09.656 INFO [stdout] thread 'test_error_parse' panicked at 'assertion failed: `(left == right)`
Nov 18 17:15:09.656 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"`,
Nov 18 17:15:09.656 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
Nov 18 17:15:09.656 INFO [stdout] stack backtrace:
Nov 18 17:15:09.656 INFO [stdout]    0:     0x564f1d3dc193 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 18 17:15:09.656 INFO [stdout]    1:     0x564f1d3d69f8 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 18 17:15:09.656 INFO [stdout]    2:     0x564f1d3db104 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/panicking.rs:211
Nov 18 17:15:09.656 INFO [stdout]    3:     0x564f1d3dadec - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/panicking.rs:221
Nov 18 17:15:09.656 INFO [stdout]    4:     0x564f1d3db810 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/panicking.rs:476
Nov 18 17:15:09.656 INFO [stdout]    5:     0x564f1d3db391 - std::panicking::continue_panic_fmt::hfc82a3747387e2c6
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/panicking.rs:390
Nov 18 17:15:09.656 INFO [stdout]    6:     0x564f1d3db2de - std::panicking::begin_panic_fmt::h17464725d83144d2
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/panicking.rs:345
Nov 18 17:15:09.656 INFO [stdout]    7:     0x564f1d1b9eb7 - file_hjson::test_error_parse::h0e18d98ea1de9d75
Nov 18 17:15:09.656 INFO [stdout]                                at tests/file_hjson.rs:71
Nov 18 17:15:09.656 INFO [stdout]    8:     0x564f1d1b9b39 - file_hjson::test_error_parse::{{closure}}::hdec16a4cc41b26c6
Nov 18 17:15:09.656 INFO [stdout]                                at tests/file_hjson.rs:66
Nov 18 17:15:09.656 INFO [stdout]    9:     0x564f1d1cb84d - core::ops::function::FnOnce::call_once::hdea04c4586e38d23
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 18 17:15:09.656 INFO [stdout]   10:     0x564f1d1d569e - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 18 17:15:09.656 INFO [stdout]                                at libtest/lib.rs:1471
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 18 17:15:09.656 INFO [stdout]   11:     0x564f1d3ea049 - __rust_maybe_catch_panic
Nov 18 17:15:09.656 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 17:15:09.656 INFO [stdout]   12:     0x564f1d1cd4f3 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 18 17:15:09.656 INFO [stdout]                                at libtest/lib.rs:1426
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 18 17:15:09.656 INFO [stdout]   13:     0x564f1d1ce394 - std::panicking::try::do_call::h031514fb401b7159
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 18 17:15:09.656 INFO [stdout]   14:     0x564f1d3ea049 - __rust_maybe_catch_panic
Nov 18 17:15:09.656 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 17:15:09.656 INFO [stdout]   15:     0x564f1d1d557c - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 18 17:15:09.656 INFO [stdout]   16:     0x564f1d3ddedd - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 18 17:15:09.656 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 18 17:15:09.656 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 18 17:15:09.656 INFO [stdout]   17:     0x7f8153dae6b9 - start_thread
Nov 18 17:15:09.656 INFO [stdout]   18:     0x7f81538ce41c - clone
Nov 18 17:15:09.656 INFO [stdout]   19:                0x0 - <unknown>
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.656 INFO [stdout] failures:
Nov 18 17:15:09.656 INFO [stdout]     test_error_parse
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.656 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:09.656 INFO [stdout] 
Nov 18 17:15:09.659 INFO [stderr] error: test failed, to rerun pass '--test file_hjson'
Nov 18 17:15:09.664 INFO [stderr] su: No module specific data is present
Nov 18 17:15:10.132 INFO running `"docker" "inspect" "e6ec8778ee39c41b93462ba0d0cd12a4ccb59db92b4fafce57997fec4f3f119d"`
Nov 18 17:15:10.309 INFO running `"docker" "rm" "-f" "e6ec8778ee39c41b93462ba0d0cd12a4ccb59db92b4fafce57997fec4f3f119d"`
Nov 18 17:15:10.607 INFO [stdout] e6ec8778ee39c41b93462ba0d0cd12a4ccb59db92b4fafce57997fec4f3f119d