Nov 18 09:20:52.427 INFO testing hybrid-clocks-0.3.4 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 09:20:52.427 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:20:52.579 INFO [stdout] 09a74fca5d9a7b59cf5290d3867a5c4e27d42ff69f9a02c29473a28b2d3b5bda
Nov 18 09:20:52.584 INFO running `"docker" "start" "-a" "09a74fca5d9a7b59cf5290d3867a5c4e27d42ff69f9a02c29473a28b2d3b5bda"`
Nov 18 09:20:53.046 INFO [stderr] usermod: no changes
Nov 18 09:20:53.111 INFO [stderr]    Compiling suppositions v0.1.4
Nov 18 09:20:54.616 INFO [stderr]    Compiling hybrid-clocks v0.3.4 (/source)
Nov 18 09:20:55.296 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.22s
Nov 18 09:20:55.301 INFO [stderr] su: No module specific data is present
Nov 18 09:20:55.640 INFO running `"docker" "inspect" "09a74fca5d9a7b59cf5290d3867a5c4e27d42ff69f9a02c29473a28b2d3b5bda"`
Nov 18 09:20:55.711 INFO running `"docker" "rm" "-f" "09a74fca5d9a7b59cf5290d3867a5c4e27d42ff69f9a02c29473a28b2d3b5bda"`
Nov 18 09:20:55.810 INFO [stdout] 09a74fca5d9a7b59cf5290d3867a5c4e27d42ff69f9a02c29473a28b2d3b5bda
Nov 18 09:20:55.819 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:20:56.007 INFO [stdout] 83e211c4fab88d274c5ae704b9a61b0e75acfeb64e41a7c89306dbb60cf9267c
Nov 18 09:20:56.012 INFO running `"docker" "start" "-a" "83e211c4fab88d274c5ae704b9a61b0e75acfeb64e41a7c89306dbb60cf9267c"`
Nov 18 09:20:56.516 INFO [stderr] usermod: no changes
Nov 18 09:20:56.565 INFO [stderr]    Compiling hybrid-clocks v0.3.4 (/source)
Nov 18 09:20:58.539 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.01s
Nov 18 09:20:58.544 INFO [stderr] su: No module specific data is present
Nov 18 09:20:58.761 INFO running `"docker" "inspect" "83e211c4fab88d274c5ae704b9a61b0e75acfeb64e41a7c89306dbb60cf9267c"`
Nov 18 09:20:58.835 INFO running `"docker" "rm" "-f" "83e211c4fab88d274c5ae704b9a61b0e75acfeb64e41a7c89306dbb60cf9267c"`
Nov 18 09:20:58.915 INFO [stdout] 83e211c4fab88d274c5ae704b9a61b0e75acfeb64e41a7c89306dbb60cf9267c
Nov 18 09:20:58.919 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 09:20:59.112 INFO [stdout] a97f06be14428cf80f11e440db075fdbfd760f5245ee33b0526b09b7523e885b
Nov 18 09:20:59.116 INFO running `"docker" "start" "-a" "a97f06be14428cf80f11e440db075fdbfd760f5245ee33b0526b09b7523e885b"`
Nov 18 09:20:59.554 INFO [stderr] usermod: no changes
Nov 18 09:20:59.616 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 18 09:20:59.628 INFO [stderr]      Running /target/debug/deps/hybrid_clocks-ac4752021959baf3
Nov 18 09:20:59.630 INFO [stdout] 
Nov 18 09:20:59.630 INFO [stdout] running 21 tests
Nov 18 09:20:59.630 INFO [stdout] test tests::fig_6_proc_1_a ... ok
Nov 18 09:20:59.632 INFO [stdout] test tests::fig_6_proc_2_c ... ok
Nov 18 09:20:59.633 INFO [stdout] test tests::fig_6_proc_0_a ... ok
Nov 18 09:20:59.633 INFO [stdout] test tests::fig_6_proc_1_b ... ok
Nov 18 09:20:59.633 INFO [stdout] test tests::handles_time_going_backwards_now ... ok
Nov 18 09:20:59.633 INFO [stdout] test tests::all_sources_same ... ok
Nov 18 09:20:59.633 INFO [stdout] test tests::fig_6_proc_2_b ... ok
Nov 18 09:20:59.634 INFO [stdout] test tests::byte_repr_should_order_as_timestamps ... ok
Nov 18 09:20:59.634 INFO [stdout] test tests::should_remember_epochs ... ok
Nov 18 09:20:59.636 INFO [stdout] test tests::handles_time_going_forwards_now ... ok
Nov 18 09:20:59.637 INFO [stdout] test tests::should_round_trip_via_key ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_account_for_time_passing_when_checking_max_error ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_ignore_clocks_too_far_forward ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_apply_configured_epoch ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::handles_time_going_forwards_observe ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_order_primarily_via_epoch ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::handles_time_going_backwards_observe ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_update_via_observed_epochs ... ok
Nov 18 09:20:59.638 INFO [stdout] test tests::should_use_time_from_larger_observed_epoch ... ok
Nov 18 09:20:59.640 INFO [stdout] test tests::supposedly_be_larger_than_observed_clock ... ok
Nov 18 09:20:59.642 INFO [stdout] test tests::supposedly_be_larger_than_observed_time ... ok
Nov 18 09:20:59.642 INFO [stdout] 
Nov 18 09:20:59.642 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 09:20:59.643 INFO [stdout] 
Nov 18 09:20:59.643 INFO [stderr]    Doc-tests hybrid-clocks
Nov 18 09:21:00.386 INFO [stdout] 
Nov 18 09:21:00.386 INFO [stdout] running 0 tests
Nov 18 09:21:00.387 INFO [stdout] 
Nov 18 09:21:00.387 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 09:21:00.387 INFO [stdout] 
Nov 18 09:21:00.396 INFO [stderr] su: No module specific data is present
Nov 18 09:21:00.723 INFO running `"docker" "inspect" "a97f06be14428cf80f11e440db075fdbfd760f5245ee33b0526b09b7523e885b"`
Nov 18 09:21:00.952 INFO running `"docker" "rm" "-f" "a97f06be14428cf80f11e440db075fdbfd760f5245ee33b0526b09b7523e885b"`
Nov 18 09:21:01.231 INFO [stdout] a97f06be14428cf80f11e440db075fdbfd760f5245ee33b0526b09b7523e885b