Dec 12 23:05:25.494 INFO testing twitter/rpc-perf against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 12 23:05:25.494 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12 23:05:26.000 INFO [stdout] 211c7de3d8d72c276e871985965321b08ab7237010258318fe445ea0cb2c313f
Dec 12 23:05:26.002 INFO running `"docker" "start" "-a" "211c7de3d8d72c276e871985965321b08ab7237010258318fe445ea0cb2c313f"`
Dec 12 23:05:28.074 INFO [stderr] usermod: no changes
Dec 12 23:05:28.343 INFO [stderr]    Compiling num-traits v0.2.5
Dec 12 23:05:28.343 INFO [stderr]    Compiling num-integer v0.1.39
Dec 12 23:05:28.351 INFO [stderr]    Compiling num-iter v0.1.37
Dec 12 23:05:28.351 INFO [stderr]    Compiling libc v0.2.42
Dec 12 23:05:28.351 INFO [stderr]    Compiling cfg-if v0.1.4
Dec 12 23:05:28.351 INFO [stderr]    Compiling odds v0.2.26
Dec 12 23:05:28.351 INFO [stderr]    Compiling byteorder v0.4.2
Dec 12 23:05:28.351 INFO [stderr]    Compiling rustc-serialize v0.3.24
Dec 12 23:05:29.119 INFO [stderr]    Compiling nodrop v0.1.12
Dec 12 23:05:29.722 INFO [stderr]    Compiling byteorder v1.1.0
Dec 12 23:05:29.951 INFO [stderr]    Compiling adler32 v1.0.3
Dec 12 23:05:31.819 INFO [stderr]    Compiling encoding_index_tests v0.1.4
Dec 12 23:05:32.156 INFO [stderr]    Compiling matches v0.1.7
Dec 12 23:05:32.627 INFO [stderr]    Compiling crc-core v0.1.2
Dec 12 23:05:32.791 INFO [stderr]    Compiling serde v1.0.70
Dec 12 23:05:32.791 INFO [stderr]    Compiling bitflags v0.7.0
Dec 12 23:05:33.147 INFO [stderr]    Compiling inflate v0.2.0
Dec 12 23:05:33.663 INFO [stderr]    Compiling histogram v0.6.9
Dec 12 23:05:33.683 INFO [stderr]    Compiling build_const v0.2.1
Dec 12 23:05:34.079 INFO [stderr]    Compiling lazycell v0.5.1
Dec 12 23:05:34.815 INFO [stderr]    Compiling chunked_transfer v0.3.1
Dec 12 23:05:35.211 INFO [stderr]    Compiling hsl v0.1.1
Dec 12 23:05:36.079 INFO [stderr]    Compiling slab v0.3.0
Dec 12 23:05:36.111 INFO [stderr]    Compiling ascii v0.7.1
Dec 12 23:05:36.571 INFO [stderr]    Compiling mpmc v0.1.2
Dec 12 23:05:37.003 INFO [stderr]    Compiling fnv v1.0.6
Dec 12 23:05:37.146 INFO [stderr]    Compiling allan v0.2.4
Dec 12 23:05:37.311 INFO [stderr]    Compiling getopts v0.2.15
Dec 12 23:05:37.532 INFO [stderr]    Compiling unicode-width v0.1.5
Dec 12 23:05:38.055 INFO [stderr]    Compiling ratelimit v0.4.2
Dec 12 23:05:38.115 INFO [stderr]    Compiling bytes v0.3.0
Dec 12 23:05:39.041 INFO [stderr]    Compiling log v0.4.3
Dec 12 23:05:41.624 INFO [stderr]    Compiling stb_truetype v0.1.2
Dec 12 23:05:44.493 INFO [stderr]    Compiling encoding-index-korean v1.20141219.5
Dec 12 23:05:44.821 INFO [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
Dec 12 23:05:45.200 INFO [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
Dec 12 23:05:48.385 INFO [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
Dec 12 23:05:48.560 INFO [stderr]    Compiling encoding-index-japanese v1.20141219.5
Dec 12 23:05:49.499 INFO [stderr]    Compiling deflate v0.7.18
Dec 12 23:05:49.848 INFO [stderr]    Compiling rand v0.3.17
Dec 12 23:05:51.067 INFO [stderr]    Compiling time v0.1.38
Dec 12 23:05:53.461 INFO [stderr]    Compiling iovec v0.1.2
Dec 12 23:05:54.588 INFO [stderr]    Compiling net2 v0.2.33
Dec 12 23:05:55.650 INFO [stderr]    Compiling clocksource v0.2.4
Dec 12 23:05:55.664 INFO [stderr]    Compiling shuteye v0.3.2
Dec 12 23:05:57.184 INFO [stderr]    Compiling arrayvec v0.3.25
Dec 12 23:05:57.841 INFO [stderr]    Compiling crc v1.5.0
Dec 12 23:06:01.517 INFO [stderr]    Compiling pad v0.1.4
Dec 12 23:06:02.612 INFO [stderr]    Compiling log v0.3.9
Dec 12 23:06:02.945 INFO [stderr]    Compiling encoding v0.2.33
Dec 12 23:06:05.663 INFO [stderr]    Compiling heatmap v0.6.6
Dec 12 23:06:06.191 INFO [stderr]    Compiling rusttype v0.1.2
Dec 12 23:06:09.633 INFO [stderr]    Compiling mio v0.6.10
Dec 12 23:06:10.131 INFO [stderr]    Compiling log-panics v1.1.0
Dec 12 23:06:12.251 INFO [stderr]    Compiling simple_logger v0.4.0
Dec 12 23:06:13.367 INFO [stderr]    Compiling uuid v0.1.18
Dec 12 23:06:16.607 INFO [stderr]    Compiling url v0.2.38
Dec 12 23:06:22.292 INFO [stderr]    Compiling num v0.1.42
Dec 12 23:06:22.294 INFO [stderr]    Compiling png v0.7.0
Dec 12 23:06:22.795 INFO [stderr]    Compiling chrono v0.2.25
Dec 12 23:06:28.173 INFO [stderr]    Compiling waterfall v0.7.1
Dec 12 23:06:30.711 INFO [stderr]    Compiling tiny_http v0.5.9
Dec 12 23:06:39.097 INFO [stderr]    Compiling toml v0.4.6
Dec 12 23:06:39.500 INFO [stderr]    Compiling tic v0.2.4
Dec 12 23:06:45.739 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Dec 12 23:06:56.347 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 28s
Dec 12 23:06:56.352 INFO [stderr] su: No module specific data is present
Dec 12 23:06:57.793 INFO running `"docker" "inspect" "211c7de3d8d72c276e871985965321b08ab7237010258318fe445ea0cb2c313f"`
Dec 12 23:06:58.279 INFO running `"docker" "rm" "-f" "211c7de3d8d72c276e871985965321b08ab7237010258318fe445ea0cb2c313f"`
Dec 12 23:06:58.676 INFO [stdout] 211c7de3d8d72c276e871985965321b08ab7237010258318fe445ea0cb2c313f
Dec 12 23:06:58.679 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12 23:06:59.131 INFO [stdout] 65b42e49ba6c03dcf6f61d530ce2fb655037d7643c23a657dde3449006c769c0
Dec 12 23:06:59.133 INFO running `"docker" "start" "-a" "65b42e49ba6c03dcf6f61d530ce2fb655037d7643c23a657dde3449006c769c0"`
Dec 12 23:07:00.759 INFO [stderr] usermod: no changes
Dec 12 23:07:00.955 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Dec 12 23:07:06.460 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.67s
Dec 12 23:07:06.473 INFO [stderr] su: No module specific data is present
Dec 12 23:07:07.680 INFO running `"docker" "inspect" "65b42e49ba6c03dcf6f61d530ce2fb655037d7643c23a657dde3449006c769c0"`
Dec 12 23:07:07.941 INFO running `"docker" "rm" "-f" "65b42e49ba6c03dcf6f61d530ce2fb655037d7643c23a657dde3449006c769c0"`
Dec 12 23:07:08.215 INFO [stdout] 65b42e49ba6c03dcf6f61d530ce2fb655037d7643c23a657dde3449006c769c0
Dec 12 23:07:08.223 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12 23:07:08.832 INFO [stdout] f224c695697c1cdf0b1f6c5066c4fa5aef24dc190f5c35a2df7a4c3d8072780e
Dec 12 23:07:08.834 INFO running `"docker" "start" "-a" "f224c695697c1cdf0b1f6c5066c4fa5aef24dc190f5c35a2df7a4c3d8072780e"`
Dec 12 23:07:10.411 INFO [stderr] usermod: no changes
Dec 12 23:07:10.590 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Dec 12 23:07:10.615 INFO [stderr]      Running /target/debug/deps/rpc_perf-27bf68da8d3780a7
Dec 12 23:07:10.615 INFO [stdout] 
Dec 12 23:07:10.615 INFO [stdout] running 73 tests
Dec 12 23:07:10.630 INFO [stdout] test codec::echo::parse::tests::test_parse_error ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::echo::gen::tests::echo_test ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::echo::parse::tests::test_parse_incomplete ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_cas ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_delete ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_append ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::echo::parse::tests::test_parse_ok ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_decr ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_incr ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_prepend ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_gets ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_quit ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_replace ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_set ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_verbosity ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_version ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_get ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_incomplete ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_flush_all ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::gen::tests::test_touch ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_error ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_invalid ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_miss ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::ping::gen::tests::test_ping ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_append ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_decr ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::ping::parse::tests::test_parse_pong ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_version ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::memcache::parse::tests::test_parse_ok ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_expire ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_flushall ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_del ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_error ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_set ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_prepend ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_incr ... ok
Dec 12 23:07:10.630 INFO [stdout] test codec::redis_inline::gen::tests::test_hset ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_invalid ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_incomplete ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_miss ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_del ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_decr ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_append ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_ok ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_expire ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_hget ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::memcache::gen::tests::test_add ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_hset ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_flushall ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_get ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_select ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_set ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_prepend ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_error ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::thrift::buffer::tests::into_vec ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_incomplete ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::gen::tests::test_get ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_inline::gen::tests::test_hget ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::thrift::buffer::tests::test_protocol_header ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::thrift::buffer::tests::test_method_name ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::thrift::buffer::tests::test_sequence_id ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::gen::tests::test_incr ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_invalid ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::thrift::buffer::tests::test_stop ... ok
Dec 12 23:07:10.631 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_miss ... ok
Dec 12 23:07:10.635 INFO [stdout] test codec::thrift::config::test_load_config ... ok
Dec 12 23:07:10.635 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_ok ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::parse::tests::test_parse_ok ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::gen::test::thrift_ping ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::testutil::thrift_subtract ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::testutil::thrift_add ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::gen::test::test_ping ... ok
Dec 12 23:07:10.639 INFO [stdout] test codec::thrift::parse::tests::test_parse_incomplete ... ok
Dec 12 23:07:10.639 INFO [stdout] 
Dec 12 23:07:10.639 INFO [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 23:07:10.639 INFO [stdout] 
Dec 12 23:07:10.667 INFO [stderr] su: No module specific data is present
Dec 12 23:07:11.533 INFO running `"docker" "inspect" "f224c695697c1cdf0b1f6c5066c4fa5aef24dc190f5c35a2df7a4c3d8072780e"`
Dec 12 23:07:11.901 INFO running `"docker" "rm" "-f" "f224c695697c1cdf0b1f6c5066c4fa5aef24dc190f5c35a2df7a4c3d8072780e"`
Dec 12 23:07:12.195 INFO [stdout] f224c695697c1cdf0b1f6c5066c4fa5aef24dc190f5c35a2df7a4c3d8072780e