Dec 06 01:57:24.625 INFO testing deifactor/clockwork-mage against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 01:57:24.625 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 06 01:57:25.203 INFO [stdout] f5a0d608fecc35fc1010deb0f3f2773b2e67e1754e3b58bd3f5234ee821a0bd4
Dec 06 01:57:25.206 INFO running `"docker" "start" "-a" "f5a0d608fecc35fc1010deb0f3f2773b2e67e1754e3b58bd3f5234ee821a0bd4"`
Dec 06 01:57:26.679 INFO [stderr] usermod: no changes
Dec 06 01:57:26.906 INFO [stderr]    Compiling slog-stdlog v3.0.2
Dec 06 01:57:26.906 INFO [stderr]    Compiling slog-async v2.3.0
Dec 06 01:57:26.906 INFO [stderr]    Compiling trackable_derive v0.1.1
Dec 06 01:57:26.906 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 06 01:57:26.924 INFO [stderr]    Compiling slog-term v2.4.0
Dec 06 01:57:26.941 INFO [stderr]    Compiling slog-kvfilter v0.7.0
Dec 06 01:57:35.566 INFO [stderr]    Compiling trackable v0.2.20
Dec 06 01:57:36.672 INFO [stderr]    Compiling sloggers v0.3.0
Dec 06 01:57:37.090 INFO [stderr]    Compiling structopt v0.2.10
Dec 06 01:57:42.995 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 06 01:57:46.929 INFO [stderr] warning: field is never used: `target`
Dec 06 01:57:46.929 INFO [stderr]   --> src/simulator.rs:14:5
Dec 06 01:57:46.929 INFO [stderr]    |
Dec 06 01:57:46.932 INFO [stderr] 14 |     target: Target,
Dec 06 01:57:46.932 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 06 01:57:46.932 INFO [stderr]    |
Dec 06 01:57:46.932 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 06 01:57:46.932 INFO [stderr] 
Dec 06 01:57:46.932 INFO [stderr] warning: method is never used: `event_log`
Dec 06 01:57:46.932 INFO [stderr]   --> src/simulator.rs:49:5
Dec 06 01:57:46.932 INFO [stderr]    |
Dec 06 01:57:46.933 INFO [stderr] 49 |     pub fn event_log(&self) -> &Vec<Event> {
Dec 06 01:57:46.933 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 01:57:46.933 INFO [stderr] 
Dec 06 01:57:50.054 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.35s
Dec 06 01:57:50.062 INFO [stderr] su: No module specific data is present
Dec 06 01:57:50.992 INFO running `"docker" "inspect" "f5a0d608fecc35fc1010deb0f3f2773b2e67e1754e3b58bd3f5234ee821a0bd4"`
Dec 06 01:57:51.293 INFO running `"docker" "rm" "-f" "f5a0d608fecc35fc1010deb0f3f2773b2e67e1754e3b58bd3f5234ee821a0bd4"`
Dec 06 01:57:51.732 INFO [stdout] f5a0d608fecc35fc1010deb0f3f2773b2e67e1754e3b58bd3f5234ee821a0bd4
Dec 06 01:57:51.738 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 06 01:57:52.193 INFO [stdout] 3afea59d67f68c7bee95d0a3c0199d0ff7da654ee1cea7307a8bbba3f279258f
Dec 06 01:57:52.195 INFO running `"docker" "start" "-a" "3afea59d67f68c7bee95d0a3c0199d0ff7da654ee1cea7307a8bbba3f279258f"`
Dec 06 01:57:53.560 INFO [stderr] usermod: no changes
Dec 06 01:57:53.689 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 06 01:57:56.473 INFO [stderr] warning: field is never used: `target`
Dec 06 01:57:56.473 INFO [stderr]   --> src/simulator.rs:14:5
Dec 06 01:57:56.473 INFO [stderr]    |
Dec 06 01:57:56.474 INFO [stderr] 14 |     target: Target,
Dec 06 01:57:56.474 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 06 01:57:56.474 INFO [stderr]    |
Dec 06 01:57:56.474 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 06 01:57:56.474 INFO [stderr] 
Dec 06 01:57:58.074 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.49s
Dec 06 01:57:58.089 INFO [stderr] su: No module specific data is present
Dec 06 01:57:59.336 INFO running `"docker" "inspect" "3afea59d67f68c7bee95d0a3c0199d0ff7da654ee1cea7307a8bbba3f279258f"`
Dec 06 01:57:59.606 INFO running `"docker" "rm" "-f" "3afea59d67f68c7bee95d0a3c0199d0ff7da654ee1cea7307a8bbba3f279258f"`
Dec 06 01:57:59.928 INFO [stdout] 3afea59d67f68c7bee95d0a3c0199d0ff7da654ee1cea7307a8bbba3f279258f
Dec 06 01:57:59.930 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 06 01:58:00.386 INFO [stdout] dde1f288b31219f55c42a3c4dbb2cd61cd4fa93f51d0021822400915a8c92d95
Dec 06 01:58:00.390 INFO running `"docker" "start" "-a" "dde1f288b31219f55c42a3c4dbb2cd61cd4fa93f51d0021822400915a8c92d95"`
Dec 06 01:58:01.992 INFO [stderr] usermod: no changes
Dec 06 01:58:02.229 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Dec 06 01:58:02.236 INFO [stderr]      Running /target/debug/deps/clockwork_mage-687d1416d5672815
Dec 06 01:58:02.241 INFO [stdout] 
Dec 06 01:58:02.241 INFO [stdout] running 15 tests
Dec 06 01:58:02.251 INFO [stdout] test player::tests::starts_locked_after_gcd ... ok
Dec 06 01:58:02.251 INFO [stdout] test player::tests::mp_deducted_on_finish ... ok
Dec 06 01:58:02.251 INFO [stdout] test rotation::tests::empty ... ok
Dec 06 01:58:02.251 INFO [stdout] test simulator::tests::starts_at_zero ... ok
Dec 06 01:58:02.251 INFO [stdout] test simulator::tests::can_begin_on_same_tick_as_perform ... ok
Dec 06 01:58:02.251 INFO [stdout] test player::tests::unlock_timer ... ok
Dec 06 01:58:02.251 INFO [stdout] test rotation::tests::repeat_takes_locks_into_account ... ok
Dec 06 01:58:02.252 INFO [stdout] test player::tests::starts_unlocked ... ok
Dec 06 01:58:02.252 INFO [stdout] test rotation::tests::repeat ... ok
Dec 06 01:58:02.252 INFO [stdout] test time::tests::test_add_assign ... ok
Dec 06 01:58:02.252 INFO [stdout] test time::tests::test_add ... ok
Dec 06 01:58:02.253 INFO [stdout] test time::tests::test_clock ... ok
Dec 06 01:58:02.253 INFO [stdout] test time::tests::test_duration_minus_duration ... ok
Dec 06 01:58:02.253 INFO [stdout] test time::tests::test_timestamp_minus_timestamp ... ok
Dec 06 01:58:02.254 INFO [stdout] test time::tests::test_timestamp_minus_duration ... ok
Dec 06 01:58:02.254 INFO [stdout] 
Dec 06 01:58:02.255 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 01:58:02.255 INFO [stdout] 
Dec 06 01:58:02.258 INFO [stderr] su: No module specific data is present
Dec 06 01:58:03.459 INFO running `"docker" "inspect" "dde1f288b31219f55c42a3c4dbb2cd61cd4fa93f51d0021822400915a8c92d95"`
Dec 06 01:58:03.802 INFO running `"docker" "rm" "-f" "dde1f288b31219f55c42a3c4dbb2cd61cd4fa93f51d0021822400915a8c92d95"`
Dec 06 01:58:04.128 INFO [stdout] dde1f288b31219f55c42a3c4dbb2cd61cd4fa93f51d0021822400915a8c92d95