Dec 05 16:15:32.597 INFO testing loggerhead/shadowsocks-rust against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514 Dec 05 16:15:32.597 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 16:15:32.974 INFO [stdout] ceb5e16dbd584721e9eb179c398213e8156545c43a3f9d2cd371572308e610d6 Dec 05 16:15:32.978 INFO running `"docker" "start" "-a" "ceb5e16dbd584721e9eb179c398213e8156545c43a3f9d2cd371572308e610d6"` Dec 05 16:15:34.136 INFO [stderr] usermod: no changes Dec 05 16:15:34.210 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 05 16:15:34.476 INFO [stderr] Compiling slog v1.7.1 Dec 05 16:15:34.485 INFO [stderr] Compiling lru_time_cache v0.5.0 Dec 05 16:15:34.492 INFO [stderr] Compiling net2 v0.2.31 Dec 05 16:15:34.494 INFO [stderr] Compiling term_size v0.2.3 Dec 05 16:15:34.494 INFO [stderr] Compiling daemonize v0.2.3 Dec 05 16:15:34.494 INFO [stderr] Compiling sig v1.0.0 Dec 05 16:15:34.500 INFO [stderr] Compiling toml v0.1.30 Dec 05 16:15:34.500 INFO [stderr] Compiling rust-crypto v0.2.36 Dec 05 16:15:36.165 INFO [stderr] Compiling chrono v0.2.25 Dec 05 16:15:36.650 INFO [stderr] Compiling clap v2.18.0 Dec 05 16:15:38.645 INFO [stderr] Compiling miow v0.1.5 Dec 05 16:15:39.366 INFO [stderr] Compiling slog-extra v0.1.2 Dec 05 16:15:39.368 INFO [stderr] Compiling slog-scope v0.2.2 Dec 05 16:15:40.452 INFO [stderr] Compiling mio v0.5.1 Dec 05 16:15:45.608 INFO [stderr] Compiling slog-stream v1.2.1 Dec 05 16:15:47.030 INFO [stderr] Compiling slog-term v1.3.5 Dec 05 16:16:09.226 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 05 16:16:10.835 INFO [stderr] warning: unused macro definition Dec 05 16:16:10.836 INFO [stderr] --> src/util.rs:9:1 Dec 05 16:16:10.836 INFO [stderr] | Dec 05 16:16:10.836 INFO [stderr] 9 | / macro_rules! io_err { Dec 05 16:16:10.836 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 05 16:16:10.837 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 05 16:16:10.837 INFO [stderr] 12 | | } Dec 05 16:16:10.837 INFO [stderr] | |_^ Dec 05 16:16:10.837 INFO [stderr] | Dec 05 16:16:10.837 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 05 16:16:10.838 INFO [stderr] Dec 05 16:16:10.942 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 05 16:16:10.942 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 05 16:16:10.942 INFO [stderr] | Dec 05 16:16:10.943 INFO [stderr] 102 | env::home_dir() Dec 05 16:16:10.943 INFO [stderr] | ^^^^^^^^^^^^^ Dec 05 16:16:10.943 INFO [stderr] | Dec 05 16:16:10.943 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 05 16:16:10.944 INFO [stderr] Dec 05 16:16:26.337 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 52.16s Dec 05 16:16:26.350 INFO [stderr] su: No module specific data is present Dec 05 16:16:27.572 INFO running `"docker" "inspect" "ceb5e16dbd584721e9eb179c398213e8156545c43a3f9d2cd371572308e610d6"` Dec 05 16:16:27.884 INFO running `"docker" "rm" "-f" "ceb5e16dbd584721e9eb179c398213e8156545c43a3f9d2cd371572308e610d6"` Dec 05 16:16:28.328 INFO [stdout] ceb5e16dbd584721e9eb179c398213e8156545c43a3f9d2cd371572308e610d6 Dec 05 16:16:28.330 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 16:16:28.684 INFO [stdout] 88ed16262323adef815fd051ec38849a34b942a8bebf86d98ed9cf29512aef2c Dec 05 16:16:28.690 INFO running `"docker" "start" "-a" "88ed16262323adef815fd051ec38849a34b942a8bebf86d98ed9cf29512aef2c"` Dec 05 16:16:29.529 INFO [stderr] usermod: no changes Dec 05 16:16:29.587 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 05 16:16:29.724 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 05 16:16:31.676 INFO [stderr] warning: unused macro definition Dec 05 16:16:31.676 INFO [stderr] --> src/util.rs:9:1 Dec 05 16:16:31.676 INFO [stderr] | Dec 05 16:16:31.676 INFO [stderr] 9 | / macro_rules! io_err { Dec 05 16:16:31.676 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 05 16:16:31.676 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 05 16:16:31.676 INFO [stderr] 12 | | } Dec 05 16:16:31.676 INFO [stderr] | |_^ Dec 05 16:16:31.676 INFO [stderr] | Dec 05 16:16:31.676 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 05 16:16:31.676 INFO [stderr] Dec 05 16:16:31.904 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 05 16:16:31.904 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 05 16:16:31.904 INFO [stderr] | Dec 05 16:16:31.904 INFO [stderr] 102 | env::home_dir() Dec 05 16:16:31.904 INFO [stderr] | ^^^^^^^^^^^^^ Dec 05 16:16:31.904 INFO [stderr] | Dec 05 16:16:31.904 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 05 16:16:31.904 INFO [stderr] Dec 05 16:16:32.410 INFO [stderr] warning: unused macro definition Dec 05 16:16:32.410 INFO [stderr] --> tests/encrypt.rs:39:1 Dec 05 16:16:32.410 INFO [stderr] | Dec 05 16:16:32.410 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt { Dec 05 16:16:32.410 INFO [stderr] 40 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) ) Dec 05 16:16:32.410 INFO [stderr] 41 | | } Dec 05 16:16:32.410 INFO [stderr] | |_^ Dec 05 16:16:32.410 INFO [stderr] | Dec 05 16:16:32.410 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 05 16:16:32.410 INFO [stderr] Dec 05 16:16:32.410 INFO [stderr] warning: unused macro definition Dec 05 16:16:32.410 INFO [stderr] --> tests/encrypt.rs:43:1 Dec 05 16:16:32.410 INFO [stderr] | Dec 05 16:16:32.410 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt { Dec 05 16:16:32.410 INFO [stderr] 44 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) ) Dec 05 16:16:32.410 INFO [stderr] 45 | | } Dec 05 16:16:32.410 INFO [stderr] | |_^ Dec 05 16:16:32.410 INFO [stderr] Dec 05 16:16:37.955 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 8.39s Dec 05 16:16:37.966 INFO [stderr] su: No module specific data is present Dec 05 16:16:38.760 INFO running `"docker" "inspect" "88ed16262323adef815fd051ec38849a34b942a8bebf86d98ed9cf29512aef2c"` Dec 05 16:16:38.981 INFO running `"docker" "rm" "-f" "88ed16262323adef815fd051ec38849a34b942a8bebf86d98ed9cf29512aef2c"` Dec 05 16:16:39.204 INFO [stdout] 88ed16262323adef815fd051ec38849a34b942a8bebf86d98ed9cf29512aef2c Dec 05 16:16:39.210 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 16:16:39.552 INFO [stdout] 989c06eee4fe63bae8bd350250919225f9e87cb698cd2b4f77d2bf48cdd9abed Dec 05 16:16:39.560 INFO running `"docker" "start" "-a" "989c06eee4fe63bae8bd350250919225f9e87cb698cd2b4f77d2bf48cdd9abed"` Dec 05 16:16:40.384 INFO [stderr] usermod: no changes Dec 05 16:16:40.432 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 05 16:16:40.602 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.19s Dec 05 16:16:40.620 INFO [stderr] Running /target/debug/deps/shadowsocks-849c780ead1c9803 Dec 05 16:16:40.624 INFO [stdout] Dec 05 16:16:40.624 INFO [stdout] running 3 tests Dec 05 16:16:40.624 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored Dec 05 16:16:40.632 INFO [stdout] test asyncdns::test::parse_response ... ok Dec 05 16:16:40.724 INFO [stderr] error: test failed, to rerun pass '--lib' Dec 05 16:16:40.724 INFO [stderr] su: No module specific data is present Dec 05 16:16:40.725 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED Dec 05 16:16:40.725 INFO [stdout] Dec 05 16:16:40.725 INFO [stdout] failures: Dec 05 16:16:40.725 INFO [stdout] Dec 05 16:16:40.725 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ---- Dec 05 16:16:40.725 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") } Dec 05 16:16:40.725 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21 Dec 05 16:16:40.725 INFO [stdout] stack backtrace: Dec 05 16:16:40.725 INFO [stdout] 0: 0x563859bbe093 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 05 16:16:40.725 INFO [stdout] 1: 0x563859bb84b8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 05 16:16:40.725 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 05 16:16:40.725 INFO [stdout] 2: 0x563859bbcd72 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/panicking.rs:211 Dec 05 16:16:40.725 INFO [stdout] 3: 0x563859bbca5c - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/panicking.rs:221 Dec 05 16:16:40.725 INFO [stdout] 4: 0x563859bbd480 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 05 16:16:40.725 INFO [stdout] at src/libstd/panicking.rs:491 Dec 05 16:16:40.725 INFO [stdout] 5: 0x563859a7bb57 - std::panicking::begin_panic::hd6d0ca96a8c0e5f7 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:425 Dec 05 16:16:40.725 INFO [stdout] 6: 0x563859a2c1f7 - shadowsocks::asyncdns::test::test_block_resolve::h1d1789d0e418d529 Dec 05 16:16:40.725 INFO [stdout] at src/asyncdns.rs:784 Dec 05 16:16:40.725 INFO [stdout] 7: 0x563859a2c4a7 - shadowsocks::asyncdns::test::ipv4_block_resolve::h6877cd4596efa151 Dec 05 16:16:40.725 INFO [stdout] at src/asyncdns.rs:792 Dec 05 16:16:40.725 INFO [stdout] 8: 0x563859a2c489 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::hf7786f0ad9973b98 Dec 05 16:16:40.725 INFO [stdout] at src/asyncdns.rs:791 Dec 05 16:16:40.725 INFO [stdout] 9: 0x563859a1cd1d - core::ops::function::FnOnce::call_once::h001c95f9d3865f2b Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 05 16:16:40.725 INFO [stdout] 10: 0x563859a4764e - >::call_box::h9ed1658cdce7f33f Dec 05 16:16:40.725 INFO [stdout] at src/libtest/lib.rs:1471 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 05 16:16:40.725 INFO [stdout] 11: 0x563859bcbe59 - __rust_maybe_catch_panic Dec 05 16:16:40.725 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 05 16:16:40.725 INFO [stdout] 12: 0x563859a3f523 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 05 16:16:40.725 INFO [stdout] at src/libtest/lib.rs:1426 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 05 16:16:40.725 INFO [stdout] 13: 0x563859a404b4 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 05 16:16:40.725 INFO [stdout] 14: 0x563859bcbe59 - __rust_maybe_catch_panic Dec 05 16:16:40.725 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 05 16:16:40.725 INFO [stdout] 15: 0x563859a4752c - >::call_box::h6465cc998309e287 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 05 16:16:40.725 INFO [stdout] 16: 0x563859bbfe1d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 05 16:16:40.725 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 05 16:16:40.725 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 05 16:16:40.725 INFO [stdout] 17: 0x7f1136a226b9 - start_thread Dec 05 16:16:40.725 INFO [stdout] 18: 0x7f113654241c - clone Dec 05 16:16:40.725 INFO [stdout] 19: 0x0 - Dec 05 16:16:40.725 INFO [stdout] Dec 05 16:16:40.726 INFO [stdout] Dec 05 16:16:40.726 INFO [stdout] failures: Dec 05 16:16:40.726 INFO [stdout] asyncdns::test::ipv4_block_resolve Dec 05 16:16:40.726 INFO [stdout] Dec 05 16:16:40.726 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out Dec 05 16:16:40.726 INFO [stdout] Dec 05 16:16:41.214 INFO running `"docker" "inspect" "989c06eee4fe63bae8bd350250919225f9e87cb698cd2b4f77d2bf48cdd9abed"` Dec 05 16:16:41.397 INFO running `"docker" "rm" "-f" "989c06eee4fe63bae8bd350250919225f9e87cb698cd2b4f77d2bf48cdd9abed"` Dec 05 16:16:41.489 INFO [stdout] 989c06eee4fe63bae8bd350250919225f9e87cb698cd2b4f77d2bf48cdd9abed