Dec 12 22:53:03.124 INFO testing ufoscout/docker-compose-wait against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 12 22:53:03.124 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:53:03.583 INFO [stdout] d32470f202243fd0e9f63f73af7eaacdf3e9a3da12be9d63542395af168f85ca
Dec 12 22:53:03.584 INFO running `"docker" "start" "-a" "d32470f202243fd0e9f63f73af7eaacdf3e9a3da12be9d63542395af168f85ca"`
Dec 12 22:53:04.059 INFO [stderr] usermod: no changes
Dec 12 22:53:04.120 INFO [stderr]    Compiling wait v2.5.0 (/source)
Dec 12 22:53:04.725 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.65s
Dec 12 22:53:04.728 INFO [stderr] su: No module specific data is present
Dec 12 22:53:05.080 INFO running `"docker" "inspect" "d32470f202243fd0e9f63f73af7eaacdf3e9a3da12be9d63542395af168f85ca"`
Dec 12 22:53:05.173 INFO running `"docker" "rm" "-f" "d32470f202243fd0e9f63f73af7eaacdf3e9a3da12be9d63542395af168f85ca"`
Dec 12 22:53:05.288 INFO [stdout] d32470f202243fd0e9f63f73af7eaacdf3e9a3da12be9d63542395af168f85ca
Dec 12 22:53:05.290 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:53:05.495 INFO [stdout] b3f90566d63d632b27d09a797658ec0d4ab1e5fd86e08abe8253796c2db78d53
Dec 12 22:53:05.500 INFO running `"docker" "start" "-a" "b3f90566d63d632b27d09a797658ec0d4ab1e5fd86e08abe8253796c2db78d53"`
Dec 12 22:53:06.007 INFO [stderr] usermod: no changes
Dec 12 22:53:06.066 INFO [stderr]    Compiling atomic-counter v1.0.1
Dec 12 22:53:06.067 INFO [stderr]    Compiling time v0.1.40
Dec 12 22:53:07.083 INFO [stderr]    Compiling wait v2.5.0 (/source)
Dec 12 22:53:07.997 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.97s
Dec 12 22:53:08.001 INFO [stderr] su: No module specific data is present
Dec 12 22:53:08.480 INFO running `"docker" "inspect" "b3f90566d63d632b27d09a797658ec0d4ab1e5fd86e08abe8253796c2db78d53"`
Dec 12 22:53:08.565 INFO running `"docker" "rm" "-f" "b3f90566d63d632b27d09a797658ec0d4ab1e5fd86e08abe8253796c2db78d53"`
Dec 12 22:53:08.650 INFO [stdout] b3f90566d63d632b27d09a797658ec0d4ab1e5fd86e08abe8253796c2db78d53
Dec 12 22:53:08.656 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 12 22:53:08.840 INFO [stdout] 76c6e9080f227912b45568d39930a436af7a075e99faa8b315c1c3ffa6ee4db0
Dec 12 22:53:08.842 INFO running `"docker" "start" "-a" "76c6e9080f227912b45568d39930a436af7a075e99faa8b315c1c3ffa6ee4db0"`
Dec 12 22:53:09.174 INFO [stderr] usermod: no changes
Dec 12 22:53:09.194 INFO [stderr]     Blocking waiting for file lock on the registry index
Dec 12 22:53:09.296 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 12 22:53:09.297 INFO [stderr]      Running /target/debug/deps/wait-90beabcd837cccbe
Dec 12 22:53:09.299 INFO [stdout] 
Dec 12 22:53:09.300 INFO [stdout] running 11 tests
Dec 12 22:53:09.300 INFO [stdout] test env_reader::test::should_return_the_default_value_if_env_variable_not_present ... ok
Dec 12 22:53:09.300 INFO [stdout] test sleeper::test::should_not_wait ... ok
Dec 12 22:53:09.300 INFO [stdout] test env_reader::test::should_return_an_env_variable ... ok
Dec 12 22:53:09.300 INFO [stdout] test tcp::test::port_should_be_closed ... ok
Dec 12 22:53:09.300 INFO [stdout] test test::should_return_int_value ... ok
Dec 12 22:53:09.300 INFO [stdout] test test::should_return_zero_when_empty_value ... ok
Dec 12 22:53:09.300 INFO [stdout] test test::should_return_zero_when_invalid_value ... ok
Dec 12 22:53:09.300 INFO [stdout] test test::should_return_zero_when_negative_value ... ok
Dec 12 22:53:09.300 INFO [stdout] test test::should_get_config_values_from_env ... ok
Dec 12 22:53:09.557 INFO [stdout] Connection received!
Dec 12 22:53:09.557 INFO [stdout] test tcp::test::port_should_be_open ... ok
Dec 12 22:53:10.319 INFO [stdout] test sleeper::test::should_wait_for_a_second ... ok
Dec 12 22:53:10.319 INFO [stdout] 
Dec 12 22:53:10.319 INFO [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:53:10.320 INFO [stdout] 
Dec 12 22:53:10.320 INFO [stdout] 
Dec 12 22:53:10.320 INFO [stdout] running 0 tests
Dec 12 22:53:10.320 INFO [stdout] 
Dec 12 22:53:10.320 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:53:10.320 INFO [stdout] 
Dec 12 22:53:10.320 INFO [stdout] 
Dec 12 22:53:10.320 INFO [stdout] running 8 tests
Dec 12 22:53:10.320 INFO [stdout] test should_execute_without_wait ... ok
Dec 12 22:53:10.320 INFO [stdout] test should_wait_5_seconds_before ... ok
Dec 12 22:53:10.320 INFO [stdout] test should_wait_10_seconds_after ... ok
Dec 12 22:53:10.320 INFO [stderr]      Running /target/debug/deps/wait-e14d0ff0b03b0fe8
Dec 12 22:53:10.320 INFO [stderr]      Running /target/debug/deps/integration_test-bc5b1fa43b751e3c
Dec 12 22:53:10.324 INFO [stdout] test should_wait_before_and_after ... ok
Dec 12 22:53:10.380 INFO [stdout] test should_exit_on_timeout ... ok
Dec 12 22:53:10.589 INFO [stdout] Connection received!
Dec 12 22:53:10.589 INFO [stdout] Connection received!
Dec 12 22:53:10.589 INFO [stdout] Connection received!
Dec 12 22:53:10.589 INFO [stdout] Connection received!
Dec 12 22:53:10.629 INFO [stdout] test should_identify_the_open_port ... ok
Dec 12 22:53:10.629 INFO [stdout] test should_wait_multiple_hosts ... ok
Dec 12 22:53:10.730 INFO [stdout] test should_fail_if_not_all_hosts_are_available ... ok
Dec 12 22:53:10.730 INFO [stdout] 
Dec 12 22:53:10.730 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:53:10.730 INFO [stdout] 
Dec 12 22:53:10.730 INFO [stderr]    Doc-tests wait
Dec 12 22:53:10.791 INFO [stdout] 
Dec 12 22:53:10.791 INFO [stdout] running 0 tests
Dec 12 22:53:10.830 INFO [stdout] 
Dec 12 22:53:10.831 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:53:10.831 INFO [stdout] 
Dec 12 22:53:10.871 INFO [stderr] su: No module specific data is present
Dec 12 22:53:11.437 INFO running `"docker" "inspect" "76c6e9080f227912b45568d39930a436af7a075e99faa8b315c1c3ffa6ee4db0"`
Dec 12 22:53:11.509 INFO running `"docker" "rm" "-f" "76c6e9080f227912b45568d39930a436af7a075e99faa8b315c1c3ffa6ee4db0"`
Dec 12 22:53:11.576 INFO [stdout] 76c6e9080f227912b45568d39930a436af7a075e99faa8b315c1c3ffa6ee4db0