Nov 01 04:30:04.923 INFO testing loggerhead/shadowsocks-rust against 1.30.0 for beta-1.31-1
Nov 01 04:30:04.924 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 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:30:05.339 INFO blam! a414e5baa5a622a717410c1027071fdd7caaf17253f1513d6bcd536970ca81b9
Nov 01 04:30:05.341 INFO running `"docker" "start" "-a" "a414e5baa5a622a717410c1027071fdd7caaf17253f1513d6bcd536970ca81b9"`
Nov 01 04:30:06.802 INFO kablam! usermod: no changes
Nov 01 04:30:06.851 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:30:07.035 INFO kablam!    Compiling slog v1.7.1
Nov 01 04:30:07.047 INFO kablam!    Compiling lru_time_cache v0.5.0
Nov 01 04:30:07.047 INFO kablam!    Compiling memchr v0.1.11
Nov 01 04:30:07.047 INFO kablam!    Compiling term_size v0.2.3
Nov 01 04:30:07.059 INFO kablam!    Compiling isatty v0.1.6
Nov 01 04:30:07.059 INFO kablam!    Compiling daemonize v0.2.3
Nov 01 04:30:07.059 INFO kablam!    Compiling sig v1.0.0
Nov 01 04:30:07.059 INFO kablam!    Compiling net2 v0.2.31
Nov 01 04:30:08.967 INFO kablam!    Compiling nix v0.5.1
Nov 01 04:30:09.076 INFO kablam!    Compiling clap v2.18.0
Nov 01 04:30:09.127 INFO kablam!    Compiling num v0.1.41
Nov 01 04:30:09.319 INFO kablam!    Compiling rust-crypto v0.2.36
Nov 01 04:30:09.571 INFO kablam!    Compiling aho-corasick v0.5.3
Nov 01 04:30:10.927 INFO kablam!    Compiling thread-id v2.0.0
Nov 01 04:30:12.668 INFO kablam!    Compiling chrono v0.2.25
Nov 01 04:30:12.696 INFO kablam!    Compiling slog-extra v0.1.2
Nov 01 04:30:12.747 INFO kablam!    Compiling slog-scope v0.2.2
Nov 01 04:30:13.197 INFO kablam!    Compiling miow v0.1.5
Nov 01 04:30:15.583 INFO kablam!    Compiling thread_local v0.2.7
Nov 01 04:30:18.925 INFO kablam!    Compiling regex v0.1.80
Nov 01 04:30:18.935 INFO kablam!    Compiling slog-stream v1.2.1
Nov 01 04:30:23.984 INFO kablam!    Compiling slog-term v1.3.5
Nov 01 04:30:31.482 INFO kablam!    Compiling mio v0.5.1
Nov 01 04:30:43.023 INFO kablam!    Compiling shadowsocks v0.6.2 (/source)
Nov 01 04:30:44.611 INFO kablam! warning: unused macro definition
Nov 01 04:30:44.611 INFO kablam!   --> src/util.rs:9:1
Nov 01 04:30:44.611 INFO kablam!    |
Nov 01 04:30:44.611 INFO kablam! 9  | / macro_rules! io_err {
Nov 01 04:30:44.611 INFO kablam! 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Nov 01 04:30:44.611 INFO kablam! 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Nov 01 04:30:44.611 INFO kablam! 12 | | }
Nov 01 04:30:44.611 INFO kablam!    | |_^
Nov 01 04:30:44.611 INFO kablam!    |
Nov 01 04:30:44.611 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:44.611 INFO kablam! 
Nov 01 04:30:44.707 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:44.707 INFO kablam!    --> src/config/running_config.rs:102:9
Nov 01 04:30:44.707 INFO kablam!     |
Nov 01 04:30:44.707 INFO kablam! 102 |         env::home_dir()
Nov 01 04:30:44.707 INFO kablam!     |         ^^^^^^^^^^^^^
Nov 01 04:30:44.707 INFO kablam!     |
Nov 01 04:30:44.707 INFO kablam!     = note: #[warn(deprecated)] on by default
Nov 01 04:30:44.707 INFO kablam! 
Nov 01 04:30:53.643 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 46.81s
Nov 01 04:30:53.657 INFO kablam! su: No module specific data is present
Nov 01 04:30:54.067 INFO running `"docker" "rm" "-f" "a414e5baa5a622a717410c1027071fdd7caaf17253f1513d6bcd536970ca81b9"`
Nov 01 04:30:54.223 INFO blam! a414e5baa5a622a717410c1027071fdd7caaf17253f1513d6bcd536970ca81b9
Nov 01 04:30:54.228 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 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:54.450 INFO blam! c8ca9de37171c5f28be691535c503c964d7f9fb61b4c1fad1c53c01b95abfce2
Nov 01 04:30:54.454 INFO running `"docker" "start" "-a" "c8ca9de37171c5f28be691535c503c964d7f9fb61b4c1fad1c53c01b95abfce2"`
Nov 01 04:30:54.842 INFO kablam! usermod: no changes
Nov 01 04:30:54.864 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:30:54.981 INFO kablam!    Compiling shadowsocks v0.6.2 (/source)
Nov 01 04:30:56.406 INFO kablam! warning: unused macro definition
Nov 01 04:30:56.406 INFO kablam!   --> src/util.rs:9:1
Nov 01 04:30:56.406 INFO kablam!    |
Nov 01 04:30:56.406 INFO kablam! 9  | / macro_rules! io_err {
Nov 01 04:30:56.406 INFO kablam! 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Nov 01 04:30:56.406 INFO kablam! 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Nov 01 04:30:56.406 INFO kablam! 12 | | }
Nov 01 04:30:56.406 INFO kablam!    | |_^
Nov 01 04:30:56.406 INFO kablam!    |
Nov 01 04:30:56.406 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:56.406 INFO kablam! 
Nov 01 04:30:56.515 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:56.515 INFO kablam!    --> src/config/running_config.rs:102:9
Nov 01 04:30:56.515 INFO kablam!     |
Nov 01 04:30:56.515 INFO kablam! 102 |         env::home_dir()
Nov 01 04:30:56.515 INFO kablam!     |         ^^^^^^^^^^^^^
Nov 01 04:30:56.515 INFO kablam!     |
Nov 01 04:30:56.515 INFO kablam!     = note: #[warn(deprecated)] on by default
Nov 01 04:30:56.515 INFO kablam! 
Nov 01 04:30:56.807 INFO kablam! warning: unused macro definition
Nov 01 04:30:56.807 INFO kablam!   --> tests/encrypt.rs:39:1
Nov 01 04:30:56.807 INFO kablam!    |
Nov 01 04:30:56.807 INFO kablam! 39 | / macro_rules! assert_raw_encrypt {
Nov 01 04:30:56.807 INFO kablam! 40 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) )
Nov 01 04:30:56.807 INFO kablam! 41 | | }
Nov 01 04:30:56.807 INFO kablam!    | |_^
Nov 01 04:30:56.807 INFO kablam!    |
Nov 01 04:30:56.807 INFO kablam!    = note: #[warn(unused_macros)] on by default
Nov 01 04:30:56.807 INFO kablam! 
Nov 01 04:30:56.807 INFO kablam! warning: unused macro definition
Nov 01 04:30:56.807 INFO kablam!   --> tests/encrypt.rs:43:1
Nov 01 04:30:56.807 INFO kablam!    |
Nov 01 04:30:56.807 INFO kablam! 43 | / macro_rules! assert_raw_decrypt {
Nov 01 04:30:56.807 INFO kablam! 44 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) )
Nov 01 04:30:56.807 INFO kablam! 45 | | }
Nov 01 04:30:56.807 INFO kablam!    | |_^
Nov 01 04:30:56.807 INFO kablam! 
Nov 01 04:31:00.891 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 6.03s
Nov 01 04:31:00.907 INFO kablam! su: No module specific data is present
Nov 01 04:31:01.591 INFO running `"docker" "rm" "-f" "c8ca9de37171c5f28be691535c503c964d7f9fb61b4c1fad1c53c01b95abfce2"`
Nov 01 04:31:01.819 INFO blam! c8ca9de37171c5f28be691535c503c964d7f9fb61b4c1fad1c53c01b95abfce2
Nov 01 04:31:01.827 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-2/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/beta-1.31-1/1.30.0:/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 +1.30.0 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:31:02.163 INFO blam! 173dccfcf374d398d6288eefee77ca907ac09ff6737b10fc78f375bf98e4279c
Nov 01 04:31:02.166 INFO running `"docker" "start" "-a" "173dccfcf374d398d6288eefee77ca907ac09ff6737b10fc78f375bf98e4279c"`
Nov 01 04:31:03.120 INFO kablam! usermod: no changes
Nov 01 04:31:03.163 INFO kablam! warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Nov 01 04:31:03.296 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Nov 01 04:31:03.330 INFO blam! 
Nov 01 04:31:03.330 INFO kablam!      Running /target/debug/deps/shadowsocks-bcdd464a8daca07a
Nov 01 04:31:03.330 INFO blam! running 3 tests
Nov 01 04:31:03.330 INFO blam! test asyncdns::test::ipv6_block_resolve ... ignored
Nov 01 04:31:03.330 INFO blam! test asyncdns::test::parse_response ... ok
Nov 01 04:31:03.396 INFO blam! test asyncdns::test::ipv4_block_resolve ... FAILED
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.396 INFO blam! failures:
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.396 INFO blam! ---- asyncdns::test::ipv4_block_resolve stdout ----
Nov 01 04:31:03.396 INFO blam! block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") }
Nov 01 04:31:03.396 INFO blam! thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21
Nov 01 04:31:03.396 INFO blam! stack backtrace:
Nov 01 04:31:03.396 INFO blam!    0:     0x557f39fef74e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 04:31:03.396 INFO blam!    1:     0x557f39fe21b6 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 04:31:03.396 INFO blam!    2:     0x557f39fe058d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:211
Nov 01 04:31:03.396 INFO blam!    3:     0x557f39fe029b - std::panicking::default_hook::h3a27b204a313406b
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:221
Nov 01 04:31:03.396 INFO blam!    4:     0x557f39fe0c3c - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:477
Nov 01 04:31:03.396 INFO blam!    5:     0x557f39eb553f - std::panicking::begin_panic::h291848f42f888e79
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:411
Nov 01 04:31:03.396 INFO blam!    6:     0x557f39e6314b - shadowsocks::asyncdns::test::test_block_resolve::h5d95c03851a17968
Nov 01 04:31:03.396 INFO blam!                                at src/asyncdns.rs:784
Nov 01 04:31:03.396 INFO blam!    7:     0x557f39e63407 - shadowsocks::asyncdns::test::ipv4_block_resolve::he365c0576630f76c
Nov 01 04:31:03.396 INFO blam!                                at src/asyncdns.rs:792
Nov 01 04:31:03.396 INFO blam!    8:     0x557f39e633e9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::h1cb98a144b7e264e
Nov 01 04:31:03.396 INFO blam!                                at src/asyncdns.rs:791
Nov 01 04:31:03.396 INFO blam!    9:     0x557f39e5e34d - core::ops::function::FnOnce::call_once::hce7c9f7d14043a88
Nov 01 04:31:03.396 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 04:31:03.396 INFO blam!   10:     0x557f39e7700e - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 01 04:31:03.396 INFO blam!                                at libtest/lib.rs:1461
Nov 01 04:31:03.396 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 04:31:03.396 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 04:31:03.396 INFO blam!   11:     0x557f39ffe669 - __rust_maybe_catch_panic
Nov 01 04:31:03.396 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 04:31:03.396 INFO blam!   12:     0x557f39e8291d - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:289
Nov 01 04:31:03.396 INFO blam!                                at libstd/panic.rs:392
Nov 01 04:31:03.396 INFO blam!                                at libtest/lib.rs:1416
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 04:31:03.396 INFO blam!   13:     0x557f39e9af54 - std::panicking::try::do_call::h1f0578498122cc00
Nov 01 04:31:03.396 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 04:31:03.396 INFO blam!                                at libstd/panic.rs:313
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:310
Nov 01 04:31:03.396 INFO blam!   14:     0x557f39ffe669 - __rust_maybe_catch_panic
Nov 01 04:31:03.396 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 04:31:03.396 INFO blam!   15:     0x557f39e8b826 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 01 04:31:03.396 INFO blam!                                at libstd/panicking.rs:289
Nov 01 04:31:03.396 INFO blam!                                at libstd/panic.rs:392
Nov 01 04:31:03.396 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 04:31:03.396 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 04:31:03.396 INFO blam!   16:     0x557f39ff121a - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 01 04:31:03.396 INFO blam!                                at liballoc/boxed.rs:656
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 04:31:03.396 INFO blam!   17:     0x557f39fe13f5 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 01 04:31:03.396 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 04:31:03.396 INFO blam!   18:     0x7fb3415f66b9 - start_thread
Nov 01 04:31:03.396 INFO blam!   19:     0x7fb34111641c - clone
Nov 01 04:31:03.396 INFO blam!   20:                0x0 - <unknown>
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.396 INFO blam! failures:
Nov 01 04:31:03.396 INFO blam!     asyncdns::test::ipv4_block_resolve
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.396 INFO blam! test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out
Nov 01 04:31:03.396 INFO blam! 
Nov 01 04:31:03.397 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 01 04:31:03.405 INFO kablam! su: No module specific data is present
Nov 01 04:31:03.889 INFO running `"docker" "rm" "-f" "173dccfcf374d398d6288eefee77ca907ac09ff6737b10fc78f375bf98e4279c"`
Nov 01 04:31:04.097 INFO blam! 173dccfcf374d398d6288eefee77ca907ac09ff6737b10fc78f375bf98e4279c
