Dec 01 11:02:15.887 INFO testing loggerhead/shadowsocks-rust against 1.30.1 for beta-1.31-5
Dec 01 11:02:15.887 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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.1 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"`
Dec 01 11:02:16.299 INFO [stdout] 0c5359bbd829fdd5c4b51c6981dd2c9aecfec3d85660c1c6494ba8de527d6c60
Dec 01 11:02:16.301 INFO running `"docker" "start" "-a" "0c5359bbd829fdd5c4b51c6981dd2c9aecfec3d85660c1c6494ba8de527d6c60"`
Dec 01 11:02:18.482 INFO [stderr] usermod: no changes
Dec 01 11:02:18.537 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:02:18.793 INFO [stderr]    Compiling slog v1.7.1
Dec 01 11:02:18.794 INFO [stderr]    Compiling lru_time_cache v0.5.0
Dec 01 11:02:18.795 INFO [stderr]    Compiling try_opt v0.1.1
Dec 01 11:02:18.797 INFO [stderr]    Compiling net2 v0.2.31
Dec 01 11:02:18.798 INFO [stderr]    Compiling memchr v0.1.11
Dec 01 11:02:18.799 INFO [stderr]    Compiling term_size v0.2.3
Dec 01 11:02:18.826 INFO [stderr]    Compiling sig v1.0.0
Dec 01 11:02:18.827 INFO [stderr]    Compiling daemonize v0.2.3
Dec 01 11:02:20.857 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 01 11:02:21.239 INFO [stderr]    Compiling thread-id v2.0.0
Dec 01 11:02:21.324 INFO [stderr]    Compiling chrono v0.2.25
Dec 01 11:02:22.105 INFO [stderr]    Compiling clap v2.18.0
Dec 01 11:02:22.184 INFO [stderr]    Compiling aho-corasick v0.5.3
Dec 01 11:02:25.024 INFO [stderr]    Compiling thread_local v0.2.7
Dec 01 11:02:25.059 INFO [stderr]    Compiling slog-extra v0.1.2
Dec 01 11:02:25.084 INFO [stderr]    Compiling slog-scope v0.2.2
Dec 01 11:02:25.128 INFO [stderr]    Compiling miow v0.1.5
Dec 01 11:02:27.270 INFO [stderr]    Compiling mio v0.5.1
Dec 01 11:02:29.655 INFO [stderr]    Compiling regex v0.1.80
Dec 01 11:02:32.164 INFO [stderr]    Compiling slog-stream v1.2.1
Dec 01 11:02:38.224 INFO [stderr]    Compiling slog-term v1.3.5
Dec 01 11:03:01.191 INFO [stderr]    Compiling shadowsocks v0.6.2 (/source)
Dec 01 11:03:02.707 INFO [stderr] warning: unused macro definition
Dec 01 11:03:02.707 INFO [stderr]   --> src/util.rs:9:1
Dec 01 11:03:02.707 INFO [stderr]    |
Dec 01 11:03:02.707 INFO [stderr] 9  | / macro_rules! io_err {
Dec 01 11:03:02.707 INFO [stderr] 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Dec 01 11:03:02.707 INFO [stderr] 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Dec 01 11:03:02.707 INFO [stderr] 12 | | }
Dec 01 11:03:02.707 INFO [stderr]    | |_^
Dec 01 11:03:02.707 INFO [stderr]    |
Dec 01 11:03:02.707 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:03:02.707 INFO [stderr] 
Dec 01 11:03:02.807 INFO [stderr] 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.
Dec 01 11:03:02.807 INFO [stderr]    --> src/config/running_config.rs:102:9
Dec 01 11:03:02.807 INFO [stderr]     |
Dec 01 11:03:02.807 INFO [stderr] 102 |         env::home_dir()
Dec 01 11:03:02.807 INFO [stderr]     |         ^^^^^^^^^^^^^
Dec 01 11:03:02.807 INFO [stderr]     |
Dec 01 11:03:02.807 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 01 11:03:02.807 INFO [stderr] 
Dec 01 11:03:14.840 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 56.33s
Dec 01 11:03:14.858 INFO [stderr] su: No module specific data is present
Dec 01 11:03:16.355 INFO running `"docker" "inspect" "0c5359bbd829fdd5c4b51c6981dd2c9aecfec3d85660c1c6494ba8de527d6c60"`
Dec 01 11:03:16.795 INFO running `"docker" "rm" "-f" "0c5359bbd829fdd5c4b51c6981dd2c9aecfec3d85660c1c6494ba8de527d6c60"`
Dec 01 11:03:17.137 INFO [stdout] 0c5359bbd829fdd5c4b51c6981dd2c9aecfec3d85660c1c6494ba8de527d6c60
Dec 01 11:03:17.147 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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.1 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"`
Dec 01 11:03:17.710 INFO [stdout] 430517a7942fd240e6a8c5e8944064fd511e58ad9249e8edee6e0e208e93b648
Dec 01 11:03:17.712 INFO running `"docker" "start" "-a" "430517a7942fd240e6a8c5e8944064fd511e58ad9249e8edee6e0e208e93b648"`
Dec 01 11:03:19.225 INFO [stderr] usermod: no changes
Dec 01 11:03:19.255 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:03:19.532 INFO [stderr]    Compiling shadowsocks v0.6.2 (/source)
Dec 01 11:03:22.871 INFO [stderr] warning: unused macro definition
Dec 01 11:03:22.871 INFO [stderr]   --> src/util.rs:9:1
Dec 01 11:03:22.871 INFO [stderr]    |
Dec 01 11:03:22.871 INFO [stderr] 9  | / macro_rules! io_err {
Dec 01 11:03:22.871 INFO [stderr] 10 | |     ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) );
Dec 01 11:03:22.871 INFO [stderr] 11 | |     ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) );
Dec 01 11:03:22.871 INFO [stderr] 12 | | }
Dec 01 11:03:22.871 INFO [stderr]    | |_^
Dec 01 11:03:22.871 INFO [stderr]    |
Dec 01 11:03:22.871 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:03:22.871 INFO [stderr] 
Dec 01 11:03:22.955 INFO [stderr] 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.
Dec 01 11:03:22.955 INFO [stderr]    --> src/config/running_config.rs:102:9
Dec 01 11:03:22.955 INFO [stderr]     |
Dec 01 11:03:22.955 INFO [stderr] 102 |         env::home_dir()
Dec 01 11:03:22.955 INFO [stderr]     |         ^^^^^^^^^^^^^
Dec 01 11:03:22.955 INFO [stderr]     |
Dec 01 11:03:22.955 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 01 11:03:22.955 INFO [stderr] 
Dec 01 11:03:23.043 INFO [stderr] warning: unused macro definition
Dec 01 11:03:23.043 INFO [stderr]   --> tests/encrypt.rs:39:1
Dec 01 11:03:23.043 INFO [stderr]    |
Dec 01 11:03:23.043 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt {
Dec 01 11:03:23.043 INFO [stderr] 40 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) )
Dec 01 11:03:23.043 INFO [stderr] 41 | | }
Dec 01 11:03:23.043 INFO [stderr]    | |_^
Dec 01 11:03:23.043 INFO [stderr]    |
Dec 01 11:03:23.043 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 11:03:23.043 INFO [stderr] 
Dec 01 11:03:23.043 INFO [stderr] warning: unused macro definition
Dec 01 11:03:23.043 INFO [stderr]   --> tests/encrypt.rs:43:1
Dec 01 11:03:23.043 INFO [stderr]    |
Dec 01 11:03:23.043 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt {
Dec 01 11:03:23.043 INFO [stderr] 44 | |     ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) )
Dec 01 11:03:23.043 INFO [stderr] 45 | | }
Dec 01 11:03:23.043 INFO [stderr]    | |_^
Dec 01 11:03:23.043 INFO [stderr] 
Dec 01 11:03:28.939 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.68s
Dec 01 11:03:28.983 INFO [stderr] su: No module specific data is present
Dec 01 11:03:30.083 INFO running `"docker" "inspect" "430517a7942fd240e6a8c5e8944064fd511e58ad9249e8edee6e0e208e93b648"`
Dec 01 11:03:30.316 INFO running `"docker" "rm" "-f" "430517a7942fd240e6a8c5e8944064fd511e58ad9249e8edee6e0e208e93b648"`
Dec 01 11:03:30.646 INFO [stdout] 430517a7942fd240e6a8c5e8944064fd511e58ad9249e8edee6e0e208e93b648
Dec 01 11:03:30.653 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/1.30.1:/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.1 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"`
Dec 01 11:03:31.103 INFO [stdout] e3ec1a47177ea7d89abf7168f1d87ecd76cd7180f1503c0a2d308ce8a728d999
Dec 01 11:03:31.109 INFO running `"docker" "start" "-a" "e3ec1a47177ea7d89abf7168f1d87ecd76cd7180f1503c0a2d308ce8a728d999"`
Dec 01 11:03:32.315 INFO [stderr] usermod: no changes
Dec 01 11:03:32.379 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs
Dec 01 11:03:32.555 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Dec 01 11:03:32.574 INFO [stderr]      Running /target/debug/deps/shadowsocks-97fff202fa9cf48b
Dec 01 11:03:32.588 INFO [stdout] 
Dec 01 11:03:32.588 INFO [stdout] running 3 tests
Dec 01 11:03:32.588 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored
Dec 01 11:03:32.593 INFO [stdout] test asyncdns::test::parse_response ... ok
Dec 01 11:03:32.691 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED
Dec 01 11:03:32.691 INFO [stderr] error: test failed, to rerun pass '--lib'
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:32.691 INFO [stderr] su: No module specific data is present
Dec 01 11:03:32.691 INFO [stdout] failures:
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:32.691 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ----
Dec 01 11:03:32.691 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") }
Dec 01 11:03:32.691 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21
Dec 01 11:03:32.691 INFO [stdout] stack backtrace:
Dec 01 11:03:32.691 INFO [stdout]    0:     0x557fb3d78cbe - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 01 11:03:32.691 INFO [stdout]    1:     0x557fb3d6b6d6 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 01 11:03:32.691 INFO [stdout]    2:     0x557fb3d69afd - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:211
Dec 01 11:03:32.691 INFO [stdout]    3:     0x557fb3d6980b - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:221
Dec 01 11:03:32.691 INFO [stdout]    4:     0x557fb3d6a1ac - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:477
Dec 01 11:03:32.691 INFO [stdout]    5:     0x557fb3d59c1f - std::panicking::begin_panic::hfffca39c903f9dd3
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:411
Dec 01 11:03:32.691 INFO [stdout]    6:     0x557fb3be821b - shadowsocks::asyncdns::test::test_block_resolve::h2f3e144ce78a078e
Dec 01 11:03:32.691 INFO [stdout]                                at src/asyncdns.rs:784
Dec 01 11:03:32.691 INFO [stdout]    7:     0x557fb3be84d7 - shadowsocks::asyncdns::test::ipv4_block_resolve::h04dcc49d9c6e9602
Dec 01 11:03:32.691 INFO [stdout]                                at src/asyncdns.rs:792
Dec 01 11:03:32.691 INFO [stdout]    8:     0x557fb3be84b9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::h0a4483ba1b8c5706
Dec 01 11:03:32.691 INFO [stdout]                                at src/asyncdns.rs:791
Dec 01 11:03:32.691 INFO [stdout]    9:     0x557fb3be32ad - core::ops::function::FnOnce::call_once::h5576b03d74ea1f81
Dec 01 11:03:32.691 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 11:03:32.691 INFO [stdout]   10:     0x557fb3bfc08e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 01 11:03:32.691 INFO [stdout]                                at libtest/lib.rs:1461
Dec 01 11:03:32.691 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 11:03:32.691 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 11:03:32.691 INFO [stdout]   11:     0x557fb3d87bc9 - __rust_maybe_catch_panic
Dec 01 11:03:32.691 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 11:03:32.691 INFO [stdout]   12:     0x557fb3c079ed - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 11:03:32.691 INFO [stdout]                                at libtest/lib.rs:1416
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 01 11:03:32.691 INFO [stdout]   13:     0x557fb3c20024 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panic.rs:313
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:310
Dec 01 11:03:32.691 INFO [stdout]   14:     0x557fb3d87bc9 - __rust_maybe_catch_panic
Dec 01 11:03:32.691 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 11:03:32.691 INFO [stdout]   15:     0x557fb3c108f6 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 01 11:03:32.691 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 11:03:32.691 INFO [stdout]   16:     0x557fb3d7a77a - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 01 11:03:32.691 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 01 11:03:32.691 INFO [stdout]   17:     0x557fb3d6a915 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 01 11:03:32.691 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 01 11:03:32.691 INFO [stdout]   18:     0x7fc0b36fc6b9 - start_thread
Dec 01 11:03:32.691 INFO [stdout]   19:     0x7fc0b321c41c - clone
Dec 01 11:03:32.691 INFO [stdout]   20:                0x0 - <unknown>
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:32.691 INFO [stdout] failures:
Dec 01 11:03:32.691 INFO [stdout]     asyncdns::test::ipv4_block_resolve
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:32.691 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out
Dec 01 11:03:32.691 INFO [stdout] 
Dec 01 11:03:33.209 INFO running `"docker" "inspect" "e3ec1a47177ea7d89abf7168f1d87ecd76cd7180f1503c0a2d308ce8a728d999"`
Dec 01 11:03:33.452 INFO running `"docker" "rm" "-f" "e3ec1a47177ea7d89abf7168f1d87ecd76cd7180f1503c0a2d308ce8a728d999"`
Dec 01 11:03:33.687 INFO [stdout] e3ec1a47177ea7d89abf7168f1d87ecd76cd7180f1503c0a2d308ce8a728d999
