Nov 16 21:08:54.314 INFO testing loggerhead/shadowsocks-rust against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982 Nov 16 21:08:54.314 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"` Nov 16 21:08:54.691 INFO [stdout] 64a2e3c9811396deda00bfbb6a10ab93650c35a0bc2dd5095c9578cf269be245 Nov 16 21:08:54.693 INFO running `"docker" "start" "-a" "64a2e3c9811396deda00bfbb6a10ab93650c35a0bc2dd5095c9578cf269be245"` Nov 16 21:08:55.883 INFO [stderr] usermod: no changes Nov 16 21:08:55.923 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:08:56.191 INFO [stderr] Compiling slog v1.7.1 Nov 16 21:08:56.194 INFO [stderr] Compiling lru_time_cache v0.5.0 Nov 16 21:08:56.199 INFO [stderr] Compiling try_opt v0.1.1 Nov 16 21:08:56.201 INFO [stderr] Compiling net2 v0.2.31 Nov 16 21:08:56.202 INFO [stderr] Compiling term_size v0.2.3 Nov 16 21:08:56.203 INFO [stderr] Compiling daemonize v0.2.3 Nov 16 21:08:56.203 INFO [stderr] Compiling sig v1.0.0 Nov 16 21:08:56.204 INFO [stderr] Compiling toml v0.1.30 Nov 16 21:08:57.484 INFO [stderr] Compiling rust-crypto v0.2.36 Nov 16 21:08:57.991 INFO [stderr] Compiling chrono v0.2.25 Nov 16 21:08:58.039 INFO [stderr] Compiling clap v2.18.0 Nov 16 21:08:59.712 INFO [stderr] Compiling slog-extra v0.1.2 Nov 16 21:09:01.171 INFO [stderr] Compiling slog-scope v0.2.2 Nov 16 21:09:01.267 INFO [stderr] Compiling miow v0.1.5 Nov 16 21:09:02.387 INFO [stderr] Compiling mio v0.5.1 Nov 16 21:09:06.379 INFO [stderr] Compiling slog-stream v1.2.1 Nov 16 21:09:10.710 INFO [stderr] Compiling slog-term v1.3.5 Nov 16 21:09:25.743 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Nov 16 21:09:27.179 INFO [stderr] warning: unused macro definition Nov 16 21:09:27.180 INFO [stderr] --> src/util.rs:9:1 Nov 16 21:09:27.180 INFO [stderr] | Nov 16 21:09:27.180 INFO [stderr] 9 | / macro_rules! io_err { Nov 16 21:09:27.180 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Nov 16 21:09:27.180 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Nov 16 21:09:27.180 INFO [stderr] 12 | | } Nov 16 21:09:27.180 INFO [stderr] | |_^ Nov 16 21:09:27.180 INFO [stderr] | Nov 16 21:09:27.180 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:27.180 INFO [stderr] Nov 16 21:09:27.279 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. Nov 16 21:09:27.279 INFO [stderr] --> src/config/running_config.rs:102:9 Nov 16 21:09:27.279 INFO [stderr] | Nov 16 21:09:27.279 INFO [stderr] 102 | env::home_dir() Nov 16 21:09:27.279 INFO [stderr] | ^^^^^^^^^^^^^ Nov 16 21:09:27.279 INFO [stderr] | Nov 16 21:09:27.279 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 16 21:09:27.279 INFO [stderr] Nov 16 21:09:38.272 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 42.37s Nov 16 21:09:38.284 INFO [stderr] su: No module specific data is present Nov 16 21:09:38.536 INFO running `"docker" "inspect" "64a2e3c9811396deda00bfbb6a10ab93650c35a0bc2dd5095c9578cf269be245"` Nov 16 21:09:38.628 INFO running `"docker" "rm" "-f" "64a2e3c9811396deda00bfbb6a10ab93650c35a0bc2dd5095c9578cf269be245"` Nov 16 21:09:38.718 INFO [stdout] 64a2e3c9811396deda00bfbb6a10ab93650c35a0bc2dd5095c9578cf269be245 Nov 16 21:09:38.722 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"` Nov 16 21:09:38.907 INFO [stdout] bee25f365ab0ed3ebf2a9b18c8bc6ca33334c4d2030679a278618c319ff4cc11 Nov 16 21:09:38.913 INFO running `"docker" "start" "-a" "bee25f365ab0ed3ebf2a9b18c8bc6ca33334c4d2030679a278618c319ff4cc11"` Nov 16 21:09:39.291 INFO [stderr] usermod: no changes Nov 16 21:09:39.319 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:09:39.454 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Nov 16 21:09:40.888 INFO [stderr] warning: unused macro definition Nov 16 21:09:40.888 INFO [stderr] --> src/util.rs:9:1 Nov 16 21:09:40.888 INFO [stderr] | Nov 16 21:09:40.888 INFO [stderr] 9 | / macro_rules! io_err { Nov 16 21:09:40.888 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Nov 16 21:09:40.888 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Nov 16 21:09:40.888 INFO [stderr] 12 | | } Nov 16 21:09:40.888 INFO [stderr] | |_^ Nov 16 21:09:40.888 INFO [stderr] | Nov 16 21:09:40.888 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:40.888 INFO [stderr] Nov 16 21:09:40.888 INFO [stderr] warning: unused macro definition Nov 16 21:09:40.888 INFO [stderr] --> tests/encrypt.rs:39:1 Nov 16 21:09:40.888 INFO [stderr] | Nov 16 21:09:40.888 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt { Nov 16 21:09:40.888 INFO [stderr] 40 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) ) Nov 16 21:09:40.888 INFO [stderr] 41 | | } Nov 16 21:09:40.888 INFO [stderr] | |_^ Nov 16 21:09:40.888 INFO [stderr] | Nov 16 21:09:40.888 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:40.888 INFO [stderr] Nov 16 21:09:40.888 INFO [stderr] warning: unused macro definition Nov 16 21:09:40.888 INFO [stderr] --> tests/encrypt.rs:43:1 Nov 16 21:09:40.888 INFO [stderr] | Nov 16 21:09:40.888 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt { Nov 16 21:09:40.888 INFO [stderr] 44 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) ) Nov 16 21:09:40.888 INFO [stderr] 45 | | } Nov 16 21:09:40.888 INFO [stderr] | |_^ Nov 16 21:09:40.888 INFO [stderr] Nov 16 21:09:40.991 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. Nov 16 21:09:40.991 INFO [stderr] --> src/config/running_config.rs:102:9 Nov 16 21:09:40.991 INFO [stderr] | Nov 16 21:09:40.991 INFO [stderr] 102 | env::home_dir() Nov 16 21:09:40.991 INFO [stderr] | ^^^^^^^^^^^^^ Nov 16 21:09:40.991 INFO [stderr] | Nov 16 21:09:40.991 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 16 21:09:40.991 INFO [stderr] Nov 16 21:09:44.923 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.61s Nov 16 21:09:44.943 INFO [stderr] su: No module specific data is present Nov 16 21:09:45.264 INFO running `"docker" "inspect" "bee25f365ab0ed3ebf2a9b18c8bc6ca33334c4d2030679a278618c319ff4cc11"` Nov 16 21:09:45.360 INFO running `"docker" "rm" "-f" "bee25f365ab0ed3ebf2a9b18c8bc6ca33334c4d2030679a278618c319ff4cc11"` Nov 16 21:09:45.497 INFO [stdout] bee25f365ab0ed3ebf2a9b18c8bc6ca33334c4d2030679a278618c319ff4cc11 Nov 16 21:09:45.501 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"` Nov 16 21:09:45.838 INFO [stdout] 3bd15fad87897f1a7a7a6455131259d67f0ed367c9297589d0cc751b0fc39c93 Nov 16 21:09:45.843 INFO running `"docker" "start" "-a" "3bd15fad87897f1a7a7a6455131259d67f0ed367c9297589d0cc751b0fc39c93"` Nov 16 21:09:46.467 INFO [stderr] usermod: no changes Nov 16 21:09:46.494 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:09:46.624 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.14s Nov 16 21:09:46.648 INFO [stderr] Running /target/debug/deps/shadowsocks-2a942670eb7126eb Nov 16 21:09:46.650 INFO [stdout] Nov 16 21:09:46.650 INFO [stdout] running 3 tests Nov 16 21:09:46.650 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored Nov 16 21:09:46.653 INFO [stdout] test asyncdns::test::parse_response ... ok Nov 16 21:09:46.736 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED Nov 16 21:09:46.736 INFO [stdout] Nov 16 21:09:46.736 INFO [stdout] failures: Nov 16 21:09:46.736 INFO [stdout] Nov 16 21:09:46.736 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ---- Nov 16 21:09:46.736 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") } Nov 16 21:09:46.736 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21 Nov 16 21:09:46.737 INFO [stdout] stack backtrace: Nov 16 21:09:46.737 INFO [stdout] 0: 0x55cfaa718f03 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h26df0f6fdb27c709 Nov 16 21:09:46.737 INFO [stdout] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 16 21:09:46.737 INFO [stdout] 1: 0x55cfaa7134d8 - std::sys_common::backtrace::_print::h84c40fecda6d638e Nov 16 21:09:46.737 INFO [stdout] at libstd/sys_common/backtrace.rs:71 Nov 16 21:09:46.737 INFO [stdout] 2: 0x55cfaa717be4 - std::panicking::default_hook::{{closure}}::h77e370c0567fc0a7 Nov 16 21:09:46.737 INFO [stdout] at libstd/sys_common/backtrace.rs:59 Nov 16 21:09:46.737 INFO [stdout] at libstd/panicking.rs:211 Nov 16 21:09:46.737 INFO [stdout] 3: 0x55cfaa7178cc - std::panicking::default_hook::hb9a45c11a33bbcb2 Nov 16 21:09:46.737 INFO [stdout] at libstd/panicking.rs:221 Nov 16 21:09:46.737 INFO [stdout] 4: 0x55cfaa7182f0 - std::panicking::rust_panic_with_hook::ha8d95048d940f754 Nov 16 21:09:46.737 INFO [stdout] at libstd/panicking.rs:476 Nov 16 21:09:46.737 INFO [stdout] 5: 0x55cfaa704c37 - std::panicking::begin_panic::hf29bbe6e30c976cf Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:410 Nov 16 21:09:46.737 INFO [stdout] 6: 0x55cfaa59031b - shadowsocks::asyncdns::test::test_block_resolve::h73e5373948c7eb96 Nov 16 21:09:46.737 INFO [stdout] at src/asyncdns.rs:784 Nov 16 21:09:46.737 INFO [stdout] 7: 0x55cfaa5905d7 - shadowsocks::asyncdns::test::ipv4_block_resolve::h14e2863c6c92c2ca Nov 16 21:09:46.737 INFO [stdout] at src/asyncdns.rs:792 Nov 16 21:09:46.737 INFO [stdout] 8: 0x55cfaa5905b9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::hb16092974a2703b5 Nov 16 21:09:46.737 INFO [stdout] at src/asyncdns.rs:791 Nov 16 21:09:46.737 INFO [stdout] 9: 0x55cfaa5819dd - core::ops::function::FnOnce::call_once::h14d7859c7609e732 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238 Nov 16 21:09:46.737 INFO [stdout] 10: 0x55cfaa5ac0ce - >::call_box::hae1ea457a0ccd8d5 Nov 16 21:09:46.737 INFO [stdout] at libtest/lib.rs:1471 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libcore/ops/function.rs:238 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673 Nov 16 21:09:46.737 INFO [stdout] 11: 0x55cfaa726f09 - __rust_maybe_catch_panic Nov 16 21:09:46.737 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 16 21:09:46.737 INFO [stdout] 12: 0x55cfaa5a3f23 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd0ded23d435d70d0 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398 Nov 16 21:09:46.737 INFO [stdout] at libtest/lib.rs:1426 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/sys_common/backtrace.rs:136 Nov 16 21:09:46.737 INFO [stdout] 13: 0x55cfaa5a4dc4 - std::panicking::try::do_call::h031514fb401b7159 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:477 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:319 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:310 Nov 16 21:09:46.737 INFO [stdout] 14: 0x55cfaa726f09 - __rust_maybe_catch_panic Nov 16 21:09:46.737 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 16 21:09:46.737 INFO [stdout] 15: 0x55cfaa5abfac - >::call_box::h44700b7a2ec6fd33 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panicking.rs:289 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/panic.rs:398 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/libstd/thread/mod.rs:476 Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:673 Nov 16 21:09:46.737 INFO [stdout] 16: 0x55cfaa71adcd - std::sys::unix::thread::Thread::new::thread_start::h8526625243d2f13b Nov 16 21:09:46.737 INFO [stdout] at /rustc/2e026d6e5d7621634147ad4c8074f1d964dbb1be/src/liballoc/boxed.rs:683 Nov 16 21:09:46.737 INFO [stdout] at libstd/sys_common/thread.rs:24 Nov 16 21:09:46.737 INFO [stdout] at libstd/sys/unix/thread.rs:90 Nov 16 21:09:46.737 INFO [stdout] 17: 0x7fcf92c4f6b9 - start_thread Nov 16 21:09:46.737 INFO [stdout] 18: 0x7fcf9276f41c - clone Nov 16 21:09:46.737 INFO [stdout] 19: 0x0 - Nov 16 21:09:46.737 INFO [stdout] Nov 16 21:09:46.737 INFO [stdout] Nov 16 21:09:46.737 INFO [stdout] failures: Nov 16 21:09:46.737 INFO [stdout] asyncdns::test::ipv4_block_resolve Nov 16 21:09:46.737 INFO [stdout] Nov 16 21:09:46.737 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out Nov 16 21:09:46.737 INFO [stdout] Nov 16 21:09:46.740 INFO [stderr] error: test failed, to rerun pass '--lib' Nov 16 21:09:46.743 INFO [stderr] su: No module specific data is present Nov 16 21:09:47.000 INFO running `"docker" "inspect" "3bd15fad87897f1a7a7a6455131259d67f0ed367c9297589d0cc751b0fc39c93"` Nov 16 21:09:47.152 INFO running `"docker" "rm" "-f" "3bd15fad87897f1a7a7a6455131259d67f0ed367c9297589d0cc751b0fc39c93"` Nov 16 21:09:47.367 INFO [stdout] 3bd15fad87897f1a7a7a6455131259d67f0ed367c9297589d0cc751b0fc39c93