Dec 13 21:43:10.090 INFO testing deifactor/clockwork-mage against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 21:43:10.090 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:10.593 INFO [stdout] a5ae9731b03dc3f71da11766762ce90604213089583b46fd0a1f5b00486867ba
Dec 13 21:43:10.596 INFO running `"docker" "start" "-a" "a5ae9731b03dc3f71da11766762ce90604213089583b46fd0a1f5b00486867ba"`
Dec 13 21:43:13.315 INFO [stderr] usermod: no changes
Dec 13 21:43:13.557 INFO [stderr]    Compiling proc-macro2 v0.4.20
Dec 13 21:43:13.603 INFO [stderr]    Compiling slog-stdlog v3.0.2
Dec 13 21:43:13.607 INFO [stderr]    Compiling slog-async v2.3.0
Dec 13 21:43:13.607 INFO [stderr]    Compiling slog-term v2.4.0
Dec 13 21:43:13.615 INFO [stderr]    Compiling slog-kvfilter v0.7.0
Dec 13 21:43:23.287 INFO [stderr]    Compiling quote v0.6.8
Dec 13 21:43:24.437 INFO [stderr]    Compiling syn v0.15.8
Dec 13 21:43:24.445 INFO [stderr]    Compiling syn v0.14.9
Dec 13 21:43:40.859 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 13 21:43:43.467 INFO [stderr]    Compiling trackable_derive v0.1.1
Dec 13 21:43:43.467 INFO [stderr]    Compiling serde_derive v1.0.79
Dec 13 21:43:48.560 INFO [stderr]    Compiling structopt v0.2.10
Dec 13 21:43:49.197 INFO [stderr]    Compiling trackable v0.2.20
Dec 13 21:44:02.972 INFO [stderr]    Compiling sloggers v0.3.0
Dec 13 21:44:08.995 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 13 21:44:11.667 INFO [stderr] warning: field is never used: `target`
Dec 13 21:44:11.667 INFO [stderr]   --> src/simulator.rs:14:5
Dec 13 21:44:11.667 INFO [stderr]    |
Dec 13 21:44:11.667 INFO [stderr] 14 |     target: Target,
Dec 13 21:44:11.667 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 13 21:44:11.667 INFO [stderr]    |
Dec 13 21:44:11.667 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 21:44:11.667 INFO [stderr] 
Dec 13 21:44:11.667 INFO [stderr] warning: method is never used: `event_log`
Dec 13 21:44:11.667 INFO [stderr]   --> src/simulator.rs:49:5
Dec 13 21:44:11.667 INFO [stderr]    |
Dec 13 21:44:11.667 INFO [stderr] 49 |     pub fn event_log(&self) -> &Vec<Event> {
Dec 13 21:44:11.667 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 21:44:11.667 INFO [stderr] 
Dec 13 21:44:15.205 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 01s
Dec 13 21:44:15.219 INFO [stderr] su: No module specific data is present
Dec 13 21:44:16.590 INFO running `"docker" "inspect" "a5ae9731b03dc3f71da11766762ce90604213089583b46fd0a1f5b00486867ba"`
Dec 13 21:44:16.967 INFO running `"docker" "rm" "-f" "a5ae9731b03dc3f71da11766762ce90604213089583b46fd0a1f5b00486867ba"`
Dec 13 21:44:17.307 INFO [stdout] a5ae9731b03dc3f71da11766762ce90604213089583b46fd0a1f5b00486867ba
Dec 13 21:44:17.319 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:44:17.889 INFO [stdout] ed3801b575e1d899a7c3b370acd6c58915d4dbcf8039f0642e5c9e7afb0b0898
Dec 13 21:44:17.892 INFO running `"docker" "start" "-a" "ed3801b575e1d899a7c3b370acd6c58915d4dbcf8039f0642e5c9e7afb0b0898"`
Dec 13 21:44:20.154 INFO [stderr] usermod: no changes
Dec 13 21:44:20.439 INFO [stderr]    Compiling clockwork-mage v0.1.0 (/source)
Dec 13 21:44:23.515 INFO [stderr] warning: field is never used: `target`
Dec 13 21:44:23.515 INFO [stderr]   --> src/simulator.rs:14:5
Dec 13 21:44:23.515 INFO [stderr]    |
Dec 13 21:44:23.515 INFO [stderr] 14 |     target: Target,
Dec 13 21:44:23.515 INFO [stderr]    |     ^^^^^^^^^^^^^^
Dec 13 21:44:23.515 INFO [stderr]    |
Dec 13 21:44:23.515 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 21:44:23.515 INFO [stderr] 
Dec 13 21:44:25.333 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 5.14s
Dec 13 21:44:25.342 INFO [stderr] su: No module specific data is present
Dec 13 21:44:26.355 INFO running `"docker" "inspect" "ed3801b575e1d899a7c3b370acd6c58915d4dbcf8039f0642e5c9e7afb0b0898"`
Dec 13 21:44:26.676 INFO running `"docker" "rm" "-f" "ed3801b575e1d899a7c3b370acd6c58915d4dbcf8039f0642e5c9e7afb0b0898"`
Dec 13 21:44:27.059 INFO [stdout] ed3801b575e1d899a7c3b370acd6c58915d4dbcf8039f0642e5c9e7afb0b0898
Dec 13 21:44:27.065 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:27.631 INFO [stdout] c85c680235353bb88b8b3c9ec16728edfdfef7712e18dd9cb1b0421767e7a862
Dec 13 21:44:27.633 INFO running `"docker" "start" "-a" "c85c680235353bb88b8b3c9ec16728edfdfef7712e18dd9cb1b0421767e7a862"`
Dec 13 21:44:29.043 INFO [stderr] usermod: no changes
Dec 13 21:44:29.275 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 13 21:44:29.284 INFO [stderr]      Running /target/debug/deps/clockwork_mage-2fcf3caab58968df
Dec 13 21:44:29.291 INFO [stdout] 
Dec 13 21:44:29.291 INFO [stdout] running 15 tests
Dec 13 21:44:29.291 INFO [stdout] test player::tests::mp_deducted_on_finish ... ok
Dec 13 21:44:29.291 INFO [stdout] test player::tests::starts_locked_after_gcd ... ok
Dec 13 21:44:29.291 INFO [stdout] test rotation::tests::repeat_takes_locks_into_account ... ok
Dec 13 21:44:29.291 INFO [stdout] test simulator::tests::can_begin_on_same_tick_as_perform ... ok
Dec 13 21:44:29.291 INFO [stdout] test rotation::tests::repeat ... ok
Dec 13 21:44:29.291 INFO [stdout] test simulator::tests::starts_at_zero ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_add ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_clock ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_add_assign ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_duration_minus_duration ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_timestamp_minus_duration ... ok
Dec 13 21:44:29.291 INFO [stdout] test rotation::tests::empty ... ok
Dec 13 21:44:29.291 INFO [stdout] test time::tests::test_timestamp_minus_timestamp ... ok
Dec 13 21:44:29.293 INFO [stdout] test player::tests::starts_unlocked ... ok
Dec 13 21:44:29.293 INFO [stdout] test player::tests::unlock_timer ... ok
Dec 13 21:44:29.293 INFO [stdout] 
Dec 13 21:44:29.293 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 21:44:29.293 INFO [stdout] 
Dec 13 21:44:29.297 INFO [stderr] su: No module specific data is present
Dec 13 21:44:30.275 INFO running `"docker" "inspect" "c85c680235353bb88b8b3c9ec16728edfdfef7712e18dd9cb1b0421767e7a862"`
Dec 13 21:44:30.649 INFO running `"docker" "rm" "-f" "c85c680235353bb88b8b3c9ec16728edfdfef7712e18dd9cb1b0421767e7a862"`
Dec 13 21:44:31.035 INFO [stdout] c85c680235353bb88b8b3c9ec16728edfdfef7712e18dd9cb1b0421767e7a862