Dec 14 19:08:29.943 INFO testing sonnerie-0.4.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 19:08:29.943 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:30.118 INFO [stdout] 7a5b42fa76d487963f6fc36ae13e296d0a0caf00b115190a2cfd16f71ba668f2
Dec 14 19:08:30.120 INFO running `"docker" "start" "-a" "7a5b42fa76d487963f6fc36ae13e296d0a0caf00b115190a2cfd16f71ba668f2"`
Dec 14 19:08:30.791 INFO [stderr] usermod: no changes
Dec 14 19:08:30.959 INFO [stderr]    Compiling escape_string v0.1.1
Dec 14 19:08:30.962 INFO [stderr]    Compiling range v0.3.1
Dec 14 19:08:30.963 INFO [stderr]    Compiling intrusive-collections v0.7.8
Dec 14 19:08:30.968 INFO [stderr]    Compiling linestream v0.1.0
Dec 14 19:08:30.969 INFO [stderr]    Compiling daemonize v0.3.0
Dec 14 19:08:30.970 INFO [stderr]    Compiling rustyline v2.1.0
Dec 14 19:08:32.903 INFO [stderr]    Compiling sonnerie-api v0.4.0
Dec 14 19:08:37.208 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 14 19:08:47.105 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.30s
Dec 14 19:08:47.113 INFO [stderr] su: No module specific data is present
Dec 14 19:08:47.744 INFO running `"docker" "inspect" "7a5b42fa76d487963f6fc36ae13e296d0a0caf00b115190a2cfd16f71ba668f2"`
Dec 14 19:08:48.071 INFO running `"docker" "rm" "-f" "7a5b42fa76d487963f6fc36ae13e296d0a0caf00b115190a2cfd16f71ba668f2"`
Dec 14 19:08:48.368 INFO [stdout] 7a5b42fa76d487963f6fc36ae13e296d0a0caf00b115190a2cfd16f71ba668f2
Dec 14 19:08:48.389 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.817 INFO [stdout] 34c90efc779fcfebc3b849a1c478616766c2e43aeb8cfa1e5e99933f678d55b5
Dec 14 19:08:48.819 INFO running `"docker" "start" "-a" "34c90efc779fcfebc3b849a1c478616766c2e43aeb8cfa1e5e99933f678d55b5"`
Dec 14 19:08:50.483 INFO [stderr] usermod: no changes
Dec 14 19:08:50.683 INFO [stderr]    Compiling sonnerie v0.4.0 (/source)
Dec 14 19:09:00.432 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.91s
Dec 14 19:09:00.446 INFO [stderr] su: No module specific data is present
Dec 14 19:09:02.003 INFO running `"docker" "inspect" "34c90efc779fcfebc3b849a1c478616766c2e43aeb8cfa1e5e99933f678d55b5"`
Dec 14 19:09:02.377 INFO running `"docker" "rm" "-f" "34c90efc779fcfebc3b849a1c478616766c2e43aeb8cfa1e5e99933f678d55b5"`
Dec 14 19:09:02.739 INFO [stdout] 34c90efc779fcfebc3b849a1c478616766c2e43aeb8cfa1e5e99933f678d55b5
Dec 14 19:09:02.741 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.212 INFO [stdout] 1bfdf5fc6c48839f7e3d45b3d3c1769a095a19f966b23d762702b326d1a07342
Dec 14 19:09:03.217 INFO running `"docker" "start" "-a" "1bfdf5fc6c48839f7e3d45b3d3c1769a095a19f966b23d762702b326d1a07342"`
Dec 14 19:09:04.867 INFO [stderr] usermod: no changes
Dec 14 19:09:04.987 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 14 19:09:05.003 INFO [stderr]      Running /target/debug/deps/sonnerie-9803a6282b65f467
Dec 14 19:09:05.015 INFO [stdout] 
Dec 14 19:09:05.015 INFO [stdout] running 40 tests
Dec 14 19:09:05.091 INFO [stdout] test db::tests::backwards_two ... ok
Dec 14 19:09:05.143 INFO [stdout] test db::tests::blocks_exact_file ... ok
Dec 14 19:09:05.175 INFO [stdout] test db::tests::backwards_break ... ok
Dec 14 19:09:05.207 INFO [stdout] test db::tests::block_overflows ... ok
Dec 14 19:09:05.317 INFO [stdout] test db::tests::discard_disk_wal ... ok
Dec 14 19:09:05.317 INFO [stdout] test db::tests::dbmeta1 ... ok
Dec 14 19:09:05.412 INFO [stdout] test db::tests::dump_like ... ok
Dec 14 19:09:05.443 INFO [stdout] test db::tests::backwards_illegal ... ok
Dec 14 19:09:05.455 INFO [stdout] test db::tests::duplicate_at_once ... ok
Dec 14 19:09:05.495 INFO [stdout] test db::tests::duplicate_seq ... ok
Dec 14 19:09:05.528 INFO [stdout] test db::tests::erase_ranges1 ... ok
Dec 14 19:09:05.555 INFO [stdout] test db::tests::erase_ranges2 ... ok
Dec 14 19:09:05.599 INFO [stdout] test db::tests::erase_ranges3 ... ok
Dec 14 19:09:05.632 INFO [stdout] test db::tests::erase_ranges4 ... ok
Dec 14 19:09:05.632 INFO [stdout] test db::tests::erase_ranges5 ... ok
Dec 14 19:09:05.709 INFO [stdout] test db::tests::insertion_bulk ... ok
Dec 14 19:09:05.786 INFO [stdout] test db::tests::generation_increases ... ok
Dec 14 19:09:05.843 INFO [stdout] test db::tests::insertion_twice ... ok
Dec 14 19:09:05.976 INFO [stdout] test db::tests::list_like ... ok
Dec 14 19:09:06.109 INFO [stdout] test db::tests::restart_offset ... ok
Dec 14 19:09:06.188 INFO [stdout] test db::tests::save_disk_wal ... ok
Dec 14 19:09:06.323 INFO [stdout] test db::tests::select_weird_ranges ... ok
Dec 14 19:09:06.520 INFO [stdout] test db::tests::two_series ... ok
Dec 14 19:09:06.587 INFO [stdout] test db::tests::stored_offset ... ok
Dec 14 19:09:06.811 INFO [stdout] test db::tests::two_tx ... ok
Dec 14 19:09:06.870 INFO [stdout] test db::tests::two_tx_reopen ... ok
Dec 14 19:09:06.872 INFO [stdout] test metadata::tests::timestamp_range ... ok
Dec 14 19:09:06.876 INFO [stdout] test wal::tests::rw1 ... ok
Dec 14 19:09:06.880 INFO [stdout] test wal::tests::rw2 ... ok
Dec 14 19:09:06.889 INFO [stdout] test wal::tests::rw3 ... ok
Dec 14 19:09:06.903 INFO [stdout] test wal::tests::rw4 ... ok
Dec 14 19:09:06.907 INFO [stdout] test db::tests::write_should_panic ... ok
Dec 14 19:09:06.911 INFO [stdout] test wal::tests::wal_overlap ... ok
Dec 14 19:09:06.911 INFO [stdout] test wal::tests::wal_to_disk ... ok
Dec 14 19:09:07.684 INFO [stdout] test db::tests::read_direction_multi_block ... ok
Dec 14 19:09:08.631 INFO [stdout] test db::tests::blocks_100_knives ... ok
Dec 14 19:09:46.415 INFO [stdout] test db::tests::blocks_10k_knives_atonce ... ok
Dec 14 19:09:50.879 INFO [stdout] test db::tests::blocks_10k_knives_restart ... ok
Dec 14 19:10:05.103 INFO [stdout] test db::tests::boundary_crossing ... test db::tests::boundary_crossing has been running for over 60 seconds
Dec 14 19:10:05.151 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.247 INFO [stdout] test db::tests::boundary_crossing ... ok
Dec 14 19:15:20.250 ERRO error running command: no output for 300 seconds
Dec 14 19:15:20.250 INFO running `"docker" "inspect" "1bfdf5fc6c48839f7e3d45b3d3c1769a095a19f966b23d762702b326d1a07342"`
Dec 14 19:15:20.635 INFO running `"docker" "rm" "-f" "1bfdf5fc6c48839f7e3d45b3d3c1769a095a19f966b23d762702b326d1a07342"`
Dec 14 19:15:21.689 INFO [stdout] 1bfdf5fc6c48839f7e3d45b3d3c1769a095a19f966b23d762702b326d1a07342