Nov 18 16:16:30.257 INFO testing desync-0.2.3 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 16:16:30.259 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 16:16:30.500 INFO [stdout] a36a9b2a24e7783b3b3cf34e8d8988dddb12937a50525d3efe30c3d3cbfd0c2b
Nov 18 16:16:30.501 INFO running `"docker" "start" "-a" "a36a9b2a24e7783b3b3cf34e8d8988dddb12937a50525d3efe30c3d3cbfd0c2b"`
Nov 18 16:16:31.082 INFO [stderr] usermod: no changes
Nov 18 16:16:31.147 INFO [stderr]    Compiling desync v0.2.3 (/source)
Nov 18 16:16:33.113 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.00s
Nov 18 16:16:33.118 INFO [stderr] su: No module specific data is present
Nov 18 16:16:33.506 INFO running `"docker" "inspect" "a36a9b2a24e7783b3b3cf34e8d8988dddb12937a50525d3efe30c3d3cbfd0c2b"`
Nov 18 16:16:33.745 INFO running `"docker" "rm" "-f" "a36a9b2a24e7783b3b3cf34e8d8988dddb12937a50525d3efe30c3d3cbfd0c2b"`
Nov 18 16:16:34.035 INFO [stdout] a36a9b2a24e7783b3b3cf34e8d8988dddb12937a50525d3efe30c3d3cbfd0c2b
Nov 18 16:16:34.041 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 16:16:34.464 INFO [stdout] a01afd34df1fd7cae7925c174a80c01c96944eebabe9b716bf17e03272a7bf23
Nov 18 16:16:34.466 INFO running `"docker" "start" "-a" "a01afd34df1fd7cae7925c174a80c01c96944eebabe9b716bf17e03272a7bf23"`
Nov 18 16:16:35.770 INFO [stderr] usermod: no changes
Nov 18 16:16:35.847 INFO [stderr]    Compiling desync v0.2.3 (/source)
Nov 18 16:16:47.842 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.04s
Nov 18 16:16:47.844 INFO [stderr] su: No module specific data is present
Nov 18 16:16:48.412 INFO running `"docker" "inspect" "a01afd34df1fd7cae7925c174a80c01c96944eebabe9b716bf17e03272a7bf23"`
Nov 18 16:16:48.547 INFO running `"docker" "rm" "-f" "a01afd34df1fd7cae7925c174a80c01c96944eebabe9b716bf17e03272a7bf23"`
Nov 18 16:16:48.772 INFO [stdout] a01afd34df1fd7cae7925c174a80c01c96944eebabe9b716bf17e03272a7bf23
Nov 18 16:16:48.774 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 16:16:48.953 INFO [stdout] e218e9213e4f4b28dc5aa3e07782682b3a98cb92586f061f05e5d99a2fd0b603
Nov 18 16:16:48.957 INFO running `"docker" "start" "-a" "e218e9213e4f4b28dc5aa3e07782682b3a98cb92586f061f05e5d99a2fd0b603"`
Nov 18 16:16:49.326 INFO [stderr] usermod: no changes
Nov 18 16:16:49.355 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Nov 18 16:16:49.376 INFO [stderr]      Running /target/debug/deps/desync-3621d799aa2e3335
Nov 18 16:16:49.376 INFO [stdout] 
Nov 18 16:16:49.376 INFO [stderr]      Running /target/debug/deps/desync-52932c3ae43a2245
Nov 18 16:16:49.376 INFO [stdout] running 0 tests
Nov 18 16:16:49.376 INFO [stdout] 
Nov 18 16:16:49.376 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:16:49.376 INFO [stdout] 
Nov 18 16:16:49.383 INFO [stdout] 
Nov 18 16:16:49.383 INFO [stdout] running 27 tests
Nov 18 16:16:49.383 INFO [stdout] test retrieve_data_synchronously ... ok
Nov 18 16:16:49.383 INFO [stdout] test retrieve_data_into_local_var ... ok
Nov 18 16:16:49.391 INFO [stdout] test scheduler::async::schedule_async ... ok
Nov 18 16:16:49.392 INFO [stdout] test scheduler::async::schedule_after_queue_released ... ok
Nov 18 16:16:49.487 INFO [stdout] test scheduler::future::future_waits_for_us ... ok
Nov 18 16:16:49.487 INFO [stdout] test scheduler::async::will_schedule_in_order ... ok
Nov 18 16:16:49.503 INFO [stdout] test scheduler::async::will_schedule_separate_queues_in_parallel ... ok
Nov 18 16:16:49.503 INFO [stdout] test scheduler::future::schedule_future ... ok
Nov 18 16:16:49.503 INFO [stdout] test scheduler::future::wait_for_future ... ok
Nov 18 16:16:49.515 INFO [stdout] test scheduler::sync::reschedule_after_immediate_sync ... ok
Nov 18 16:16:49.528 INFO [stdout] test scheduler::sync::schedule_sync ... ok
Nov 18 16:16:49.584 INFO [stdout] test dropping_while_running_isnt_obviously_bad ... ok
Nov 18 16:16:49.588 INFO [stdout] test scheduler::suspend::safe_to_drop_suspended_queue ... ok
Nov 18 16:16:49.615 INFO [stdout] test scheduler::sync::sync_has_synchronous_lifetime ... ok
Nov 18 16:16:49.647 INFO [stdout] test scheduler::suspend::suspend_queue_with_local_drain ... ok
Nov 18 16:16:49.655 INFO [stdout] test scheduler::sync::schedule_sync_after_async ... ok
Nov 18 16:16:49.707 INFO [stdout] test scheduler::sync::sync_drains_with_no_threads ... ok
Nov 18 16:16:49.747 INFO [stdout] test update_data_asynchronously ... ok
Nov 18 16:16:49.752 INFO [stdout] test scheduler::thread_management::will_despawn_extra_threads ... ok
Nov 18 16:16:49.809 INFO [stdout] test update_data_with_future ... ok
Nov 18 16:16:49.859 INFO [stdout] test wait_for_future ... ok
Nov 18 16:16:52.109 INFO [stdout] test update_data_with_future_1000_times ... ok
Nov 18 16:16:52.115 INFO [stdout] test scheduler::async::async_runs_in_order_1000_iter ... ok
Nov 18 16:16:52.121 INFO [stdout] test update_data_asynchronously_1000_times ... ok
Nov 18 16:16:52.539 INFO [stdout] test scheduler::suspend::resume_before_suspend ... ok
Nov 18 16:16:52.684 INFO [stdout] test scheduler::async::async_only_runs_once ... ok
Nov 18 16:16:56.644 INFO [stdout] test scheduler::suspend::suspend_queue ... ok
Nov 18 16:16:56.644 INFO [stdout] 
Nov 18 16:16:56.644 INFO [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:16:56.644 INFO [stdout] 
Nov 18 16:16:56.647 INFO [stderr]      Running /target/debug/deps/pipe-9ba2078882aba941
Nov 18 16:16:56.648 INFO [stdout] 
Nov 18 16:16:56.648 INFO [stdout] running 5 tests
Nov 18 16:16:56.650 INFO [stdout] test pipe_through_stream_closes ... ok
Nov 18 16:16:56.650 INFO [stdout] test pipe_through ... ok
Nov 18 16:16:56.661 INFO [stdout] test pipe_in_simple_stream ... ok
Nov 18 16:16:56.661 INFO [stdout] test pipe_in_mpsc_receiver ... ok
Nov 18 16:16:56.676 INFO [stdout] test pipe_through_produces_backpressure ... ok
Nov 18 16:16:56.676 INFO [stdout] 
Nov 18 16:16:56.676 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:16:56.676 INFO [stdout] 
Nov 18 16:16:56.678 INFO [stderr]    Doc-tests desync
Nov 18 16:16:57.667 INFO [stdout] 
Nov 18 16:16:57.667 INFO [stdout] running 9 tests
Nov 18 16:17:05.087 INFO [stdout] test src/scheduler.rs - scheduler (line 33) ... ok
Nov 18 16:17:05.592 INFO [stdout] test src/lib.rs -  (line 60) ... ok
Nov 18 16:17:05.651 INFO [stdout] test src/lib.rs -  (line 37) ... ok
Nov 18 16:17:06.125 INFO [stdout] test src/lib.rs -  (line 11) ... ok
Nov 18 16:17:06.571 INFO [stdout] test src/pipe.rs - pipe (line 20) ... ok
Nov 18 16:17:07.026 INFO [stdout] test src/lib.rs -  (line 20) ... ok
Nov 18 16:17:07.034 INFO [stdout] test src/scheduler.rs - scheduler (line 19) ... ok
Nov 18 16:17:07.224 INFO [stdout] test src/pipe.rs - pipe::pipe (line 164) ... ok
Nov 18 16:17:07.737 INFO [stdout] test src/scheduler.rs - scheduler (line 9) ... ok
Nov 18 16:17:07.737 INFO [stdout] 
Nov 18 16:17:07.737 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:17:07.737 INFO [stdout] 
Nov 18 16:17:07.754 INFO [stderr] su: No module specific data is present
Nov 18 16:17:08.037 INFO running `"docker" "inspect" "e218e9213e4f4b28dc5aa3e07782682b3a98cb92586f061f05e5d99a2fd0b603"`
Nov 18 16:17:08.140 INFO running `"docker" "rm" "-f" "e218e9213e4f4b28dc5aa3e07782682b3a98cb92586f061f05e5d99a2fd0b603"`
Nov 18 16:17:08.216 INFO [stdout] e218e9213e4f4b28dc5aa3e07782682b3a98cb92586f061f05e5d99a2fd0b603