Nov 17 07:44:13.461 INFO testing boustrophedon/taskerizer-prototype against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 17 07:44:13.461 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 07:44:13.967 INFO [stdout] 25ca05b3e6d9224b800fc04d85ad2777070c64882993397961861945e594eee1
Nov 17 07:44:13.969 INFO running `"docker" "start" "-a" "25ca05b3e6d9224b800fc04d85ad2777070c64882993397961861945e594eee1"`
Nov 17 07:44:16.055 INFO [stderr] usermod: no changes
Nov 17 07:44:16.296 INFO [stderr]    Compiling libsqlite3-sys v0.9.3
Nov 17 07:44:16.307 INFO [stderr]    Compiling num-integer v0.1.39
Nov 17 07:44:16.308 INFO [stderr]    Compiling syn v0.14.8
Nov 17 07:44:18.827 INFO [stderr]    Compiling chrono v0.4.5
Nov 17 07:44:36.715 INFO [stderr]    Compiling rusqlite v0.14.0
Nov 17 07:44:37.606 INFO [stderr]    Compiling synstructure v0.9.0
Nov 17 07:44:37.606 INFO [stderr]    Compiling structopt-derive v0.2.10
Nov 17 07:44:44.938 INFO [stderr]    Compiling failure_derive v0.1.2
Nov 17 07:44:45.824 INFO [stderr]    Compiling structopt v0.2.10
Nov 17 07:44:48.855 INFO [stderr]    Compiling failure v0.1.2
Nov 17 07:44:49.855 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Nov 17 07:44:56.439 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 40.35s
Nov 17 07:44:56.447 INFO [stderr] su: No module specific data is present
Nov 17 07:44:57.966 INFO running `"docker" "inspect" "25ca05b3e6d9224b800fc04d85ad2777070c64882993397961861945e594eee1"`
Nov 17 07:44:58.321 INFO running `"docker" "rm" "-f" "25ca05b3e6d9224b800fc04d85ad2777070c64882993397961861945e594eee1"`
Nov 17 07:44:58.715 INFO [stdout] 25ca05b3e6d9224b800fc04d85ad2777070c64882993397961861945e594eee1
Nov 17 07:44:58.724 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 07:44:59.252 INFO [stdout] 3a55a7f9ce5ea8aaed933af5b8c6db7d2b81de4ad49ead6c96753c91d95dccfb
Nov 17 07:44:59.254 INFO running `"docker" "start" "-a" "3a55a7f9ce5ea8aaed933af5b8c6db7d2b81de4ad49ead6c96753c91d95dccfb"`
Nov 17 07:45:01.131 INFO [stderr] usermod: no changes
Nov 17 07:45:01.265 INFO [stderr]    Compiling byteorder v1.2.4
Nov 17 07:45:01.267 INFO [stderr]    Compiling tempfile v3.0.3
Nov 17 07:45:02.647 INFO [stderr]    Compiling rusty-fork v0.2.0
Nov 17 07:45:04.563 INFO [stderr]    Compiling proptest v0.8.4
Nov 17 07:45:23.222 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Nov 17 07:45:42.488 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 41.34s
Nov 17 07:45:42.499 INFO [stderr] su: No module specific data is present
Nov 17 07:45:43.651 INFO running `"docker" "inspect" "3a55a7f9ce5ea8aaed933af5b8c6db7d2b81de4ad49ead6c96753c91d95dccfb"`
Nov 17 07:45:43.975 INFO running `"docker" "rm" "-f" "3a55a7f9ce5ea8aaed933af5b8c6db7d2b81de4ad49ead6c96753c91d95dccfb"`
Nov 17 07:45:44.375 INFO [stdout] 3a55a7f9ce5ea8aaed933af5b8c6db7d2b81de4ad49ead6c96753c91d95dccfb
Nov 17 07:45:44.384 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 17 07:45:44.892 INFO [stdout] 4874013321e6a5881f8ed11ccc1a8042edc28f06ed642bd740b7f7024442d6b7
Nov 17 07:45:44.896 INFO running `"docker" "start" "-a" "4874013321e6a5881f8ed11ccc1a8042edc28f06ed642bd740b7f7024442d6b7"`
Nov 17 07:45:45.868 INFO [stderr] usermod: no changes
Nov 17 07:45:45.983 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 17 07:45:45.999 INFO [stderr]      Running /target/debug/deps/taskerizer_prototype-e2c64ed908157d57
Nov 17 07:45:46.003 INFO [stdout] 
Nov 17 07:45:46.003 INFO [stdout] running 39 tests
Nov 17 07:45:46.011 INFO [stdout] test db::backend::select_tests::test_select_task_single ... ok
Nov 17 07:45:46.011 INFO [stdout] test db::backend::select_tests::test_select_task_list ... ok
Nov 17 07:45:46.012 INFO [stdout] test db::backend::select_tests::test_select_task_two ... ok
Nov 17 07:45:46.067 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current ... ok
Nov 17 07:45:46.079 INFO [stdout] test db::tests::choose_current::test_db_choose_current_empty ... ok
Nov 17 07:45:46.079 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current ... ok
Nov 17 07:45:46.095 INFO [stdout] test db::tests::add::test_db_add ... ok
Nov 17 07:45:46.095 INFO [stdout] test db::tests::choose_current::test_db_choose_current_error_p ... ok
Nov 17 07:45:46.102 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_break ... ok
Nov 17 07:45:46.102 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_task ... ok
Nov 17 07:45:46.113 INFO [stdout] test db::tests::get_current::test_db_get_current_no_tasks ... ok
Nov 17 07:45:46.114 INFO [stdout] test db::tests::get_current::test_db_get_current_one_break ... ok
Nov 17 07:45:46.127 INFO [stdout] test db::tests::get_current::test_db_get_current_ordering_two ... ok
Nov 17 07:45:46.127 INFO [stdout] test db::tests::get_current::test_db_get_current_one_task ... ok
Nov 17 07:45:46.138 INFO [stdout] test db::tests::get_current::test_db_get_current_task_vs_break ... ok
Nov 17 07:45:46.138 INFO [stdout] test db::tests::get_current::test_db_get_current_two_max_u32 ... ok
Nov 17 07:45:46.163 INFO [stdout] test db::tests::list::test_db_list_added_manually ... ok
Nov 17 07:45:46.219 INFO [stdout] test db::tests::list::test_db_list_empty ... ok
Nov 17 07:45:46.223 INFO [stdout] test db::tests::list::test_db_list_invalid_task_empty ... ok
Nov 17 07:45:46.231 INFO [stdout] test db::tests::list::test_db_list_invalid_task_zero_priority ... ok
Nov 17 07:45:46.475 INFO [stdout] test db::tests::test_db_metadata ... ok
Nov 17 07:45:46.475 INFO [stdout] test db::tests::test_db_open_err_bad_dir ... ok
Nov 17 07:45:46.667 INFO [stdout] test db::tests::test_db_open_on_disk ... ok
Nov 17 07:45:46.667 INFO [stdout] test task::test::test_task_fmt_long_1 ... ok
Nov 17 07:45:46.668 INFO [stdout] test task::test::test_task_fmt_long_2 ... ok
Nov 17 07:45:46.668 INFO [stdout] test task::test::test_task_fmt_long_3 ... ok
Nov 17 07:45:46.668 INFO [stdout] test task::test::test_task_fmt_row_1 ... ok
Nov 17 07:45:46.669 INFO [stdout] test task::test::test_task_fmt_row_2 ... ok
Nov 17 07:45:46.669 INFO [stdout] test task::test::test_task_nonempty_task ... ok
Nov 17 07:45:46.669 INFO [stdout] test task::test::test_task_null_desc ... ok
Nov 17 07:45:46.743 INFO [stdout] test task::test::test_task_valid_arb_task ... ok
Nov 17 07:45:46.751 INFO [stdout] test task::test::test_task_zero_priority ... ok
Nov 17 07:45:46.791 INFO [stdout] test db::backend::select_tests::test_select_task_list_arb ... ok
Nov 17 07:45:47.655 INFO [stdout] test db::tests::add::test_db_add_arb ... ok
Nov 17 07:45:47.715 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current_arb ... ok
Nov 17 07:45:53.285 INFO [stdout] test db::tests::choose_current::test_db_choose_current_arb ... ok
Nov 17 07:45:53.638 INFO [stdout] test db::tests::get_current::test_db_get_current_arb ... ok
Nov 17 07:45:53.955 INFO [stdout] test db::tests::list::test_db_list_arb ... ok
Nov 17 07:45:54.601 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current_arb ... ok
Nov 17 07:45:54.601 INFO [stdout] 
Nov 17 07:45:54.602 INFO [stdout] test result: ok. 39 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.602 INFO [stdout] 
Nov 17 07:45:54.604 INFO [stderr]      Running /target/debug/deps/tkzr-396c2ebcd627a682
Nov 17 07:45:54.606 INFO [stdout] 
Nov 17 07:45:54.606 INFO [stdout] running 0 tests
Nov 17 07:45:54.606 INFO [stdout] 
Nov 17 07:45:54.607 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.607 INFO [stdout] 
Nov 17 07:45:54.607 INFO [stderr]      Running /target/debug/deps/add-0d7495638a168d9c
Nov 17 07:45:54.623 INFO [stdout] 
Nov 17 07:45:54.623 INFO [stdout] running 4 tests
Nov 17 07:45:54.674 INFO [stdout] test test_cmd_add_priority_0 ... ok
Nov 17 07:45:54.693 INFO [stdout] test test_cmd_add_two ... ok
Nov 17 07:45:54.705 INFO [stdout] test test_cmd_add_empty_task ... ok
Nov 17 07:45:54.705 INFO [stdout] test test_cmd_add ... ok
Nov 17 07:45:54.706 INFO [stdout] 
Nov 17 07:45:54.706 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.706 INFO [stdout] 
Nov 17 07:45:54.707 INFO [stderr]      Running /target/debug/deps/current-3bfd6e5d217619c8
Nov 17 07:45:54.709 INFO [stdout] 
Nov 17 07:45:54.709 INFO [stdout] running 4 tests
Nov 17 07:45:54.754 INFO [stdout] test test_cmd_current_empty ... ok
Nov 17 07:45:54.761 INFO [stdout] test test_cmd_current_1 ... ok
Nov 17 07:45:54.774 INFO [stdout] test test_cmd_current_3_interspersed ... ok
Nov 17 07:45:54.798 INFO [stdout] test test_cmd_current_2 ... ok
Nov 17 07:45:54.798 INFO [stdout] 
Nov 17 07:45:54.799 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.799 INFO [stdout] 
Nov 17 07:45:54.799 INFO [stderr]      Running /target/debug/deps/list-741156d8000615a3
Nov 17 07:45:54.801 INFO [stdout] 
Nov 17 07:45:54.802 INFO [stdout] running 2 tests
Nov 17 07:45:54.851 INFO [stdout] test test_cmd_list ... ok
Nov 17 07:45:54.869 INFO [stdout] test test_cmd_list_two ... ok
Nov 17 07:45:54.869 INFO [stdout] 
Nov 17 07:45:54.870 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.870 INFO [stdout] 
Nov 17 07:45:54.875 INFO [stderr]      Running /target/debug/deps/test_utils-6c8d25cf7751f0ee
Nov 17 07:45:54.879 INFO [stdout] 
Nov 17 07:45:54.879 INFO [stdout] running 0 tests
Nov 17 07:45:54.879 INFO [stdout] 
Nov 17 07:45:54.880 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:54.880 INFO [stdout] 
Nov 17 07:45:54.881 INFO [stderr]    Doc-tests taskerizer-prototype
Nov 17 07:45:57.337 INFO [stdout] 
Nov 17 07:45:57.337 INFO [stdout] running 0 tests
Nov 17 07:45:57.337 INFO [stdout] 
Nov 17 07:45:57.337 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:57.337 INFO [stdout] 
Nov 17 07:45:57.351 INFO [stderr] su: No module specific data is present
Nov 17 07:45:58.128 INFO running `"docker" "inspect" "4874013321e6a5881f8ed11ccc1a8042edc28f06ed642bd740b7f7024442d6b7"`
Nov 17 07:45:58.360 INFO running `"docker" "rm" "-f" "4874013321e6a5881f8ed11ccc1a8042edc28f06ed642bd740b7f7024442d6b7"`
Nov 17 07:45:58.541 INFO [stdout] 4874013321e6a5881f8ed11ccc1a8042edc28f06ed642bd740b7f7024442d6b7