Nov 16 09:55:28.637 INFO testing twitter/rpc-perf against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 09:55:28.637 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:29.143 INFO [stdout] c4d47df635e658ef181af679e4ff34e3a03829a707d0d89b30577b124792082d
Nov 16 09:55:29.148 INFO running `"docker" "start" "-a" "c4d47df635e658ef181af679e4ff34e3a03829a707d0d89b30577b124792082d"`
Nov 16 09:55:31.053 INFO [stderr] usermod: no changes
Nov 16 09:55:31.272 INFO [stderr]    Compiling num-traits v0.2.5
Nov 16 09:55:31.275 INFO [stderr]    Compiling num-integer v0.1.39
Nov 16 09:55:31.276 INFO [stderr]    Compiling num-iter v0.1.37
Nov 16 09:55:31.278 INFO [stderr]    Compiling libc v0.2.42
Nov 16 09:55:31.290 INFO [stderr]    Compiling byteorder v1.1.0
Nov 16 09:55:31.295 INFO [stderr]    Compiling odds v0.2.26
Nov 16 09:55:31.295 INFO [stderr]    Compiling byteorder v0.4.2
Nov 16 09:55:31.295 INFO [stderr]    Compiling rustc-serialize v0.3.24
Nov 16 09:55:34.560 INFO [stderr]    Compiling encoding_index_tests v0.1.4
Nov 16 09:55:35.451 INFO [stderr]    Compiling adler32 v1.0.3
Nov 16 09:55:35.983 INFO [stderr]    Compiling nodrop v0.1.12
Nov 16 09:55:36.015 INFO [stderr]    Compiling cfg-if v0.1.4
Nov 16 09:55:36.293 INFO [stderr]    Compiling crc-core v0.1.2
Nov 16 09:55:36.555 INFO [stderr]    Compiling bitflags v0.7.0
Nov 16 09:55:36.827 INFO [stderr]    Compiling build_const v0.2.1
Nov 16 09:55:36.931 INFO [stderr]    Compiling serde v1.0.70
Nov 16 09:55:37.184 INFO [stderr]    Compiling matches v0.1.7
Nov 16 09:55:37.244 INFO [stderr]    Compiling inflate v0.2.0
Nov 16 09:55:37.783 INFO [stderr]    Compiling histogram v0.6.9
Nov 16 09:55:38.224 INFO [stderr]    Compiling chunked_transfer v0.3.1
Nov 16 09:55:39.783 INFO [stderr]    Compiling slab v0.3.0
Nov 16 09:55:39.804 INFO [stderr]    Compiling hsl v0.1.1
Nov 16 09:55:40.122 INFO [stderr]    Compiling lazycell v0.5.1
Nov 16 09:55:40.184 INFO [stderr]    Compiling ascii v0.7.1
Nov 16 09:55:40.795 INFO [stderr]    Compiling unicode-width v0.1.5
Nov 16 09:55:41.199 INFO [stderr]    Compiling fnv v1.0.6
Nov 16 09:55:41.575 INFO [stderr]    Compiling mpmc v0.1.2
Nov 16 09:55:41.829 INFO [stderr]    Compiling getopts v0.2.15
Nov 16 09:55:42.141 INFO [stderr]    Compiling allan v0.2.4
Nov 16 09:55:42.192 INFO [stderr]    Compiling ratelimit v0.4.2
Nov 16 09:55:42.945 INFO [stderr]    Compiling bytes v0.3.0
Nov 16 09:55:43.188 INFO [stderr]    Compiling stb_truetype v0.1.2
Nov 16 09:55:45.819 INFO [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
Nov 16 09:55:47.376 INFO [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
Nov 16 09:55:49.055 INFO [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
Nov 16 09:55:49.374 INFO [stderr]    Compiling encoding-index-korean v1.20141219.5
Nov 16 09:55:52.165 INFO [stderr]    Compiling encoding-index-japanese v1.20141219.5
Nov 16 09:55:53.727 INFO [stderr]    Compiling log v0.4.3
Nov 16 09:55:54.595 INFO [stderr]    Compiling deflate v0.7.18
Nov 16 09:55:54.863 INFO [stderr]    Compiling arrayvec v0.3.25
Nov 16 09:55:55.211 INFO [stderr]    Compiling crc v1.5.0
Nov 16 09:55:56.035 INFO [stderr]    Compiling rand v0.3.17
Nov 16 09:55:57.504 INFO [stderr]    Compiling time v0.1.38
Nov 16 09:55:58.784 INFO [stderr]    Compiling iovec v0.1.2
Nov 16 09:56:00.424 INFO [stderr]    Compiling net2 v0.2.33
Nov 16 09:56:00.432 INFO [stderr]    Compiling clocksource v0.2.4
Nov 16 09:56:01.243 INFO [stderr]    Compiling shuteye v0.3.2
Nov 16 09:56:01.957 INFO [stderr]    Compiling pad v0.1.4
Nov 16 09:56:05.775 INFO [stderr]    Compiling log v0.3.9
Nov 16 09:56:07.127 INFO [stderr]    Compiling encoding v0.2.33
Nov 16 09:56:07.951 INFO [stderr]    Compiling rusttype v0.1.2
Nov 16 09:56:10.499 INFO [stderr]    Compiling heatmap v0.6.6
Nov 16 09:56:11.087 INFO [stderr]    Compiling mio v0.6.10
Nov 16 09:56:14.143 INFO [stderr]    Compiling log-panics v1.1.0
Nov 16 09:56:15.543 INFO [stderr]    Compiling simple_logger v0.4.0
Nov 16 09:56:17.925 INFO [stderr]    Compiling uuid v0.1.18
Nov 16 09:56:21.269 INFO [stderr]    Compiling url v0.2.38
Nov 16 09:56:22.939 INFO [stderr]    Compiling num v0.1.42
Nov 16 09:56:22.940 INFO [stderr]    Compiling png v0.7.0
Nov 16 09:56:23.793 INFO [stderr]    Compiling chrono v0.2.25
Nov 16 09:56:32.387 INFO [stderr]    Compiling waterfall v0.7.1
Nov 16 09:56:36.105 INFO [stderr]    Compiling tiny_http v0.5.9
Nov 16 09:56:47.703 INFO [stderr]    Compiling tic v0.2.4
Nov 16 09:56:50.619 INFO [stderr]    Compiling toml v0.4.6
Nov 16 09:56:56.142 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Nov 16 09:57:06.158 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 35s
Nov 16 09:57:06.179 INFO [stderr] su: No module specific data is present
Nov 16 09:57:09.189 INFO running `"docker" "inspect" "c4d47df635e658ef181af679e4ff34e3a03829a707d0d89b30577b124792082d"`
Nov 16 09:57:09.441 INFO running `"docker" "rm" "-f" "c4d47df635e658ef181af679e4ff34e3a03829a707d0d89b30577b124792082d"`
Nov 16 09:57:09.691 INFO [stdout] c4d47df635e658ef181af679e4ff34e3a03829a707d0d89b30577b124792082d
Nov 16 09:57:09.698 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:10.283 INFO [stdout] 83bdff3e23b09e028b86dee5a446f642011da3c145e732a141e665a92ceeb8bc
Nov 16 09:57:10.291 INFO running `"docker" "start" "-a" "83bdff3e23b09e028b86dee5a446f642011da3c145e732a141e665a92ceeb8bc"`
Nov 16 09:57:12.047 INFO [stderr] usermod: no changes
Nov 16 09:57:12.271 INFO [stderr]    Compiling rpc-perf v2.1.0-pre (/source)
Nov 16 09:57:17.068 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.99s
Nov 16 09:57:17.081 INFO [stderr] su: No module specific data is present
Nov 16 09:57:17.581 INFO running `"docker" "inspect" "83bdff3e23b09e028b86dee5a446f642011da3c145e732a141e665a92ceeb8bc"`
Nov 16 09:57:17.773 INFO running `"docker" "rm" "-f" "83bdff3e23b09e028b86dee5a446f642011da3c145e732a141e665a92ceeb8bc"`
Nov 16 09:57:18.086 INFO [stdout] 83bdff3e23b09e028b86dee5a446f642011da3c145e732a141e665a92ceeb8bc
Nov 16 09:57:18.095 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:18.550 INFO [stdout] 549f75adebb2b03a84aca08abc49acdfe25aca007de66b9d5e55f4b86adb6ba8
Nov 16 09:57:18.553 INFO running `"docker" "start" "-a" "549f75adebb2b03a84aca08abc49acdfe25aca007de66b9d5e55f4b86adb6ba8"`
Nov 16 09:57:19.799 INFO [stderr] usermod: no changes
Nov 16 09:57:19.967 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Nov 16 09:57:19.983 INFO [stderr]      Running /target/debug/deps/rpc_perf-9e10edff2393c673
Nov 16 09:57:19.999 INFO [stdout] 
Nov 16 09:57:19.999 INFO [stdout] running 73 tests
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_append ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::echo::parse::tests::test_parse_ok ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::echo::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_cas ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_add ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::echo::gen::tests::echo_test ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::echo::parse::tests::test_parse_error ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_quit ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_replace ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_set ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_touch ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_verbosity ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::gen::tests::test_version ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_error ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_miss ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_ok ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::memcache::parse::tests::test_parse_version ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::ping::gen::tests::test_ping ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::ping::parse::tests::test_parse_pong ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_append ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_decr ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_del ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_expire ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_flushall ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_get ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_hget ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_hset ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_incr ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_prepend ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::gen::tests::test_set ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_error ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_miss ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_inline::parse::tests::test_parse_ok ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_append ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_decr ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_del ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_expire ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_flushall ... ok
Nov 16 09:57:20.011 INFO [stdout] test codec::redis_resp::gen::tests::test_get ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_hget ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_hset ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_incr ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_prepend ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_select ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::gen::tests::test_set ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_error ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_invalid ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_miss ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::redis_resp::parse::tests::test_parse_ok ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::thrift::buffer::tests::into_vec ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::thrift::buffer::tests::test_method_name ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::thrift::buffer::tests::test_protocol_header ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::thrift::buffer::tests::test_sequence_id ... ok
Nov 16 09:57:20.012 INFO [stdout] test codec::thrift::buffer::tests::test_stop ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_decr ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_incr ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_prepend ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_gets ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_flush_all ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_get ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::memcache::gen::tests::test_delete ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::thrift::config::test_load_config ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::thrift::gen::test::test_ping ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::thrift::parse::tests::test_parse_ok ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::thrift::parse::tests::test_parse_incomplete ... ok
Nov 16 09:57:20.047 INFO [stdout] test codec::thrift::gen::test::thrift_ping ... ok
Nov 16 09:57:20.055 INFO [stdout] test codec::thrift::testutil::thrift_add ... ok
Nov 16 09:57:20.055 INFO [stdout] test codec::thrift::testutil::thrift_subtract ... ok
Nov 16 09:57:20.055 INFO [stdout] 
Nov 16 09:57:20.055 INFO [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 09:57:20.055 INFO [stdout] 
Nov 16 09:57:20.063 INFO [stderr] su: No module specific data is present
Nov 16 09:57:21.063 INFO running `"docker" "inspect" "549f75adebb2b03a84aca08abc49acdfe25aca007de66b9d5e55f4b86adb6ba8"`
Nov 16 09:57:21.343 INFO running `"docker" "rm" "-f" "549f75adebb2b03a84aca08abc49acdfe25aca007de66b9d5e55f4b86adb6ba8"`
Nov 16 09:57:21.617 INFO [stdout] 549f75adebb2b03a84aca08abc49acdfe25aca007de66b9d5e55f4b86adb6ba8