Dec 15 04:41:26.066 INFO testing mio-0.6.16 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 04:41:26.067 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 04:41:26.321 INFO [stdout] 1f7e844c89b37f5f17ae451486760d9f3efb9355296af019e386818773d5c176 Dec 15 04:41:26.323 INFO running `"docker" "start" "-a" "1f7e844c89b37f5f17ae451486760d9f3efb9355296af019e386818773d5c176"` Dec 15 04:41:26.767 INFO [stderr] usermod: no changes Dec 15 04:41:26.827 INFO [stderr] Compiling mio v0.6.16 (/source) Dec 15 04:41:29.389 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.60s Dec 15 04:41:29.398 INFO [stderr] su: No module specific data is present Dec 15 04:41:29.748 INFO running `"docker" "inspect" "1f7e844c89b37f5f17ae451486760d9f3efb9355296af019e386818773d5c176"` Dec 15 04:41:29.903 INFO running `"docker" "rm" "-f" "1f7e844c89b37f5f17ae451486760d9f3efb9355296af019e386818773d5c176"` Dec 15 04:41:30.031 INFO [stdout] 1f7e844c89b37f5f17ae451486760d9f3efb9355296af019e386818773d5c176 Dec 15 04:41:30.034 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 04:41:30.255 INFO [stdout] 9911669374f291d7e7f3f5ded5fbeaec24b8038c46432bb19ef30b144f945c08 Dec 15 04:41:30.257 INFO running `"docker" "start" "-a" "9911669374f291d7e7f3f5ded5fbeaec24b8038c46432bb19ef30b144f945c08"` Dec 15 04:41:30.776 INFO [stderr] usermod: no changes Dec 15 04:41:30.834 INFO [stderr] Compiling env_logger v0.4.3 Dec 15 04:41:31.920 INFO [stderr] Compiling mio v0.6.16 (/source) Dec 15 04:41:40.567 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 9.80s Dec 15 04:41:40.573 INFO [stderr] su: No module specific data is present Dec 15 04:41:41.005 INFO running `"docker" "inspect" "9911669374f291d7e7f3f5ded5fbeaec24b8038c46432bb19ef30b144f945c08"` Dec 15 04:41:41.132 INFO running `"docker" "rm" "-f" "9911669374f291d7e7f3f5ded5fbeaec24b8038c46432bb19ef30b144f945c08"` Dec 15 04:41:41.271 INFO [stdout] 9911669374f291d7e7f3f5ded5fbeaec24b8038c46432bb19ef30b144f945c08 Dec 15 04:41:41.273 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 15 04:41:41.539 INFO [stdout] c971946caadbc682b7715b327435e929225e8cac7b9cf69a2e43a4645de3a474 Dec 15 04:41:41.541 INFO running `"docker" "start" "-a" "c971946caadbc682b7715b327435e929225e8cac7b9cf69a2e43a4645de3a474"` Dec 15 04:41:42.494 INFO [stderr] usermod: no changes Dec 15 04:41:42.553 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.04s Dec 15 04:41:42.555 INFO [stderr] Running /target/debug/deps/mio-f25fb1571797ba7e Dec 15 04:41:42.559 INFO [stdout] Dec 15 04:41:42.559 INFO [stdout] running 3 tests Dec 15 04:41:42.559 INFO [stdout] test event_imp::test_debug_pollopt ... ok Dec 15 04:41:42.559 INFO [stdout] test event_imp::test_debug_ready ... ok Dec 15 04:41:42.559 INFO [stdout] test poll::as_raw_fd ... ok Dec 15 04:41:42.559 INFO [stdout] Dec 15 04:41:42.559 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:41:42.559 INFO [stdout] Dec 15 04:41:42.560 INFO [stderr] Running /target/debug/deps/test-3541cba869282172 Dec 15 04:41:42.561 INFO [stdout] Dec 15 04:41:42.561 INFO [stdout] running 60 tests Dec 15 04:41:42.571 INFO [stdout] test test_custom_evented::smoke ... ok Dec 15 04:41:42.579 INFO [stdout] test test_close_on_drop::test_close_on_drop ... ok Dec 15 04:41:42.620 INFO [stdout] test test_double_register::test_double_register ... ok Dec 15 04:41:42.702 INFO [stdout] test test_echo_server::test_echo_server ... ok Dec 15 04:41:42.702 INFO [stdout] test test_local_addr_ready::local_addr_ready ... ok Dec 15 04:41:42.723 INFO [stdout] test test_custom_evented::stress::with_small_events_collection ... ok Dec 15 04:41:43.095 INFO [stdout] test test_multicast::test_multicast ... FAILED Dec 15 04:41:43.137 INFO [stdout] test test_custom_evented::stress::multi_threaded_poll ... ok Dec 15 04:41:43.176 INFO [stdout] test test_notify::test_notify_capacity ... ok Dec 15 04:41:43.177 INFO [stdout] test test_oneshot::test_tcp_edge_oneshot ... ok Dec 15 04:41:43.271 INFO [stdout] test test_notify::test_notify_drop ... ok Dec 15 04:41:43.273 INFO [stdout] test test_poll_channel::test_dropping_receive_before_poll ... ok Dec 15 04:41:43.465 INFO [stdout] test test_poll::test_poll_closes_fd ... ok Dec 15 04:41:43.525 INFO [stdout] test test_poll_channel::test_mixing_channel_with_socket ... ok Dec 15 04:41:43.599 INFO [stdout] test test_broken_pipe::broken_pipe ... ok Dec 15 04:41:43.781 INFO [stdout] test test_notify::test_notify ... ok Dec 15 04:41:43.859 INFO [stdout] test test_poll_channel::test_poll_channel_level ... ok Dec 15 04:41:44.243 INFO [stdout] test test_custom_evented::drop_registration_from_non_main_thread ... ok Dec 15 04:41:44.343 INFO [stdout] test test_register_deregister::test_register_deregister ... ok Dec 15 04:41:44.375 INFO [stdout] test test_poll_channel::test_poll_channel_edge ... ok Dec 15 04:41:44.383 INFO [stdout] test test_register_multiple_event_loops::test_tcp_register_multiple_event_loops ... ok Dec 15 04:41:44.383 INFO [stdout] test test_poll_channel::test_poll_channel_writable ... ok Dec 15 04:41:44.395 INFO [stdout] test test_register_multiple_event_loops::test_udp_register_multiple_event_loops ... ok Dec 15 04:41:44.491 INFO [stdout] test test_register_deregister::test_register_empty_interest ... ok Dec 15 04:41:44.499 INFO [stdout] test test_smoke::add_then_drop ... ok Dec 15 04:41:44.595 INFO [stdout] test test_smoke::run_once_with_nothing ... ok Dec 15 04:41:44.611 INFO [stdout] test test_tcp::accept ... ok Dec 15 04:41:44.631 INFO [stdout] test test_tcp::bind_twice_bad ... ok Dec 15 04:41:44.639 INFO [stdout] test test_tcp::connect ... ok Dec 15 04:41:44.679 INFO [stdout] test test_tcp::connect_error ... ok Dec 15 04:41:44.679 INFO [stdout] test test_tcp::connect_then_close ... ok Dec 15 04:41:44.844 INFO [stdout] test test_tcp::connection_reset_by_peer ... ok Dec 15 04:41:44.967 INFO [stdout] test test_tcp::listen_then_close ... ok Dec 15 04:41:44.980 INFO [stdout] test test_tcp::multiple_writes_immediate_success ... ok Dec 15 04:41:45.054 INFO [stdout] test test_tcp::peek ... ok Dec 15 04:41:45.127 INFO [stdout] test test_poll_channel::test_sending_from_other_thread_while_polling ... ok Dec 15 04:41:45.299 INFO [stdout] test test_tcp::read ... ok Dec 15 04:41:45.311 INFO [stdout] test test_tcp::test_tcp_sockets_are_send ... ok Dec 15 04:41:45.455 INFO [stdout] test test_tcp::write ... ok Dec 15 04:41:45.471 INFO [stdout] test test_poll_channel::test_poll_channel_oneshot ... ok Dec 15 04:41:45.495 INFO [stdout] test test_tcp::write_error ... ok Dec 15 04:41:45.495 INFO [stdout] test test_tcp_level::test_tcp_listener_level_triggered ... ok Dec 15 04:41:45.599 INFO [stdout] test test_subprocess_pipe::test_subprocess_pipe ... ok Dec 15 04:41:45.871 INFO [stdout] test test_tick::test_tick ... ok Dec 15 04:41:46.255 INFO [stdout] test test_tcp_level::test_tcp_stream_level_triggered ... ok Dec 15 04:41:46.255 INFO [stdout] test test_udp_socket::test_udp_socket ... ok Dec 15 04:41:46.263 INFO [stdout] test test_udp_socket::test_udp_socket_discard ... ok Dec 15 04:41:46.279 INFO [stdout] test test_udp_socket::test_udp_socket_send_recv ... ok Dec 15 04:41:46.279 INFO [stdout] test test_uds_shutdown::test_echo_server ... ok Dec 15 04:41:46.287 INFO [stdout] test test_unix_echo_server::test_unix_echo_server ... ok Dec 15 04:41:46.308 INFO [stdout] test test_unix_pass_fd::test_unix_pass_fd ... ok Dec 15 04:41:46.323 INFO [stdout] test test_write_then_drop::write_then_deregister ... ok Dec 15 04:41:46.331 INFO [stdout] test test_write_then_drop::write_then_drop ... ok Dec 15 04:41:46.383 INFO [stdout] test test_udp_level::test_udp_level_triggered ... ok Dec 15 04:41:46.399 INFO [stdout] test test_reregister_without_poll::test_reregister_different_without_poll ... ok Dec 15 04:41:47.222 INFO [stdout] test test_tcp::write_bufs ... ok Dec 15 04:41:49.595 INFO [stdout] test test_tcp::read_bufs ... ok Dec 15 04:41:50.202 INFO [stdout] test test_custom_evented::set_readiness_before_register ... ok Dec 15 04:41:51.562 INFO [stdout] test test_battery::test_echo_server ... ok Dec 15 04:41:52.933 INFO [stdout] test test_custom_evented::stress::single_threaded_poll ... ok Dec 15 04:41:52.933 INFO [stdout] Dec 15 04:41:52.933 INFO [stdout] failures: Dec 15 04:41:52.933 INFO [stdout] Dec 15 04:41:52.933 INFO [stdout] ---- test_multicast::test_multicast stdout ---- Dec 15 04:41:52.933 INFO [stdout] thread 'test_multicast::test_multicast' panicked at 'called `Result::unwrap()` on an `Err` value: Os { code: 19, kind: Other, message: "No such device" }', src/libcore/result.rs:1009:5 Dec 15 04:41:52.933 INFO [stdout] stack backtrace: Dec 15 04:41:52.933 INFO [stdout] 0: 0x559bd6909673 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 04:41:52.934 INFO [stdout] 1: 0x559bd6902818 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 04:41:52.934 INFO [stdout] 2: 0x559bd6907002 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 04:41:52.934 INFO [stdout] 3: 0x559bd6906cf7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/panicking.rs:218 Dec 15 04:41:52.934 INFO [stdout] 4: 0x559bd6907710 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/panicking.rs:487 Dec 15 04:41:52.934 INFO [stdout] 5: 0x559bd6907291 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 04:41:52.934 INFO [stdout] 6: 0x559bd6907175 - rust_begin_unwind Dec 15 04:41:52.934 INFO [stdout] at src/libstd/panicking.rs:321 Dec 15 04:41:52.934 INFO [stdout] 7: 0x559bd691df5c - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 15 04:41:52.934 INFO [stdout] at src/libcore/panicking.rs:95 Dec 15 04:41:52.934 INFO [stdout] 8: 0x559bd689e480 - core::result::unwrap_failed::h5c3ff512ebfd018c Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 15 04:41:52.934 INFO [stdout] 9: 0x559bd689d73f - >::unwrap::hcdd915dcbb861fd2 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:808 Dec 15 04:41:52.934 INFO [stdout] 10: 0x559bd67a60d6 - test::test_multicast::test_multicast::h6540fceaac4e1fff Dec 15 04:41:52.934 INFO [stdout] at test/test_multicast.rs:83 Dec 15 04:41:52.934 INFO [stdout] 11: 0x559bd67a5c29 - test::test_multicast::test_multicast::{{closure}}::h01ffcc353bba3f59 Dec 15 04:41:52.934 INFO [stdout] at test/test_multicast.rs:70 Dec 15 04:41:52.934 INFO [stdout] 12: 0x559bd6749f5d - core::ops::function::FnOnce::call_once::h8b3f7f12575cbe8f Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 04:41:52.934 INFO [stdout] 13: 0x559bd68581ce - >::call_box::h3cf534772c089f15 Dec 15 04:41:52.934 INFO [stdout] at src/libtest/lib.rs:1483 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 04:41:52.934 INFO [stdout] 14: 0x559bd691c769 - __rust_maybe_catch_panic Dec 15 04:41:52.934 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 04:41:52.934 INFO [stdout] 15: 0x559bd6875b77 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 04:41:52.934 INFO [stdout] at src/libtest/lib.rs:1438 Dec 15 04:41:52.934 INFO [stdout] 16: 0x559bd6850a84 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 15 04:41:52.934 INFO [stdout] 17: 0x559bd68510f4 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 04:41:52.934 INFO [stdout] 18: 0x559bd691c769 - __rust_maybe_catch_panic Dec 15 04:41:52.934 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 04:41:52.934 INFO [stdout] 19: 0x559bd68580ac - >::call_box::h260fd1ad8e2854a6 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 04:41:52.934 INFO [stdout] 20: 0x559bd691068d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 15 04:41:52.934 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 15 04:41:52.934 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 15 04:41:52.934 INFO [stdout] 21: 0x7effd3b966b9 - start_thread Dec 15 04:41:52.934 INFO [stdout] 22: 0x7effd36b641c - clone Dec 15 04:41:52.934 INFO [stdout] 23: 0x0 - Dec 15 04:41:52.934 INFO [stdout] Dec 15 04:41:52.934 INFO [stdout] Dec 15 04:41:52.934 INFO [stdout] failures: Dec 15 04:41:52.934 INFO [stdout] test_multicast::test_multicast Dec 15 04:41:52.934 INFO [stdout] Dec 15 04:41:52.934 INFO [stdout] test result: FAILED. 59 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:41:52.934 INFO [stdout] Dec 15 04:41:52.935 INFO [stderr] error: test failed, to rerun pass '--test test' Dec 15 04:41:52.935 INFO [stderr] su: No module specific data is present Dec 15 04:41:53.255 INFO running `"docker" "inspect" "c971946caadbc682b7715b327435e929225e8cac7b9cf69a2e43a4645de3a474"` Dec 15 04:41:53.522 INFO running `"docker" "rm" "-f" "c971946caadbc682b7715b327435e929225e8cac7b9cf69a2e43a4645de3a474"` Dec 15 04:41:53.769 INFO [stdout] c971946caadbc682b7715b327435e929225e8cac7b9cf69a2e43a4645de3a474