Dec 14 00:55:39.957 INFO testing boustrophedon/taskerizer-prototype against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 00:55:39.957 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 00:55:40.311 INFO [stdout] 0c216cf38bcb35e994376e2583f923122606c998b466704ce8f743f00b42112f
Dec 14 00:55:40.315 INFO running `"docker" "start" "-a" "0c216cf38bcb35e994376e2583f923122606c998b466704ce8f743f00b42112f"`
Dec 14 00:55:41.251 INFO [stderr] usermod: no changes
Dec 14 00:55:41.431 INFO [stderr]    Compiling libsqlite3-sys v0.9.3
Dec 14 00:55:41.431 INFO [stderr]    Compiling rand v0.5.5
Dec 14 00:55:41.431 INFO [stderr]    Compiling num-integer v0.1.39
Dec 14 00:55:41.431 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 14 00:55:41.431 INFO [stderr]    Compiling failure v0.1.2
Dec 14 00:55:44.995 INFO [stderr]    Compiling chrono v0.4.5
Dec 14 00:55:51.459 INFO [stderr]    Compiling structopt v0.2.10
Dec 14 00:55:59.859 INFO [stderr]    Compiling rusqlite v0.14.0
Dec 14 00:56:03.147 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Dec 14 00:56:11.063 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.77s
Dec 14 00:56:11.079 INFO [stderr] su: No module specific data is present
Dec 14 00:56:12.069 INFO running `"docker" "inspect" "0c216cf38bcb35e994376e2583f923122606c998b466704ce8f743f00b42112f"`
Dec 14 00:56:12.343 INFO running `"docker" "rm" "-f" "0c216cf38bcb35e994376e2583f923122606c998b466704ce8f743f00b42112f"`
Dec 14 00:56:12.639 INFO [stdout] 0c216cf38bcb35e994376e2583f923122606c998b466704ce8f743f00b42112f
Dec 14 00:56:12.645 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 00:56:13.209 INFO [stdout] 61d84ddb07ce1e948d2935db49280a3224014c5e4caea6a59dd5aabd93126523
Dec 14 00:56:13.211 INFO running `"docker" "start" "-a" "61d84ddb07ce1e948d2935db49280a3224014c5e4caea6a59dd5aabd93126523"`
Dec 14 00:56:14.116 INFO [stderr] usermod: no changes
Dec 14 00:56:14.250 INFO [stderr]    Compiling byteorder v1.2.4
Dec 14 00:56:14.251 INFO [stderr]    Compiling tempfile v3.0.3
Dec 14 00:56:15.582 INFO [stderr]    Compiling rusty-fork v0.2.0
Dec 14 00:56:17.319 INFO [stderr]    Compiling proptest v0.8.4
Dec 14 00:56:34.260 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Dec 14 00:56:56.376 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 42.23s
Dec 14 00:56:56.402 INFO [stderr] su: No module specific data is present
Dec 14 00:56:57.132 INFO running `"docker" "inspect" "61d84ddb07ce1e948d2935db49280a3224014c5e4caea6a59dd5aabd93126523"`
Dec 14 00:56:57.323 INFO running `"docker" "rm" "-f" "61d84ddb07ce1e948d2935db49280a3224014c5e4caea6a59dd5aabd93126523"`
Dec 14 00:56:57.751 INFO [stdout] 61d84ddb07ce1e948d2935db49280a3224014c5e4caea6a59dd5aabd93126523
Dec 14 00:56:57.759 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 00:56:58.224 INFO [stdout] 81d96a5c89cafc110c0dfed4b3d4547103dd24fbfbaba9245eeeafefd4798846
Dec 14 00:56:58.235 INFO running `"docker" "start" "-a" "81d96a5c89cafc110c0dfed4b3d4547103dd24fbfbaba9245eeeafefd4798846"`
Dec 14 00:56:59.274 INFO [stderr] usermod: no changes
Dec 14 00:56:59.574 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Dec 14 00:56:59.583 INFO [stderr]      Running /target/debug/deps/taskerizer_prototype-9ade7e6ceda752cb
Dec 14 00:56:59.591 INFO [stdout] 
Dec 14 00:56:59.591 INFO [stdout] running 53 tests
Dec 14 00:56:59.607 INFO [stdout] test db::backend::select_tests::test_select_task_two ... ok
Dec 14 00:56:59.607 INFO [stdout] test db::backend::select_tests::test_select_task_list ... ok
Dec 14 00:56:59.607 INFO [stdout] test db::backend::select_tests::test_select_task_single ... ok
Dec 14 00:56:59.607 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current ... ok
Dec 14 00:56:59.625 INFO [stdout] test db::tests::add::test_db_add ... ok
Dec 14 00:56:59.625 INFO [stdout] test db::tests::choose_current::test_db_choose_current_error_p ... ok
Dec 14 00:56:59.639 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_break ... ok
Dec 14 00:56:59.639 INFO [stdout] test db::tests::choose_current::test_db_choose_current_empty ... ok
Dec 14 00:56:59.639 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current ... ok
Dec 14 00:56:59.647 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_task ... ok
Dec 14 00:56:59.647 INFO [stdout] test db::tests::get_current::test_db_get_current_no_tasks ... ok
Dec 14 00:56:59.647 INFO [stdout] test db::tests::get_current::test_db_get_current_one_break ... ok
Dec 14 00:56:59.651 INFO [stdout] test db::tests::get_current::test_db_get_current_one_task ... ok
Dec 14 00:56:59.651 INFO [stdout] test db::tests::get_current::test_db_get_current_ordering_two ... ok
Dec 14 00:56:59.656 INFO [stdout] test db::tests::get_current::test_db_get_current_two_max_u32 ... ok
Dec 14 00:56:59.656 INFO [stdout] test db::tests::get_current::test_db_get_current_task_vs_break ... ok
Dec 14 00:56:59.668 INFO [stdout] test db::tests::list::test_db_list_added_manually ... ok
Dec 14 00:56:59.694 INFO [stdout] test db::tests::list::test_db_list_empty ... ok
Dec 14 00:56:59.703 INFO [stdout] test db::tests::list::test_db_list_invalid_task_empty ... ok
Dec 14 00:56:59.715 INFO [stdout] test db::tests::list::test_db_list_invalid_task_zero_priority ... ok
Dec 14 00:56:59.799 INFO [stdout] test db::tests::test_db_metadata ... ok
Dec 14 00:56:59.808 INFO [stdout] test db::tests::test_db_open_err_bad_dir ... ok
Dec 14 00:56:59.891 INFO [stdout] test db::tests::test_db_open_on_disk ... ok
Dec 14 00:56:59.919 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_commit ... ok
Dec 14 00:57:00.276 INFO [stdout] test db::backend::select_tests::test_select_task_list_arb ... ok
Dec 14 00:57:00.299 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_rollback ... ok
Dec 14 00:57:00.995 INFO [stdout] test db::tests::add::test_db_add_arb ... ok
Dec 14 00:57:00.995 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_1 ... ok
Dec 14 00:57:01.047 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_2 ... ok
Dec 14 00:57:01.755 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current_arb ... ok
Dec 14 00:57:01.763 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_empty ... ok
Dec 14 00:57:08.703 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_commit_arb ... ok
Dec 14 00:57:09.773 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_rollback_arb ... ok
Dec 14 00:57:09.773 INFO [stdout] test db::transaction::tests::list::test_tx_list_task_commit ... ok
Dec 14 00:57:09.773 INFO [stdout] test db::transaction::tests::list::test_tx_list_task_rollback ... ok
Dec 14 00:57:09.783 INFO [stdout] test db::transaction::tests::set_current::test_tx_set_current ... ok
Dec 14 00:57:10.391 INFO [stdout] test db::tests::choose_current::test_db_choose_current_arb ... ok
Dec 14 00:57:10.415 INFO [stdout] test task::test::test_task_fmt_long_1 ... ok
Dec 14 00:57:10.415 INFO [stdout] test task::test::test_task_fmt_long_2 ... ok
Dec 14 00:57:10.415 INFO [stdout] test task::test::test_task_fmt_long_3 ... ok
Dec 14 00:57:10.419 INFO [stdout] test task::test::test_task_fmt_row_1 ... ok
Dec 14 00:57:10.431 INFO [stdout] test task::test::test_task_fmt_row_2 ... ok
Dec 14 00:57:10.455 INFO [stdout] test task::test::test_task_nonempty_task ... ok
Dec 14 00:57:10.459 INFO [stdout] test task::test::test_task_null_desc ... ok
Dec 14 00:57:10.611 INFO [stdout] test task::test::test_task_valid_arb_task ... ok
Dec 14 00:57:10.622 INFO [stdout] test task::test::test_task_zero_priority ... ok
Dec 14 00:57:10.806 INFO [stdout] test db::tests::list::test_db_list_arb ... ok
Dec 14 00:57:11.433 INFO [stdout] test db::tests::get_current::test_db_get_current_arb ... ok
Dec 14 00:57:11.935 INFO [stdout] test db::transaction::tests::list::test_tx_add_task_commit_arb ... ok
Dec 14 00:57:13.191 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_arb ... ok
Dec 14 00:57:13.483 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current_arb ... ok
Dec 14 00:57:14.359 INFO [stdout] test db::transaction::tests::list::test_tx_add_task_rollback_arb ... ok
Dec 14 00:57:15.016 INFO [stdout] test db::transaction::tests::set_current::test_tx_set_current_arb ... ok
Dec 14 00:57:15.016 INFO [stdout] 
Dec 14 00:57:15.016 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.016 INFO [stdout] 
Dec 14 00:57:15.019 INFO [stderr]      Running /target/debug/deps/tkzr-8f3c826bf54bc2d1
Dec 14 00:57:15.027 INFO [stderr]      Running /target/debug/deps/add-26682e16fe64f9f7
Dec 14 00:57:15.027 INFO [stdout] 
Dec 14 00:57:15.027 INFO [stdout] running 0 tests
Dec 14 00:57:15.027 INFO [stdout] 
Dec 14 00:57:15.027 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.027 INFO [stdout] 
Dec 14 00:57:15.035 INFO [stdout] 
Dec 14 00:57:15.035 INFO [stdout] running 4 tests
Dec 14 00:57:15.083 INFO [stdout] test test_cmd_add_priority_0 ... ok
Dec 14 00:57:15.084 INFO [stdout] test test_cmd_add_empty_task ... ok
Dec 14 00:57:15.103 INFO [stdout] test test_cmd_add ... ok
Dec 14 00:57:15.113 INFO [stdout] test test_cmd_add_two ... ok
Dec 14 00:57:15.113 INFO [stdout] 
Dec 14 00:57:15.113 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.113 INFO [stdout] 
Dec 14 00:57:15.121 INFO [stdout] 
Dec 14 00:57:15.121 INFO [stderr]      Running /target/debug/deps/current-398aee0c8af68a3f
Dec 14 00:57:15.121 INFO [stdout] running 4 tests
Dec 14 00:57:15.200 INFO [stdout] test test_cmd_current_empty ... ok
Dec 14 00:57:15.214 INFO [stdout] test test_cmd_current_1 ... ok
Dec 14 00:57:15.231 INFO [stdout] test test_cmd_current_3_interspersed ... ok
Dec 14 00:57:15.242 INFO [stdout] test test_cmd_current_2 ... ok
Dec 14 00:57:15.242 INFO [stdout] 
Dec 14 00:57:15.242 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.242 INFO [stdout] 
Dec 14 00:57:15.247 INFO [stderr]      Running /target/debug/deps/list-74c5cb151d240d10
Dec 14 00:57:15.251 INFO [stdout] 
Dec 14 00:57:15.251 INFO [stdout] running 3 tests
Dec 14 00:57:15.307 INFO [stdout] test test_cmd_list_two ... ok
Dec 14 00:57:15.343 INFO [stdout] test test_cmd_list_four ... ok
Dec 14 00:57:15.355 INFO [stdout] test test_cmd_list ... ok
Dec 14 00:57:15.355 INFO [stdout] 
Dec 14 00:57:15.355 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.355 INFO [stdout] 
Dec 14 00:57:15.356 INFO [stderr]      Running /target/debug/deps/test_utils-a9e6ebccc6cdc779
Dec 14 00:57:15.359 INFO [stdout] 
Dec 14 00:57:15.359 INFO [stdout] running 0 tests
Dec 14 00:57:15.360 INFO [stdout] 
Dec 14 00:57:15.360 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:15.360 INFO [stdout] 
Dec 14 00:57:15.360 INFO [stderr]    Doc-tests taskerizer-prototype
Dec 14 00:57:18.390 INFO [stdout] 
Dec 14 00:57:18.390 INFO [stdout] running 0 tests
Dec 14 00:57:18.390 INFO [stdout] 
Dec 14 00:57:18.390 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 00:57:18.390 INFO [stdout] 
Dec 14 00:57:18.407 INFO [stderr] su: No module specific data is present
Dec 14 00:57:19.009 INFO running `"docker" "inspect" "81d96a5c89cafc110c0dfed4b3d4547103dd24fbfbaba9245eeeafefd4798846"`
Dec 14 00:57:19.291 INFO running `"docker" "rm" "-f" "81d96a5c89cafc110c0dfed4b3d4547103dd24fbfbaba9245eeeafefd4798846"`
Dec 14 00:57:19.547 INFO [stdout] 81d96a5c89cafc110c0dfed4b3d4547103dd24fbfbaba9245eeeafefd4798846