Nov 17 02:22:08.583 INFO testing gastamper/sholog against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 02:22:08.583 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 02:22:08.827 INFO [stdout] 719e0d5b9ae10c9e139afb119c63f2ec54e8f13aeb3f8dae987b0cf8a152ebf9
Nov 17 02:22:08.827 INFO running `"docker" "start" "-a" "719e0d5b9ae10c9e139afb119c63f2ec54e8f13aeb3f8dae987b0cf8a152ebf9"`
Nov 17 02:22:09.327 INFO [stderr] usermod: no changes
Nov 17 02:22:09.453 INFO [stderr]    Compiling openssl v0.10.11
Nov 17 02:22:09.453 INFO [stderr]    Compiling openssl-src v110.0.5+1.1.0h
Nov 17 02:22:09.455 INFO [stderr]    Compiling rust-crypto v0.2.36
Nov 17 02:22:10.097 INFO [stderr]    Compiling openssl-sys v0.9.35
Nov 17 02:23:52.977 INFO [stderr]    Compiling sholog v0.1.0 (/source)
Nov 17 02:23:56.962 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 47s
Nov 17 02:23:56.967 INFO [stderr] su: No module specific data is present
Nov 17 02:23:57.953 INFO running `"docker" "inspect" "719e0d5b9ae10c9e139afb119c63f2ec54e8f13aeb3f8dae987b0cf8a152ebf9"`
Nov 17 02:23:58.315 INFO running `"docker" "rm" "-f" "719e0d5b9ae10c9e139afb119c63f2ec54e8f13aeb3f8dae987b0cf8a152ebf9"`
Nov 17 02:23:58.616 INFO [stdout] 719e0d5b9ae10c9e139afb119c63f2ec54e8f13aeb3f8dae987b0cf8a152ebf9
Nov 17 02:23:58.621 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 02:23:59.195 INFO [stdout] 831981e4dbfea3d6f469adcd2cf8f993aea185e37b8e361276512e7776235593
Nov 17 02:23:59.200 INFO running `"docker" "start" "-a" "831981e4dbfea3d6f469adcd2cf8f993aea185e37b8e361276512e7776235593"`
Nov 17 02:24:00.299 INFO [stderr] usermod: no changes
Nov 17 02:24:00.521 INFO [stderr]    Compiling sholog v0.1.0 (/source)
Nov 17 02:24:03.767 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.43s
Nov 17 02:24:03.778 INFO [stderr] su: No module specific data is present
Nov 17 02:24:04.130 INFO running `"docker" "inspect" "831981e4dbfea3d6f469adcd2cf8f993aea185e37b8e361276512e7776235593"`
Nov 17 02:24:04.283 INFO running `"docker" "rm" "-f" "831981e4dbfea3d6f469adcd2cf8f993aea185e37b8e361276512e7776235593"`
Nov 17 02:24:04.415 INFO [stdout] 831981e4dbfea3d6f469adcd2cf8f993aea185e37b8e361276512e7776235593
Nov 17 02:24:04.422 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 02:24:04.739 INFO [stdout] 30f4b9c6ba4b7f8e0468184c245752d44073e3165ec5224337d08fb803cd204f
Nov 17 02:24:04.741 INFO running `"docker" "start" "-a" "30f4b9c6ba4b7f8e0468184c245752d44073e3165ec5224337d08fb803cd204f"`
Nov 17 02:24:05.473 INFO [stderr] usermod: no changes
Nov 17 02:24:05.563 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 17 02:24:05.578 INFO [stderr]      Running /target/debug/deps/sholog-4a2a8d2730ca48ab
Nov 17 02:24:05.580 INFO [stdout] 
Nov 17 02:24:05.580 INFO [stdout] running 1 test
Nov 17 02:24:05.664 INFO [stdout] test test::testssl ... FAILED
Nov 17 02:24:05.664 INFO [stdout] 
Nov 17 02:24:05.664 INFO [stdout] failures:
Nov 17 02:24:05.664 INFO [stdout] 
Nov 17 02:24:05.664 INFO [stdout] ---- test::testssl stdout ----
Nov 17 02:24:05.664 INFO [stdout] Server: Building SSL Acceptor
Nov 17 02:24:05.664 INFO [stdout] thread 'test::testssl' panicked at 'called `Result::unwrap()` on an `Err` value: ErrorStack([Error { code: 33558530, library: "system library", function: "fopen", reason: "No such file or directory", file: "crypto/bio/bss_file.c", line: 292, data: "fopen(\'server.key\',\'r\')" }, Error { code: 537346050, library: "BIO routines", function: "file_ctrl", reason: "system lib", file: "crypto/bio/bss_file.c", line: 294 }, Error { code: 336265218, library: "SSL routines", function: "SSL_CTX_use_PrivateKey_file", reason: "system lib", file: "ssl/ssl_rsa.c", line: 534 }])', libcore/result.rs:1009:5
Nov 17 02:24:05.664 INFO [stdout] stack backtrace:
Nov 17 02:24:05.664 INFO [stdout]    0:     0x558a026a74a3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 02:24:05.664 INFO [stdout]    1:     0x558a026a1308 - std::sys_common::backtrace::_print::h84c40fecda6d638e
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 02:24:05.664 INFO [stdout]    2:     0x558a026a5a14 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 02:24:05.664 INFO [stdout]    3:     0x558a026a56fc - std::panicking::default_hook::hb9a45c11a33bbcb2
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 02:24:05.664 INFO [stdout]    4:     0x558a026a6120 - std::panicking::rust_panic_with_hook::ha8d95048d940f754
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 02:24:05.664 INFO [stdout]    5:     0x558a026a5ca1 - std::panicking::continue_panic_fmt::hfc82a3747387e2c6
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 02:24:05.664 INFO [stdout]    6:     0x558a026a5b85 - rust_begin_unwind
Nov 17 02:24:05.664 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 02:24:05.664 INFO [stdout]    7:     0x558a026b703c - core::panicking::panic_fmt::h0b2a5728bdf6d5e2
Nov 17 02:24:05.664 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 02:24:05.664 INFO [stdout]    8:     0x558a0254e0c0 - core::result::unwrap_failed::hf169f966ecdef3b2
Nov 17 02:24:05.664 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/macros.rs:26
Nov 17 02:24:05.664 INFO [stdout]    9:     0x558a0254d832 - <core::result::Result<T, E>>::unwrap::hf7b15681c4372ad2
Nov 17 02:24:05.664 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/result.rs:808
Nov 17 02:24:05.664 INFO [stdout]   10:     0x558a024f5255 - sholog::test::test_ssl::hb5d5798c60bd2cef
Nov 17 02:24:05.664 INFO [stdout]                                at src/test.rs:10
Nov 17 02:24:05.664 INFO [stdout]   11:     0x558a024f68bc - sholog::test::testssl::h9828d0d7d66d4654
Nov 17 02:24:05.664 INFO [stdout]                                at src/test.rs:70
Nov 17 02:24:05.664 INFO [stdout]   12:     0x558a024f6899 - sholog::test::testssl::{{closure}}::h193f4dc8f0151130
Nov 17 02:24:05.664 INFO [stdout]                                at src/test.rs:69
Nov 17 02:24:05.664 INFO [stdout]   13:     0x558a0250549d - core::ops::function::FnOnce::call_once::hf2ec7a8a67e3d49c
Nov 17 02:24:05.664 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 02:24:05.664 INFO [stdout]   14:     0x558a0250fa3e - <F as alloc::boxed::FnBox<A>>::call_box::hae1ea457a0ccd8d5
Nov 17 02:24:05.664 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 02:24:05.664 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238
Nov 17 02:24:05.664 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 02:24:05.665 INFO [stdout]   15:     0x558a026b58f9 - __rust_maybe_catch_panic
Nov 17 02:24:05.665 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 02:24:05.665 INFO [stdout]   16:     0x558a02507893 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 02:24:05.665 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136
Nov 17 02:24:05.665 INFO [stdout]   17:     0x558a02508734 - std::panicking::try::do_call::h031514fb401b7159
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310
Nov 17 02:24:05.665 INFO [stdout]   18:     0x558a026b58f9 - __rust_maybe_catch_panic
Nov 17 02:24:05.665 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 02:24:05.665 INFO [stdout]   19:     0x558a0250f91c - <F as alloc::boxed::FnBox<A>>::call_box::h44700b7a2ec6fd33
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673
Nov 17 02:24:05.665 INFO [stdout]   20:     0x558a026a971d - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b
Nov 17 02:24:05.665 INFO [stdout]                                at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683
Nov 17 02:24:05.665 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 02:24:05.665 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 02:24:05.665 INFO [stdout]   21:     0x7facd291c6b9 - start_thread
Nov 17 02:24:05.665 INFO [stdout]   22:     0x7facd243c41c - clone
Nov 17 02:24:05.665 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 02:24:05.665 INFO [stdout] 
Nov 17 02:24:05.665 INFO [stdout] 
Nov 17 02:24:05.665 INFO [stdout] failures:
Nov 17 02:24:05.665 INFO [stdout]     test::testssl
Nov 17 02:24:05.665 INFO [stdout] 
Nov 17 02:24:05.665 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 02:24:05.665 INFO [stdout] 
Nov 17 02:24:05.671 INFO [stderr] error: test failed, to rerun pass '--bin sholog'
Nov 17 02:24:05.671 INFO [stderr] su: No module specific data is present
Nov 17 02:24:06.109 INFO running `"docker" "inspect" "30f4b9c6ba4b7f8e0468184c245752d44073e3165ec5224337d08fb803cd204f"`
Nov 17 02:24:06.247 INFO running `"docker" "rm" "-f" "30f4b9c6ba4b7f8e0468184c245752d44073e3165ec5224337d08fb803cd204f"`
Nov 17 02:24:06.400 INFO [stdout] 30f4b9c6ba4b7f8e0468184c245752d44073e3165ec5224337d08fb803cd204f