Dec 05 12:28:10.286 INFO testing nuclearfurnace/synchrotron against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 12:28:10.286 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 05 12:28:10.664 INFO [stdout] 6e11edf5cdbe133e93eca6ada92ec944b6421899795142bc389af3dcd566a3a1
Dec 05 12:28:10.664 INFO running `"docker" "start" "-a" "6e11edf5cdbe133e93eca6ada92ec944b6421899795142bc389af3dcd566a3a1"`
Dec 05 12:28:11.610 INFO [stderr] usermod: no changes
Dec 05 12:28:11.986 INFO [stderr]    Compiling synchrotron v0.1.0 (/source)
Dec 05 12:28:12.006 INFO [stderr]    Compiling log v0.4.6
Dec 05 12:28:12.006 INFO [stderr]    Compiling crossbeam-utils v0.3.2
Dec 05 12:28:12.032 INFO [stderr]    Compiling futures-turnstyle v3.0.0
Dec 05 12:28:12.032 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 05 12:28:12.044 INFO [stderr]    Compiling bytes v0.4.11
Dec 05 12:28:12.044 INFO [stderr]    Compiling crossbeam-epoch v0.5.2
Dec 05 12:28:12.044 INFO [stderr]    Compiling crossbeam-epoch v0.6.0
Dec 05 12:28:14.940 INFO [stderr]    Compiling parking_lot_core v0.4.0
Dec 05 12:28:14.945 INFO [stderr]    Compiling mio v0.6.16
Dec 05 12:28:14.963 INFO [stderr]    Compiling log v0.3.9
Dec 05 12:28:14.989 INFO [stderr]    Compiling crossbeam-epoch v0.4.3
Dec 05 12:28:15.086 INFO [stderr]    Compiling tokio-evacuate v1.1.0
Dec 05 12:28:17.055 INFO [stderr]    Compiling generic-array v0.8.3
Dec 05 12:28:17.061 INFO [stderr]    Compiling num-integer v0.1.39
Dec 05 12:28:17.444 INFO [stderr]    Compiling btoi v0.4.0
Dec 05 12:28:17.470 INFO [stderr]    Compiling serde_json v1.0.33
Dec 05 12:28:17.504 INFO [stderr]    Compiling crossbeam-deque v0.6.1
Dec 05 12:28:17.553 INFO [stderr]    Compiling crossbeam-deque v0.5.2
Dec 05 12:28:19.660 INFO [stderr]    Compiling nom v4.1.0
Dec 05 12:28:19.757 INFO [stderr]    Compiling slog-stdlog v3.0.2
Dec 05 12:28:19.970 INFO [stderr]    Compiling tokio-io v0.1.9
Dec 05 12:28:20.072 INFO [stderr]    Compiling digest v0.6.2
Dec 05 12:28:20.120 INFO [stderr]    Compiling regex v1.0.5
Dec 05 12:28:21.361 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 05 12:28:21.412 INFO [stderr]    Compiling crossbeam-arccell v0.6.4
Dec 05 12:28:22.539 INFO [stderr]    Compiling rand v0.6.1
Dec 05 12:28:26.827 INFO [stderr]    Compiling tokio-threadpool v0.1.7
Dec 05 12:28:27.276 INFO [stderr]    Compiling pruefung v0.2.1
Dec 05 12:28:30.584 INFO [stderr]    Compiling chrono v0.4.6
Dec 05 12:28:30.728 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 05 12:28:44.867 INFO [stderr]    Compiling tokio-codec v0.1.1
Dec 05 12:28:45.594 INFO [stderr]    Compiling tokio-reactor v0.1.6
Dec 05 12:28:46.336 INFO [stderr]    Compiling mio-uds v0.6.7
Dec 05 12:28:46.748 INFO [stderr]    Compiling hdrhistogram v6.1.0
Dec 05 12:28:47.416 INFO [stderr]    Compiling config v0.9.1
Dec 05 12:28:48.900 INFO [stderr]    Compiling crossbeam v0.4.1
Dec 05 12:28:50.472 INFO [stderr]    Compiling tokio-fs v0.1.3
Dec 05 12:29:03.252 INFO [stderr]    Compiling env_logger v0.5.13
Dec 05 12:29:03.316 INFO [stderr]    Compiling slog-term v2.4.0
Dec 05 12:29:03.440 INFO [stderr]    Compiling parking_lot v0.7.0
Dec 05 12:29:03.515 INFO [stderr]    Compiling tokio-uds v0.2.2
Dec 05 12:29:03.600 INFO [stderr]    Compiling tokio-udp v0.1.2
Dec 05 12:29:03.812 INFO [stderr]    Compiling tokio-tcp v0.1.2
Dec 05 12:29:12.500 INFO [stderr]    Compiling hotmic v0.2.1
Dec 05 12:29:13.004 INFO [stderr]    Compiling tokio v0.1.11
Dec 05 12:29:17.188 INFO [stderr]    Compiling tokio-io-pool v0.1.4
Dec 05 12:29:23.284 INFO [stderr] warning: unused variable: `client_addr`
Dec 05 12:29:23.284 INFO [stderr]    --> src/listener.rs:194:17
Dec 05 12:29:23.284 INFO [stderr]     |
Dec 05 12:29:23.284 INFO [stderr] 194 |             let client_addr = client.peer_addr().unwrap();
Dec 05 12:29:23.284 INFO [stderr]     |                 ^^^^^^^^^^^ help: consider using `_client_addr` instead
Dec 05 12:29:23.284 INFO [stderr]     |
Dec 05 12:29:23.284 INFO [stderr]     = note: #[warn(unused_variables)] on by default
Dec 05 12:29:23.284 INFO [stderr] 
Dec 05 12:29:23.864 INFO [stderr] warning: method is never used: `update_latency`
Dec 05 12:29:23.864 INFO [stderr]    --> src/metrics/mod.rs:102:5
Dec 05 12:29:23.864 INFO [stderr]     |
Dec 05 12:29:23.864 INFO [stderr] 102 |     pub fn update_latency(&mut self, key: Metrics, start: Instant, stop: Instant) {
Dec 05 12:29:23.864 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 05 12:29:23.864 INFO [stderr]     |
Dec 05 12:29:23.864 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 05 12:29:23.864 INFO [stderr] 
Dec 05 12:29:38.489 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 26s
Dec 05 12:29:38.508 INFO [stderr] su: No module specific data is present
Dec 05 12:29:40.264 INFO running `"docker" "inspect" "6e11edf5cdbe133e93eca6ada92ec944b6421899795142bc389af3dcd566a3a1"`
Dec 05 12:29:40.648 INFO running `"docker" "rm" "-f" "6e11edf5cdbe133e93eca6ada92ec944b6421899795142bc389af3dcd566a3a1"`
Dec 05 12:29:41.012 INFO [stdout] 6e11edf5cdbe133e93eca6ada92ec944b6421899795142bc389af3dcd566a3a1
Dec 05 12:29:41.018 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 05 12:29:41.468 INFO [stdout] 376a9bccc8bd6fb442cfd49ac0200eed310d0ba00816f625222d6cc181831ef4
Dec 05 12:29:41.470 INFO running `"docker" "start" "-a" "376a9bccc8bd6fb442cfd49ac0200eed310d0ba00816f625222d6cc181831ef4"`
Dec 05 12:29:43.912 INFO [stderr] usermod: no changes
Dec 05 12:29:44.345 INFO [stderr]    Compiling num-complex v0.1.43
Dec 05 12:29:44.360 INFO [stderr]    Compiling num-bigint v0.1.44
Dec 05 12:29:44.360 INFO [stderr]    Compiling num-iter v0.1.37
Dec 05 12:29:48.554 INFO [stderr]    Compiling num-rational v0.1.42
Dec 05 12:29:50.744 INFO [stderr]    Compiling num v0.1.42
Dec 05 12:29:51.182 INFO [stderr]    Compiling spectral v0.6.0
Dec 05 12:29:52.522 INFO [stderr]    Compiling synchrotron v0.1.0 (/source)
Dec 05 12:29:55.724 INFO [stderr] warning: unused variable: `client_addr`
Dec 05 12:29:55.724 INFO [stderr]    --> src/listener.rs:194:17
Dec 05 12:29:55.724 INFO [stderr]     |
Dec 05 12:29:55.724 INFO [stderr] 194 |             let client_addr = client.peer_addr().unwrap();
Dec 05 12:29:55.724 INFO [stderr]     |                 ^^^^^^^^^^^ help: consider using `_client_addr` instead
Dec 05 12:29:55.724 INFO [stderr]     |
Dec 05 12:29:55.724 INFO [stderr]     = note: #[warn(unused_variables)] on by default
Dec 05 12:29:55.724 INFO [stderr] 
Dec 05 12:29:56.306 INFO [stderr] warning: method is never used: `update_latency`
Dec 05 12:29:56.306 INFO [stderr]    --> src/metrics/mod.rs:102:5
Dec 05 12:29:56.306 INFO [stderr]     |
Dec 05 12:29:56.306 INFO [stderr] 102 |     pub fn update_latency(&mut self, key: Metrics, start: Instant, stop: Instant) {
Dec 05 12:29:56.306 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 05 12:29:56.306 INFO [stderr]     |
Dec 05 12:29:56.306 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 05 12:29:56.306 INFO [stderr] 
Dec 05 12:29:59.718 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.77s
Dec 05 12:29:59.753 INFO [stderr] su: No module specific data is present
Dec 05 12:30:01.569 INFO running `"docker" "inspect" "376a9bccc8bd6fb442cfd49ac0200eed310d0ba00816f625222d6cc181831ef4"`
Dec 05 12:30:01.902 INFO running `"docker" "rm" "-f" "376a9bccc8bd6fb442cfd49ac0200eed310d0ba00816f625222d6cc181831ef4"`
Dec 05 12:30:02.207 INFO [stdout] 376a9bccc8bd6fb442cfd49ac0200eed310d0ba00816f625222d6cc181831ef4
Dec 05 12:30:02.212 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 05 12:30:02.702 INFO [stdout] 8b8e673f2d7d51e78bb98e5e032179beb68456bf2968186ac31b3b337c34676a
Dec 05 12:30:02.704 INFO running `"docker" "start" "-a" "8b8e673f2d7d51e78bb98e5e032179beb68456bf2968186ac31b3b337c34676a"`
Dec 05 12:30:04.943 INFO [stderr] usermod: no changes
Dec 05 12:30:05.223 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.25s
Dec 05 12:30:05.256 INFO [stderr]      Running /target/debug/deps/synchrotron-b8e4e3d6d1b5c0b7
Dec 05 12:30:05.261 INFO [stdout] 
Dec 05 12:30:05.261 INFO [stdout] running 26 tests
Dec 05 12:30:05.262 INFO [stdout] test backend::redis::tests::test_get_data_buffer ... ok
Dec 05 12:30:05.276 INFO [stdout] test protocol::redis::tests::bench_parse_get_simple ... ok
Dec 05 12:30:05.292 INFO [stdout] test protocol::redis::tests::bench_ping_upper ... ok
Dec 05 12:30:05.304 INFO [stdout] test protocol::redis::tests::bench_short_circuit_1_no_array_crlf ... ok
Dec 05 12:30:05.304 INFO [stdout] test protocol::redis::tests::bench_short_circuit_0_zero_data ... ok
Dec 05 12:30:05.304 INFO [stdout] test backend::redis::tests::test_is_multi_message ... ok
Dec 05 12:30:05.305 INFO [stdout] test protocol::redis::tests::bench_short_circuit_3_partial_arg ... ok
Dec 05 12:30:05.305 INFO [stdout] test protocol::redis::tests::bench_short_circuit_2_no_arg_len_crlf ... ok
Dec 05 12:30:05.305 INFO [stdout] test protocol::redis::tests::bench_ping_lower ... ok
Dec 05 12:30:05.320 INFO [stdout] test protocol::redis::tests::bench_short_circuit_4_missing_arg ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_error ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_get_simple ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_null ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_bulk_with_null ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::bench_short_circuit_5_arg_len_past_end ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_ping ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_arg_len_past_end ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_quit ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_missing_arg ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_no_arg_len_crlf ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_no_array_crlf ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_partial_arg ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_status ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_short_circuit_zero_data ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_integer ... ok
Dec 05 12:30:05.321 INFO [stdout] test protocol::redis::tests::parse_ok ... ok
Dec 05 12:30:05.321 INFO [stdout] 
Dec 05 12:30:05.321 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 12:30:05.321 INFO [stdout] 
Dec 05 12:30:05.337 INFO [stderr] su: No module specific data is present
Dec 05 12:30:07.075 INFO running `"docker" "inspect" "8b8e673f2d7d51e78bb98e5e032179beb68456bf2968186ac31b3b337c34676a"`
Dec 05 12:30:07.402 INFO running `"docker" "rm" "-f" "8b8e673f2d7d51e78bb98e5e032179beb68456bf2968186ac31b3b337c34676a"`
Dec 05 12:30:07.782 INFO [stdout] 8b8e673f2d7d51e78bb98e5e032179beb68456bf2968186ac31b3b337c34676a