Dec 06 22:12:47.952 INFO testing sonnerie-0.4.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 22:12:47.952 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:12:48.140 INFO [stdout] 4d94ade5e8ceeb9ab2a3914cf37939ebe4d83bee87cef99fe657dc0cd137b333
Dec 06 22:12:48.146 INFO running `"docker" "start" "-a" "4d94ade5e8ceeb9ab2a3914cf37939ebe4d83bee87cef99fe657dc0cd137b333"`
Dec 06 22:12:48.593 INFO [stderr] usermod: no changes
Dec 06 22:12:48.740 INFO [stderr]    Compiling escape_string v0.1.1
Dec 06 22:12:48.742 INFO [stderr]    Compiling intrusive-collections v0.7.8
Dec 06 22:12:48.742 INFO [stderr]    Compiling range v0.3.1
Dec 06 22:12:48.742 INFO [stderr]    Compiling libsqlite3-sys v0.9.3
Dec 06 22:12:48.749 INFO [stderr]    Compiling linestream v0.1.0
Dec 06 22:12:50.064 INFO [stderr]    Compiling sonnerie-api v0.4.0
Dec 06 22:12:51.705 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 06 22:12:54.651 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 06 22:13:02.942 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.33s
Dec 06 22:13:02.957 INFO [stderr] su: No module specific data is present
Dec 06 22:13:04.387 INFO running `"docker" "inspect" "4d94ade5e8ceeb9ab2a3914cf37939ebe4d83bee87cef99fe657dc0cd137b333"`
Dec 06 22:13:04.774 INFO running `"docker" "rm" "-f" "4d94ade5e8ceeb9ab2a3914cf37939ebe4d83bee87cef99fe657dc0cd137b333"`
Dec 06 22:13:05.084 INFO [stdout] 4d94ade5e8ceeb9ab2a3914cf37939ebe4d83bee87cef99fe657dc0cd137b333
Dec 06 22:13:05.092 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:13:05.692 INFO [stdout] 3289f2032c8aa70a905b917ba28056a3ad6d72cc977abd55211ab3fe51b97c53
Dec 06 22:13:05.698 INFO running `"docker" "start" "-a" "3289f2032c8aa70a905b917ba28056a3ad6d72cc977abd55211ab3fe51b97c53"`
Dec 06 22:13:06.876 INFO [stderr] usermod: no changes
Dec 06 22:13:07.097 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 06 22:13:17.497 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.57s
Dec 06 22:13:17.512 INFO [stderr] su: No module specific data is present
Dec 06 22:13:18.568 INFO running `"docker" "inspect" "3289f2032c8aa70a905b917ba28056a3ad6d72cc977abd55211ab3fe51b97c53"`
Dec 06 22:13:18.896 INFO running `"docker" "rm" "-f" "3289f2032c8aa70a905b917ba28056a3ad6d72cc977abd55211ab3fe51b97c53"`
Dec 06 22:13:19.236 INFO [stdout] 3289f2032c8aa70a905b917ba28056a3ad6d72cc977abd55211ab3fe51b97c53
Dec 06 22:13:19.244 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 22:13:19.751 INFO [stdout] f26927cac5ac5a13f78fb432755a0aa933385d20a1f61af9eca603da2ef95393
Dec 06 22:13:19.758 INFO running `"docker" "start" "-a" "f26927cac5ac5a13f78fb432755a0aa933385d20a1f61af9eca603da2ef95393"`
Dec 06 22:13:21.568 INFO [stderr] usermod: no changes
Dec 06 22:13:21.698 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 06 22:13:21.720 INFO [stderr]      Running /target/debug/deps/sonnerie-a31c062469da09f2
Dec 06 22:13:21.744 INFO [stdout] 
Dec 06 22:13:21.746 INFO [stdout] running 40 tests
Dec 06 22:13:21.876 INFO [stdout] test db::tests::backwards_break ... ok
Dec 06 22:13:21.929 INFO [stdout] test db::tests::backwards_two ... ok
Dec 06 22:13:21.936 INFO [stdout] test db::tests::blocks_exact_file ... ok
Dec 06 22:13:21.976 INFO [stdout] test db::tests::block_overflows ... ok
Dec 06 22:13:22.077 INFO [stdout] test db::tests::dbmeta1 ... ok
Dec 06 22:13:22.100 INFO [stdout] test db::tests::discard_disk_wal ... ok
Dec 06 22:13:22.212 INFO [stdout] test db::tests::dump_like ... ok
Dec 06 22:13:22.336 INFO [stdout] test db::tests::duplicate_at_once ... ok
Dec 06 22:13:22.344 INFO [stdout] test db::tests::duplicate_seq ... ok
Dec 06 22:13:22.344 INFO [stdout] test db::tests::backwards_illegal ... ok
Dec 06 22:13:22.432 INFO [stdout] test db::tests::erase_ranges1 ... ok
Dec 06 22:13:22.437 INFO [stdout] test db::tests::erase_ranges3 ... ok
Dec 06 22:13:22.476 INFO [stdout] test db::tests::erase_ranges2 ... ok
Dec 06 22:13:22.500 INFO [stdout] test db::tests::erase_ranges5 ... ok
Dec 06 22:13:22.551 INFO [stdout] test db::tests::erase_ranges4 ... ok
Dec 06 22:13:22.608 INFO [stdout] test db::tests::insertion_bulk ... ok
Dec 06 22:13:22.681 INFO [stdout] test db::tests::generation_increases ... ok
Dec 06 22:13:22.704 INFO [stdout] test db::tests::insertion_twice ... ok
Dec 06 22:13:22.740 INFO [stdout] test db::tests::list_like ... ok
Dec 06 22:13:22.957 INFO [stdout] test db::tests::restart_offset ... ok
Dec 06 22:13:22.988 INFO [stdout] test db::tests::save_disk_wal ... ok
Dec 06 22:13:23.172 INFO [stdout] test db::tests::select_weird_ranges ... ok
Dec 06 22:13:23.324 INFO [stdout] test db::tests::two_series ... ok
Dec 06 22:13:23.325 INFO [stdout] test db::tests::stored_offset ... ok
Dec 06 22:13:23.578 INFO [stdout] test db::tests::two_tx_reopen ... ok
Dec 06 22:13:23.620 INFO [stdout] test db::tests::two_tx ... ok
Dec 06 22:13:23.632 INFO [stdout] test metadata::tests::timestamp_range ... ok
Dec 06 22:13:23.632 INFO [stdout] test wal::tests::rw1 ... ok
Dec 06 22:13:23.632 INFO [stdout] test wal::tests::rw2 ... ok
Dec 06 22:13:23.632 INFO [stdout] test wal::tests::rw3 ... ok
Dec 06 22:13:23.633 INFO [stdout] test wal::tests::rw4 ... ok
Dec 06 22:13:23.633 INFO [stdout] test wal::tests::wal_overlap ... ok
Dec 06 22:13:23.641 INFO [stdout] test wal::tests::wal_to_disk ... ok
Dec 06 22:13:23.691 INFO [stdout] test db::tests::write_should_panic ... ok
Dec 06 22:13:24.629 INFO [stdout] test db::tests::read_direction_multi_block ... ok
Dec 06 22:13:24.798 INFO [stdout] test db::tests::blocks_100_knives ... ok
Dec 06 22:14:06.692 INFO [stdout] test db::tests::blocks_10k_knives_atonce ... ok
Dec 06 22:14:08.184 INFO [stdout] test db::tests::blocks_10k_knives_restart ... ok
Dec 06 22:14:21.876 INFO [stdout] test db::tests::boundary_crossing ... test db::tests::boundary_crossing has been running for over 60 seconds
Dec 06 22:14:21.932 INFO [stdout] test db::tests::boundary_crossing_bulk_load ... test db::tests::boundary_crossing_bulk_load has been running for over 60 seconds
Dec 06 22:14:41.653 INFO [stdout] test db::tests::boundary_crossing ... ok
Dec 06 22:19:41.659 ERRO error running command: no output for 300 seconds
Dec 06 22:19:41.659 INFO running `"docker" "inspect" "f26927cac5ac5a13f78fb432755a0aa933385d20a1f61af9eca603da2ef95393"`
Dec 06 22:19:41.918 INFO running `"docker" "rm" "-f" "f26927cac5ac5a13f78fb432755a0aa933385d20a1f61af9eca603da2ef95393"`
Dec 06 22:19:42.911 INFO [stdout] f26927cac5ac5a13f78fb432755a0aa933385d20a1f61af9eca603da2ef95393