Dec 14 00:55:41.051 INFO testing boustrophedon/taskerizer-prototype against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 14 00:55:41.051 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 00:55:41.370 INFO [stdout] 46119d2bf345b82016649655c333653eac8cdd342ddb873d2d180ff390964a52 Dec 14 00:55:41.373 INFO running `"docker" "start" "-a" "46119d2bf345b82016649655c333653eac8cdd342ddb873d2d180ff390964a52"` Dec 14 00:55:42.619 INFO [stderr] usermod: no changes Dec 14 00:55:42.799 INFO [stderr] Compiling libsqlite3-sys v0.9.3 Dec 14 00:55:42.799 INFO [stderr] Compiling rand v0.5.5 Dec 14 00:55:42.815 INFO [stderr] Compiling num-integer v0.1.39 Dec 14 00:55:42.823 INFO [stderr] Compiling syn v0.14.8 Dec 14 00:55:45.577 INFO [stderr] Compiling chrono v0.4.5 Dec 14 00:56:00.257 INFO [stderr] Compiling synstructure v0.9.0 Dec 14 00:56:00.257 INFO [stderr] Compiling structopt-derive v0.2.10 Dec 14 00:56:06.384 INFO [stderr] Compiling structopt v0.2.10 Dec 14 00:56:07.911 INFO [stderr] Compiling failure_derive v0.1.2 Dec 14 00:56:10.091 INFO [stderr] Compiling rusqlite v0.14.0 Dec 14 00:56:11.294 INFO [stderr] Compiling failure v0.1.2 Dec 14 00:56:13.755 INFO [stderr] Compiling taskerizer-prototype v0.1.0 (/source) Dec 14 00:56:20.711 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 38.06s Dec 14 00:56:20.752 INFO [stderr] su: No module specific data is present Dec 14 00:56:21.096 INFO running `"docker" "inspect" "46119d2bf345b82016649655c333653eac8cdd342ddb873d2d180ff390964a52"` Dec 14 00:56:21.233 INFO running `"docker" "rm" "-f" "46119d2bf345b82016649655c333653eac8cdd342ddb873d2d180ff390964a52"` Dec 14 00:56:21.337 INFO [stdout] 46119d2bf345b82016649655c333653eac8cdd342ddb873d2d180ff390964a52 Dec 14 00:56:21.344 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 00:56:21.618 INFO [stdout] 7fb58872a7afd7dac8ea623478cccb4fe40c7caa09e01c26d9c47bd581436143 Dec 14 00:56:21.618 INFO running `"docker" "start" "-a" "7fb58872a7afd7dac8ea623478cccb4fe40c7caa09e01c26d9c47bd581436143"` Dec 14 00:56:22.587 INFO [stderr] usermod: no changes Dec 14 00:56:22.833 INFO [stderr] Compiling byteorder v1.2.4 Dec 14 00:56:22.834 INFO [stderr] Compiling wait-timeout v0.1.5 Dec 14 00:56:22.834 INFO [stderr] Compiling tempfile v3.0.3 Dec 14 00:56:26.059 INFO [stderr] Compiling rusty-fork v0.2.0 Dec 14 00:56:28.242 INFO [stderr] Compiling proptest v0.8.4 Dec 14 00:56:44.763 INFO [stderr] Compiling taskerizer-prototype v0.1.0 (/source) Dec 14 00:57:04.220 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 41.61s Dec 14 00:57:04.232 INFO [stderr] su: No module specific data is present Dec 14 00:57:04.713 INFO running `"docker" "inspect" "7fb58872a7afd7dac8ea623478cccb4fe40c7caa09e01c26d9c47bd581436143"` Dec 14 00:57:04.899 INFO running `"docker" "rm" "-f" "7fb58872a7afd7dac8ea623478cccb4fe40c7caa09e01c26d9c47bd581436143"` Dec 14 00:57:05.130 INFO [stdout] 7fb58872a7afd7dac8ea623478cccb4fe40c7caa09e01c26d9c47bd581436143 Dec 14 00:57:05.137 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 00:57:05.619 INFO [stdout] bbba087ce06afb67cb051ac66014caf8328417f48acbb87cc9b3fffee8447352 Dec 14 00:57:05.624 INFO running `"docker" "start" "-a" "bbba087ce06afb67cb051ac66014caf8328417f48acbb87cc9b3fffee8447352"` Dec 14 00:57:06.691 INFO [stderr] usermod: no changes Dec 14 00:57:06.859 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.14s Dec 14 00:57:06.872 INFO [stderr] Running /target/debug/deps/taskerizer_prototype-00a14295c1868b5d Dec 14 00:57:06.879 INFO [stdout] Dec 14 00:57:06.879 INFO [stdout] running 53 tests Dec 14 00:57:06.939 INFO [stdout] test db::backend::select_tests::test_select_task_list ... ok Dec 14 00:57:06.945 INFO [stdout] test db::backend::select_tests::test_select_task_single ... ok Dec 14 00:57:06.945 INFO [stdout] test db::backend::select_tests::test_select_task_two ... ok Dec 14 00:57:06.951 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current ... ok Dec 14 00:57:06.959 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current ... ok Dec 14 00:57:06.959 INFO [stdout] test db::tests::choose_current::test_db_choose_current_empty ... ok Dec 14 00:57:06.975 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_break ... ok Dec 14 00:57:06.981 INFO [stdout] test db::tests::add::test_db_add ... ok Dec 14 00:57:06.981 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_task ... ok Dec 14 00:57:06.981 INFO [stdout] test db::tests::get_current::test_db_get_current_no_tasks ... ok Dec 14 00:57:06.981 INFO [stdout] test db::tests::get_current::test_db_get_current_one_break ... ok Dec 14 00:57:06.991 INFO [stdout] test db::tests::get_current::test_db_get_current_one_task ... ok Dec 14 00:57:06.999 INFO [stdout] test db::tests::choose_current::test_db_choose_current_error_p ... ok Dec 14 00:57:07.015 INFO [stdout] test db::tests::get_current::test_db_get_current_ordering_two ... ok Dec 14 00:57:07.015 INFO [stdout] test db::tests::get_current::test_db_get_current_task_vs_break ... ok Dec 14 00:57:07.019 INFO [stdout] test db::tests::get_current::test_db_get_current_two_max_u32 ... ok Dec 14 00:57:07.019 INFO [stdout] test db::tests::list::test_db_list_added_manually ... ok Dec 14 00:57:07.019 INFO [stdout] test db::tests::list::test_db_list_empty ... ok Dec 14 00:57:07.105 INFO [stdout] test db::tests::list::test_db_list_invalid_task_empty ... ok Dec 14 00:57:07.111 INFO [stdout] test db::tests::list::test_db_list_invalid_task_zero_priority ... ok Dec 14 00:57:07.195 INFO [stdout] test db::tests::test_db_metadata ... ok Dec 14 00:57:07.212 INFO [stdout] test db::tests::test_db_open_err_bad_dir ... ok Dec 14 00:57:07.339 INFO [stdout] test db::tests::test_db_open_on_disk ... ok Dec 14 00:57:07.351 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_commit ... ok Dec 14 00:57:07.768 INFO [stdout] test db::backend::select_tests::test_select_task_list_arb ... ok Dec 14 00:57:07.771 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_rollback ... ok Dec 14 00:57:08.744 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current_arb ... ok Dec 14 00:57:08.771 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_1 ... ok Dec 14 00:57:08.771 INFO [stdout] test db::tests::add::test_db_add_arb ... ok Dec 14 00:57:08.771 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_2 ... ok Dec 14 00:57:08.783 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_empty ... ok Dec 14 00:57:17.315 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_commit_arb ... ok Dec 14 00:57:18.047 INFO [stdout] test db::transaction::tests::add::test_tx_add_task_rollback_arb ... ok Dec 14 00:57:18.047 INFO [stdout] test db::transaction::tests::list::test_tx_list_task_commit ... ok Dec 14 00:57:18.067 INFO [stdout] test db::transaction::tests::list::test_tx_list_task_rollback ... ok Dec 14 00:57:18.127 INFO [stdout] test db::transaction::tests::set_current::test_tx_set_current ... ok Dec 14 00:57:18.231 INFO [stdout] test db::tests::list::test_db_list_arb ... ok Dec 14 00:57:18.232 INFO [stdout] test task::test::test_task_fmt_long_1 ... ok Dec 14 00:57:18.232 INFO [stdout] test task::test::test_task_fmt_long_2 ... ok Dec 14 00:57:18.232 INFO [stdout] test task::test::test_task_fmt_long_3 ... ok Dec 14 00:57:18.232 INFO [stdout] test task::test::test_task_fmt_row_1 ... ok Dec 14 00:57:18.233 INFO [stdout] test task::test::test_task_fmt_row_2 ... ok Dec 14 00:57:18.233 INFO [stdout] test task::test::test_task_nonempty_task ... ok Dec 14 00:57:18.233 INFO [stdout] test task::test::test_task_null_desc ... ok Dec 14 00:57:18.396 INFO [stdout] test task::test::test_task_valid_arb_task ... ok Dec 14 00:57:18.402 INFO [stdout] test task::test::test_task_zero_priority ... ok Dec 14 00:57:18.552 INFO [stdout] test db::tests::get_current::test_db_get_current_arb ... ok Dec 14 00:57:18.835 INFO [stdout] test db::tests::choose_current::test_db_choose_current_arb ... ok Dec 14 00:57:18.957 INFO [stdout] test db::transaction::tests::list::test_tx_add_task_commit_arb ... ok Dec 14 00:57:19.975 INFO [stdout] test db::transaction::tests::get_current::test_tx_get_current_task_arb ... ok Dec 14 00:57:20.691 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current_arb ... ok Dec 14 00:57:22.205 INFO [stdout] test db::transaction::tests::list::test_tx_add_task_rollback_arb ... ok Dec 14 00:57:22.555 INFO [stdout] test db::transaction::tests::set_current::test_tx_set_current_arb ... ok Dec 14 00:57:22.555 INFO [stderr] Running /target/debug/deps/tkzr-1a7941b146728252 Dec 14 00:57:22.555 INFO [stdout] Dec 14 00:57:22.555 INFO [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.555 INFO [stdout] Dec 14 00:57:22.555 INFO [stdout] Dec 14 00:57:22.555 INFO [stderr] Running /target/debug/deps/add-6f65df65f47460e3 Dec 14 00:57:22.555 INFO [stdout] running 0 tests Dec 14 00:57:22.555 INFO [stdout] Dec 14 00:57:22.555 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.555 INFO [stdout] Dec 14 00:57:22.561 INFO [stdout] Dec 14 00:57:22.561 INFO [stdout] running 4 tests Dec 14 00:57:22.606 INFO [stdout] test test_cmd_add_empty_task ... ok Dec 14 00:57:22.606 INFO [stdout] test test_cmd_add_priority_0 ... ok Dec 14 00:57:22.623 INFO [stdout] test test_cmd_add ... ok Dec 14 00:57:22.655 INFO [stdout] test test_cmd_add_two ... ok Dec 14 00:57:22.655 INFO [stderr] Running /target/debug/deps/current-03ba0bf101ef4cd8 Dec 14 00:57:22.655 INFO [stdout] Dec 14 00:57:22.655 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.655 INFO [stdout] Dec 14 00:57:22.655 INFO [stdout] Dec 14 00:57:22.655 INFO [stdout] running 4 tests Dec 14 00:57:22.732 INFO [stdout] test test_cmd_current_empty ... ok Dec 14 00:57:22.733 INFO [stdout] test test_cmd_current_2 ... ok Dec 14 00:57:22.733 INFO [stdout] test test_cmd_current_1 ... ok Dec 14 00:57:22.733 INFO [stdout] test test_cmd_current_3_interspersed ... ok Dec 14 00:57:22.733 INFO [stdout] Dec 14 00:57:22.733 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.733 INFO [stdout] Dec 14 00:57:22.733 INFO [stdout] Dec 14 00:57:22.733 INFO [stdout] running 3 tests Dec 14 00:57:22.733 INFO [stderr] Running /target/debug/deps/list-52a3fa413d2ead12 Dec 14 00:57:22.809 INFO [stdout] test test_cmd_list_two ... ok Dec 14 00:57:22.810 INFO [stdout] test test_cmd_list ... ok Dec 14 00:57:22.833 INFO [stdout] test test_cmd_list_four ... ok Dec 14 00:57:22.833 INFO [stderr] Running /target/debug/deps/test_utils-49f21425a02c236a Dec 14 00:57:22.833 INFO [stdout] Dec 14 00:57:22.833 INFO [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.833 INFO [stdout] Dec 14 00:57:22.841 INFO [stdout] Dec 14 00:57:22.841 INFO [stderr] Doc-tests taskerizer-prototype Dec 14 00:57:22.841 INFO [stdout] running 0 tests Dec 14 00:57:22.841 INFO [stdout] Dec 14 00:57:22.841 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:22.841 INFO [stdout] Dec 14 00:57:25.684 INFO [stdout] Dec 14 00:57:25.684 INFO [stdout] running 0 tests Dec 14 00:57:25.684 INFO [stdout] Dec 14 00:57:25.684 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 14 00:57:25.684 INFO [stdout] Dec 14 00:57:25.696 INFO [stderr] su: No module specific data is present Dec 14 00:57:26.048 INFO running `"docker" "inspect" "bbba087ce06afb67cb051ac66014caf8328417f48acbb87cc9b3fffee8447352"` Dec 14 00:57:26.173 INFO running `"docker" "rm" "-f" "bbba087ce06afb67cb051ac66014caf8328417f48acbb87cc9b3fffee8447352"` Dec 14 00:57:26.271 INFO [stdout] bbba087ce06afb67cb051ac66014caf8328417f48acbb87cc9b3fffee8447352