Dec 06 20:47:23.740 INFO testing tarpc-bincode-transport-0.3.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 20:47:23.741 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 20:47:24.057 INFO [stdout] 4a9d6da2bfdc3f951f1c343de789743ef65a2305fbd184bce2c09e9affe84028
Dec 06 20:47:24.060 INFO running `"docker" "start" "-a" "4a9d6da2bfdc3f951f1c343de789743ef65a2305fbd184bce2c09e9affe84028"`
Dec 06 20:47:25.924 INFO [stderr] usermod: no changes
Dec 06 20:47:25.972 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 06 20:47:26.165 INFO [stderr]    Compiling byteorder v1.2.7
Dec 06 20:47:26.165 INFO [stderr]    Compiling pin-utils v0.1.0-alpha.3
Dec 06 20:47:26.172 INFO [stderr]    Compiling futures-core-preview v0.3.0-alpha.10
Dec 06 20:47:26.220 INFO [stderr]    Compiling tarpc-trace v0.1.0
Dec 06 20:47:28.400 INFO [stderr]    Compiling futures-channel-preview v0.3.0-alpha.10
Dec 06 20:47:28.400 INFO [stderr]    Compiling futures-io-preview v0.3.0-alpha.10
Dec 06 20:47:29.641 INFO [stderr]    Compiling bytes v0.4.11
Dec 06 20:47:29.641 INFO [stderr]    Compiling bincode v1.0.1
Dec 06 20:47:31.623 INFO [stderr]    Compiling futures-sink-preview v0.3.0-alpha.10
Dec 06 20:47:33.094 INFO [stderr]    Compiling tokio-io v0.1.10
Dec 06 20:47:33.216 INFO [stderr]    Compiling futures-util-preview v0.3.0-alpha.10
Dec 06 20:47:36.401 INFO [stderr]    Compiling tokio-codec v0.1.1
Dec 06 20:47:36.401 INFO [stderr]    Compiling tokio-reactor v0.1.7
Dec 06 20:47:36.401 INFO [stderr]    Compiling tokio-fs v0.1.4
Dec 06 20:47:43.152 INFO [stderr]    Compiling tokio-uds v0.2.4
Dec 06 20:47:43.152 INFO [stderr]    Compiling tokio-udp v0.1.3
Dec 06 20:47:43.152 INFO [stderr]    Compiling tokio-tcp v0.1.2
Dec 06 20:47:45.494 INFO [stderr]    Compiling futures-executor-preview v0.3.0-alpha.10
Dec 06 20:47:49.465 INFO [stderr]    Compiling tokio v0.1.13
Dec 06 20:47:50.620 INFO [stderr]    Compiling futures-preview v0.3.0-alpha.10
Dec 06 20:47:52.271 INFO [stderr]    Compiling tarpc-lib v0.2.0
Dec 06 20:47:54.179 INFO [stderr]    Compiling async-bincode v0.4.9
Dec 06 20:47:58.291 INFO [stderr]    Compiling tarpc-bincode-transport v0.3.0 (/source)
Dec 06 20:48:02.299 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 36.35s
Dec 06 20:48:02.307 INFO [stderr] su: No module specific data is present
Dec 06 20:48:02.634 INFO running `"docker" "inspect" "4a9d6da2bfdc3f951f1c343de789743ef65a2305fbd184bce2c09e9affe84028"`
Dec 06 20:48:02.718 INFO running `"docker" "rm" "-f" "4a9d6da2bfdc3f951f1c343de789743ef65a2305fbd184bce2c09e9affe84028"`
Dec 06 20:48:02.820 INFO [stdout] 4a9d6da2bfdc3f951f1c343de789743ef65a2305fbd184bce2c09e9affe84028
Dec 06 20:48:02.822 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 20:48:03.000 INFO [stdout] 722f47ddb58b7b11471001d12cefd4f48ab67422ce0cf439e23bb9b89425a3a2
Dec 06 20:48:03.001 INFO running `"docker" "start" "-a" "722f47ddb58b7b11471001d12cefd4f48ab67422ce0cf439e23bb9b89425a3a2"`
Dec 06 20:48:03.533 INFO [stderr] usermod: no changes
Dec 06 20:48:03.567 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 06 20:48:03.724 INFO [stderr]    Compiling tokio-serde v0.2.0
Dec 06 20:48:04.293 INFO [stderr]    Compiling tarpc-bincode-transport v0.3.0 (/source)
Dec 06 20:48:24.168 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 20.61s
Dec 06 20:48:24.181 INFO [stderr] su: No module specific data is present
Dec 06 20:48:24.845 INFO running `"docker" "inspect" "722f47ddb58b7b11471001d12cefd4f48ab67422ce0cf439e23bb9b89425a3a2"`
Dec 06 20:48:25.166 INFO running `"docker" "rm" "-f" "722f47ddb58b7b11471001d12cefd4f48ab67422ce0cf439e23bb9b89425a3a2"`
Dec 06 20:48:25.548 INFO [stdout] 722f47ddb58b7b11471001d12cefd4f48ab67422ce0cf439e23bb9b89425a3a2
Dec 06 20:48:25.554 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06 20:48:25.989 INFO [stdout] 27da5f8934f8fd808ad5cecdae2d53da3030f77f11d2c2627f6b4b0403cce161
Dec 06 20:48:25.991 INFO running `"docker" "start" "-a" "27da5f8934f8fd808ad5cecdae2d53da3030f77f11d2c2627f6b4b0403cce161"`
Dec 06 20:48:26.797 INFO [stderr] usermod: no changes
Dec 06 20:48:26.823 INFO [stderr] warning: the cargo feature `rename-dependency` is now stable and is no longer necessary to be listed in the manifest
Dec 06 20:48:26.994 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Dec 06 20:48:27.011 INFO [stderr]      Running /target/debug/deps/tarpc_bincode_transport-9fe30bb853223051
Dec 06 20:48:27.027 INFO [stderr]      Running /target/debug/deps/bench-068fbce0ff581db9
Dec 06 20:48:27.027 INFO [stdout] 
Dec 06 20:48:27.027 INFO [stdout] running 0 tests
Dec 06 20:48:27.027 INFO [stdout] 
Dec 06 20:48:27.027 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:48:27.027 INFO [stdout] 
Dec 06 20:48:27.027 INFO [stdout] 
Dec 06 20:48:27.027 INFO [stdout] running 1 test
Dec 06 20:48:39.383 INFO [stdout] Of 10000 runs:
Dec 06 20:48:39.383 INFO [stdout] 	Successful: 10000
Dec 06 20:48:39.383 INFO [stdout] 	Unsuccessful: 0
Dec 06 20:48:39.383 INFO [stdout] 	Mean: 1.233672ms
Dec 06 20:48:39.383 INFO [stdout] 	Median: 866.207µs
Dec 06 20:48:39.383 INFO [stdout] 	Std Dev: 1.562131ms
Dec 06 20:48:39.383 INFO [stdout] 	Min: 283.284µs
Dec 06 20:48:39.383 INFO [stdout] 	Max: 35.411221ms
Dec 06 20:48:39.383 INFO [stdout] 	Quartiles: (757.581µs, 866.207µs, 1.000416ms)
Dec 06 20:48:39.384 INFO [stdout] test bench_small_packet ... ok
Dec 06 20:48:39.384 INFO [stdout] 
Dec 06 20:48:39.384 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:48:39.384 INFO [stdout] 
Dec 06 20:48:39.385 INFO [stderr]      Running /target/debug/deps/cancel-32a55261d33472d8
Dec 06 20:48:39.388 INFO [stdout] 
Dec 06 20:48:39.388 INFO [stdout] running 1 test
Dec 06 20:48:39.420 INFO [stdout] test cancel_slower ... ok
Dec 06 20:48:39.420 INFO [stdout] 
Dec 06 20:48:39.420 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:48:39.420 INFO [stdout] 
Dec 06 20:48:39.422 INFO [stderr]      Running /target/debug/deps/pushback-0f2383aca680db83
Dec 06 20:48:39.430 INFO [stdout] 
Dec 06 20:48:39.430 INFO [stdout] running 1 test
Dec 06 20:48:39.477 INFO [stdout] done
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [fbfbeb35bbffafcd40] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [ae737fe73b8fffff1f] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [1dd9f77d5fd5aeda6b] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [28e7f47fb35fedece7] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [6dbddf6b7afdf7fff7] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [90feeffd6f87c7fdeb] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.001 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [71febaedebf9ff5f5c] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.002 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [b4ee1bbb27ffffea55] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.002 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [773fffce3ffff3fd63] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.002 INFO [stderr] ERROR 2018-12-06T20:48:49Z: pushback: [50faff5f7dbd1f9e8b] request error: TimedOut: Response did not complete before deadline of 2018-12-06T20:48:49Zs.
Dec 06 20:48:49.054 INFO [stdout] test ping_pong ... ok
Dec 06 20:48:49.054 INFO [stdout] 
Dec 06 20:48:49.054 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:48:49.054 INFO [stdout] 
Dec 06 20:48:49.055 INFO [stderr]    Doc-tests tarpc-bincode-transport
Dec 06 20:48:57.797 INFO [stdout] 
Dec 06 20:48:57.797 INFO [stdout] running 0 tests
Dec 06 20:48:57.797 INFO [stdout] 
Dec 06 20:48:57.797 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 20:48:57.797 INFO [stdout] 
Dec 06 20:48:57.808 INFO [stderr] su: No module specific data is present
Dec 06 20:48:58.055 INFO running `"docker" "inspect" "27da5f8934f8fd808ad5cecdae2d53da3030f77f11d2c2627f6b4b0403cce161"`
Dec 06 20:48:58.154 INFO running `"docker" "rm" "-f" "27da5f8934f8fd808ad5cecdae2d53da3030f77f11d2c2627f6b4b0403cce161"`
Dec 06 20:48:58.256 INFO [stdout] 27da5f8934f8fd808ad5cecdae2d53da3030f77f11d2c2627f6b4b0403cce161