Nov 18 16:32:55.012 INFO testing daemon-engine-0.1.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 16:32:55.012 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 16:32:55.423 INFO [stdout] 60cd7e21800fd70136b6c8b2c4ec889b4653b3c622287b661e39294eead5346b
Nov 18 16:32:55.431 INFO running `"docker" "start" "-a" "60cd7e21800fd70136b6c8b2c4ec889b4653b3c622287b661e39294eead5346b"`
Nov 18 16:32:56.918 INFO [stderr] usermod: no changes
Nov 18 16:32:57.143 INFO [stderr]    Compiling tokio-serde v0.2.0
Nov 18 16:32:58.238 INFO [stderr]    Compiling tokio-serde-json-mirror v0.1.0
Nov 18 16:32:59.287 INFO [stderr]    Compiling daemon-engine v0.1.0 (/source)
Nov 18 16:33:02.722 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:02.722 INFO [stderr]   --> src/client.rs:15:41
Nov 18 16:33:02.722 INFO [stderr]    |
Nov 18 16:33:02.722 INFO [stderr] 15 | use tokio_io::codec::length_delimited::{FramedRead, FramedWrite};
Nov 18 16:33:02.722 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 18 16:33:02.722 INFO [stderr]    |
Nov 18 16:33:02.722 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 18 16:33:02.722 INFO [stderr] 
Nov 18 16:33:02.724 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:02.724 INFO [stderr]   --> src/client.rs:15:53
Nov 18 16:33:02.724 INFO [stderr]    |
Nov 18 16:33:02.724 INFO [stderr] 15 | use tokio_io::codec::length_delimited::{FramedRead, FramedWrite};
Nov 18 16:33:02.724 INFO [stderr]    |                                                     ^^^^^^^^^^^
Nov 18 16:33:02.724 INFO [stderr] 
Nov 18 16:33:02.724 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:02.724 INFO [stderr]   --> src/client.rs:24:43
Nov 18 16:33:02.724 INFO [stderr]    |
Nov 18 16:33:02.724 INFO [stderr] 24 | type Receive<T, MSG> = Arc<Mutex<ReadJson<FramedRead<ReadHalf<T>>, MSG>>>;
Nov 18 16:33:02.724 INFO [stderr]    |                                           ^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 16:33:02.724 INFO [stderr] 
Nov 18 16:33:02.724 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:02.724 INFO [stderr]   --> src/client.rs:27:45
Nov 18 16:33:02.724 INFO [stderr]    |
Nov 18 16:33:02.724 INFO [stderr] 27 | type Transmit<T, MSG> = Arc<Mutex<WriteJson<FramedWrite<WriteHalf<T>>, MSG>>>;
Nov 18 16:33:02.724 INFO [stderr]    |                                             ^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 16:33:02.724 INFO [stderr] 
Nov 18 16:33:02.724 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:02.724 INFO [stderr]    --> src/client.rs:104:68
Nov 18 16:33:02.725 INFO [stderr]     |
Nov 18 16:33:02.725 INFO [stderr] 104 |         let receive = Arc::new(Mutex::new(ReadJson::<_, RESP>::new(FramedRead::new(
Nov 18 16:33:02.725 INFO [stderr]     |                                                                    ^^^^^^^^^^^^^^^
Nov 18 16:33:02.725 INFO [stderr] 
Nov 18 16:33:02.725 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:02.725 INFO [stderr]    --> src/client.rs:107:69
Nov 18 16:33:02.725 INFO [stderr]     |
Nov 18 16:33:02.725 INFO [stderr] 107 |         let transmit = Arc::new(Mutex::new(WriteJson::<_, REQ>::new(FramedWrite::new(
Nov 18 16:33:02.725 INFO [stderr]     |                                                                     ^^^^^^^^^^^^^^^^
Nov 18 16:33:02.725 INFO [stderr] 
Nov 18 16:33:12.907 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.95s
Nov 18 16:33:12.919 INFO [stderr] su: No module specific data is present
Nov 18 16:33:13.204 INFO running `"docker" "inspect" "60cd7e21800fd70136b6c8b2c4ec889b4653b3c622287b661e39294eead5346b"`
Nov 18 16:33:13.306 INFO running `"docker" "rm" "-f" "60cd7e21800fd70136b6c8b2c4ec889b4653b3c622287b661e39294eead5346b"`
Nov 18 16:33:13.457 INFO [stdout] 60cd7e21800fd70136b6c8b2c4ec889b4653b3c622287b661e39294eead5346b
Nov 18 16:33:13.461 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 16:33:13.650 INFO [stdout] 4e0c84db520e7b60741216c52499900f1e7dfa3e40b297a58bc50f4894c29b75
Nov 18 16:33:13.652 INFO running `"docker" "start" "-a" "4e0c84db520e7b60741216c52499900f1e7dfa3e40b297a58bc50f4894c29b75"`
Nov 18 16:33:14.334 INFO [stderr] usermod: no changes
Nov 18 16:33:14.623 INFO [stderr]    Compiling daemon-engine v0.1.0 (/source)
Nov 18 16:33:19.279 INFO [stderr] warning: unused import: `Instant`
Nov 18 16:33:19.279 INFO [stderr]   --> src/lib.rs:51:31
Nov 18 16:33:19.279 INFO [stderr]    |
Nov 18 16:33:19.279 INFO [stderr] 51 |     use std::time::{Duration, Instant};
Nov 18 16:33:19.279 INFO [stderr]    |                               ^^^^^^^
Nov 18 16:33:19.279 INFO [stderr]    |
Nov 18 16:33:19.279 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Nov 18 16:33:19.279 INFO [stderr] 
Nov 18 16:33:19.298 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:19.298 INFO [stderr]   --> src/client.rs:15:41
Nov 18 16:33:19.298 INFO [stderr]    |
Nov 18 16:33:19.298 INFO [stderr] 15 | use tokio_io::codec::length_delimited::{FramedRead, FramedWrite};
Nov 18 16:33:19.298 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 18 16:33:19.298 INFO [stderr]    |
Nov 18 16:33:19.298 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 18 16:33:19.298 INFO [stderr] 
Nov 18 16:33:19.298 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:19.298 INFO [stderr]   --> src/client.rs:15:53
Nov 18 16:33:19.298 INFO [stderr]    |
Nov 18 16:33:19.298 INFO [stderr] 15 | use tokio_io::codec::length_delimited::{FramedRead, FramedWrite};
Nov 18 16:33:19.298 INFO [stderr]    |                                                     ^^^^^^^^^^^
Nov 18 16:33:19.298 INFO [stderr] 
Nov 18 16:33:19.298 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:19.298 INFO [stderr]   --> src/client.rs:24:43
Nov 18 16:33:19.298 INFO [stderr]    |
Nov 18 16:33:19.298 INFO [stderr] 24 | type Receive<T, MSG> = Arc<Mutex<ReadJson<FramedRead<ReadHalf<T>>, MSG>>>;
Nov 18 16:33:19.298 INFO [stderr]    |                                           ^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 16:33:19.298 INFO [stderr] 
Nov 18 16:33:19.298 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:19.298 INFO [stderr]   --> src/client.rs:27:45
Nov 18 16:33:19.298 INFO [stderr]    |
Nov 18 16:33:19.298 INFO [stderr] 27 | type Transmit<T, MSG> = Arc<Mutex<WriteJson<FramedWrite<WriteHalf<T>>, MSG>>>;
Nov 18 16:33:19.298 INFO [stderr]    |                                             ^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 18 16:33:19.298 INFO [stderr] 
Nov 18 16:33:19.299 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedRead': Moved to tokio-codec
Nov 18 16:33:19.299 INFO [stderr]    --> src/client.rs:104:68
Nov 18 16:33:19.299 INFO [stderr]     |
Nov 18 16:33:19.299 INFO [stderr] 104 |         let receive = Arc::new(Mutex::new(ReadJson::<_, RESP>::new(FramedRead::new(
Nov 18 16:33:19.299 INFO [stderr]     |                                                                    ^^^^^^^^^^^^^^^
Nov 18 16:33:19.299 INFO [stderr] 
Nov 18 16:33:19.299 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::length_delimited::FramedWrite': Moved to tokio-codec
Nov 18 16:33:19.299 INFO [stderr]    --> src/client.rs:107:69
Nov 18 16:33:19.299 INFO [stderr]     |
Nov 18 16:33:19.299 INFO [stderr] 107 |         let transmit = Arc::new(Mutex::new(WriteJson::<_, REQ>::new(FramedWrite::new(
Nov 18 16:33:19.299 INFO [stderr]     |                                                                     ^^^^^^^^^^^^^^^^
Nov 18 16:33:19.299 INFO [stderr] 
Nov 18 16:33:23.739 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.36s
Nov 18 16:33:23.753 INFO [stderr] su: No module specific data is present
Nov 18 16:33:24.147 INFO running `"docker" "inspect" "4e0c84db520e7b60741216c52499900f1e7dfa3e40b297a58bc50f4894c29b75"`
Nov 18 16:33:24.232 INFO running `"docker" "rm" "-f" "4e0c84db520e7b60741216c52499900f1e7dfa3e40b297a58bc50f4894c29b75"`
Nov 18 16:33:24.337 INFO [stdout] 4e0c84db520e7b60741216c52499900f1e7dfa3e40b297a58bc50f4894c29b75
Nov 18 16:33:24.340 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 18 16:33:24.523 INFO [stdout] fe1e0d56e60c83258d4dcd3ae197d0c251adc0c9e517010a091450bdb2fcb9c1
Nov 18 16:33:24.523 INFO running `"docker" "start" "-a" "fe1e0d56e60c83258d4dcd3ae197d0c251adc0c9e517010a091450bdb2fcb9c1"`
Nov 18 16:33:25.279 INFO [stderr] usermod: no changes
Nov 18 16:33:25.512 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Nov 18 16:33:25.543 INFO [stderr]      Running /target/debug/deps/daemon_engine-619040be54b3dca0
Nov 18 16:33:25.549 INFO [stdout] 
Nov 18 16:33:25.549 INFO [stdout] running 3 tests
Nov 18 16:33:25.556 INFO [stdout] [TEST] Creating server
Nov 18 16:33:25.556 INFO [stdout] [TEST] Awaiting connect
Nov 18 16:33:25.557 INFO [stdout] [TEST] Creating client
Nov 18 16:33:25.557 INFO [stdout] test user::tests::test_get_groups ... ok
Nov 18 16:33:25.567 INFO [stdout] [TEST] Writing Data
Nov 18 16:33:25.579 INFO [stdout] test client::tests::client_ping_pong ... ok
Nov 18 16:33:27.561 INFO [stdout] [TEST] Reading Data
Nov 18 16:34:25.544 INFO [stdout] test tests::it_works ... test tests::it_works has been running for over 60 seconds
Nov 18 16:39:25.547 ERRO error running command: no output for 300 seconds
Nov 18 16:39:25.547 INFO running `"docker" "inspect" "fe1e0d56e60c83258d4dcd3ae197d0c251adc0c9e517010a091450bdb2fcb9c1"`
Nov 18 16:39:25.640 INFO running `"docker" "rm" "-f" "fe1e0d56e60c83258d4dcd3ae197d0c251adc0c9e517010a091450bdb2fcb9c1"`
Nov 18 16:39:25.996 INFO [stdout] fe1e0d56e60c83258d4dcd3ae197d0c251adc0c9e517010a091450bdb2fcb9c1