Dec 13 21:43:19.469 INFO testing deifactor/clockwork-mage against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 21:43:19.469 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 13 21:43:20.092 INFO [stdout] 4fa6657edcbaaea1f0a26e3bab5510b0da645c89ca7838bdc360b383942d6221
Dec 13 21:43:20.094 INFO running `"docker" "start" "-a" "4fa6657edcbaaea1f0a26e3bab5510b0da645c89ca7838bdc360b383942d6221"`
Dec 13 21:43:23.064 INFO [stderr] usermod: no changes
Dec 13 21:43:23.327 INFO [stderr]    Compiling slog-stdlog v3.0.2
Dec 13 21:43:23.327 INFO [stderr]    Compiling slog-async v2.3.0
Dec 13 21:43:23.328 INFO [stderr]    Compiling trackable_derive v0.1.1
Dec 13 21:43:23.328 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 13 21:43:23.328 INFO [stderr]    Compiling slog-term v2.4.0
Dec 13 21:43:23.389 INFO [stderr]    Compiling slog-kvfilter v0.7.0
Dec 13 21:43:33.511 INFO [stderr]    Compiling trackable v0.2.20
Dec 13 21:43:35.633 INFO [stderr]    Compiling sloggers v0.3.0
Dec 13 21:43:36.288 INFO [stderr]    Compiling structopt v0.2.10
Dec 13 21:43:42.471 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 13 21:43:46.187 INFO [stderr] warning: field is never used: `target`
Dec 13 21:43:46.187 INFO [stderr]   --> src/simulator.rs:14:5
Dec 13 21:43:46.188 INFO [stderr]    |
Dec 13 21:43:46.188 INFO [stderr] 14 |     target: Target,
Dec 13 21:43:46.188 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 13 21:43:46.189 INFO [stderr]    |
Dec 13 21:43:46.189 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 21:43:46.189 INFO [stderr] 
Dec 13 21:43:46.189 INFO [stderr] warning: method is never used: `event_log`
Dec 13 21:43:46.190 INFO [stderr]   --> src/simulator.rs:49:5
Dec 13 21:43:46.190 INFO [stderr]    |
Dec 13 21:43:46.190 INFO [stderr] 49 |     pub fn event_log(&self) -> &Vec<Event> {
Dec 13 21:43:46.190 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 21:43:46.191 INFO [stderr] 
Dec 13 21:43:49.492 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 26.40s
Dec 13 21:43:49.502 INFO [stderr] su: No module specific data is present
Dec 13 21:43:51.131 INFO running `"docker" "inspect" "4fa6657edcbaaea1f0a26e3bab5510b0da645c89ca7838bdc360b383942d6221"`
Dec 13 21:43:51.417 INFO running `"docker" "rm" "-f" "4fa6657edcbaaea1f0a26e3bab5510b0da645c89ca7838bdc360b383942d6221"`
Dec 13 21:43:51.847 INFO [stdout] 4fa6657edcbaaea1f0a26e3bab5510b0da645c89ca7838bdc360b383942d6221
Dec 13 21:43:51.853 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 13 21:43:52.294 INFO [stdout] dd543781290882132e78b5fec638a4887150f7cf8f9f3b8239a438f21057390e
Dec 13 21:43:52.297 INFO running `"docker" "start" "-a" "dd543781290882132e78b5fec638a4887150f7cf8f9f3b8239a438f21057390e"`
Dec 13 21:43:54.058 INFO [stderr] usermod: no changes
Dec 13 21:43:54.239 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 13 21:43:57.530 INFO [stderr] warning: field is never used: `target`
Dec 13 21:43:57.530 INFO [stderr]   --> src/simulator.rs:14:5
Dec 13 21:43:57.530 INFO [stderr]    |
Dec 13 21:43:57.530 INFO [stderr] 14 |     target: Target,
Dec 13 21:43:57.530 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 13 21:43:57.531 INFO [stderr]    |
Dec 13 21:43:57.531 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 21:43:57.531 INFO [stderr] 
Dec 13 21:43:59.506 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.42s
Dec 13 21:43:59.527 INFO [stderr] su: No module specific data is present
Dec 13 21:44:00.427 INFO running `"docker" "inspect" "dd543781290882132e78b5fec638a4887150f7cf8f9f3b8239a438f21057390e"`
Dec 13 21:44:00.785 INFO running `"docker" "rm" "-f" "dd543781290882132e78b5fec638a4887150f7cf8f9f3b8239a438f21057390e"`
Dec 13 21:44:01.243 INFO [stdout] dd543781290882132e78b5fec638a4887150f7cf8f9f3b8239a438f21057390e
Dec 13 21:44:01.245 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 13 21:44:01.758 INFO [stdout] 3b62c3b202251e9271c866fc4efdc7d11fa335094a2ef41a67032611ee9ba514
Dec 13 21:44:01.760 INFO running `"docker" "start" "-a" "3b62c3b202251e9271c866fc4efdc7d11fa335094a2ef41a67032611ee9ba514"`
Dec 13 21:44:04.395 INFO [stderr] usermod: no changes
Dec 13 21:44:04.683 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Dec 13 21:44:04.687 INFO [stderr]      Running /target/debug/deps/clockwork_mage-0bcea0d23ea9a55a
Dec 13 21:44:04.690 INFO [stdout] 
Dec 13 21:44:04.690 INFO [stdout] running 15 tests
Dec 13 21:44:04.690 INFO [stdout] test simulator::tests::can_begin_on_same_tick_as_perform ... ok
Dec 13 21:44:04.691 INFO [stdout] test player::tests::starts_unlocked ... ok
Dec 13 21:44:04.695 INFO [stdout] test rotation::tests::repeat_takes_locks_into_account ... ok
Dec 13 21:44:04.695 INFO [stdout] test rotation::tests::repeat ... ok
Dec 13 21:44:04.707 INFO [stdout] test player::tests::unlock_timer ... ok
Dec 13 21:44:04.707 INFO [stdout] test player::tests::starts_locked_after_gcd ... ok
Dec 13 21:44:04.707 INFO [stdout] test player::tests::mp_deducted_on_finish ... ok
Dec 13 21:44:04.707 INFO [stdout] test simulator::tests::starts_at_zero ... ok
Dec 13 21:44:04.708 INFO [stdout] test time::tests::test_add ... ok
Dec 13 21:44:04.708 INFO [stdout] test time::tests::test_add_assign ... ok
Dec 13 21:44:04.708 INFO [stdout] test rotation::tests::empty ... ok
Dec 13 21:44:04.709 INFO [stdout] test time::tests::test_duration_minus_duration ... ok
Dec 13 21:44:04.709 INFO [stdout] test time::tests::test_clock ... ok
Dec 13 21:44:04.715 INFO [stdout] test time::tests::test_timestamp_minus_duration ... ok
Dec 13 21:44:04.715 INFO [stdout] test time::tests::test_timestamp_minus_timestamp ... ok
Dec 13 21:44:04.715 INFO [stdout] 
Dec 13 21:44:04.716 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:44:04.716 INFO [stdout] 
Dec 13 21:44:04.723 INFO [stderr] su: No module specific data is present
Dec 13 21:44:06.011 INFO running `"docker" "inspect" "3b62c3b202251e9271c866fc4efdc7d11fa335094a2ef41a67032611ee9ba514"`
Dec 13 21:44:06.413 INFO running `"docker" "rm" "-f" "3b62c3b202251e9271c866fc4efdc7d11fa335094a2ef41a67032611ee9ba514"`
Dec 13 21:44:06.719 INFO [stdout] 3b62c3b202251e9271c866fc4efdc7d11fa335094a2ef41a67032611ee9ba514