Dec 14 17:37:18.608 INFO testing tarpc-bincode-transport-0.3.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 17:37:18.608 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 14 17:37:19.115 INFO [stdout] 5ac56eb3df40f2948bfdfc09b65874aaadd072b9df7be7bbb793ec2178e92106
Dec 14 17:37:19.117 INFO running `"docker" "start" "-a" "5ac56eb3df40f2948bfdfc09b65874aaadd072b9df7be7bbb793ec2178e92106"`
Dec 14 17:37:21.254 INFO [stderr] usermod: no changes
Dec 14 17:37:21.335 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 14 17:37:21.572 INFO [stderr]    Compiling futures-core-preview v0.3.0-alpha.10
Dec 14 17:37:21.580 INFO [stderr]    Compiling tokio-io v0.1.10
Dec 14 17:37:21.580 INFO [stderr]    Compiling serde v1.0.82
Dec 14 17:37:23.597 INFO [stderr]    Compiling futures-channel-preview v0.3.0-alpha.10
Dec 14 17:37:23.603 INFO [stderr]    Compiling futures-io-preview v0.3.0-alpha.10
Dec 14 17:37:25.795 INFO [stderr]    Compiling futures-sink-preview v0.3.0-alpha.10
Dec 14 17:37:26.656 INFO [stderr]    Compiling tokio-codec v0.1.1
Dec 14 17:37:26.656 INFO [stderr]    Compiling tokio-reactor v0.1.7
Dec 14 17:37:26.662 INFO [stderr]    Compiling tokio-fs v0.1.4
Dec 14 17:37:28.151 INFO [stderr]    Compiling futures-util-preview v0.3.0-alpha.10
Dec 14 17:37:37.181 INFO [stderr]    Compiling tokio-udp v0.1.3
Dec 14 17:37:37.188 INFO [stderr]    Compiling tokio-uds v0.2.4
Dec 14 17:37:37.195 INFO [stderr]    Compiling tokio-tcp v0.1.2
Dec 14 17:37:45.338 INFO [stderr]    Compiling futures-executor-preview v0.3.0-alpha.10
Dec 14 17:37:46.275 INFO [stderr]    Compiling tokio v0.1.13
Dec 14 17:37:49.659 INFO [stderr]    Compiling futures-preview v0.3.0-alpha.10
Dec 14 17:37:50.707 INFO [stderr]    Compiling tarpc-trace v0.1.0
Dec 14 17:37:50.707 INFO [stderr]    Compiling bincode v1.0.1
Dec 14 17:37:53.344 INFO [stderr]    Compiling tarpc-lib v0.2.0
Dec 14 17:37:53.760 INFO [stderr]    Compiling async-bincode v0.4.9
Dec 14 17:37:57.675 INFO [stderr]    Compiling tarpc-bincode-transport v0.3.0 (/source)
Dec 14 17:38:02.159 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 40.88s
Dec 14 17:38:02.171 INFO [stderr] su: No module specific data is present
Dec 14 17:38:02.479 INFO running `"docker" "inspect" "5ac56eb3df40f2948bfdfc09b65874aaadd072b9df7be7bbb793ec2178e92106"`
Dec 14 17:38:02.651 INFO running `"docker" "rm" "-f" "5ac56eb3df40f2948bfdfc09b65874aaadd072b9df7be7bbb793ec2178e92106"`
Dec 14 17:38:02.762 INFO [stdout] 5ac56eb3df40f2948bfdfc09b65874aaadd072b9df7be7bbb793ec2178e92106
Dec 14 17:38:02.765 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 14 17:38:02.978 INFO [stdout] a9e5aef2dcb9501f0146d3a78264e2525ebf6c985dae39f4ae682665376b5637
Dec 14 17:38:02.980 INFO running `"docker" "start" "-a" "a9e5aef2dcb9501f0146d3a78264e2525ebf6c985dae39f4ae682665376b5637"`
Dec 14 17:38:03.421 INFO [stderr] usermod: no changes
Dec 14 17:38:03.487 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 14 17:38:03.622 INFO [stderr]    Compiling tokio-serde v0.2.0
Dec 14 17:38:04.282 INFO [stderr]    Compiling tarpc-bincode-transport v0.3.0 (/source)
Dec 14 17:38:23.682 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 20.23s
Dec 14 17:38:23.707 INFO [stderr] su: No module specific data is present
Dec 14 17:38:24.137 INFO running `"docker" "inspect" "a9e5aef2dcb9501f0146d3a78264e2525ebf6c985dae39f4ae682665376b5637"`
Dec 14 17:38:24.239 INFO running `"docker" "rm" "-f" "a9e5aef2dcb9501f0146d3a78264e2525ebf6c985dae39f4ae682665376b5637"`
Dec 14 17:38:24.386 INFO [stdout] a9e5aef2dcb9501f0146d3a78264e2525ebf6c985dae39f4ae682665376b5637
Dec 14 17:38:24.388 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 14 17:38:24.699 INFO [stdout] 14e4025166845e4481c1f2457be98008ed6a6b3083837942825da22fa43c82c3
Dec 14 17:38:24.705 INFO running `"docker" "start" "-a" "14e4025166845e4481c1f2457be98008ed6a6b3083837942825da22fa43c82c3"`
Dec 14 17:38:25.361 INFO [stderr] usermod: no changes
Dec 14 17:38:25.391 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 14 17:38:25.563 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Dec 14 17:38:25.567 INFO [stdout] 
Dec 14 17:38:25.567 INFO [stdout] running 0 tests
Dec 14 17:38:25.567 INFO [stdout] 
Dec 14 17:38:25.567 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:38:25.567 INFO [stdout] 
Dec 14 17:38:25.567 INFO [stderr]      Running /target/debug/deps/tarpc_bincode_transport-38891eb43cee0663
Dec 14 17:38:25.567 INFO [stderr]      Running /target/debug/deps/bench-c79d769b7becd192
Dec 14 17:38:25.568 INFO [stdout] 
Dec 14 17:38:25.568 INFO [stdout] running 1 test
Dec 14 17:38:37.220 INFO [stdout] Of 10000 runs:
Dec 14 17:38:37.220 INFO [stdout] 	Successful: 10000
Dec 14 17:38:37.220 INFO [stdout] 	Unsuccessful: 0
Dec 14 17:38:37.220 INFO [stdout] 	Mean: 1.163165ms
Dec 14 17:38:37.220 INFO [stdout] 	Median: 862.599µs
Dec 14 17:38:37.220 INFO [stdout] 	Std Dev: 1.456319ms
Dec 14 17:38:37.220 INFO [stdout] 	Min: 283.114µs
Dec 14 17:38:37.220 INFO [stdout] 	Max: 27.969474ms
Dec 14 17:38:37.220 INFO [stdout] 	Quartiles: (733.716µs, 862.599µs, 998.713µs)
Dec 14 17:38:37.221 INFO [stdout] test bench_small_packet ... ok
Dec 14 17:38:37.221 INFO [stdout] 
Dec 14 17:38:37.222 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:38:37.222 INFO [stdout] 
Dec 14 17:38:37.222 INFO [stderr]      Running /target/debug/deps/cancel-20881e3adcca4a8b
Dec 14 17:38:37.225 INFO [stdout] 
Dec 14 17:38:37.225 INFO [stdout] running 1 test
Dec 14 17:38:37.247 INFO [stdout] test cancel_slower ... ok
Dec 14 17:38:37.278 INFO [stdout] 
Dec 14 17:38:37.279 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:38:37.279 INFO [stdout] 
Dec 14 17:38:37.279 INFO [stdout] 
Dec 14 17:38:37.279 INFO [stdout] running 1 test
Dec 14 17:38:37.279 INFO [stdout] done
Dec 14 17:38:37.279 INFO [stderr]      Running /target/debug/deps/pushback-7d806df7dc7037e8
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [1dcfffbdd53af94eb3] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [68ffffe7f7eb8efe2e] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [27f7b7fb7fdef8106] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [7871bfeffefebd9fb1] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [72ed7eefbf9fead3e5] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.001 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [36defd1e5676bfef4a] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.002 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [405fdfdffffcedff3e] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.002 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [aafcdd2fbfe7bfaf27] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.004 INFO [stderr] ERROR 2018-12-14T17:38:47Z: pushback: [aaa78fab9edd7fed42] request error: TimedOut: Response did not complete before deadline of 2018-12-14T17:38:47Zs.
Dec 14 17:38:47.049 INFO [stdout] test ping_pong ... ok
Dec 14 17:38:47.049 INFO [stdout] 
Dec 14 17:38:47.049 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:38:47.049 INFO [stdout] 
Dec 14 17:38:47.049 INFO [stderr]    Doc-tests tarpc-bincode-transport
Dec 14 17:38:54.775 INFO [stdout] 
Dec 14 17:38:54.775 INFO [stdout] running 0 tests
Dec 14 17:38:54.775 INFO [stdout] 
Dec 14 17:38:54.775 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:38:54.775 INFO [stdout] 
Dec 14 17:38:54.787 INFO [stderr] su: No module specific data is present
Dec 14 17:38:55.084 INFO running `"docker" "inspect" "14e4025166845e4481c1f2457be98008ed6a6b3083837942825da22fa43c82c3"`
Dec 14 17:38:55.204 INFO running `"docker" "rm" "-f" "14e4025166845e4481c1f2457be98008ed6a6b3083837942825da22fa43c82c3"`
Dec 14 17:38:55.311 INFO [stdout] 14e4025166845e4481c1f2457be98008ed6a6b3083837942825da22fa43c82c3