Dec 06 22:12:48.467 INFO testing sonnerie-0.4.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 22:12:48.467 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.628 INFO [stdout] ebb6d52d5c677551b39d38b5034a1c0ce20baeb895d5ffe5d107fb7e26bb1277
Dec 06 22:12:48.634 INFO running `"docker" "start" "-a" "ebb6d52d5c677551b39d38b5034a1c0ce20baeb895d5ffe5d107fb7e26bb1277"`
Dec 06 22:12:49.360 INFO [stderr] usermod: no changes
Dec 06 22:12:49.613 INFO [stderr]    Compiling escape_string v0.1.1
Dec 06 22:12:49.613 INFO [stderr]    Compiling intrusive-collections v0.7.8
Dec 06 22:12:49.621 INFO [stderr]    Compiling libsqlite3-sys v0.9.3
Dec 06 22:12:49.622 INFO [stderr]    Compiling linestream v0.1.0
Dec 06 22:12:49.622 INFO [stderr]    Compiling daemonize v0.3.0
Dec 06 22:12:51.064 INFO [stderr]    Compiling sonnerie-api v0.4.0
Dec 06 22:12:52.436 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 06 22:12:55.280 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 06 22:13:03.772 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.39s
Dec 06 22:13:03.780 INFO [stderr] su: No module specific data is present
Dec 06 22:13:05.012 INFO running `"docker" "inspect" "ebb6d52d5c677551b39d38b5034a1c0ce20baeb895d5ffe5d107fb7e26bb1277"`
Dec 06 22:13:05.320 INFO running `"docker" "rm" "-f" "ebb6d52d5c677551b39d38b5034a1c0ce20baeb895d5ffe5d107fb7e26bb1277"`
Dec 06 22:13:05.711 INFO [stdout] ebb6d52d5c677551b39d38b5034a1c0ce20baeb895d5ffe5d107fb7e26bb1277
Dec 06 22:13:05.714 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:06.162 INFO [stdout] 9aed62b41c0943b9fe3f174a4d2a4eb92ae578c50cb8a00711678581ae304d3e
Dec 06 22:13:06.165 INFO running `"docker" "start" "-a" "9aed62b41c0943b9fe3f174a4d2a4eb92ae578c50cb8a00711678581ae304d3e"`
Dec 06 22:13:07.472 INFO [stderr] usermod: no changes
Dec 06 22:13:07.659 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 06 22:13:18.220 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.73s
Dec 06 22:13:18.232 INFO [stderr] su: No module specific data is present
Dec 06 22:13:19.416 INFO running `"docker" "inspect" "9aed62b41c0943b9fe3f174a4d2a4eb92ae578c50cb8a00711678581ae304d3e"`
Dec 06 22:13:19.794 INFO running `"docker" "rm" "-f" "9aed62b41c0943b9fe3f174a4d2a4eb92ae578c50cb8a00711678581ae304d3e"`
Dec 06 22:13:20.188 INFO [stdout] 9aed62b41c0943b9fe3f174a4d2a4eb92ae578c50cb8a00711678581ae304d3e
Dec 06 22:13:20.190 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:20.626 INFO [stdout] bf2575c3220d58df501ffcb2ed5978f242da0652dfd9fe0fbe929b3afe1028ea
Dec 06 22:13:20.632 INFO running `"docker" "start" "-a" "bf2575c3220d58df501ffcb2ed5978f242da0652dfd9fe0fbe929b3afe1028ea"`
Dec 06 22:13:22.508 INFO [stderr] usermod: no changes
Dec 06 22:13:22.842 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.31s
Dec 06 22:13:22.852 INFO [stderr]      Running /target/debug/deps/sonnerie-629767514ec8cddf
Dec 06 22:13:22.859 INFO [stdout] 
Dec 06 22:13:22.859 INFO [stdout] running 40 tests
Dec 06 22:13:23.065 INFO [stdout] test db::tests::backwards_two ... ok
Dec 06 22:13:23.082 INFO [stdout] test db::tests::block_overflows ... ok
Dec 06 22:13:23.102 INFO [stdout] test db::tests::backwards_break ... ok
Dec 06 22:13:23.165 INFO [stdout] test db::tests::blocks_exact_file ... ok
Dec 06 22:13:23.441 INFO [stdout] test db::tests::backwards_illegal ... ok
Dec 06 22:13:23.501 INFO [stdout] test db::tests::discard_disk_wal ... ok
Dec 06 22:13:23.552 INFO [stdout] test db::tests::dbmeta1 ... ok
Dec 06 22:13:23.661 INFO [stdout] test db::tests::duplicate_seq ... ok
Dec 06 22:13:23.666 INFO [stdout] test db::tests::duplicate_at_once ... ok
Dec 06 22:13:23.710 INFO [stdout] test db::tests::dump_like ... ok
Dec 06 22:13:23.754 INFO [stdout] test db::tests::erase_ranges2 ... ok
Dec 06 22:13:23.760 INFO [stdout] test db::tests::erase_ranges1 ... ok
Dec 06 22:13:23.808 INFO [stdout] test db::tests::erase_ranges3 ... ok
Dec 06 22:13:23.858 INFO [stdout] test db::tests::erase_ranges5 ... ok
Dec 06 22:13:23.883 INFO [stdout] test db::tests::erase_ranges4 ... ok
Dec 06 22:13:24.036 INFO [stdout] test db::tests::insertion_bulk ... ok
Dec 06 22:13:24.111 INFO [stdout] test db::tests::generation_increases ... ok
Dec 06 22:13:24.130 INFO [stdout] test db::tests::insertion_twice ... ok
Dec 06 22:13:24.196 INFO [stdout] test db::tests::list_like ... ok
Dec 06 22:13:24.317 INFO [stdout] test db::tests::restart_offset ... ok
Dec 06 22:13:24.349 INFO [stdout] test db::tests::save_disk_wal ... ok
Dec 06 22:13:24.492 INFO [stdout] test db::tests::select_weird_ranges ... ok
Dec 06 22:13:24.767 INFO [stdout] test db::tests::two_series ... ok
Dec 06 22:13:24.804 INFO [stdout] test db::tests::stored_offset ... ok
Dec 06 22:13:24.972 INFO [stdout] test db::tests::two_tx ... ok
Dec 06 22:13:25.049 INFO [stdout] test db::tests::two_tx_reopen ... ok
Dec 06 22:13:25.051 INFO [stdout] test metadata::tests::timestamp_range ... ok
Dec 06 22:13:25.052 INFO [stdout] test wal::tests::rw1 ... ok
Dec 06 22:13:25.052 INFO [stdout] test wal::tests::rw2 ... ok
Dec 06 22:13:25.052 INFO [stdout] test wal::tests::rw3 ... ok
Dec 06 22:13:25.052 INFO [stdout] test wal::tests::rw4 ... ok
Dec 06 22:13:25.052 INFO [stdout] test wal::tests::wal_overlap ... ok
Dec 06 22:13:25.053 INFO [stdout] test wal::tests::wal_to_disk ... ok
Dec 06 22:13:25.096 INFO [stdout] test db::tests::write_should_panic ... ok
Dec 06 22:13:26.100 INFO [stdout] test db::tests::blocks_100_knives ... ok
Dec 06 22:13:26.224 INFO [stdout] test db::tests::read_direction_multi_block ... ok
Dec 06 22:14:04.222 INFO [stdout] test db::tests::blocks_10k_knives_atonce ... ok
Dec 06 22:14:05.649 INFO [stdout] test db::tests::blocks_10k_knives_restart ... ok
Dec 06 22:14:23.072 INFO [stdout] test db::tests::boundary_crossing ... test db::tests::boundary_crossing has been running for over 60 seconds
Dec 06 22:14:23.090 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:36.832 INFO [stdout] test db::tests::boundary_crossing ... ok
Dec 06 22:19:36.838 ERRO error running command: no output for 300 seconds
Dec 06 22:19:36.838 INFO running `"docker" "inspect" "bf2575c3220d58df501ffcb2ed5978f242da0652dfd9fe0fbe929b3afe1028ea"`
Dec 06 22:19:37.121 INFO running `"docker" "rm" "-f" "bf2575c3220d58df501ffcb2ed5978f242da0652dfd9fe0fbe929b3afe1028ea"`
Dec 06 22:19:37.872 INFO [stdout] bf2575c3220d58df501ffcb2ed5978f242da0652dfd9fe0fbe929b3afe1028ea