Nov 17 07:44:12.843 INFO testing boustrophedon/taskerizer-prototype against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 07:44:12.843 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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.340 INFO [stdout] af27a56034038ac767dc5d8ea920d74118ad6161b8e6ec9973495495d52c570e
Nov 17 07:44:13.347 INFO running `"docker" "start" "-a" "af27a56034038ac767dc5d8ea920d74118ad6161b8e6ec9973495495d52c570e"`
Nov 17 07:44:14.826 INFO [stderr] usermod: no changes
Nov 17 07:44:15.056 INFO [stderr]    Compiling libsqlite3-sys v0.9.3
Nov 17 07:44:15.057 INFO [stderr]    Compiling rand v0.5.5
Nov 17 07:44:15.057 INFO [stderr]    Compiling num-integer v0.1.39
Nov 17 07:44:15.099 INFO [stderr]    Compiling structopt-derive v0.2.10
Nov 17 07:44:19.139 INFO [stderr]    Compiling chrono v0.4.5
Nov 17 07:44:32.277 INFO [stderr]    Compiling structopt v0.2.10
Nov 17 07:44:37.497 INFO [stderr]    Compiling rusqlite v0.14.0
Nov 17 07:44:40.403 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Nov 17 07:44:46.623 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 31.78s
Nov 17 07:44:46.635 INFO [stderr] su: No module specific data is present
Nov 17 07:44:47.357 INFO running `"docker" "inspect" "af27a56034038ac767dc5d8ea920d74118ad6161b8e6ec9973495495d52c570e"`
Nov 17 07:44:47.655 INFO running `"docker" "rm" "-f" "af27a56034038ac767dc5d8ea920d74118ad6161b8e6ec9973495495d52c570e"`
Nov 17 07:44:47.899 INFO [stdout] af27a56034038ac767dc5d8ea920d74118ad6161b8e6ec9973495495d52c570e
Nov 17 07:44:47.907 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:48.432 INFO [stdout] e45035d5b0a4bbf35d589c3be8a03b199ea0ecbe89a5e7b146578a02dfff5aa1
Nov 17 07:44:48.439 INFO running `"docker" "start" "-a" "e45035d5b0a4bbf35d589c3be8a03b199ea0ecbe89a5e7b146578a02dfff5aa1"`
Nov 17 07:44:49.435 INFO [stderr] usermod: no changes
Nov 17 07:44:49.609 INFO [stderr]    Compiling byteorder v1.2.4
Nov 17 07:44:49.615 INFO [stderr]    Compiling wait-timeout v0.1.5
Nov 17 07:44:49.615 INFO [stderr]    Compiling tempfile v3.0.3
Nov 17 07:44:51.367 INFO [stderr]    Compiling rusty-fork v0.2.0
Nov 17 07:44:53.154 INFO [stderr]    Compiling proptest v0.8.4
Nov 17 07:45:10.131 INFO [stderr]    Compiling taskerizer-prototype v0.1.0 (/source)
Nov 17 07:45:29.124 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 39.65s
Nov 17 07:45:29.135 INFO [stderr] su: No module specific data is present
Nov 17 07:45:30.278 INFO running `"docker" "inspect" "e45035d5b0a4bbf35d589c3be8a03b199ea0ecbe89a5e7b146578a02dfff5aa1"`
Nov 17 07:45:30.743 INFO running `"docker" "rm" "-f" "e45035d5b0a4bbf35d589c3be8a03b199ea0ecbe89a5e7b146578a02dfff5aa1"`
Nov 17 07:45:30.963 INFO [stdout] e45035d5b0a4bbf35d589c3be8a03b199ea0ecbe89a5e7b146578a02dfff5aa1
Nov 17 07:45:30.972 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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:31.637 INFO [stdout] 259a2d0837894b6874da632a96b4c2ad3f0356b10c63308827bd92ef9ec467a2
Nov 17 07:45:31.639 INFO running `"docker" "start" "-a" "259a2d0837894b6874da632a96b4c2ad3f0356b10c63308827bd92ef9ec467a2"`
Nov 17 07:45:32.707 INFO [stderr] usermod: no changes
Nov 17 07:45:32.895 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Nov 17 07:45:32.903 INFO [stderr]      Running /target/debug/deps/taskerizer_prototype-d6a9f94d59cbd70e
Nov 17 07:45:32.903 INFO [stdout] 
Nov 17 07:45:32.903 INFO [stdout] running 39 tests
Nov 17 07:45:32.963 INFO [stdout] test db::backend::select_tests::test_select_task_list ... ok
Nov 17 07:45:32.963 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current ... ok
Nov 17 07:45:32.967 INFO [stdout] test db::backend::select_tests::test_select_task_two ... ok
Nov 17 07:45:32.967 INFO [stdout] test db::backend::select_tests::test_select_task_single ... ok
Nov 17 07:45:32.975 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current ... ok
Nov 17 07:45:32.975 INFO [stdout] test db::tests::add::test_db_add ... ok
Nov 17 07:45:32.978 INFO [stdout] test db::tests::choose_current::test_db_choose_current_error_p ... ok
Nov 17 07:45:32.978 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_break ... ok
Nov 17 07:45:32.983 INFO [stdout] test db::tests::choose_current::test_db_choose_current_one_task ... ok
Nov 17 07:45:33.043 INFO [stdout] test db::tests::get_current::test_db_get_current_no_tasks ... ok
Nov 17 07:45:33.051 INFO [stdout] test db::tests::choose_current::test_db_choose_current_empty ... ok
Nov 17 07:45:33.059 INFO [stdout] test db::tests::get_current::test_db_get_current_one_task ... ok
Nov 17 07:45:33.059 INFO [stdout] test db::tests::get_current::test_db_get_current_ordering_two ... ok
Nov 17 07:45:33.059 INFO [stdout] test db::tests::get_current::test_db_get_current_task_vs_break ... ok
Nov 17 07:45:33.059 INFO [stdout] test db::tests::get_current::test_db_get_current_two_max_u32 ... ok
Nov 17 07:45:33.059 INFO [stdout] test db::tests::list::test_db_list_added_manually ... ok
Nov 17 07:45:33.067 INFO [stdout] test db::tests::get_current::test_db_get_current_one_break ... ok
Nov 17 07:45:33.075 INFO [stdout] test db::tests::list::test_db_list_empty ... ok
Nov 17 07:45:33.086 INFO [stdout] test db::tests::list::test_db_list_invalid_task_empty ... ok
Nov 17 07:45:33.091 INFO [stdout] test db::tests::list::test_db_list_invalid_task_zero_priority ... ok
Nov 17 07:45:33.279 INFO [stdout] test db::tests::test_db_metadata ... ok
Nov 17 07:45:33.280 INFO [stdout] test db::tests::test_db_open_err_bad_dir ... ok
Nov 17 07:45:33.427 INFO [stdout] test db::tests::test_db_open_on_disk ... ok
Nov 17 07:45:33.428 INFO [stdout] test task::test::test_task_fmt_long_1 ... ok
Nov 17 07:45:33.428 INFO [stdout] test task::test::test_task_fmt_long_2 ... ok
Nov 17 07:45:33.428 INFO [stdout] test task::test::test_task_fmt_long_3 ... ok
Nov 17 07:45:33.428 INFO [stdout] test task::test::test_task_fmt_row_1 ... ok
Nov 17 07:45:33.451 INFO [stdout] test task::test::test_task_fmt_row_2 ... ok
Nov 17 07:45:33.452 INFO [stdout] test task::test::test_task_nonempty_task ... ok
Nov 17 07:45:33.452 INFO [stdout] test task::test::test_task_null_desc ... ok
Nov 17 07:45:33.723 INFO [stdout] test task::test::test_task_valid_arb_task ... ok
Nov 17 07:45:33.724 INFO [stdout] test task::test::test_task_zero_priority ... ok
Nov 17 07:45:34.041 INFO [stdout] test db::backend::select_tests::test_select_task_list_arb ... ok
Nov 17 07:45:34.895 INFO [stdout] test db::tests::add::test_db_add_arb ... ok
Nov 17 07:45:35.111 INFO [stdout] test commands::test_dispatch::test_runcmd_add_current_arb ... ok
Nov 17 07:45:41.491 INFO [stdout] test db::tests::list::test_db_list_arb ... ok
Nov 17 07:45:42.295 INFO [stdout] test db::tests::choose_current::test_db_choose_current_arb ... ok
Nov 17 07:45:42.411 INFO [stdout] test db::tests::get_current::test_db_get_current_arb ... ok
Nov 17 07:45:42.970 INFO [stdout] test commands::test_dispatch::test_runcmd_add_multiple_current_arb ... ok
Nov 17 07:45:42.970 INFO [stdout] 
Nov 17 07:45:42.970 INFO [stdout] test result: ok. 39 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:42.970 INFO [stdout] 
Nov 17 07:45:42.970 INFO [stderr]      Running /target/debug/deps/tkzr-d672ef33ec9b0e8f
Nov 17 07:45:42.995 INFO [stdout] 
Nov 17 07:45:42.995 INFO [stdout] running 0 tests
Nov 17 07:45:42.995 INFO [stdout] 
Nov 17 07:45:42.995 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:42.995 INFO [stdout] 
Nov 17 07:45:42.995 INFO [stderr]      Running /target/debug/deps/add-121315cfa8095654
Nov 17 07:45:42.998 INFO [stdout] 
Nov 17 07:45:42.998 INFO [stdout] running 4 tests
Nov 17 07:45:43.087 INFO [stdout] test test_cmd_add_empty_task ... ok
Nov 17 07:45:43.090 INFO [stdout] test test_cmd_add_priority_0 ... ok
Nov 17 07:45:43.105 INFO [stdout] test test_cmd_add ... ok
Nov 17 07:45:43.123 INFO [stdout] test test_cmd_add_two ... ok
Nov 17 07:45:43.123 INFO [stdout] 
Nov 17 07:45:43.123 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:43.123 INFO [stdout] 
Nov 17 07:45:43.124 INFO [stderr]      Running /target/debug/deps/current-078ea6907b83f45c
Nov 17 07:45:43.126 INFO [stdout] 
Nov 17 07:45:43.127 INFO [stdout] running 4 tests
Nov 17 07:45:43.175 INFO [stdout] test test_cmd_current_empty ... ok
Nov 17 07:45:43.201 INFO [stdout] test test_cmd_current_3_interspersed ... ok
Nov 17 07:45:43.202 INFO [stdout] test test_cmd_current_1 ... ok
Nov 17 07:45:43.204 INFO [stdout] test test_cmd_current_2 ... ok
Nov 17 07:45:43.204 INFO [stdout] 
Nov 17 07:45:43.204 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:43.204 INFO [stdout] 
Nov 17 07:45:43.211 INFO [stderr]      Running /target/debug/deps/list-616b95b070592c32
Nov 17 07:45:43.211 INFO [stdout] 
Nov 17 07:45:43.211 INFO [stdout] running 2 tests
Nov 17 07:45:43.297 INFO [stdout] test test_cmd_list ... ok
Nov 17 07:45:43.309 INFO [stdout] test test_cmd_list_two ... ok
Nov 17 07:45:43.309 INFO [stdout] 
Nov 17 07:45:43.309 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:43.309 INFO [stdout] 
Nov 17 07:45:43.309 INFO [stderr]      Running /target/debug/deps/test_utils-ede3fe043c4761c4
Nov 17 07:45:43.317 INFO [stdout] 
Nov 17 07:45:43.317 INFO [stdout] running 0 tests
Nov 17 07:45:43.317 INFO [stdout] 
Nov 17 07:45:43.317 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:43.317 INFO [stdout] 
Nov 17 07:45:43.317 INFO [stderr]    Doc-tests taskerizer-prototype
Nov 17 07:45:45.629 INFO [stdout] 
Nov 17 07:45:45.629 INFO [stdout] running 0 tests
Nov 17 07:45:45.629 INFO [stdout] 
Nov 17 07:45:45.629 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:45:45.629 INFO [stdout] 
Nov 17 07:45:45.636 INFO [stderr] su: No module specific data is present
Nov 17 07:45:46.378 INFO running `"docker" "inspect" "259a2d0837894b6874da632a96b4c2ad3f0356b10c63308827bd92ef9ec467a2"`
Nov 17 07:45:46.653 INFO running `"docker" "rm" "-f" "259a2d0837894b6874da632a96b4c2ad3f0356b10c63308827bd92ef9ec467a2"`
Nov 17 07:45:47.045 INFO [stdout] 259a2d0837894b6874da632a96b4c2ad3f0356b10c63308827bd92ef9ec467a2