Dec 14 19:08:29.661 INFO testing sonnerie-0.4.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 19:08:29.661 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 14 19:08:29.915 INFO [stdout] 07fa87bb50bfa384ba9519522bd91a030811a2c0a58711185ad26f67b3a5a72a
Dec 14 19:08:29.916 INFO running `"docker" "start" "-a" "07fa87bb50bfa384ba9519522bd91a030811a2c0a58711185ad26f67b3a5a72a"`
Dec 14 19:08:30.347 INFO [stderr] usermod: no changes
Dec 14 19:08:30.503 INFO [stderr]    Compiling escape_string v0.1.1
Dec 14 19:08:30.504 INFO [stderr]    Compiling intrusive-collections v0.7.8
Dec 14 19:08:30.504 INFO [stderr]    Compiling range v0.3.1
Dec 14 19:08:30.505 INFO [stderr]    Compiling linestream v0.1.0
Dec 14 19:08:30.506 INFO [stderr]    Compiling daemonize v0.3.0
Dec 14 19:08:30.506 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 14 19:08:30.507 INFO [stderr]    Compiling rustyline v2.1.0
Dec 14 19:08:32.968 INFO [stderr]    Compiling sonnerie-api v0.4.0
Dec 14 19:08:37.775 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 14 19:08:47.135 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.77s
Dec 14 19:08:47.159 INFO [stderr] su: No module specific data is present
Dec 14 19:08:47.775 INFO running `"docker" "inspect" "07fa87bb50bfa384ba9519522bd91a030811a2c0a58711185ad26f67b3a5a72a"`
Dec 14 19:08:48.129 INFO running `"docker" "rm" "-f" "07fa87bb50bfa384ba9519522bd91a030811a2c0a58711185ad26f67b3a5a72a"`
Dec 14 19:08:48.431 INFO [stdout] 07fa87bb50bfa384ba9519522bd91a030811a2c0a58711185ad26f67b3a5a72a
Dec 14 19:08:48.437 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 14 19:08:48.916 INFO [stdout] 1fb972b437e38e5c396cee9e3f27e79e4ac24db7da61950cbcc5340f6ef8cf9f
Dec 14 19:08:48.923 INFO running `"docker" "start" "-a" "1fb972b437e38e5c396cee9e3f27e79e4ac24db7da61950cbcc5340f6ef8cf9f"`
Dec 14 19:08:50.651 INFO [stderr] usermod: no changes
Dec 14 19:08:50.835 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 14 19:09:01.319 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.65s
Dec 14 19:09:01.331 INFO [stderr] su: No module specific data is present
Dec 14 19:09:02.408 INFO running `"docker" "inspect" "1fb972b437e38e5c396cee9e3f27e79e4ac24db7da61950cbcc5340f6ef8cf9f"`
Dec 14 19:09:02.759 INFO running `"docker" "rm" "-f" "1fb972b437e38e5c396cee9e3f27e79e4ac24db7da61950cbcc5340f6ef8cf9f"`
Dec 14 19:09:03.146 INFO [stdout] 1fb972b437e38e5c396cee9e3f27e79e4ac24db7da61950cbcc5340f6ef8cf9f
Dec 14 19:09:03.151 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 14 19:09:03.543 INFO [stdout] abdb35f9ae5aa3de5061a88f0f6e2a917079c341996075f9bb134ecb9112f7bc
Dec 14 19:09:03.549 INFO running `"docker" "start" "-a" "abdb35f9ae5aa3de5061a88f0f6e2a917079c341996075f9bb134ecb9112f7bc"`
Dec 14 19:09:05.410 INFO [stderr] usermod: no changes
Dec 14 19:09:05.655 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Dec 14 19:09:05.667 INFO [stderr]      Running /target/debug/deps/sonnerie-d13613ad847e6a6f
Dec 14 19:09:05.679 INFO [stdout] 
Dec 14 19:09:05.679 INFO [stdout] running 40 tests
Dec 14 19:09:05.751 INFO [stdout] test db::tests::backwards_break ... ok
Dec 14 19:09:05.787 INFO [stdout] test db::tests::backwards_two ... ok
Dec 14 19:09:05.872 INFO [stdout] test db::tests::block_overflows ... ok
Dec 14 19:09:05.881 INFO [stdout] test db::tests::blocks_exact_file ... ok
Dec 14 19:09:06.076 INFO [stdout] test db::tests::discard_disk_wal ... ok
Dec 14 19:09:06.128 INFO [stdout] test db::tests::dbmeta1 ... ok
Dec 14 19:09:06.188 INFO [stdout] test db::tests::backwards_illegal ... ok
Dec 14 19:09:06.202 INFO [stdout] test db::tests::duplicate_at_once ... ok
Dec 14 19:09:06.297 INFO [stdout] test db::tests::duplicate_seq ... ok
Dec 14 19:09:06.356 INFO [stdout] test db::tests::dump_like ... ok
Dec 14 19:09:06.379 INFO [stdout] test db::tests::erase_ranges1 ... ok
Dec 14 19:09:06.448 INFO [stdout] test db::tests::erase_ranges2 ... ok
Dec 14 19:09:06.464 INFO [stdout] test db::tests::erase_ranges3 ... ok
Dec 14 19:09:06.519 INFO [stdout] test db::tests::erase_ranges4 ... ok
Dec 14 19:09:06.536 INFO [stdout] test db::tests::erase_ranges5 ... ok
Dec 14 19:09:06.668 INFO [stdout] test db::tests::insertion_bulk ... ok
Dec 14 19:09:06.706 INFO [stdout] test db::tests::generation_increases ... ok
Dec 14 19:09:06.746 INFO [stdout] test db::tests::insertion_twice ... ok
Dec 14 19:09:06.866 INFO [stdout] test db::tests::list_like ... ok
Dec 14 19:09:07.004 INFO [stdout] test db::tests::save_disk_wal ... ok
Dec 14 19:09:07.051 INFO [stdout] test db::tests::restart_offset ... ok
Dec 14 19:09:07.121 INFO [stdout] test db::tests::select_weird_ranges ... ok
Dec 14 19:09:07.343 INFO [stdout] test db::tests::two_series ... ok
Dec 14 19:09:07.395 INFO [stdout] test db::tests::stored_offset ... ok
Dec 14 19:09:07.506 INFO [stdout] test db::tests::two_tx ... ok
Dec 14 19:09:07.654 INFO [stdout] test db::tests::two_tx_reopen ... ok
Dec 14 19:09:07.667 INFO [stdout] test db::tests::write_should_panic ... ok
Dec 14 19:09:07.675 INFO [stdout] test metadata::tests::timestamp_range ... ok
Dec 14 19:09:07.679 INFO [stdout] test wal::tests::rw1 ... ok
Dec 14 19:09:07.683 INFO [stdout] test wal::tests::rw2 ... ok
Dec 14 19:09:07.683 INFO [stdout] test wal::tests::rw3 ... ok
Dec 14 19:09:07.683 INFO [stdout] test wal::tests::rw4 ... ok
Dec 14 19:09:07.699 INFO [stdout] test wal::tests::wal_overlap ... ok
Dec 14 19:09:07.700 INFO [stdout] test wal::tests::wal_to_disk ... ok
Dec 14 19:09:08.827 INFO [stdout] test db::tests::read_direction_multi_block ... ok
Dec 14 19:09:09.181 INFO [stdout] test db::tests::blocks_100_knives ... ok
Dec 14 19:09:46.691 INFO [stdout] test db::tests::blocks_10k_knives_atonce ... ok
Dec 14 19:09:50.259 INFO [stdout] test db::tests::blocks_10k_knives_restart ... ok
Dec 14 19:10:05.763 INFO [stdout] test db::tests::boundary_crossing ... test db::tests::boundary_crossing has been running for over 60 seconds
Dec 14 19:10:05.787 INFO [stdout] test db::tests::boundary_crossing_bulk_load ... test db::tests::boundary_crossing_bulk_load has been running for over 60 seconds
Dec 14 19:10:20.811 INFO [stdout] test db::tests::boundary_crossing ... ok
Dec 14 19:15:20.823 ERRO error running command: no output for 300 seconds
Dec 14 19:15:20.823 INFO running `"docker" "inspect" "abdb35f9ae5aa3de5061a88f0f6e2a917079c341996075f9bb134ecb9112f7bc"`
Dec 14 19:15:21.108 INFO running `"docker" "rm" "-f" "abdb35f9ae5aa3de5061a88f0f6e2a917079c341996075f9bb134ecb9112f7bc"`
Dec 14 19:15:22.389 INFO [stdout] abdb35f9ae5aa3de5061a88f0f6e2a917079c341996075f9bb134ecb9112f7bc