Nov 16 21:08:58.507 INFO testing loggerhead/shadowsocks-rust against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 16 21:08:58.509 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:58.945 INFO [stdout] 28dd5cb98ca19e012c9d16adbef626ceef2b85e4e0110f59a1ae950c03f8e918 Nov 16 21:08:58.951 INFO running `"docker" "start" "-a" "28dd5cb98ca19e012c9d16adbef626ceef2b85e4e0110f59a1ae950c03f8e918"` Nov 16 21:09:00.571 INFO [stderr] usermod: no changes Nov 16 21:09:00.603 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:09:00.812 INFO [stderr] Compiling slog v1.7.1 Nov 16 21:09:00.819 INFO [stderr] Compiling lru_time_cache v0.5.0 Nov 16 21:09:00.819 INFO [stderr] Compiling memchr v0.1.11 Nov 16 21:09:00.819 INFO [stderr] Compiling isatty v0.1.6 Nov 16 21:09:00.819 INFO [stderr] Compiling term_size v0.2.3 Nov 16 21:09:00.819 INFO [stderr] Compiling sig v1.0.0 Nov 16 21:09:00.819 INFO [stderr] Compiling daemonize v0.2.3 Nov 16 21:09:00.819 INFO [stderr] Compiling net2 v0.2.31 Nov 16 21:09:02.375 INFO [stderr] Compiling nix v0.5.1 Nov 16 21:09:02.567 INFO [stderr] Compiling rust-crypto v0.2.36 Nov 16 21:09:02.629 INFO [stderr] Compiling thread-id v2.0.0 Nov 16 21:09:02.817 INFO [stderr] Compiling chrono v0.2.25 Nov 16 21:09:03.188 INFO [stderr] Compiling clap v2.18.0 Nov 16 21:09:05.085 INFO [stderr] Compiling aho-corasick v0.5.3 Nov 16 21:09:05.093 INFO [stderr] Compiling thread_local v0.2.7 Nov 16 21:09:05.137 INFO [stderr] Compiling miow v0.1.5 Nov 16 21:09:05.161 INFO [stderr] Compiling slog-extra v0.1.2 Nov 16 21:09:06.613 INFO [stderr] Compiling slog-scope v0.2.2 Nov 16 21:09:11.295 INFO [stderr] Compiling regex v0.1.80 Nov 16 21:09:11.615 INFO [stderr] Compiling slog-stream v1.2.1 Nov 16 21:09:12.764 INFO [stderr] Compiling slog-term v1.3.5 Nov 16 21:09:16.447 INFO [stderr] Compiling mio v0.5.1 Nov 16 21:09:29.695 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Nov 16 21:09:31.011 INFO [stderr] warning: unused macro definition Nov 16 21:09:31.011 INFO [stderr] --> src/util.rs:9:1 Nov 16 21:09:31.011 INFO [stderr] | Nov 16 21:09:31.011 INFO [stderr] 9 | / macro_rules! io_err { Nov 16 21:09:31.011 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Nov 16 21:09:31.011 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Nov 16 21:09:31.011 INFO [stderr] 12 | | } Nov 16 21:09:31.011 INFO [stderr] | |_^ Nov 16 21:09:31.011 INFO [stderr] | Nov 16 21:09:31.011 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:31.011 INFO [stderr] Nov 16 21:09:31.087 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:31.087 INFO [stderr] --> src/config/running_config.rs:102:9 Nov 16 21:09:31.087 INFO [stderr] | Nov 16 21:09:31.087 INFO [stderr] 102 | env::home_dir() Nov 16 21:09:31.087 INFO [stderr] | ^^^^^^^^^^^^^ Nov 16 21:09:31.087 INFO [stderr] | Nov 16 21:09:31.087 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 16 21:09:31.087 INFO [stderr] Nov 16 21:09:39.723 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 39.13s Nov 16 21:09:39.754 INFO [stderr] su: No module specific data is present Nov 16 21:09:40.521 INFO running `"docker" "inspect" "28dd5cb98ca19e012c9d16adbef626ceef2b85e4e0110f59a1ae950c03f8e918"` Nov 16 21:09:40.805 INFO running `"docker" "rm" "-f" "28dd5cb98ca19e012c9d16adbef626ceef2b85e4e0110f59a1ae950c03f8e918"` Nov 16 21:09:41.135 INFO [stdout] 28dd5cb98ca19e012c9d16adbef626ceef2b85e4e0110f59a1ae950c03f8e918 Nov 16 21:09:41.139 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:41.606 INFO [stdout] 1174f7056813806a5d6eb2e880eeeacbd250e2c3317ed7d8714d5fee58ff82b1 Nov 16 21:09:41.617 INFO running `"docker" "start" "-a" "1174f7056813806a5d6eb2e880eeeacbd250e2c3317ed7d8714d5fee58ff82b1"` Nov 16 21:09:42.196 INFO [stderr] usermod: no changes Nov 16 21:09:42.220 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:09:42.338 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Nov 16 21:09:43.770 INFO [stderr] warning: unused macro definition Nov 16 21:09:43.770 INFO [stderr] --> src/util.rs:9:1 Nov 16 21:09:43.770 INFO [stderr] | Nov 16 21:09:43.770 INFO [stderr] 9 | / macro_rules! io_err { Nov 16 21:09:43.770 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Nov 16 21:09:43.770 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Nov 16 21:09:43.770 INFO [stderr] 12 | | } Nov 16 21:09:43.770 INFO [stderr] | |_^ Nov 16 21:09:43.770 INFO [stderr] | Nov 16 21:09:43.770 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:43.770 INFO [stderr] Nov 16 21:09:43.871 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:43.871 INFO [stderr] --> src/config/running_config.rs:102:9 Nov 16 21:09:43.871 INFO [stderr] | Nov 16 21:09:43.871 INFO [stderr] 102 | env::home_dir() Nov 16 21:09:43.871 INFO [stderr] | ^^^^^^^^^^^^^ Nov 16 21:09:43.871 INFO [stderr] | Nov 16 21:09:43.871 INFO [stderr] = note: #[warn(deprecated)] on by default Nov 16 21:09:43.871 INFO [stderr] Nov 16 21:09:43.895 INFO [stderr] warning: unused macro definition Nov 16 21:09:43.895 INFO [stderr] --> tests/encrypt.rs:39:1 Nov 16 21:09:43.895 INFO [stderr] | Nov 16 21:09:43.895 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt { Nov 16 21:09:43.895 INFO [stderr] 40 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) ) Nov 16 21:09:43.895 INFO [stderr] 41 | | } Nov 16 21:09:43.895 INFO [stderr] | |_^ Nov 16 21:09:43.895 INFO [stderr] | Nov 16 21:09:43.895 INFO [stderr] = note: #[warn(unused_macros)] on by default Nov 16 21:09:43.895 INFO [stderr] Nov 16 21:09:43.895 INFO [stderr] warning: unused macro definition Nov 16 21:09:43.895 INFO [stderr] --> tests/encrypt.rs:43:1 Nov 16 21:09:43.895 INFO [stderr] | Nov 16 21:09:43.895 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt { Nov 16 21:09:43.895 INFO [stderr] 44 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) ) Nov 16 21:09:43.895 INFO [stderr] 45 | | } Nov 16 21:09:43.895 INFO [stderr] | |_^ Nov 16 21:09:43.895 INFO [stderr] Nov 16 21:09:47.267 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.04s Nov 16 21:09:47.286 INFO [stderr] su: No module specific data is present Nov 16 21:09:47.600 INFO running `"docker" "inspect" "1174f7056813806a5d6eb2e880eeeacbd250e2c3317ed7d8714d5fee58ff82b1"` Nov 16 21:09:47.675 INFO running `"docker" "rm" "-f" "1174f7056813806a5d6eb2e880eeeacbd250e2c3317ed7d8714d5fee58ff82b1"` Nov 16 21:09:47.796 INFO [stdout] 1174f7056813806a5d6eb2e880eeeacbd250e2c3317ed7d8714d5fee58ff82b1 Nov 16 21:09:47.799 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:47.955 INFO [stdout] 3fcc9d1609c77feb224d31db6f8a690ca266a07a2024215c7cd72dbdae0bdf6e Nov 16 21:09:47.960 INFO running `"docker" "start" "-a" "3fcc9d1609c77feb224d31db6f8a690ca266a07a2024215c7cd72dbdae0bdf6e"` Nov 16 21:09:48.392 INFO [stderr] usermod: no changes Nov 16 21:09:48.424 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Nov 16 21:09:48.539 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.13s Nov 16 21:09:48.559 INFO [stderr] Running /target/debug/deps/shadowsocks-de1ea24a1e26c58c Nov 16 21:09:48.561 INFO [stdout] Nov 16 21:09:48.561 INFO [stdout] running 3 tests Nov 16 21:09:48.562 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored Nov 16 21:09:48.567 INFO [stdout] test asyncdns::test::parse_response ... ok Nov 16 21:09:48.660 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED Nov 16 21:09:48.660 INFO [stdout] Nov 16 21:09:48.660 INFO [stdout] failures: Nov 16 21:09:48.661 INFO [stdout] Nov 16 21:09:48.661 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ---- Nov 16 21:09:48.661 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") } Nov 16 21:09:48.661 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21 Nov 16 21:09:48.662 INFO [stdout] stack backtrace: Nov 16 21:09:48.662 INFO [stdout] 0: 0x558829ccf053 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8 Nov 16 21:09:48.662 INFO [stdout] at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Nov 16 21:09:48.663 INFO [stdout] 1: 0x558829cc94e8 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb Nov 16 21:09:48.663 INFO [stdout] at libstd/sys_common/backtrace.rs:71 Nov 16 21:09:48.665 INFO [stdout] 2: 0x558829ccdd34 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097 Nov 16 21:09:48.666 INFO [stdout] at libstd/sys_common/backtrace.rs:59 Nov 16 21:09:48.666 INFO [stdout] at libstd/panicking.rs:211 Nov 16 21:09:48.666 INFO [stdout] 3: 0x558829ccda1c - std::panicking::default_hook::h98c2a01ef734e4b0 Nov 16 21:09:48.667 INFO [stdout] at libstd/panicking.rs:221 Nov 16 21:09:48.667 INFO [stdout] 4: 0x558829cce440 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752 Nov 16 21:09:48.667 INFO [stdout] at libstd/panicking.rs:476 Nov 16 21:09:48.668 INFO [stdout] 5: 0x558829cad677 - std::panicking::begin_panic::hf00942386ddc0c19 Nov 16 21:09:48.668 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410 Nov 16 21:09:48.668 INFO [stdout] 6: 0x558829b4168b - shadowsocks::asyncdns::test::test_block_resolve::hc8c4063258a64bf6 Nov 16 21:09:48.669 INFO [stdout] at src/asyncdns.rs:784 Nov 16 21:09:48.669 INFO [stdout] 7: 0x558829b41947 - shadowsocks::asyncdns::test::ipv4_block_resolve::he0319ef03342a37b Nov 16 21:09:48.669 INFO [stdout] at src/asyncdns.rs:792 Nov 16 21:09:48.669 INFO [stdout] 8: 0x558829b41929 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::h48a40eb4ab1d2204 Nov 16 21:09:48.669 INFO [stdout] at src/asyncdns.rs:791 Nov 16 21:09:48.669 INFO [stdout] 9: 0x558829b32d6d - core::ops::function::FnOnce::call_once::h41f4672c7d46da61 Nov 16 21:09:48.669 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 16 21:09:48.670 INFO [stdout] 10: 0x558829b5d24e - >::call_box::h9dcb7fc9b8bb4240 Nov 16 21:09:48.670 INFO [stdout] at libtest/lib.rs:1471 Nov 16 21:09:48.670 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238 Nov 16 21:09:48.670 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 16 21:09:48.670 INFO [stdout] 11: 0x558829cdcf49 - __rust_maybe_catch_panic Nov 16 21:09:48.670 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 16 21:09:48.671 INFO [stdout] 12: 0x558829b552b3 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86 Nov 16 21:09:48.671 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 16 21:09:48.671 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 16 21:09:48.671 INFO [stdout] at libtest/lib.rs:1426 Nov 16 21:09:48.671 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136 Nov 16 21:09:48.671 INFO [stdout] 13: 0x558829b56164 - std::panicking::try::do_call::h4132042e0cbea3ae Nov 16 21:09:48.672 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477 Nov 16 21:09:48.672 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319 Nov 16 21:09:48.672 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310 Nov 16 21:09:48.672 INFO [stdout] 14: 0x558829cdcf49 - __rust_maybe_catch_panic Nov 16 21:09:48.672 INFO [stdout] at libpanic_unwind/lib.rs:102 Nov 16 21:09:48.672 INFO [stdout] 15: 0x558829b5d37c - >::call_box::hab7f616f05880bf6 Nov 16 21:09:48.673 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289 Nov 16 21:09:48.673 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398 Nov 16 21:09:48.673 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476 Nov 16 21:09:48.673 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673 Nov 16 21:09:48.673 INFO [stdout] 16: 0x558829cd0e0d - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038 Nov 16 21:09:48.673 INFO [stdout] at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683 Nov 16 21:09:48.673 INFO [stdout] at libstd/sys_common/thread.rs:24 Nov 16 21:09:48.674 INFO [stdout] at libstd/sys/unix/thread.rs:90 Nov 16 21:09:48.674 INFO [stdout] 17: 0x7f99248dd6b9 - start_thread Nov 16 21:09:48.674 INFO [stdout] 18: 0x7f99243fd41c - clone Nov 16 21:09:48.674 INFO [stdout] 19: 0x0 - Nov 16 21:09:48.674 INFO [stdout] Nov 16 21:09:48.674 INFO [stdout] Nov 16 21:09:48.675 INFO [stdout] failures: Nov 16 21:09:48.675 INFO [stdout] asyncdns::test::ipv4_block_resolve Nov 16 21:09:48.675 INFO [stdout] Nov 16 21:09:48.675 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out Nov 16 21:09:48.675 INFO [stdout] Nov 16 21:09:48.675 INFO [stderr] error: test failed, to rerun pass '--lib' Nov 16 21:09:48.676 INFO [stderr] su: No module specific data is present Nov 16 21:09:49.054 INFO running `"docker" "inspect" "3fcc9d1609c77feb224d31db6f8a690ca266a07a2024215c7cd72dbdae0bdf6e"` Nov 16 21:09:49.169 INFO running `"docker" "rm" "-f" "3fcc9d1609c77feb224d31db6f8a690ca266a07a2024215c7cd72dbdae0bdf6e"` Nov 16 21:09:49.247 INFO [stdout] 3fcc9d1609c77feb224d31db6f8a690ca266a07a2024215c7cd72dbdae0bdf6e