Nov 01 04:29:44.760 INFO testing loggerhead/shadowsocks-rust against beta-2018-10-30 for beta-1.31-1
Nov 01 04:29:44.761 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 04:29:45.396 INFO blam! 17678a2082646c3513ec89c504bb9b6c45d8a5e71f6c26472e154bd0c2daeb9a
Nov 01 04:29:45.398 INFO running `"docker" "start" "-a" "17678a2082646c3513ec89c504bb9b6c45d8a5e71f6c26472e154bd0c2daeb9a"`
Nov 01 04:29:47.935 INFO kablam! usermod: no changes
Nov 01 04:29:47.999 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:29:48.208 INFO kablam!    Compiling slog v1.7.1
Nov 01 04:29:48.209 INFO kablam!    Compiling lru_time_cache v0.5.0
Nov 01 04:29:48.219 INFO kablam!    Compiling memchr v0.1.11
Nov 01 04:29:48.221 INFO kablam!    Compiling net2 v0.2.31
Nov 01 04:29:48.222 INFO kablam!    Compiling isatty v0.1.6
Nov 01 04:29:48.224 INFO kablam!    Compiling term_size v0.2.3
Nov 01 04:29:48.226 INFO kablam!    Compiling daemonize v0.2.3
Nov 01 04:29:48.227 INFO kablam!    Compiling sig v1.0.0
Nov 01 04:29:50.103 INFO kablam!    Compiling nix v0.5.1
Nov 01 04:29:50.131 INFO kablam!    Compiling clap v2.18.0
Nov 01 04:29:50.905 INFO kablam!    Compiling thread-id v2.0.0
Nov 01 04:29:50.910 INFO kablam!    Compiling aho-corasick v0.5.3
Nov 01 04:29:50.934 INFO kablam!    Compiling chrono v0.2.25
Nov 01 04:29:52.397 INFO kablam!    Compiling slog-extra v0.1.2
Nov 01 04:29:52.397 INFO kablam!    Compiling slog-scope v0.2.2
Nov 01 04:29:52.487 INFO kablam!    Compiling thread_local v0.2.7
Nov 01 04:29:55.796 INFO kablam!    Compiling miow v0.1.5
Nov 01 04:29:56.631 INFO kablam!    Compiling regex v0.1.80
Nov 01 04:29:57.904 INFO kablam!    Compiling slog-stream v1.2.1
Nov 01 04:30:00.985 INFO kablam!    Compiling slog-term v1.3.5
Nov 01 04:30:04.723 INFO kablam!    Compiling mio v0.5.1
Nov 01 04:30:23.287 INFO kablam!    Compiling shadowsocks v0.6.2 (/source)
Nov 01 04:30:24.719 INFO kablam! warning: unused macro definition
Nov 01 04:30:24.719 INFO kablam!   --> src/util.rs:9:1
Nov 01 04:30:24.719 INFO kablam!    |
Nov 01 04:30:24.719 INFO kablam! 9  | / macro_rules! io_err {
Nov 01 04:30:24.719 INFO kablam! 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Nov 01 04:30:24.719 INFO kablam! 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Nov 01 04:30:24.719 INFO kablam! 12 | | }
Nov 01 04:30:24.719 INFO kablam!    | |_^
Nov 01 04:30:24.719 INFO kablam!    |
Nov 01 04:30:24.719 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:24.719 INFO kablam! 
Nov 01 04:30:24.803 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Nov 01 04:30:24.803 INFO kablam!    --> src/config/running_config.rs:102:9
Nov 01 04:30:24.803 INFO kablam!     |
Nov 01 04:30:24.803 INFO kablam! 102 |         env::home_dir()
Nov 01 04:30:24.803 INFO kablam!     |         ^^^^^^^^^^^^^
Nov 01 04:30:24.803 INFO kablam!     |
Nov 01 04:30:24.803 INFO kablam!     = note: #[warn(deprecated)] on by default
Nov 01 04:30:24.803 INFO kablam! 
Nov 01 04:30:38.031 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 50.07s
Nov 01 04:30:38.051 INFO kablam! su: No module specific data is present
Nov 01 04:30:38.882 INFO running `"docker" "rm" "-f" "17678a2082646c3513ec89c504bb9b6c45d8a5e71f6c26472e154bd0c2daeb9a"`
Nov 01 04:30:39.143 INFO blam! 17678a2082646c3513ec89c504bb9b6c45d8a5e71f6c26472e154bd0c2daeb9a
Nov 01 04:30:39.152 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 04:30:39.675 INFO blam! ac342b294f71a5f16aba6f228da9718633857fe09af26a236fb1548256ce697e
Nov 01 04:30:39.731 INFO running `"docker" "start" "-a" "ac342b294f71a5f16aba6f228da9718633857fe09af26a236fb1548256ce697e"`
Nov 01 04:30:40.896 INFO kablam! usermod: no changes
Nov 01 04:30:40.940 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:30:41.075 INFO kablam!    Compiling shadowsocks v0.6.2 (/source)
Nov 01 04:30:43.179 INFO kablam! warning: unused macro definition
Nov 01 04:30:43.179 INFO kablam!   --> src/util.rs:9:1
Nov 01 04:30:43.179 INFO kablam!    |
Nov 01 04:30:43.179 INFO kablam! 9  | / macro_rules! io_err {
Nov 01 04:30:43.179 INFO kablam! 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Nov 01 04:30:43.179 INFO kablam! 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Nov 01 04:30:43.179 INFO kablam! 12 | | }
Nov 01 04:30:43.179 INFO kablam!    | |_^
Nov 01 04:30:43.179 INFO kablam!    |
Nov 01 04:30:43.179 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:43.179 INFO kablam! 
Nov 01 04:30:43.327 INFO kablam! warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Nov 01 04:30:43.328 INFO kablam!    --> src/config/running_config.rs:102:9
Nov 01 04:30:43.328 INFO kablam!     |
Nov 01 04:30:43.328 INFO kablam! 102 |         env::home_dir()
Nov 01 04:30:43.329 INFO kablam!     |         ^^^^^^^^^^^^^
Nov 01 04:30:43.329 INFO kablam!     |
Nov 01 04:30:43.329 INFO kablam!     = note: #[warn(deprecated)] on by default
Nov 01 04:30:43.329 INFO kablam! 
Nov 01 04:30:43.805 INFO kablam! warning: unused macro definition
Nov 01 04:30:43.805 INFO kablam!   --> tests/encrypt.rs:39:1
Nov 01 04:30:43.806 INFO kablam!    |
Nov 01 04:30:43.806 INFO kablam! 39 | / macro_rules! assert_raw_encrypt {
Nov 01 04:30:43.806 INFO kablam! 40 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) )
Nov 01 04:30:43.807 INFO kablam! 41 | | }
Nov 01 04:30:43.807 INFO kablam!    | |_^
Nov 01 04:30:43.808 INFO kablam!    |
Nov 01 04:30:43.808 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:43.808 INFO kablam! 
Nov 01 04:30:43.808 INFO kablam! warning: unused macro definition
Nov 01 04:30:43.809 INFO kablam!   --> tests/encrypt.rs:43:1
Nov 01 04:30:43.809 INFO kablam!    |
Nov 01 04:30:43.809 INFO kablam! 43 | / macro_rules! assert_raw_decrypt {
Nov 01 04:30:43.810 INFO kablam! 44 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) )
Nov 01 04:30:43.810 INFO kablam! 45 | | }
Nov 01 04:30:43.810 INFO kablam!    | |_^
Nov 01 04:30:43.811 INFO kablam! 
Nov 01 04:30:48.647 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 7.71s
Nov 01 04:30:48.671 INFO kablam! su: No module specific data is present
Nov 01 04:30:49.299 INFO running `"docker" "rm" "-f" "ac342b294f71a5f16aba6f228da9718633857fe09af26a236fb1548256ce697e"`
Nov 01 04:30:49.537 INFO blam! ac342b294f71a5f16aba6f228da9718633857fe09af26a236fb1548256ce697e
Nov 01 04:30:49.538 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 04:30:50.119 INFO blam! 5cccc6f59fe032feaaaf0a947d15aa5c9727195e6b678fd77045687dabec2993
Nov 01 04:30:50.121 INFO running `"docker" "start" "-a" "5cccc6f59fe032feaaaf0a947d15aa5c9727195e6b678fd77045687dabec2993"`
Nov 01 04:30:50.882 INFO kablam! usermod: no changes
Nov 01 04:30:50.923 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:30:51.040 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Nov 01 04:30:51.060 INFO kablam!      Running /target/debug/deps/shadowsocks-200920a02e06afc3
Nov 01 04:30:51.062 INFO blam! 
Nov 01 04:30:51.062 INFO blam! running 3 tests
Nov 01 04:30:51.062 INFO blam! test asyncdns::test::ipv6_block_resolve ... ignored
Nov 01 04:30:51.063 INFO blam! test asyncdns::test::parse_response ... ok
Nov 01 04:30:51.128 INFO blam! test asyncdns::test::ipv4_block_resolve ... FAILED
Nov 01 04:30:51.128 INFO blam! 
Nov 01 04:30:51.128 INFO blam! failures:
Nov 01 04:30:51.128 INFO blam! 
Nov 01 04:30:51.128 INFO blam! ---- asyncdns::test::ipv4_block_resolve stdout ----
Nov 01 04:30:51.128 INFO blam! block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") }
Nov 01 04:30:51.128 INFO blam! thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21
Nov 01 04:30:51.128 INFO blam! stack backtrace:
Nov 01 04:30:51.128 INFO blam!    0:     0x55b2c2b63bdf - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 01 04:30:51.128 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 04:30:51.128 INFO blam!    1:     0x55b2c2b6d2b7 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 01 04:30:51.128 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 04:30:51.128 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 04:30:51.128 INFO blam!    2:     0x55b2c2b67e4f - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 01 04:30:51.128 INFO blam!                                at libstd/panicking.rs:211
Nov 01 04:30:51.128 INFO blam!    3:     0x55b2c2b67b4e - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 01 04:30:51.128 INFO blam!                                at libstd/panicking.rs:221
Nov 01 04:30:51.128 INFO blam!    4:     0x55b2c2b6852e - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 01 04:30:51.128 INFO blam!                                at libstd/panicking.rs:476
Nov 01 04:30:51.128 INFO blam!    5:     0x55b2c2b51ef7 - std::panicking::begin_panic::h56db568bf1076e94
Nov 01 04:30:51.128 INFO blam!                                at libstd/panicking.rs:410
Nov 01 04:30:51.128 INFO blam!    6:     0x55b2c29db52b - shadowsocks::asyncdns::test::test_block_resolve::h403c9e168211e0ff
Nov 01 04:30:51.128 INFO blam!                                at src/asyncdns.rs:784
Nov 01 04:30:51.128 INFO blam!    7:     0x55b2c29db7e7 - shadowsocks::asyncdns::test::ipv4_block_resolve::hd53fe1ca90ab3e91
Nov 01 04:30:51.128 INFO blam!                                at src/asyncdns.rs:792
Nov 01 04:30:51.128 INFO blam!    8:     0x55b2c29db7c9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::he269b11145eaca01
Nov 01 04:30:51.128 INFO blam!                                at src/asyncdns.rs:791
Nov 01 04:30:51.128 INFO blam!    9:     0x55b2c29cce3d - core::ops::function::FnOnce::call_once::hbd07208d3104bd8e
Nov 01 04:30:51.128 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 04:30:51.128 INFO blam!   10:     0x55b2c29f00fe - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 01 04:30:51.128 INFO blam!                                at libtest/lib.rs:1468
Nov 01 04:30:51.128 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 04:30:51.128 INFO blam!                                at liballoc/boxed.rs:672
Nov 01 04:30:51.128 INFO blam!   11:     0x55b2c2b784e9 - __rust_maybe_catch_panic
Nov 01 04:30:51.128 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 01 04:30:51.128 INFO blam!   12:     0x55b2c2a11d62 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 01 04:30:51.128 INFO blam!                                at libstd/panicking.rs:289
Nov 01 04:30:51.129 INFO blam!                                at libstd/panic.rs:392
Nov 01 04:30:51.129 INFO blam!                                at libtest/lib.rs:1423
Nov 01 04:30:51.129 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 04:30:51.129 INFO blam!   13:     0x55b2c2a12814 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 01 04:30:51.129 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 04:30:51.129 INFO blam!                                at libstd/panic.rs:313
Nov 01 04:30:51.129 INFO blam!                                at libstd/panicking.rs:310
Nov 01 04:30:51.129 INFO blam!   14:     0x55b2c2b784e9 - __rust_maybe_catch_panic
Nov 01 04:30:51.129 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 01 04:30:51.129 INFO blam!   15:     0x55b2c29ffa3c - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 01 04:30:51.129 INFO blam!                                at libstd/panicking.rs:289
Nov 01 04:30:51.129 INFO blam!                                at libstd/panic.rs:392
Nov 01 04:30:51.129 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 04:30:51.129 INFO blam!                                at liballoc/boxed.rs:672
Nov 01 04:30:51.129 INFO blam!   16:     0x55b2c2b65bbd - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 01 04:30:51.129 INFO blam!                                at liballoc/boxed.rs:682
Nov 01 04:30:51.129 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 04:30:51.129 INFO blam!   17:     0x55b2c2b58975 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 01 04:30:51.129 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 04:30:51.129 INFO blam!   18:     0x7faafc3466b9 - start_thread
Nov 01 04:30:51.129 INFO blam!   19:     0x7faafbe6641c - clone
Nov 01 04:30:51.129 INFO blam!   20:                0x0 - <unknown>
Nov 01 04:30:51.129 INFO blam! 
Nov 01 04:30:51.129 INFO blam! 
Nov 01 04:30:51.129 INFO blam! failures:
Nov 01 04:30:51.129 INFO blam!     asyncdns::test::ipv4_block_resolve
Nov 01 04:30:51.129 INFO blam! 
Nov 01 04:30:51.129 INFO blam! test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out
Nov 01 04:30:51.129 INFO blam! 
Nov 01 04:30:51.136 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 01 04:30:51.137 INFO kablam! su: No module specific data is present
Nov 01 04:30:51.469 INFO running `"docker" "rm" "-f" "5cccc6f59fe032feaaaf0a947d15aa5c9727195e6b678fd77045687dabec2993"`
Nov 01 04:30:51.579 INFO blam! 5cccc6f59fe032feaaaf0a947d15aa5c9727195e6b678fd77045687dabec2993
