Dec 13 12:30:49.032 INFO testing loggerhead/shadowsocks-rust against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550 Dec 13 12:30:49.032 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 12:30:49.525 INFO [stdout] 9900ac6b4ea3dc17827bb3b693c71f85dc3c57976acf1efdd26ad07ad2f920d4 Dec 13 12:30:49.527 INFO running `"docker" "start" "-a" "9900ac6b4ea3dc17827bb3b693c71f85dc3c57976acf1efdd26ad07ad2f920d4"` Dec 13 12:30:51.019 INFO [stderr] usermod: no changes Dec 13 12:30:51.061 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:30:51.267 INFO [stderr] Compiling slog v1.7.1 Dec 13 12:30:51.291 INFO [stderr] Compiling lru_time_cache v0.5.0 Dec 13 12:30:51.295 INFO [stderr] Compiling net2 v0.2.31 Dec 13 12:30:51.295 INFO [stderr] Compiling isatty v0.1.6 Dec 13 12:30:51.295 INFO [stderr] Compiling term_size v0.2.3 Dec 13 12:30:51.295 INFO [stderr] Compiling daemonize v0.2.3 Dec 13 12:30:51.299 INFO [stderr] Compiling sig v1.0.0 Dec 13 12:30:51.299 INFO [stderr] Compiling nix v0.5.1 Dec 13 12:30:53.553 INFO [stderr] Compiling toml v0.1.30 Dec 13 12:30:53.732 INFO [stderr] Compiling clap v2.18.0 Dec 13 12:30:53.755 INFO [stderr] Compiling rust-crypto v0.2.36 Dec 13 12:30:53.811 INFO [stderr] Compiling chrono v0.2.25 Dec 13 12:30:57.780 INFO [stderr] Compiling slog-extra v0.1.2 Dec 13 12:30:57.808 INFO [stderr] Compiling slog-scope v0.2.2 Dec 13 12:30:57.835 INFO [stderr] Compiling miow v0.1.5 Dec 13 12:31:06.551 INFO [stderr] Compiling slog-stream v1.2.1 Dec 13 12:31:09.247 INFO [stderr] Compiling slog-term v1.3.5 Dec 13 12:31:12.724 INFO [stderr] Compiling mio v0.5.1 Dec 13 12:31:32.645 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 13 12:31:34.423 INFO [stderr] warning: unused macro definition Dec 13 12:31:34.423 INFO [stderr] --> src/util.rs:9:1 Dec 13 12:31:34.423 INFO [stderr] | Dec 13 12:31:34.423 INFO [stderr] 9 | / macro_rules! io_err { Dec 13 12:31:34.423 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 13 12:31:34.423 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 13 12:31:34.423 INFO [stderr] 12 | | } Dec 13 12:31:34.423 INFO [stderr] | |_^ Dec 13 12:31:34.423 INFO [stderr] | Dec 13 12:31:34.423 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:34.423 INFO [stderr] Dec 13 12:31:34.503 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 13 12:31:34.503 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 13 12:31:34.503 INFO [stderr] | Dec 13 12:31:34.503 INFO [stderr] 102 | env::home_dir() Dec 13 12:31:34.503 INFO [stderr] | ^^^^^^^^^^^^^ Dec 13 12:31:34.503 INFO [stderr] | Dec 13 12:31:34.503 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 13 12:31:34.503 INFO [stderr] Dec 13 12:31:47.737 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 56.69s Dec 13 12:31:47.750 INFO [stderr] su: No module specific data is present Dec 13 12:31:48.421 INFO running `"docker" "inspect" "9900ac6b4ea3dc17827bb3b693c71f85dc3c57976acf1efdd26ad07ad2f920d4"` Dec 13 12:31:48.683 INFO running `"docker" "rm" "-f" "9900ac6b4ea3dc17827bb3b693c71f85dc3c57976acf1efdd26ad07ad2f920d4"` Dec 13 12:31:49.000 INFO [stdout] 9900ac6b4ea3dc17827bb3b693c71f85dc3c57976acf1efdd26ad07ad2f920d4 Dec 13 12:31:49.001 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 12:31:49.423 INFO [stdout] 2d6f4c8f536ac908fcd77f89385d2c4792f2bc08022009bc260d65a245b03234 Dec 13 12:31:49.429 INFO running `"docker" "start" "-a" "2d6f4c8f536ac908fcd77f89385d2c4792f2bc08022009bc260d65a245b03234"` Dec 13 12:31:50.747 INFO [stderr] usermod: no changes Dec 13 12:31:50.790 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:31:50.991 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 13 12:31:53.307 INFO [stderr] warning: unused macro definition Dec 13 12:31:53.307 INFO [stderr] --> src/util.rs:9:1 Dec 13 12:31:53.307 INFO [stderr] | Dec 13 12:31:53.307 INFO [stderr] 9 | / macro_rules! io_err { Dec 13 12:31:53.307 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 13 12:31:53.307 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 13 12:31:53.307 INFO [stderr] 12 | | } Dec 13 12:31:53.307 INFO [stderr] | |_^ Dec 13 12:31:53.307 INFO [stderr] | Dec 13 12:31:53.307 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:53.307 INFO [stderr] Dec 13 12:31:53.383 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 13 12:31:53.383 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 13 12:31:53.383 INFO [stderr] | Dec 13 12:31:53.383 INFO [stderr] 102 | env::home_dir() Dec 13 12:31:53.383 INFO [stderr] | ^^^^^^^^^^^^^ Dec 13 12:31:53.383 INFO [stderr] | Dec 13 12:31:53.383 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 13 12:31:53.383 INFO [stderr] Dec 13 12:31:54.603 INFO [stderr] warning: unused macro definition Dec 13 12:31:54.603 INFO [stderr] --> tests/encrypt.rs:39:1 Dec 13 12:31:54.603 INFO [stderr] | Dec 13 12:31:54.603 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt { Dec 13 12:31:54.603 INFO [stderr] 40 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) ) Dec 13 12:31:54.603 INFO [stderr] 41 | | } Dec 13 12:31:54.603 INFO [stderr] | |_^ Dec 13 12:31:54.603 INFO [stderr] | Dec 13 12:31:54.603 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:54.603 INFO [stderr] Dec 13 12:31:54.603 INFO [stderr] warning: unused macro definition Dec 13 12:31:54.603 INFO [stderr] --> tests/encrypt.rs:43:1 Dec 13 12:31:54.603 INFO [stderr] | Dec 13 12:31:54.603 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt { Dec 13 12:31:54.603 INFO [stderr] 44 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) ) Dec 13 12:31:54.603 INFO [stderr] 45 | | } Dec 13 12:31:54.603 INFO [stderr] | |_^ Dec 13 12:31:54.603 INFO [stderr] Dec 13 12:31:59.843 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 9.06s Dec 13 12:31:59.863 INFO [stderr] su: No module specific data is present Dec 13 12:32:00.699 INFO running `"docker" "inspect" "2d6f4c8f536ac908fcd77f89385d2c4792f2bc08022009bc260d65a245b03234"` Dec 13 12:32:00.935 INFO running `"docker" "rm" "-f" "2d6f4c8f536ac908fcd77f89385d2c4792f2bc08022009bc260d65a245b03234"` Dec 13 12:32:01.145 INFO [stdout] 2d6f4c8f536ac908fcd77f89385d2c4792f2bc08022009bc260d65a245b03234 Dec 13 12:32:01.153 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 12:32:01.455 INFO [stdout] 606650c7e08a48079daec2db9f9d638023388592ba85f4fc422693ad1920739c Dec 13 12:32:01.455 INFO running `"docker" "start" "-a" "606650c7e08a48079daec2db9f9d638023388592ba85f4fc422693ad1920739c"` Dec 13 12:32:02.206 INFO [stderr] usermod: no changes Dec 13 12:32:02.232 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:32:02.334 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.11s Dec 13 12:32:02.340 INFO [stderr] Running /target/debug/deps/shadowsocks-67cd11fbc042d7a8 Dec 13 12:32:02.343 INFO [stdout] Dec 13 12:32:02.343 INFO [stdout] running 3 tests Dec 13 12:32:02.343 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored Dec 13 12:32:02.343 INFO [stdout] test asyncdns::test::parse_response ... ok Dec 13 12:32:02.477 INFO [stderr] error: test failed, to rerun pass '--lib' Dec 13 12:32:02.477 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED Dec 13 12:32:02.477 INFO [stderr] su: No module specific data is present Dec 13 12:32:02.477 INFO [stdout] Dec 13 12:32:02.477 INFO [stdout] failures: Dec 13 12:32:02.477 INFO [stdout] Dec 13 12:32:02.477 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ---- Dec 13 12:32:02.477 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") } Dec 13 12:32:02.477 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21 Dec 13 12:32:02.477 INFO [stdout] stack backtrace: Dec 13 12:32:02.477 INFO [stdout] 0: 0x55e2e4543453 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 13 12:32:02.477 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 12:32:02.477 INFO [stdout] 1: 0x55e2e453d4d8 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 13 12:32:02.477 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 12:32:02.477 INFO [stdout] 2: 0x55e2e4541cb2 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 13 12:32:02.477 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 12:32:02.477 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 12:32:02.477 INFO [stdout] 3: 0x55e2e45419a7 - std::panicking::default_hook::hf0c15a583008ed0d Dec 13 12:32:02.477 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 12:32:02.477 INFO [stdout] 4: 0x55e2e45423c0 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 13 12:32:02.477 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 12:32:02.477 INFO [stdout] 5: 0x55e2e452c017 - std::panicking::begin_panic::hb97fe459dd86e1c1 Dec 13 12:32:02.477 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421 Dec 13 12:32:02.477 INFO [stdout] 6: 0x55e2e43b5f67 - shadowsocks::asyncdns::test::test_block_resolve::h63af553968ff830f Dec 13 12:32:02.477 INFO [stdout] at src/asyncdns.rs:784 Dec 13 12:32:02.477 INFO [stdout] 7: 0x55e2e43b6217 - shadowsocks::asyncdns::test::ipv4_block_resolve::h8c3d4d65aa0de556 Dec 13 12:32:02.477 INFO [stdout] at src/asyncdns.rs:792 Dec 13 12:32:02.477 INFO [stdout] 8: 0x55e2e43b61f9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::h8e0124be1c8d4575 Dec 13 12:32:02.477 INFO [stdout] at src/asyncdns.rs:791 Dec 13 12:32:02.477 INFO [stdout] 9: 0x55e2e43a6bbd - core::ops::function::FnOnce::call_once::h00b1645e281ea9ef Dec 13 12:32:02.477 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 12:32:02.478 INFO [stdout] 10: 0x55e2e43d0abe - >::call_box::hac337ba1af72dc2b Dec 13 12:32:02.478 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 12:32:02.478 INFO [stdout] 11: 0x55e2e4551219 - __rust_maybe_catch_panic Dec 13 12:32:02.478 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 12:32:02.478 INFO [stdout] 12: 0x55e2e43ee2c7 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 12:32:02.478 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 12:32:02.478 INFO [stdout] 13: 0x55e2e43c91d4 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 13 12:32:02.478 INFO [stdout] 14: 0x55e2e43c97c4 - std::panicking::try::do_call::h5e99f92fbf448cf6 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 13 12:32:02.478 INFO [stdout] 15: 0x55e2e4551219 - __rust_maybe_catch_panic Dec 13 12:32:02.478 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 12:32:02.478 INFO [stdout] 16: 0x55e2e43d099c - >::call_box::h8ae063aff93ccdb6 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 12:32:02.478 INFO [stdout] 17: 0x55e2e45451dd - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 13 12:32:02.478 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 13 12:32:02.478 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 12:32:02.478 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 12:32:02.478 INFO [stdout] 18: 0x7fe1b8fbb6b9 - start_thread Dec 13 12:32:02.478 INFO [stdout] 19: 0x7fe1b8adb41c - clone Dec 13 12:32:02.478 INFO [stdout] 20: 0x0 - Dec 13 12:32:02.478 INFO [stdout] Dec 13 12:32:02.478 INFO [stdout] Dec 13 12:32:02.478 INFO [stdout] failures: Dec 13 12:32:02.478 INFO [stdout] asyncdns::test::ipv4_block_resolve Dec 13 12:32:02.478 INFO [stdout] Dec 13 12:32:02.478 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out Dec 13 12:32:02.478 INFO [stdout] Dec 13 12:32:03.267 INFO running `"docker" "inspect" "606650c7e08a48079daec2db9f9d638023388592ba85f4fc422693ad1920739c"` Dec 13 12:32:03.527 INFO running `"docker" "rm" "-f" "606650c7e08a48079daec2db9f9d638023388592ba85f4fc422693ad1920739c"` Dec 13 12:32:03.774 INFO [stdout] 606650c7e08a48079daec2db9f9d638023388592ba85f4fc422693ad1920739c