Nov 17 02:22:11.172 INFO testing gastamper/sholog against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 02:22:11.172 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 02:22:11.428 INFO [stdout] ab6b890f8bbcb0a6e2feb8a90a1738243ed4d3df2f3ffaf0a2dbff1891456008
Nov 17 02:22:11.430 INFO running `"docker" "start" "-a" "ab6b890f8bbcb0a6e2feb8a90a1738243ed4d3df2f3ffaf0a2dbff1891456008"`
Nov 17 02:22:11.942 INFO [stderr] usermod: no changes
Nov 17 02:22:12.040 INFO [stderr]    Compiling gcc v0.3.54
Nov 17 02:22:12.042 INFO [stderr]    Compiling num-integer v0.1.39
Nov 17 02:22:12.042 INFO [stderr]    Compiling openssl v0.10.11
Nov 17 02:22:12.046 INFO [stderr]    Compiling bitflags v1.0.3
Nov 17 02:22:12.046 INFO [stderr]    Compiling vec_map v0.8.1
Nov 17 02:22:12.046 INFO [stderr]    Compiling cfg-if v0.1.4
Nov 17 02:22:12.046 INFO [stderr]    Compiling atty v0.2.11
Nov 17 02:22:12.046 INFO [stderr]    Compiling hostname v0.1.5
Nov 17 02:22:12.235 INFO [stderr]    Compiling textwrap v0.10.0
Nov 17 02:22:12.705 INFO [stderr]    Compiling rand v0.3.22
Nov 17 02:22:12.714 INFO [stderr]    Compiling log v0.4.3
Nov 17 02:22:13.124 INFO [stderr]    Compiling clap v2.32.0
Nov 17 02:22:14.248 INFO [stderr]    Compiling chrono v0.4.5
Nov 17 02:22:14.265 INFO [stderr]    Compiling openssl-src v110.0.5+1.1.0h
Nov 17 02:22:14.265 INFO [stderr]    Compiling rust-crypto v0.2.36
Nov 17 02:22:14.784 INFO [stderr]    Compiling openssl-sys v0.9.35
Nov 17 02:23:56.296 INFO [stderr]    Compiling sholog v0.1.0 (/source)
Nov 17 02:23:59.514 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 47s
Nov 17 02:23:59.521 INFO [stderr] su: No module specific data is present
Nov 17 02:24:00.115 INFO running `"docker" "inspect" "ab6b890f8bbcb0a6e2feb8a90a1738243ed4d3df2f3ffaf0a2dbff1891456008"`
Nov 17 02:24:00.403 INFO running `"docker" "rm" "-f" "ab6b890f8bbcb0a6e2feb8a90a1738243ed4d3df2f3ffaf0a2dbff1891456008"`
Nov 17 02:24:00.699 INFO [stdout] ab6b890f8bbcb0a6e2feb8a90a1738243ed4d3df2f3ffaf0a2dbff1891456008
Nov 17 02:24:00.709 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 02:24:01.230 INFO [stdout] 668292f0a264daa05df4975ac23716fdfd0bd76a72f4575056933d4136f9df4b
Nov 17 02:24:01.235 INFO running `"docker" "start" "-a" "668292f0a264daa05df4975ac23716fdfd0bd76a72f4575056933d4136f9df4b"`
Nov 17 02:24:02.565 INFO [stderr] usermod: no changes
Nov 17 02:24:02.691 INFO [stderr]    Compiling sholog v0.1.0 (/source)
Nov 17 02:24:05.167 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.56s
Nov 17 02:24:05.175 INFO [stderr] su: No module specific data is present
Nov 17 02:24:05.658 INFO running `"docker" "inspect" "668292f0a264daa05df4975ac23716fdfd0bd76a72f4575056933d4136f9df4b"`
Nov 17 02:24:05.800 INFO running `"docker" "rm" "-f" "668292f0a264daa05df4975ac23716fdfd0bd76a72f4575056933d4136f9df4b"`
Nov 17 02:24:05.961 INFO [stdout] 668292f0a264daa05df4975ac23716fdfd0bd76a72f4575056933d4136f9df4b
Nov 17 02:24:05.966 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 17 02:24:06.171 INFO [stdout] cd346a5641f63aed09de5aac960ebda87b222162ca5af05feb2e1fe3e5521fbe
Nov 17 02:24:06.173 INFO running `"docker" "start" "-a" "cd346a5641f63aed09de5aac960ebda87b222162ca5af05feb2e1fe3e5521fbe"`
Nov 17 02:24:06.919 INFO [stderr] usermod: no changes
Nov 17 02:24:07.011 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 17 02:24:07.015 INFO [stderr]      Running /target/debug/deps/sholog-75df76caaf55c88d
Nov 17 02:24:07.019 INFO [stdout] 
Nov 17 02:24:07.019 INFO [stdout] running 1 test
Nov 17 02:24:07.093 INFO [stdout] test test::testssl ... FAILED
Nov 17 02:24:07.093 INFO [stdout] 
Nov 17 02:24:07.093 INFO [stdout] failures:
Nov 17 02:24:07.093 INFO [stdout] 
Nov 17 02:24:07.093 INFO [stdout] ---- test::testssl stdout ----
Nov 17 02:24:07.093 INFO [stdout] Server: Building SSL Acceptor
Nov 17 02:24:07.093 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:07.093 INFO [stdout] stack backtrace:
Nov 17 02:24:07.094 INFO [stdout]    0:     0x55ab96af76e3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 17 02:24:07.094 INFO [stdout]    1:     0x55ab96af1408 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 17 02:24:07.094 INFO [stdout]    2:     0x55ab96af5c54 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/panicking.rs:211
Nov 17 02:24:07.094 INFO [stdout]    3:     0x55ab96af593c - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/panicking.rs:221
Nov 17 02:24:07.094 INFO [stdout]    4:     0x55ab96af6360 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/panicking.rs:476
Nov 17 02:24:07.094 INFO [stdout]    5:     0x55ab96af5ee1 - std::panicking::continue_panic_fmt::h61fd5a7b1a268752
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/panicking.rs:390
Nov 17 02:24:07.094 INFO [stdout]    6:     0x55ab96af5dc5 - rust_begin_unwind
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/panicking.rs:325
Nov 17 02:24:07.094 INFO [stdout]    7:     0x55ab96b071ac - core::panicking::panic_fmt::h400423a63e8ecedb
Nov 17 02:24:07.094 INFO [stdout]                                at libcore/panicking.rs:77
Nov 17 02:24:07.094 INFO [stdout]    8:     0x55ab9699e0a0 - core::result::unwrap_failed::hf80f9264a5c1e04a
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/macros.rs:26
Nov 17 02:24:07.094 INFO [stdout]    9:     0x55ab9699d812 - <core::result::Result<T, E>>::unwrap::hfb9cdec0076e26b5
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/result.rs:808
Nov 17 02:24:07.094 INFO [stdout]   10:     0x55ab96947255 - sholog::test::test_ssl::h21e5a7ce1bde0908
Nov 17 02:24:07.094 INFO [stdout]                                at src/test.rs:10
Nov 17 02:24:07.094 INFO [stdout]   11:     0x55ab969488bc - sholog::test::testssl::h28d5761e81ff3633
Nov 17 02:24:07.094 INFO [stdout]                                at src/test.rs:70
Nov 17 02:24:07.094 INFO [stdout]   12:     0x55ab96948899 - sholog::test::testssl::{{closure}}::hc1ad223f8718190c
Nov 17 02:24:07.094 INFO [stdout]                                at src/test.rs:69
Nov 17 02:24:07.094 INFO [stdout]   13:     0x55ab9695731d - core::ops::function::FnOnce::call_once::h7fffc9a3c4264561
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 02:24:07.094 INFO [stdout]   14:     0x55ab9696171e - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 17 02:24:07.094 INFO [stdout]                                at libtest/lib.rs:1471
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 02:24:07.094 INFO [stdout]   15:     0x55ab96b05a29 - __rust_maybe_catch_panic
Nov 17 02:24:07.094 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 02:24:07.094 INFO [stdout]   16:     0x55ab96959783 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 02:24:07.094 INFO [stdout]                                at libtest/lib.rs:1426
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 17 02:24:07.094 INFO [stdout]   17:     0x55ab9695a634 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 17 02:24:07.094 INFO [stdout]   18:     0x55ab96b05a29 - __rust_maybe_catch_panic
Nov 17 02:24:07.094 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 17 02:24:07.094 INFO [stdout]   19:     0x55ab9696184c - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 17 02:24:07.094 INFO [stdout]   20:     0x55ab96af984d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 17 02:24:07.094 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 17 02:24:07.094 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 17 02:24:07.094 INFO [stdout]   21:     0x7fcfd51fe6b9 - start_thread
Nov 17 02:24:07.094 INFO [stdout]   22:     0x7fcfd4d1e41c - clone
Nov 17 02:24:07.094 INFO [stdout]   23:                0x0 - <unknown>
Nov 17 02:24:07.094 INFO [stdout] 
Nov 17 02:24:07.094 INFO [stdout] 
Nov 17 02:24:07.094 INFO [stdout] failures:
Nov 17 02:24:07.094 INFO [stdout]     test::testssl
Nov 17 02:24:07.094 INFO [stdout] 
Nov 17 02:24:07.094 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 02:24:07.094 INFO [stdout] 
Nov 17 02:24:07.096 INFO [stderr] error: test failed, to rerun pass '--bin sholog'
Nov 17 02:24:07.098 INFO [stderr] su: No module specific data is present
Nov 17 02:24:07.480 INFO running `"docker" "inspect" "cd346a5641f63aed09de5aac960ebda87b222162ca5af05feb2e1fe3e5521fbe"`
Nov 17 02:24:07.681 INFO running `"docker" "rm" "-f" "cd346a5641f63aed09de5aac960ebda87b222162ca5af05feb2e1fe3e5521fbe"`
Nov 17 02:24:07.962 INFO [stdout] cd346a5641f63aed09de5aac960ebda87b222162ca5af05feb2e1fe3e5521fbe