Nov 18 17:14:45.425 INFO testing config-0.9.1 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 17:14:45.425 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:45.607 INFO [stdout] 00c9cb0c793e8e5e71047569f9ebc442ba3329c36c080779d2dba7c29cf1ca5d
Nov 18 17:14:45.609 INFO running `"docker" "start" "-a" "00c9cb0c793e8e5e71047569f9ebc442ba3329c36c080779d2dba7c29cf1ca5d"`
Nov 18 17:14:46.505 INFO [stderr] usermod: no changes
Nov 18 17:14:46.592 INFO [stderr]    Compiling config v0.9.1 (/source)
Nov 18 17:14:50.479 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.95s
Nov 18 17:14:50.487 INFO [stderr] su: No module specific data is present
Nov 18 17:14:51.284 INFO running `"docker" "inspect" "00c9cb0c793e8e5e71047569f9ebc442ba3329c36c080779d2dba7c29cf1ca5d"`
Nov 18 17:14:51.523 INFO running `"docker" "rm" "-f" "00c9cb0c793e8e5e71047569f9ebc442ba3329c36c080779d2dba7c29cf1ca5d"`
Nov 18 17:14:51.758 INFO [stdout] 00c9cb0c793e8e5e71047569f9ebc442ba3329c36c080779d2dba7c29cf1ca5d
Nov 18 17:14:51.763 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:52.194 INFO [stdout] ede3e25775a93a3e65f3cc73ef092a19ef1810a6074a59eb9144112723ec8cc1
Nov 18 17:14:52.201 INFO running `"docker" "start" "-a" "ede3e25775a93a3e65f3cc73ef092a19ef1810a6074a59eb9144112723ec8cc1"`
Nov 18 17:14:53.172 INFO [stderr] usermod: no changes
Nov 18 17:14:53.315 INFO [stderr]    Compiling config v0.9.1 (/source)
Nov 18 17:15:12.415 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.20s
Nov 18 17:15:12.428 INFO [stderr] su: No module specific data is present
Nov 18 17:15:12.825 INFO running `"docker" "inspect" "ede3e25775a93a3e65f3cc73ef092a19ef1810a6074a59eb9144112723ec8cc1"`
Nov 18 17:15:12.993 INFO running `"docker" "rm" "-f" "ede3e25775a93a3e65f3cc73ef092a19ef1810a6074a59eb9144112723ec8cc1"`
Nov 18 17:15:13.180 INFO [stdout] ede3e25775a93a3e65f3cc73ef092a19ef1810a6074a59eb9144112723ec8cc1
Nov 18 17:15:13.184 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:13.425 INFO [stdout] 01e25ce23643dceeb0ad45d9167c25b3c9c41e0d226d9de4220efe262d88670d
Nov 18 17:15:13.427 INFO running `"docker" "start" "-a" "01e25ce23643dceeb0ad45d9167c25b3c9c41e0d226d9de4220efe262d88670d"`
Nov 18 17:15:13.856 INFO [stderr] usermod: no changes
Nov 18 17:15:13.971 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 18 17:15:13.984 INFO [stderr]      Running /target/debug/deps/config-4e2a79c871bde4f4
Nov 18 17:15:13.992 INFO [stdout] 
Nov 18 17:15:13.993 INFO [stdout] running 6 tests
Nov 18 17:15:13.995 INFO [stdout] test path::parser::test::test_id ... ok
Nov 18 17:15:13.996 INFO [stdout] test path::parser::test::test_child ... ok
Nov 18 17:15:13.996 INFO [stdout] test path::parser::test::test_subscript ... ok
Nov 18 17:15:13.999 INFO [stdout] test path::parser::test::test_subscript_neg ... ok
Nov 18 17:15:14.000 INFO [stdout] test path::parser::test::test_id_dash ... ok
Nov 18 17:15:14.000 INFO [stdout] test ser::test::test_struct ... ok
Nov 18 17:15:14.000 INFO [stdout] 
Nov 18 17:15:14.001 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.001 INFO [stdout] 
Nov 18 17:15:14.002 INFO [stderr]      Running /target/debug/deps/datetime-bad30b52f85a7a67
Nov 18 17:15:14.009 INFO [stdout] 
Nov 18 17:15:14.009 INFO [stdout] running 2 tests
Nov 18 17:15:14.015 INFO [stdout] test test_datetime ... ok
Nov 18 17:15:14.021 INFO [stdout] test test_datetime_string ... ok
Nov 18 17:15:14.021 INFO [stdout] 
Nov 18 17:15:14.022 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.022 INFO [stdout] 
Nov 18 17:15:14.031 INFO [stderr]      Running /target/debug/deps/env-2969edafd3027c6d
Nov 18 17:15:14.040 INFO [stdout] 
Nov 18 17:15:14.041 INFO [stdout] running 5 tests
Nov 18 17:15:14.060 INFO [stdout] test test_empty_value_is_ignored ... ok
Nov 18 17:15:14.061 INFO [stdout] test test_default ... ok
Nov 18 17:15:14.061 INFO [stdout] test test_separator_behavior ... ok
Nov 18 17:15:14.062 INFO [stdout] test test_prefix_is_removed_from_key ... ok
Nov 18 17:15:14.062 INFO [stdout] test test_prefix_with_variant_forms_of_spelling ... ok
Nov 18 17:15:14.062 INFO [stdout] 
Nov 18 17:15:14.062 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.062 INFO [stdout] 
Nov 18 17:15:14.063 INFO [stderr]      Running /target/debug/deps/errors-81dc95f50d78c867
Nov 18 17:15:14.064 INFO [stdout] 
Nov 18 17:15:14.064 INFO [stdout] running 3 tests
Nov 18 17:15:14.067 INFO [stdout] test test_error_parse ... ok
Nov 18 17:15:14.076 INFO [stdout] test test_error_type_detached ... ok
Nov 18 17:15:14.076 INFO [stdout] test test_error_type ... ok
Nov 18 17:15:14.076 INFO [stdout] 
Nov 18 17:15:14.076 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.076 INFO [stdout] 
Nov 18 17:15:14.077 INFO [stderr]      Running /target/debug/deps/file-dacb2132301e1054
Nov 18 17:15:14.084 INFO [stdout] 
Nov 18 17:15:14.084 INFO [stdout] running 5 tests
Nov 18 17:15:14.085 INFO [stdout] test test_file_auto_not_found ... ok
Nov 18 17:15:14.085 INFO [stdout] test test_file_ext ... ok
Nov 18 17:15:14.086 INFO [stdout] test test_file_auto ... ok
Nov 18 17:15:14.087 INFO [stdout] test test_file_required_not_found ... ok
Nov 18 17:15:14.087 INFO [stdout] test test_file_not_required ... ok
Nov 18 17:15:14.088 INFO [stdout] 
Nov 18 17:15:14.088 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.088 INFO [stdout] 
Nov 18 17:15:14.088 INFO [stderr]      Running /target/debug/deps/file_hjson-8c82221ea5339eef
Nov 18 17:15:14.096 INFO [stdout] 
Nov 18 17:15:14.097 INFO [stdout] running 2 tests
Nov 18 17:15:14.111 INFO [stdout] test test_file ... ok
Nov 18 17:15:14.169 INFO [stdout] test test_error_parse ... FAILED
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.169 INFO [stdout] failures:
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.169 INFO [stdout] ---- test_error_parse stdout ----
Nov 18 17:15:14.169 INFO [stdout] thread 'test_error_parse' panicked at 'assertion failed: `(left == right)`
Nov 18 17:15:14.169 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:14.169 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:14.169 INFO [stdout] stack backtrace:
Nov 18 17:15:14.169 INFO [stdout]    0:     0x564d04def693 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 18 17:15:14.169 INFO [stdout]    1:     0x564d04de9db8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 18 17:15:14.169 INFO [stdout]    2:     0x564d04dee604 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/panicking.rs:211
Nov 18 17:15:14.169 INFO [stdout]    3:     0x564d04dee2ec - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/panicking.rs:221
Nov 18 17:15:14.169 INFO [stdout]    4:     0x564d04deed10 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/panicking.rs:476
Nov 18 17:15:14.169 INFO [stdout]    5:     0x564d04dee891 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/panicking.rs:390
Nov 18 17:15:14.169 INFO [stdout]    6:     0x564d04dee7de - std::panicking::begin_panic_fmt::hfa6f19d29682fcb6
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/panicking.rs:345
Nov 18 17:15:14.169 INFO [stdout]    7:     0x564d04bca2b7 - file_hjson::test_error_parse::h86ae9f97aaaec04e
Nov 18 17:15:14.169 INFO [stdout]                                at tests/file_hjson.rs:71
Nov 18 17:15:14.169 INFO [stdout]    8:     0x564d04bc9f39 - file_hjson::test_error_parse::{{closure}}::h7736bc70842ff8f7
Nov 18 17:15:14.169 INFO [stdout]                                at tests/file_hjson.rs:66
Nov 18 17:15:14.169 INFO [stdout]    9:     0x564d04bdbc6d - core::ops::function::FnOnce::call_once::he25258f4dc7aa9c1
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 18 17:15:14.169 INFO [stdout]   10:     0x564d04be588e - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 18 17:15:14.169 INFO [stdout]                                at libtest/lib.rs:1471
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 18 17:15:14.169 INFO [stdout]   11:     0x564d04dfd439 - __rust_maybe_catch_panic
Nov 18 17:15:14.169 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 17:15:14.169 INFO [stdout]   12:     0x564d04bdd8f3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 18 17:15:14.169 INFO [stdout]                                at libtest/lib.rs:1426
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 18 17:15:14.169 INFO [stdout]   13:     0x564d04bde7a4 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 18 17:15:14.169 INFO [stdout]   14:     0x564d04dfd439 - __rust_maybe_catch_panic
Nov 18 17:15:14.169 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 18 17:15:14.169 INFO [stdout]   15:     0x564d04be59bc - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 18 17:15:14.169 INFO [stdout]   16:     0x564d04df12cd - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 18 17:15:14.169 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 18 17:15:14.169 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 18 17:15:14.169 INFO [stdout]   17:     0x7fef1af336b9 - start_thread
Nov 18 17:15:14.169 INFO [stdout]   18:     0x7fef1aa5341c - clone
Nov 18 17:15:14.169 INFO [stdout]   19:                0x0 - <unknown>
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.169 INFO [stdout] failures:
Nov 18 17:15:14.169 INFO [stdout]     test_error_parse
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.169 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:15:14.169 INFO [stdout] 
Nov 18 17:15:14.182 INFO [stderr] error: test failed, to rerun pass '--test file_hjson'
Nov 18 17:15:14.182 INFO [stderr] su: No module specific data is present
Nov 18 17:15:14.452 INFO running `"docker" "inspect" "01e25ce23643dceeb0ad45d9167c25b3c9c41e0d226d9de4220efe262d88670d"`
Nov 18 17:15:14.531 INFO running `"docker" "rm" "-f" "01e25ce23643dceeb0ad45d9167c25b3c9c41e0d226d9de4220efe262d88670d"`
Nov 18 17:15:14.632 INFO [stdout] 01e25ce23643dceeb0ad45d9167c25b3c9c41e0d226d9de4220efe262d88670d