Dec 05 22:59:59.771 INFO testing gastamper/sholog against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514 Dec 05 22:59:59.771 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 05 22:59:59.915 INFO [stdout] 63f69522631513d65ccd6747dd6068287579f16e32f80a7e210eedf91d8f6689 Dec 05 22:59:59.918 INFO running `"docker" "start" "-a" "63f69522631513d65ccd6747dd6068287579f16e32f80a7e210eedf91d8f6689"` Dec 05 23:00:00.292 INFO [stderr] usermod: no changes Dec 05 23:00:00.406 INFO [stderr] Compiling openssl-src v110.0.5+1.1.0h Dec 05 23:00:00.408 INFO [stderr] Compiling rust-crypto v0.2.36 Dec 05 23:00:00.408 INFO [stderr] Compiling chrono v0.4.5 Dec 05 23:00:01.177 INFO [stderr] Compiling openssl-sys v0.9.35 Dec 05 23:01:25.793 INFO [stderr] Compiling openssl v0.10.11 Dec 05 23:01:30.412 INFO [stderr] Compiling sholog v0.1.0 (/source) Dec 05 23:01:33.474 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 33s Dec 05 23:01:33.480 INFO [stderr] su: No module specific data is present Dec 05 23:01:33.761 INFO running `"docker" "inspect" "63f69522631513d65ccd6747dd6068287579f16e32f80a7e210eedf91d8f6689"` Dec 05 23:01:33.813 INFO running `"docker" "rm" "-f" "63f69522631513d65ccd6747dd6068287579f16e32f80a7e210eedf91d8f6689"` Dec 05 23:01:33.893 INFO [stdout] 63f69522631513d65ccd6747dd6068287579f16e32f80a7e210eedf91d8f6689 Dec 05 23:01:33.909 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 05 23:01:34.063 INFO [stdout] 5402105033ae667d354bcc3840a6362f918481db6cf7a695cd80cf1254eede33 Dec 05 23:01:34.065 INFO running `"docker" "start" "-a" "5402105033ae667d354bcc3840a6362f918481db6cf7a695cd80cf1254eede33"` Dec 05 23:01:34.432 INFO [stderr] usermod: no changes Dec 05 23:01:34.511 INFO [stderr] Compiling sholog v0.1.0 (/source) Dec 05 23:01:37.002 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.55s Dec 05 23:01:37.008 INFO [stderr] su: No module specific data is present Dec 05 23:01:37.322 INFO running `"docker" "inspect" "5402105033ae667d354bcc3840a6362f918481db6cf7a695cd80cf1254eede33"` Dec 05 23:01:37.428 INFO running `"docker" "rm" "-f" "5402105033ae667d354bcc3840a6362f918481db6cf7a695cd80cf1254eede33"` Dec 05 23:01:37.515 INFO [stdout] 5402105033ae667d354bcc3840a6362f918481db6cf7a695cd80cf1254eede33 Dec 05 23:01:37.518 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 05 23:01:37.780 INFO [stdout] 40d408328d54a064437e14fc30472de4beb6083285a9d65be9904cdaf704fcea Dec 05 23:01:37.782 INFO running `"docker" "start" "-a" "40d408328d54a064437e14fc30472de4beb6083285a9d65be9904cdaf704fcea"` Dec 05 23:01:38.267 INFO [stderr] usermod: no changes Dec 05 23:01:38.348 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.06s Dec 05 23:01:38.361 INFO [stderr] Running /target/debug/deps/sholog-0442dd6bb780953a Dec 05 23:01:38.367 INFO [stdout] Dec 05 23:01:38.367 INFO [stdout] running 1 test Dec 05 23:01:38.439 INFO [stdout] test test::testssl ... FAILED Dec 05 23:01:38.439 INFO [stdout] Dec 05 23:01:38.439 INFO [stdout] failures: Dec 05 23:01:38.439 INFO [stdout] Dec 05 23:01:38.439 INFO [stdout] ---- test::testssl stdout ---- Dec 05 23:01:38.439 INFO [stdout] Server: Building SSL Acceptor Dec 05 23:01:38.439 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 }])', src/libcore/result.rs:1009:5 Dec 05 23:01:38.439 INFO [stdout] stack backtrace: Dec 05 23:01:38.439 INFO [stdout] 0: 0x558467009613 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 05 23:01:38.439 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 05 23:01:38.439 INFO [stdout] 1: 0x5584670032c8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 05 23:01:38.440 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 05 23:01:38.440 INFO [stdout] 2: 0x558467007b82 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 05 23:01:38.440 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 05 23:01:38.440 INFO [stdout] at src/libstd/panicking.rs:211 Dec 05 23:01:38.440 INFO [stdout] 3: 0x55846700786c - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 05 23:01:38.440 INFO [stdout] at src/libstd/panicking.rs:221 Dec 05 23:01:38.440 INFO [stdout] 4: 0x558467008290 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 05 23:01:38.440 INFO [stdout] at src/libstd/panicking.rs:491 Dec 05 23:01:38.440 INFO [stdout] 5: 0x558467007e11 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 05 23:01:38.440 INFO [stdout] at src/libstd/panicking.rs:398 Dec 05 23:01:38.440 INFO [stdout] 6: 0x558467007cf5 - rust_begin_unwind Dec 05 23:01:38.440 INFO [stdout] at src/libstd/panicking.rs:325 Dec 05 23:01:38.440 INFO [stdout] 7: 0x55846701901c - core::panicking::panic_fmt::haf17319257746a90 Dec 05 23:01:38.440 INFO [stdout] at src/libcore/panicking.rs:95 Dec 05 23:01:38.440 INFO [stdout] 8: 0x558466eaf7e0 - core::result::unwrap_failed::hec01ac8c2ddc89fd Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 05 23:01:38.440 INFO [stdout] 9: 0x558466eaec60 - >::unwrap::h29d01337a502ffaf Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:808 Dec 05 23:01:38.440 INFO [stdout] 10: 0x558466e59243 - sholog::test::test_ssl::h84d16856bb0aa5b0 Dec 05 23:01:38.440 INFO [stdout] at src/test.rs:10 Dec 05 23:01:38.440 INFO [stdout] 11: 0x558466e5a85b - sholog::test::testssl::h2bae1b88ab883b70 Dec 05 23:01:38.440 INFO [stdout] at src/test.rs:70 Dec 05 23:01:38.440 INFO [stdout] 12: 0x558466e5a839 - sholog::test::testssl::{{closure}}::h6b9ffcbb58aa9367 Dec 05 23:01:38.440 INFO [stdout] at src/test.rs:69 Dec 05 23:01:38.440 INFO [stdout] 13: 0x558466e6902d - core::ops::function::FnOnce::call_once::hf1d101dc49e2822e Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 05 23:01:38.440 INFO [stdout] 14: 0x558466e739ce - >::call_box::h9ed1658cdce7f33f Dec 05 23:01:38.440 INFO [stdout] at src/libtest/lib.rs:1471 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 05 23:01:38.440 INFO [stdout] 15: 0x558467017839 - __rust_maybe_catch_panic Dec 05 23:01:38.440 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 05 23:01:38.440 INFO [stdout] 16: 0x558466e6b8a3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 05 23:01:38.440 INFO [stdout] at src/libtest/lib.rs:1426 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 05 23:01:38.440 INFO [stdout] 17: 0x558466e6c834 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 05 23:01:38.440 INFO [stdout] 18: 0x558467017839 - __rust_maybe_catch_panic Dec 05 23:01:38.440 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 05 23:01:38.440 INFO [stdout] 19: 0x558466e738ac - >::call_box::h6465cc998309e287 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 05 23:01:38.440 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 05 23:01:38.441 INFO [stdout] 20: 0x55846700b75d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 05 23:01:38.441 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 05 23:01:38.441 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 05 23:01:38.441 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 05 23:01:38.441 INFO [stdout] 21: 0x7f91a80ea6b9 - start_thread Dec 05 23:01:38.441 INFO [stdout] 22: 0x7f91a7c0a41c - clone Dec 05 23:01:38.441 INFO [stdout] 23: 0x0 - Dec 05 23:01:38.441 INFO [stdout] Dec 05 23:01:38.441 INFO [stdout] Dec 05 23:01:38.441 INFO [stdout] failures: Dec 05 23:01:38.441 INFO [stdout] test::testssl Dec 05 23:01:38.441 INFO [stdout] Dec 05 23:01:38.441 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 23:01:38.441 INFO [stdout] Dec 05 23:01:38.441 INFO [stderr] error: test failed, to rerun pass '--bin sholog' Dec 05 23:01:38.442 INFO [stderr] su: No module specific data is present Dec 05 23:01:38.650 INFO running `"docker" "inspect" "40d408328d54a064437e14fc30472de4beb6083285a9d65be9904cdaf704fcea"` Dec 05 23:01:38.712 INFO running `"docker" "rm" "-f" "40d408328d54a064437e14fc30472de4beb6083285a9d65be9904cdaf704fcea"` Dec 05 23:01:38.778 INFO [stdout] 40d408328d54a064437e14fc30472de4beb6083285a9d65be9904cdaf704fcea