Nov 16 09:55:42.471 INFO testing twitter/rpc-perf against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 09:55:42.472 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 09:55:43.058 INFO [stdout] 963e21574a21fbd3c67b1d57a03e7da637281d5875735182484d78fb99737144
Nov 16 09:55:43.060 INFO running `"docker" "start" "-a" "963e21574a21fbd3c67b1d57a03e7da637281d5875735182484d78fb99737144"`
Nov 16 09:55:45.582 INFO [stderr] usermod: no changes
Nov 16 09:55:45.758 INFO [stderr]    Compiling num-traits v0.2.5
Nov 16 09:55:45.758 INFO [stderr]    Compiling num-integer v0.1.39
Nov 16 09:55:45.759 INFO [stderr]    Compiling num-iter v0.1.37
Nov 16 09:55:45.765 INFO [stderr]    Compiling libc v0.2.42
Nov 16 09:55:45.811 INFO [stderr]    Compiling encoding_index_tests v0.1.4
Nov 16 09:55:45.812 INFO [stderr]    Compiling odds v0.2.26
Nov 16 09:55:45.812 INFO [stderr]    Compiling byteorder v0.4.2
Nov 16 09:55:45.812 INFO [stderr]    Compiling nodrop v0.1.12
Nov 16 09:55:46.744 INFO [stderr]    Compiling cfg-if v0.1.4
Nov 16 09:55:46.901 INFO [stderr]    Compiling byteorder v1.1.0
Nov 16 09:55:47.540 INFO [stderr]    Compiling rustc-serialize v0.3.24
Nov 16 09:55:48.749 INFO [stderr]    Compiling adler32 v1.0.3
Nov 16 09:55:50.715 INFO [stderr]    Compiling crc-core v0.1.2
Nov 16 09:55:50.721 INFO [stderr]    Compiling bitflags v0.7.0
Nov 16 09:55:51.012 INFO [stderr]    Compiling serde v1.0.70
Nov 16 09:55:51.136 INFO [stderr]    Compiling build_const v0.2.1
Nov 16 09:55:51.547 INFO [stderr]    Compiling matches v0.1.7
Nov 16 09:55:52.428 INFO [stderr]    Compiling histogram v0.6.9
Nov 16 09:55:52.515 INFO [stderr]    Compiling inflate v0.2.0
Nov 16 09:55:52.679 INFO [stderr]    Compiling lazycell v0.5.1
Nov 16 09:55:53.843 INFO [stderr]    Compiling chunked_transfer v0.3.1
Nov 16 09:55:54.207 INFO [stderr]    Compiling slab v0.3.0
Nov 16 09:55:54.435 INFO [stderr]    Compiling hsl v0.1.1
Nov 16 09:55:54.473 INFO [stderr]    Compiling ascii v0.7.1
Nov 16 09:55:54.555 INFO [stderr]    Compiling getopts v0.2.15
Nov 16 09:55:54.939 INFO [stderr]    Compiling mpmc v0.1.2
Nov 16 09:55:55.568 INFO [stderr]    Compiling fnv v1.0.6
Nov 16 09:55:55.691 INFO [stderr]    Compiling unicode-width v0.1.5
Nov 16 09:55:56.047 INFO [stderr]    Compiling allan v0.2.4
Nov 16 09:55:56.453 INFO [stderr]    Compiling ratelimit v0.4.2
Nov 16 09:55:56.465 INFO [stderr]    Compiling bytes v0.3.0
Nov 16 09:55:56.644 INFO [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
Nov 16 09:55:59.067 INFO [stderr]    Compiling encoding-index-korean v1.20141219.5
Nov 16 09:56:00.312 INFO [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
Nov 16 09:56:03.131 INFO [stderr]    Compiling encoding-index-japanese v1.20141219.5
Nov 16 09:56:03.415 INFO [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
Nov 16 09:56:03.683 INFO [stderr]    Compiling log v0.4.3
Nov 16 09:56:04.952 INFO [stderr]    Compiling stb_truetype v0.1.2
Nov 16 09:56:07.087 INFO [stderr]    Compiling deflate v0.7.18
Nov 16 09:56:07.235 INFO [stderr]    Compiling crc v1.5.0
Nov 16 09:56:07.403 INFO [stderr]    Compiling arrayvec v0.3.25
Nov 16 09:56:07.823 INFO [stderr]    Compiling rand v0.3.17
Nov 16 09:56:09.883 INFO [stderr]    Compiling time v0.1.38
Nov 16 09:56:10.599 INFO [stderr]    Compiling net2 v0.2.33
Nov 16 09:56:11.645 INFO [stderr]    Compiling iovec v0.1.2
Nov 16 09:56:13.855 INFO [stderr]    Compiling clocksource v0.2.4
Nov 16 09:56:14.015 INFO [stderr]    Compiling shuteye v0.3.2
Nov 16 09:56:15.683 INFO [stderr]    Compiling pad v0.1.4
Nov 16 09:56:19.376 INFO [stderr]    Compiling log v0.3.9
Nov 16 09:56:19.504 INFO [stderr]    Compiling encoding v0.2.33
Nov 16 09:56:19.656 INFO [stderr]    Compiling rusttype v0.1.2
Nov 16 09:56:23.389 INFO [stderr]    Compiling heatmap v0.6.6
Nov 16 09:56:27.058 INFO [stderr]    Compiling mio v0.6.10
Nov 16 09:56:28.747 INFO [stderr]    Compiling simple_logger v0.4.0
Nov 16 09:56:30.092 INFO [stderr]    Compiling log-panics v1.1.0
Nov 16 09:56:37.153 INFO [stderr]    Compiling uuid v0.1.18
Nov 16 09:56:39.360 INFO [stderr]    Compiling num v0.1.42
Nov 16 09:56:39.375 INFO [stderr]    Compiling png v0.7.0
Nov 16 09:56:40.097 INFO [stderr]    Compiling chrono v0.2.25
Nov 16 09:56:40.649 INFO [stderr]    Compiling url v0.2.38
Nov 16 09:56:48.424 INFO [stderr]    Compiling waterfall v0.7.1
Nov 16 09:56:52.182 INFO [stderr]    Compiling tiny_http v0.5.9
Nov 16 09:57:04.556 INFO [stderr]    Compiling tic v0.2.4
Nov 16 09:57:05.441 INFO [stderr]    Compiling toml v0.4.6
Nov 16 09:57:11.571 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Nov 16 09:57:21.515 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 35s
Nov 16 09:57:21.524 INFO [stderr] su: No module specific data is present
Nov 16 09:57:22.271 INFO running `"docker" "inspect" "963e21574a21fbd3c67b1d57a03e7da637281d5875735182484d78fb99737144"`
Nov 16 09:57:22.587 INFO running `"docker" "rm" "-f" "963e21574a21fbd3c67b1d57a03e7da637281d5875735182484d78fb99737144"`
Nov 16 09:57:22.960 INFO [stdout] 963e21574a21fbd3c67b1d57a03e7da637281d5875735182484d78fb99737144
Nov 16 09:57:22.967 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 09:57:23.556 INFO [stdout] af7e37f5b7c408c490ac086168db57c3fc6e66535d47b1872d1eeb8f2e0de9e4
Nov 16 09:57:23.560 INFO running `"docker" "start" "-a" "af7e37f5b7c408c490ac086168db57c3fc6e66535d47b1872d1eeb8f2e0de9e4"`
Nov 16 09:57:24.531 INFO [stderr] usermod: no changes
Nov 16 09:57:24.692 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Nov 16 09:57:29.536 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.96s
Nov 16 09:57:29.536 INFO [stderr] su: No module specific data is present
Nov 16 09:57:30.160 INFO running `"docker" "inspect" "af7e37f5b7c408c490ac086168db57c3fc6e66535d47b1872d1eeb8f2e0de9e4"`
Nov 16 09:57:30.405 INFO running `"docker" "rm" "-f" "af7e37f5b7c408c490ac086168db57c3fc6e66535d47b1872d1eeb8f2e0de9e4"`
Nov 16 09:57:30.612 INFO [stdout] af7e37f5b7c408c490ac086168db57c3fc6e66535d47b1872d1eeb8f2e0de9e4
Nov 16 09:57:30.614 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 09:57:31.143 INFO [stdout] e9819027e94fcdf1973e37fd8e90896a9c579a0edc41e48db10b37fb46d88f13
Nov 16 09:57:31.149 INFO running `"docker" "start" "-a" "e9819027e94fcdf1973e37fd8e90896a9c579a0edc41e48db10b37fb46d88f13"`
Nov 16 09:57:32.507 INFO [stderr] usermod: no changes
Nov 16 09:57:32.696 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Nov 16 09:57:32.698 INFO [stderr]      Running /target/debug/deps/rpc_perf-1a48df5e2e52e803
Nov 16 09:57:32.709 INFO [stdout] 
Nov 16 09:57:32.709 INFO [stdout] running 73 tests
Nov 16 09:57:32.711 INFO [stdout] test codec::echo::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:32.712 INFO [stdout] test codec::echo::parse::tests::test_parse_error ... ok
Nov 16 09:57:32.712 INFO [stdout] test codec::echo::gen::tests::echo_test ... ok
Nov 16 09:57:32.712 INFO [stdout] test codec::memcache::gen::tests::test_flush_all ... ok
Nov 16 09:57:32.713 INFO [stdout] test codec::memcache::gen::tests::test_get ... ok
Nov 16 09:57:32.713 INFO [stdout] test codec::memcache::gen::tests::test_gets ... ok
Nov 16 09:57:32.713 INFO [stdout] test codec::memcache::gen::tests::test_incr ... ok
Nov 16 09:57:32.714 INFO [stdout] test codec::memcache::gen::tests::test_prepend ... ok
Nov 16 09:57:32.714 INFO [stdout] test codec::memcache::gen::tests::test_quit ... ok
Nov 16 09:57:32.714 INFO [stdout] test codec::memcache::gen::tests::test_replace ... ok
Nov 16 09:57:32.715 INFO [stdout] test codec::memcache::gen::tests::test_set ... ok
Nov 16 09:57:32.715 INFO [stdout] test codec::memcache::gen::tests::test_touch ... ok
Nov 16 09:57:32.716 INFO [stdout] test codec::memcache::gen::tests::test_verbosity ... ok
Nov 16 09:57:32.716 INFO [stdout] test codec::memcache::gen::tests::test_version ... ok
Nov 16 09:57:32.716 INFO [stdout] test codec::memcache::parse::tests::test_parse_error ... ok
Nov 16 09:57:32.716 INFO [stdout] test codec::memcache::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:32.717 INFO [stdout] test codec::memcache::gen::tests::test_delete ... ok
Nov 16 09:57:32.717 INFO [stdout] test codec::memcache::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:32.717 INFO [stdout] test codec::memcache::gen::tests::test_add ... ok
Nov 16 09:57:32.718 INFO [stdout] test codec::echo::parse::tests::test_parse_ok ... ok
Nov 16 09:57:32.718 INFO [stdout] test codec::ping::gen::tests::test_ping ... ok
Nov 16 09:57:32.719 INFO [stdout] test codec::memcache::parse::tests::test_parse_version ... ok
Nov 16 09:57:32.719 INFO [stdout] test codec::memcache::parse::tests::test_parse_miss ... ok
Nov 16 09:57:32.719 INFO [stdout] test codec::memcache::parse::tests::test_parse_ok ... ok
Nov 16 09:57:32.719 INFO [stdout] test codec::redis_inline::gen::tests::test_del ... ok
Nov 16 09:57:32.720 INFO [stdout] test codec::redis_inline::gen::tests::test_expire ... ok
Nov 16 09:57:32.720 INFO [stdout] test codec::redis_inline::gen::tests::test_flushall ... ok
Nov 16 09:57:32.720 INFO [stdout] test codec::redis_inline::gen::tests::test_get ... ok
Nov 16 09:57:32.721 INFO [stdout] test codec::redis_inline::gen::tests::test_hget ... ok
Nov 16 09:57:32.721 INFO [stdout] test codec::redis_inline::gen::tests::test_hset ... ok
Nov 16 09:57:32.721 INFO [stdout] test codec::redis_inline::gen::tests::test_incr ... ok
Nov 16 09:57:32.722 INFO [stdout] test codec::redis_inline::gen::tests::test_prepend ... ok
Nov 16 09:57:32.722 INFO [stdout] test codec::redis_inline::gen::tests::test_set ... ok
Nov 16 09:57:32.722 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_error ... ok
Nov 16 09:57:32.723 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:32.723 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:32.724 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_miss ... ok
Nov 16 09:57:32.724 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_ok ... ok
Nov 16 09:57:32.724 INFO [stdout] test codec::redis_resp::gen::tests::test_append ... ok
Nov 16 09:57:32.725 INFO [stdout] test codec::redis_resp::gen::tests::test_decr ... ok
Nov 16 09:57:32.725 INFO [stdout] test codec::ping::parse::tests::test_parse_pong ... ok
Nov 16 09:57:32.725 INFO [stdout] test codec::redis_resp::gen::tests::test_del ... ok
Nov 16 09:57:32.726 INFO [stdout] test codec::redis_inline::gen::tests::test_append ... ok
Nov 16 09:57:32.726 INFO [stdout] test codec::redis_inline::gen::tests::test_decr ... ok
Nov 16 09:57:32.726 INFO [stdout] test codec::redis_resp::gen::tests::test_flushall ... ok
Nov 16 09:57:32.726 INFO [stdout] test codec::redis_resp::gen::tests::test_expire ... ok
Nov 16 09:57:32.727 INFO [stdout] test codec::memcache::gen::tests::test_append ... ok
Nov 16 09:57:32.727 INFO [stdout] test codec::memcache::gen::tests::test_cas ... ok
Nov 16 09:57:32.727 INFO [stdout] test codec::memcache::gen::tests::test_decr ... ok
Nov 16 09:57:32.728 INFO [stdout] test codec::redis_resp::gen::tests::test_incr ... ok
Nov 16 09:57:32.728 INFO [stdout] test codec::redis_resp::gen::tests::test_hset ... ok
Nov 16 09:57:32.728 INFO [stdout] test codec::redis_resp::gen::tests::test_hget ... ok
Nov 16 09:57:32.729 INFO [stdout] test codec::redis_resp::gen::tests::test_set ... ok
Nov 16 09:57:32.729 INFO [stdout] test codec::redis_resp::gen::tests::test_get ... ok
Nov 16 09:57:32.729 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:32.730 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:32.730 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_miss ... ok
Nov 16 09:57:32.730 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_ok ... ok
Nov 16 09:57:32.734 INFO [stdout] test codec::thrift::buffer::tests::into_vec ... ok
Nov 16 09:57:32.734 INFO [stdout] test codec::thrift::buffer::tests::test_method_name ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::buffer::tests::test_protocol_header ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::buffer::tests::test_sequence_id ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::config::test_load_config ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::buffer::tests::test_stop ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::gen::test::test_ping ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::parse::tests::test_parse_ok ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::gen::test::thrift_ping ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::testutil::thrift_add ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::thrift::testutil::thrift_subtract ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_error ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::redis_resp::gen::tests::test_select ... ok
Nov 16 09:57:32.735 INFO [stdout] test codec::redis_resp::gen::tests::test_prepend ... ok
Nov 16 09:57:32.735 INFO [stdout] 
Nov 16 09:57:32.735 INFO [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 09:57:32.735 INFO [stdout] 
Nov 16 09:57:32.735 INFO [stderr] su: No module specific data is present
Nov 16 09:57:33.625 INFO running `"docker" "inspect" "e9819027e94fcdf1973e37fd8e90896a9c579a0edc41e48db10b37fb46d88f13"`
Nov 16 09:57:33.922 INFO running `"docker" "rm" "-f" "e9819027e94fcdf1973e37fd8e90896a9c579a0edc41e48db10b37fb46d88f13"`
Nov 16 09:57:34.175 INFO [stdout] e9819027e94fcdf1973e37fd8e90896a9c579a0edc41e48db10b37fb46d88f13