Dec 13 12:30:50.775 INFO testing loggerhead/shadowsocks-rust against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 13 12:30:50.775 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:51.199 INFO [stdout] a864ca08e8b0aaedaf2eb79f492eb983bb31f885b12e3fe65b9de65b83f7d803 Dec 13 12:30:51.201 INFO running `"docker" "start" "-a" "a864ca08e8b0aaedaf2eb79f492eb983bb31f885b12e3fe65b9de65b83f7d803"` Dec 13 12:30:53.263 INFO [stderr] usermod: no changes Dec 13 12:30:53.331 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:30:53.559 INFO [stderr] Compiling slog v1.7.1 Dec 13 12:30:53.560 INFO [stderr] Compiling try_opt v0.1.1 Dec 13 12:30:53.560 INFO [stderr] Compiling lru_time_cache v0.5.0 Dec 13 12:30:53.623 INFO [stderr] Compiling net2 v0.2.31 Dec 13 12:30:53.627 INFO [stderr] Compiling term_size v0.2.3 Dec 13 12:30:53.632 INFO [stderr] Compiling daemonize v0.2.3 Dec 13 12:30:53.632 INFO [stderr] Compiling sig v1.0.0 Dec 13 12:30:53.643 INFO [stderr] Compiling toml v0.1.30 Dec 13 12:30:55.299 INFO [stderr] Compiling rust-crypto v0.2.36 Dec 13 12:30:55.915 INFO [stderr] Compiling chrono v0.2.25 Dec 13 12:30:56.019 INFO [stderr] Compiling clap v2.18.0 Dec 13 12:30:58.786 INFO [stderr] Compiling slog-extra v0.1.2 Dec 13 12:30:58.787 INFO [stderr] Compiling slog-scope v0.2.2 Dec 13 12:31:00.728 INFO [stderr] Compiling miow v0.1.5 Dec 13 12:31:02.712 INFO [stderr] Compiling mio v0.5.1 Dec 13 12:31:07.484 INFO [stderr] Compiling slog-stream v1.2.1 Dec 13 12:31:10.259 INFO [stderr] Compiling slog-term v1.3.5 Dec 13 12:31:31.407 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 13 12:31:33.199 INFO [stderr] warning: unused macro definition Dec 13 12:31:33.199 INFO [stderr] --> src/util.rs:9:1 Dec 13 12:31:33.199 INFO [stderr] | Dec 13 12:31:33.199 INFO [stderr] 9 | / macro_rules! io_err { Dec 13 12:31:33.199 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 13 12:31:33.199 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 13 12:31:33.199 INFO [stderr] 12 | | } Dec 13 12:31:33.199 INFO [stderr] | |_^ Dec 13 12:31:33.199 INFO [stderr] | Dec 13 12:31:33.199 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:33.199 INFO [stderr] Dec 13 12:31:33.303 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:33.303 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 13 12:31:33.303 INFO [stderr] | Dec 13 12:31:33.304 INFO [stderr] 102 | env::home_dir() Dec 13 12:31:33.304 INFO [stderr] | ^^^^^^^^^^^^^ Dec 13 12:31:33.304 INFO [stderr] | Dec 13 12:31:33.305 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 13 12:31:33.305 INFO [stderr] Dec 13 12:31:48.011 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 54.72s Dec 13 12:31:48.031 INFO [stderr] su: No module specific data is present Dec 13 12:31:48.703 INFO running `"docker" "inspect" "a864ca08e8b0aaedaf2eb79f492eb983bb31f885b12e3fe65b9de65b83f7d803"` Dec 13 12:31:49.051 INFO running `"docker" "rm" "-f" "a864ca08e8b0aaedaf2eb79f492eb983bb31f885b12e3fe65b9de65b83f7d803"` Dec 13 12:31:49.367 INFO [stdout] a864ca08e8b0aaedaf2eb79f492eb983bb31f885b12e3fe65b9de65b83f7d803 Dec 13 12:31:49.375 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.884 INFO [stdout] e346632dba1cce900425254325fce82c42412611cb6de50099ae7579d2a8b03d Dec 13 12:31:49.887 INFO running `"docker" "start" "-a" "e346632dba1cce900425254325fce82c42412611cb6de50099ae7579d2a8b03d"` Dec 13 12:31:51.326 INFO [stderr] usermod: no changes Dec 13 12:31:51.406 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:31:51.641 INFO [stderr] Compiling shadowsocks v0.6.2 (/source) Dec 13 12:31:54.011 INFO [stderr] warning: unused macro definition Dec 13 12:31:54.011 INFO [stderr] --> tests/encrypt.rs:39:1 Dec 13 12:31:54.011 INFO [stderr] | Dec 13 12:31:54.015 INFO [stderr] 39 | / macro_rules! assert_raw_encrypt { Dec 13 12:31:54.015 INFO [stderr] 40 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_encrypt, $data) ) Dec 13 12:31:54.015 INFO [stderr] 41 | | } Dec 13 12:31:54.015 INFO [stderr] | |_^ Dec 13 12:31:54.015 INFO [stderr] | Dec 13 12:31:54.016 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:54.016 INFO [stderr] Dec 13 12:31:54.016 INFO [stderr] warning: unused macro definition Dec 13 12:31:54.016 INFO [stderr] --> tests/encrypt.rs:43:1 Dec 13 12:31:54.017 INFO [stderr] | Dec 13 12:31:54.017 INFO [stderr] 43 | / macro_rules! assert_raw_decrypt { Dec 13 12:31:54.017 INFO [stderr] 44 | | ($cryptor:expr, $data:expr) => ( assert_do!($cryptor, raw_decrypt, $data) ) Dec 13 12:31:54.017 INFO [stderr] 45 | | } Dec 13 12:31:54.017 INFO [stderr] | |_^ Dec 13 12:31:54.017 INFO [stderr] Dec 13 12:31:55.088 INFO [stderr] warning: unused macro definition Dec 13 12:31:55.089 INFO [stderr] --> src/util.rs:9:1 Dec 13 12:31:55.089 INFO [stderr] | Dec 13 12:31:55.089 INFO [stderr] 9 | / macro_rules! io_err { Dec 13 12:31:55.089 INFO [stderr] 10 | | ($desc:expr) => ( io::Error::new(io::ErrorKind::Other, $desc) ); Dec 13 12:31:55.090 INFO [stderr] 11 | | ($fmt:expr, $($arg:tt)*) => ( io::Error::new(io::ErrorKind::Other, format!($fmt, $($arg)*)) ); Dec 13 12:31:55.090 INFO [stderr] 12 | | } Dec 13 12:31:55.090 INFO [stderr] | |_^ Dec 13 12:31:55.090 INFO [stderr] | Dec 13 12:31:55.091 INFO [stderr] = note: #[warn(unused_macros)] on by default Dec 13 12:31:55.091 INFO [stderr] Dec 13 12:31:55.311 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:55.311 INFO [stderr] --> src/config/running_config.rs:102:9 Dec 13 12:31:55.311 INFO [stderr] | Dec 13 12:31:55.312 INFO [stderr] 102 | env::home_dir() Dec 13 12:31:55.312 INFO [stderr] | ^^^^^^^^^^^^^ Dec 13 12:31:55.312 INFO [stderr] | Dec 13 12:31:55.312 INFO [stderr] = note: #[warn(deprecated)] on by default Dec 13 12:31:55.312 INFO [stderr] Dec 13 12:32:01.063 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 9.70s Dec 13 12:32:01.075 INFO [stderr] su: No module specific data is present Dec 13 12:32:01.477 INFO running `"docker" "inspect" "e346632dba1cce900425254325fce82c42412611cb6de50099ae7579d2a8b03d"` Dec 13 12:32:01.637 INFO running `"docker" "rm" "-f" "e346632dba1cce900425254325fce82c42412611cb6de50099ae7579d2a8b03d"` Dec 13 12:32:01.805 INFO [stdout] e346632dba1cce900425254325fce82c42412611cb6de50099ae7579d2a8b03d Dec 13 12:32:01.808 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:02.038 INFO [stdout] 2a571273d7b65a0acc0a6a60885ca70d01b824f731acf2c167fa6847d46d951c Dec 13 12:32:02.040 INFO running `"docker" "start" "-a" "2a571273d7b65a0acc0a6a60885ca70d01b824f731acf2c167fa6847d46d951c"` Dec 13 12:32:02.669 INFO [stderr] usermod: no changes Dec 13 12:32:02.730 INFO [stderr] warning: file found to be present in multiple build targets: /source/src/bin/main.rs Dec 13 12:32:02.903 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.19s Dec 13 12:32:02.920 INFO [stderr] Running /target/debug/deps/shadowsocks-9719a0fe7cb5c579 Dec 13 12:32:02.931 INFO [stdout] Dec 13 12:32:02.931 INFO [stdout] running 3 tests Dec 13 12:32:02.931 INFO [stdout] test asyncdns::test::ipv6_block_resolve ... ignored Dec 13 12:32:02.931 INFO [stdout] test asyncdns::test::parse_response ... ok Dec 13 12:32:03.081 INFO [stdout] test asyncdns::test::ipv4_block_resolve ... FAILED Dec 13 12:32:03.081 INFO [stdout] Dec 13 12:32:03.081 INFO [stdout] failures: Dec 13 12:32:03.081 INFO [stdout] Dec 13 12:32:03.081 INFO [stdout] ---- asyncdns::test::ipv4_block_resolve stdout ---- Dec 13 12:32:03.081 INFO [stdout] block_resolve failed: Custom { kind: Other, error: StringError("failed to lookup address information: Temporary failure in name resolution") } Dec 13 12:32:03.081 INFO [stdout] thread 'asyncdns::test::ipv4_block_resolve' panicked at 'assertion failed: false', src/asyncdns.rs:784:21 Dec 13 12:32:03.081 INFO [stdout] stack backtrace: Dec 13 12:32:03.081 INFO [stdout] 0: 0x563f8e44d153 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 12:32:03.081 INFO [stdout] 1: 0x563f8e4471d8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 12:32:03.081 INFO [stdout] 2: 0x563f8e44b9b2 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 12:32:03.081 INFO [stdout] 3: 0x563f8e44b6a7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 12:32:03.081 INFO [stdout] 4: 0x563f8e44c0c0 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 12:32:03.081 INFO [stdout] 5: 0x563f8e428cb7 - std::panicking::begin_panic::h240205cc5b297a27 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421 Dec 13 12:32:03.081 INFO [stdout] 6: 0x563f8e2b7a37 - shadowsocks::asyncdns::test::test_block_resolve::h568585ffa82743de Dec 13 12:32:03.081 INFO [stdout] at src/asyncdns.rs:784 Dec 13 12:32:03.081 INFO [stdout] 7: 0x563f8e2b7ce7 - shadowsocks::asyncdns::test::ipv4_block_resolve::h15e63c96a460810f Dec 13 12:32:03.081 INFO [stdout] at src/asyncdns.rs:792 Dec 13 12:32:03.081 INFO [stdout] 8: 0x563f8e2b7cc9 - shadowsocks::asyncdns::test::ipv4_block_resolve::{{closure}}::ha73febb4639177a9 Dec 13 12:32:03.081 INFO [stdout] at src/asyncdns.rs:791 Dec 13 12:32:03.081 INFO [stdout] 9: 0x563f8e2a86dd - core::ops::function::FnOnce::call_once::h9a688636f1d3e034 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 12:32:03.081 INFO [stdout] 10: 0x563f8e2d23fe - >::call_box::h3cf534772c089f15 Dec 13 12:32:03.081 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 12:32:03.081 INFO [stdout] 11: 0x563f8e45af19 - __rust_maybe_catch_panic Dec 13 12:32:03.081 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 12:32:03.081 INFO [stdout] 12: 0x563f8e2efda7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 12:32:03.081 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 12:32:03.081 INFO [stdout] 13: 0x563f8e2cacb4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 13 12:32:03.081 INFO [stdout] 14: 0x563f8e2cb324 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 13 12:32:03.081 INFO [stdout] 15: 0x563f8e45af19 - __rust_maybe_catch_panic Dec 13 12:32:03.081 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 12:32:03.081 INFO [stdout] 16: 0x563f8e2d22dc - >::call_box::h260fd1ad8e2854a6 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 12:32:03.081 INFO [stdout] 17: 0x563f8e44eedd - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 13 12:32:03.081 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 12:32:03.081 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 12:32:03.081 INFO [stdout] 18: 0x7effb873b6b9 - start_thread Dec 13 12:32:03.081 INFO [stdout] 19: 0x7effb825b41c - clone Dec 13 12:32:03.081 INFO [stdout] 20: 0x0 - Dec 13 12:32:03.081 INFO [stdout] Dec 13 12:32:03.081 INFO [stdout] Dec 13 12:32:03.081 INFO [stdout] failures: Dec 13 12:32:03.081 INFO [stdout] asyncdns::test::ipv4_block_resolve Dec 13 12:32:03.081 INFO [stdout] Dec 13 12:32:03.081 INFO [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out Dec 13 12:32:03.082 INFO [stdout] Dec 13 12:32:03.087 INFO [stderr] error: test failed, to rerun pass '--lib' Dec 13 12:32:03.087 INFO [stderr] su: No module specific data is present Dec 13 12:32:03.981 INFO running `"docker" "inspect" "2a571273d7b65a0acc0a6a60885ca70d01b824f731acf2c167fa6847d46d951c"` Dec 13 12:32:04.349 INFO running `"docker" "rm" "-f" "2a571273d7b65a0acc0a6a60885ca70d01b824f731acf2c167fa6847d46d951c"` Dec 13 12:32:04.607 INFO [stdout] 2a571273d7b65a0acc0a6a60885ca70d01b824f731acf2c167fa6847d46d951c