Dec 14 12:53:33.977 INFO testing zmq-pw-0.9.8 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 12:53:33.978 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 14 12:53:34.237 INFO [stdout] 588d3e9422a53f7b6f652b9070f8cadbdfb84bc3d0c04ddfc52f643ee119aced
Dec 14 12:53:34.240 INFO running `"docker" "start" "-a" "588d3e9422a53f7b6f652b9070f8cadbdfb84bc3d0c04ddfc52f643ee119aced"`
Dec 14 12:53:34.694 INFO [stderr] usermod: no changes
Dec 14 12:53:34.829 INFO [stderr]    Compiling zmq-pw-sys v0.9.8
Dec 14 12:55:31.740 INFO [stderr] warning: redundant linker flag specified for library `zmq-pw`
Dec 14 12:55:31.740 INFO [stderr] 
Dec 14 12:55:31.781 INFO [stderr]    Compiling zmq-pw v0.9.8 (/source)
Dec 14 12:55:34.922 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 00s
Dec 14 12:55:34.940 INFO [stderr] su: No module specific data is present
Dec 14 12:55:35.814 INFO running `"docker" "inspect" "588d3e9422a53f7b6f652b9070f8cadbdfb84bc3d0c04ddfc52f643ee119aced"`
Dec 14 12:55:36.079 INFO running `"docker" "rm" "-f" "588d3e9422a53f7b6f652b9070f8cadbdfb84bc3d0c04ddfc52f643ee119aced"`
Dec 14 12:55:36.499 INFO [stdout] 588d3e9422a53f7b6f652b9070f8cadbdfb84bc3d0c04ddfc52f643ee119aced
Dec 14 12:55:36.504 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 14 12:55:36.875 INFO [stdout] 9862bb22e66b5e715c3d212f2dfd6c915425b4d72eb3bd6683210b328b600ae0
Dec 14 12:55:36.877 INFO running `"docker" "start" "-a" "9862bb22e66b5e715c3d212f2dfd6c915425b4d72eb3bd6683210b328b600ae0"`
Dec 14 12:55:38.063 INFO [stderr] usermod: no changes
Dec 14 12:55:38.307 INFO [stderr]    Compiling memchr v0.1.11
Dec 14 12:55:38.307 INFO [stderr]    Compiling time v0.1.41
Dec 14 12:55:38.323 INFO [stderr]    Compiling thread-id v2.0.0
Dec 14 12:55:38.323 INFO [stderr]    Compiling nix v0.7.0
Dec 14 12:55:38.323 INFO [stderr]    Compiling tempfile v2.2.0
Dec 14 12:55:39.889 INFO [stderr]    Compiling thread_local v0.2.7
Dec 14 12:55:40.416 INFO [stderr]    Compiling aho-corasick v0.5.3
Dec 14 12:55:43.840 INFO [stderr]    Compiling pulse v0.5.3
Dec 14 12:55:44.783 INFO [stderr]    Compiling regex v0.1.80
Dec 14 12:55:47.107 INFO [stderr]    Compiling timebomb v0.1.2
Dec 14 12:55:57.401 INFO [stderr]    Compiling env_logger v0.3.5
Dec 14 12:55:59.455 INFO [stderr]    Compiling quickcheck v0.4.1
Dec 14 12:56:02.541 INFO [stderr]    Compiling zmq-pw v0.9.8 (/source)
Dec 14 12:56:44.640 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 06s
Dec 14 12:56:44.667 INFO [stderr] su: No module specific data is present
Dec 14 12:56:46.120 INFO running `"docker" "inspect" "9862bb22e66b5e715c3d212f2dfd6c915425b4d72eb3bd6683210b328b600ae0"`
Dec 14 12:56:46.532 INFO running `"docker" "rm" "-f" "9862bb22e66b5e715c3d212f2dfd6c915425b4d72eb3bd6683210b328b600ae0"`
Dec 14 12:56:46.907 INFO [stdout] 9862bb22e66b5e715c3d212f2dfd6c915425b4d72eb3bd6683210b328b600ae0
Dec 14 12:56:46.913 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 14 12:56:47.370 INFO [stdout] 235228a0d96386af581c7b5ec33a38f5279811451ea06b2f0edbfe54e8da5872
Dec 14 12:56:47.372 INFO running `"docker" "start" "-a" "235228a0d96386af581c7b5ec33a38f5279811451ea06b2f0edbfe54e8da5872"`
Dec 14 12:56:49.618 INFO [stderr] usermod: no changes
Dec 14 12:56:49.801 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Dec 14 12:56:49.814 INFO [stderr]      Running /target/debug/deps/zmq_pw-3e9e23523f97b345
Dec 14 12:56:49.822 INFO [stdout] 
Dec 14 12:56:49.822 INFO [stdout] running 0 tests
Dec 14 12:56:49.822 INFO [stdout] 
Dec 14 12:56:49.822 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.822 INFO [stdout] 
Dec 14 12:56:49.822 INFO [stderr]      Running /target/debug/deps/compile_tests-c1dfeef6f919f783
Dec 14 12:56:49.825 INFO [stdout] 
Dec 14 12:56:49.825 INFO [stdout] running 0 tests
Dec 14 12:56:49.825 INFO [stdout] 
Dec 14 12:56:49.825 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.825 INFO [stdout] 
Dec 14 12:56:49.825 INFO [stderr]      Running /target/debug/deps/connection-d70f481aa2b44667
Dec 14 12:56:49.833 INFO [stdout] 
Dec 14 12:56:49.833 INFO [stdout] running 7 tests
Dec 14 12:56:49.851 INFO [stdout] test test_poll_tcp ... ok
Dec 14 12:56:49.852 INFO [stdout] test test_poll_inproc ... ok
Dec 14 12:56:49.853 INFO [stdout] test test_tcp ... ok
Dec 14 12:56:49.861 INFO [stdout] test test_inproc ... ok
Dec 14 12:56:49.862 INFO [stdout] test unix::test_external_poll_ipc ... ok
Dec 14 12:56:49.863 INFO [stdout] test unix::test_external_poll_inproc ... ok
Dec 14 12:56:49.864 INFO [stdout] test unix::test_external_poll_tcp ... ok
Dec 14 12:56:49.864 INFO [stdout] 
Dec 14 12:56:49.864 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.864 INFO [stdout] 
Dec 14 12:56:49.867 INFO [stderr]      Running /target/debug/deps/curve-3b1a0759ddbc952f
Dec 14 12:56:49.870 INFO [stdout] 
Dec 14 12:56:49.870 INFO [stdout] running 1 test
Dec 14 12:56:49.919 INFO [stdout] this is it foo
Dec 14 12:56:49.919 INFO [stdout] test test_curve_messages ... ok
Dec 14 12:56:49.919 INFO [stdout] 
Dec 14 12:56:49.919 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.919 INFO [stdout] 
Dec 14 12:56:49.919 INFO [stderr]      Running /target/debug/deps/error-830a04ab412796d3
Dec 14 12:56:49.928 INFO [stdout] 
Dec 14 12:56:49.928 INFO [stdout] running 1 test
Dec 14 12:56:49.931 INFO [stdout] test from_raw_eintr ... ok
Dec 14 12:56:49.931 INFO [stdout] 
Dec 14 12:56:49.931 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.931 INFO [stdout] 
Dec 14 12:56:49.943 INFO [stderr]      Running /target/debug/deps/message-7648c6cafc35bab7
Dec 14 12:56:49.954 INFO [stdout] 
Dec 14 12:56:49.954 INFO [stdout] running 3 tests
Dec 14 12:56:49.983 INFO [stdout] test msg_cmp_eq ... ok
Dec 14 12:56:49.983 INFO [stdout] test msg_vec_roundtrip ... ok
Dec 14 12:56:49.983 INFO [stdout] test msg_cmp_ne ... ok
Dec 14 12:56:49.983 INFO [stdout] 
Dec 14 12:56:49.983 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:49.983 INFO [stdout] 
Dec 14 12:56:49.987 INFO [stderr]      Running /target/debug/deps/test-164a076945215e2c
Dec 14 12:56:49.995 INFO [stdout] 
Dec 14 12:56:49.995 INFO [stdout] running 50 tests
Dec 14 12:56:50.036 INFO [stdout] test test_curve_keypair ... ok
Dec 14 12:56:50.038 INFO [stdout] test test_disconnect ... ok
Dec 14 12:56:50.039 INFO [stdout] test test_exchanging_strings ... ok
Dec 14 12:56:50.047 INFO [stdout] test test_create_stream_socket ... ok
Dec 14 12:56:50.047 INFO [stdout] test test_exchanging_multipart ... ok
Dec 14 12:56:50.047 INFO [stdout] test test_ctx_nohang ... ok
Dec 14 12:56:50.047 INFO [stdout] test test_exchanging_bytes ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_exchanging_messages ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_getset_affinity ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_getset_curve_publickey ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_disconnect_err ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_getset_backlog ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_getset_conflate ... ok
Dec 14 12:56:50.052 INFO [stdout] test test_get_fd ... ok
Dec 14 12:56:50.054 INFO [stdout] test test_get_socket_type ... ok
Dec 14 12:56:50.054 INFO [stdout] test test_getset_identity ... ok
Dec 14 12:56:50.054 INFO [stdout] test test_getset_immediate ... ok
Dec 14 12:56:50.054 INFO [stdout] test test_getset_keepalive ... ok
Dec 14 12:56:50.058 INFO [stdout] test test_getset_ipv6 ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_curve_serverkey ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_curve_server ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_curve_secretkey ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_keepalive_cnt ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_plain_password ... ok
Dec 14 12:56:50.059 INFO [stdout] test test_getset_multicast_hops ... ok
Dec 14 12:56:50.065 INFO [stdout] test test_getset_keepalive_idle ... ok
Dec 14 12:56:50.065 INFO [stdout] test test_getset_plain_server ... ok
Dec 14 12:56:50.065 INFO [stdout] test test_getset_maxmsgsize ... ok
Dec 14 12:56:50.065 INFO [stdout] test test_getset_linger ... ok
Dec 14 12:56:50.065 INFO [stdout] test test_getset_rcvbuf ... ok
Dec 14 12:56:50.072 INFO [stdout] test test_getset_rcvhwm ... ok
Dec 14 12:56:50.074 INFO [stdout] test test_getset_plain_username ... ok
Dec 14 12:56:50.074 INFO [stdout] test test_getset_reconnect_ivl ... ok
Dec 14 12:56:50.075 INFO [stdout] test test_getset_rcvtimeo ... ok
Dec 14 12:56:50.075 INFO [stdout] test test_getset_rate ... ok
Dec 14 12:56:50.078 INFO [stdout] test test_getset_recovery_ivl ... ok
Dec 14 12:56:50.078 INFO [stdout] test test_getset_reconnect_ivl_max ... ok
Dec 14 12:56:50.079 INFO [stdout] test test_getset_sndhwm ... ok
Dec 14 12:56:50.079 INFO [stdout] test test_into_io_error ... ok
Dec 14 12:56:50.079 INFO [stdout] test test_getset_sndbuf ... ok
Dec 14 12:56:50.091 INFO [stdout] test test_getset_zap_domain ... ok
Dec 14 12:56:50.091 INFO [stdout] test test_getset_sndtimeo ... ok
Dec 14 12:56:50.095 INFO [stdout] test test_getset_tos ... ok
Dec 14 12:56:50.095 INFO [stdout] test test_getset_socks_proxy ... ok
Dec 14 12:56:50.095 INFO [stdout] test test_version ... ok
Dec 14 12:56:50.095 INFO [stdout] test test_zmq_error ... ok
Dec 14 12:56:50.103 INFO [stdout] test test_subscription ... ok
Dec 14 12:56:50.103 INFO [stdout] test test_raw_roundtrip ... ok
Dec 14 12:56:50.104 INFO [stdout] test test_getset_tcp_keepalive_intvl ... ok
Dec 14 12:56:51.085 INFO [stdout] test test_polling ... ok
Dec 14 12:56:51.085 INFO [stdout] 
Dec 14 12:56:51.085 INFO [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:51.085 INFO [stdout] 
Dec 14 12:56:51.086 INFO [stderr]      Running /target/debug/deps/z85-c5df7c290345b3a8
Dec 14 12:56:51.089 INFO [stdout] 
Dec 14 12:56:51.089 INFO [stdout] running 3 tests
Dec 14 12:56:51.090 INFO [stdout] test test_decode_errors ... ok
Dec 14 12:56:51.090 INFO [stdout] test test_z85 ... ok
Dec 14 12:56:51.134 INFO [stdout] test z85_roundtrip ... ok
Dec 14 12:56:51.134 INFO [stdout] 
Dec 14 12:56:51.135 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:51.135 INFO [stdout] 
Dec 14 12:56:51.136 INFO [stderr]    Doc-tests zmq-pw
Dec 14 12:56:52.227 INFO [stdout] 
Dec 14 12:56:52.227 INFO [stdout] running 1 test
Dec 14 12:56:54.099 INFO [stdout] test src/lib.rs - Socket::get_events (line 478) ... ok
Dec 14 12:56:54.099 INFO [stdout] 
Dec 14 12:56:54.099 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 12:56:54.099 INFO [stdout] 
Dec 14 12:56:54.110 INFO [stderr] su: No module specific data is present
Dec 14 12:56:55.221 INFO running `"docker" "inspect" "235228a0d96386af581c7b5ec33a38f5279811451ea06b2f0edbfe54e8da5872"`
Dec 14 12:56:55.419 INFO running `"docker" "rm" "-f" "235228a0d96386af581c7b5ec33a38f5279811451ea06b2f0edbfe54e8da5872"`
Dec 14 12:56:55.631 INFO [stdout] 235228a0d96386af581c7b5ec33a38f5279811451ea06b2f0edbfe54e8da5872