Dec 07 00:40:08.993 INFO testing rusqlite-0.15.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 00:40:08.993 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 00:40:09.569 INFO [stdout] 8b14f54782db6d3ca30296a4e8f1602dc3742747edd489bd17386fae7d3dd19f
Dec 07 00:40:09.574 INFO running `"docker" "start" "-a" "8b14f54782db6d3ca30296a4e8f1602dc3742747edd489bd17386fae7d3dd19f"`
Dec 07 00:40:10.960 INFO [stderr] usermod: no changes
Dec 07 00:40:11.176 INFO [stderr]    Compiling rusqlite v0.15.0 (/source)
Dec 07 00:40:13.873 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.87s
Dec 07 00:40:13.893 INFO [stderr] su: No module specific data is present
Dec 07 00:40:14.942 INFO running `"docker" "inspect" "8b14f54782db6d3ca30296a4e8f1602dc3742747edd489bd17386fae7d3dd19f"`
Dec 07 00:40:15.144 INFO running `"docker" "rm" "-f" "8b14f54782db6d3ca30296a4e8f1602dc3742747edd489bd17386fae7d3dd19f"`
Dec 07 00:40:15.332 INFO [stdout] 8b14f54782db6d3ca30296a4e8f1602dc3742747edd489bd17386fae7d3dd19f
Dec 07 00:40:15.334 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 00:40:15.664 INFO [stdout] 6dd897bf5909f3163223f323fcce01701f3f3a47baa0994550860f26e052ad47
Dec 07 00:40:15.666 INFO running `"docker" "start" "-a" "6dd897bf5909f3163223f323fcce01701f3f3a47baa0994550860f26e052ad47"`
Dec 07 00:40:16.844 INFO [stderr] usermod: no changes
Dec 07 00:40:17.116 INFO [stderr]    Compiling rusqlite v0.15.0 (/source)
Dec 07 00:40:26.630 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.76s
Dec 07 00:40:26.640 INFO [stderr] su: No module specific data is present
Dec 07 00:40:27.656 INFO running `"docker" "inspect" "6dd897bf5909f3163223f323fcce01701f3f3a47baa0994550860f26e052ad47"`
Dec 07 00:40:27.986 INFO running `"docker" "rm" "-f" "6dd897bf5909f3163223f323fcce01701f3f3a47baa0994550860f26e052ad47"`
Dec 07 00:40:28.331 INFO [stdout] 6dd897bf5909f3163223f323fcce01701f3f3a47baa0994550860f26e052ad47
Dec 07 00:40:28.335 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 00:40:28.802 INFO [stdout] 7306d741e58348ed3f1a34c1dd5be5f9e2a9096a9b9fbc214033927181e8d7ca
Dec 07 00:40:28.808 INFO running `"docker" "start" "-a" "7306d741e58348ed3f1a34c1dd5be5f9e2a9096a9b9fbc214033927181e8d7ca"`
Dec 07 00:40:29.847 INFO [stderr] usermod: no changes
Dec 07 00:40:29.988 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 07 00:40:29.993 INFO [stderr]      Running /target/debug/deps/rusqlite-6f866dc6e4e6e7e8
Dec 07 00:40:30.000 INFO [stdout] 
Dec 07 00:40:30.000 INFO [stdout] running 69 tests
Dec 07 00:40:30.000 INFO [stdout] test busy::test::test_busy_handler ... ignored
Dec 07 00:40:30.000 INFO [stdout] test busy::test::test_busy_timeout ... ignored
Dec 07 00:40:30.015 INFO [stdout] test cache::test::test_set_capacity ... ok
Dec 07 00:40:30.016 INFO [stdout] test cache::test::test_cache ... ok
Dec 07 00:40:30.016 INFO [stdout] test cache::test::test_ddl ... ok
Dec 07 00:40:30.016 INFO [stdout] test cache::test::test_cache_key ... ok
Dec 07 00:40:30.016 INFO [stdout] test cache::test::test_discard ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_insert_different_tables ... ok
Dec 07 00:40:30.016 INFO [stdout] test cache::test::test_connection_close ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_by_column_name ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_and_then_named ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_execute_named ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_by_column_name_ignore_case ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_map_named ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_row ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_insert ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_exists ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_stmt_execute_named ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_unbound_parameters_are_null ... ok
Dec 07 00:40:30.016 INFO [stdout] test statement::test::test_query_named ... ok
Dec 07 00:40:30.016 INFO [stdout] test test::query_and_then_tests::test_query_and_then_custom_error_fails ... ok
Dec 07 00:40:30.016 INFO [stdout] test test::query_and_then_tests::test_query_and_then_custom_error ... ok
Dec 07 00:40:30.016 INFO [stdout] test test::query_and_then_tests::test_query_and_then_fails ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::query_and_then_tests::test_query_row_and_then_custom_error_fails ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::query_and_then_tests::test_dynamic ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_close_retry ... ok
Dec 07 00:40:30.024 INFO [stdout] test statement::test::test_unbound_parameters_are_reused ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_get_raw ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::query_and_then_tests::test_query_row_and_then_custom_error ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_execute ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_execute_select ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_execute_batch ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_interrupt_close ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_is_autocommit ... ok
Dec 07 00:40:30.024 INFO [stdout] test test::test_last_insert_rowid ... ok
Dec 07 00:40:30.032 INFO [stdout] test test::query_and_then_tests::test_query_and_then ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_pragma_query_row ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_open_with_flags ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_open ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_notnull_constraint_error ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_prepare_column_names ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_prepare_query ... ok
Dec 07 00:40:30.038 INFO [stdout] test test::test_prepare_failures ... ok
Dec 07 00:40:30.039 INFO [stdout] test test::test_query_map ... ok
Dec 07 00:40:30.039 INFO [stdout] test test::test_version_string ... ok
Dec 07 00:40:30.039 INFO [stdout] test test::test_statement_debugging ... ok
Dec 07 00:40:30.039 INFO [stdout] test test::test_query_row ... ok
Dec 07 00:40:30.039 INFO [stdout] test test::test_prepare_execute ... ok
Dec 07 00:40:30.052 INFO [stdout] test transaction::test::test_ignore_drop_behavior ... ok
Dec 07 00:40:30.052 INFO [stdout] test transaction::test::test_explicit_rollback_commit ... ok
Dec 07 00:40:30.052 INFO [stdout] test transaction::test::test_drop ... ok
Dec 07 00:40:30.066 INFO [stdout] test types::test::test_blob ... ok
Dec 07 00:40:30.066 INFO [stdout] test types::from_sql::test::test_integral_ranges ... ok
Dec 07 00:40:30.066 INFO [stdout] test transaction::test::test_savepoint ... ok
Dec 07 00:40:30.066 INFO [stdout] test transaction::test::test_savepoint_names ... ok
Dec 07 00:40:30.066 INFO [stdout] test test::test_persistence ... ok
Dec 07 00:40:30.066 INFO [stdout] test types::test::test_dynamic_type ... ok
Dec 07 00:40:30.066 INFO [stdout] test types::test::test_string ... ok
Dec 07 00:40:30.066 INFO [stdout] test types::test::test_empty_blob ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::test::test_option ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::test::test_str ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::test::test_value ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::to_sql::test::test_integral_types ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::test::test_mismatched_types ... ok
Dec 07 00:40:30.071 INFO [stdout] test types::to_sql::test::test_cow_str ... ok
Dec 07 00:40:30.073 INFO [stdout] test types::time::test::test_timespec ... ok
Dec 07 00:40:30.080 INFO [stdout] test test::test_concurrent_transactions_busy_commit ... ok
Dec 07 00:40:31.268 INFO [stdout] test test::test_interrupt ... ok
Dec 07 00:40:35.028 INFO [stdout] test busy::test::test_default_busy ... ok
Dec 07 00:40:35.028 INFO [stdout] 
Dec 07 00:40:35.028 INFO [stdout] test result: ok. 67 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 07 00:40:35.028 INFO [stdout] 
Dec 07 00:40:35.028 INFO [stdout] 
Dec 07 00:40:35.028 INFO [stdout] running 1 test
Dec 07 00:40:35.028 INFO [stderr]      Running /target/debug/deps/config_log-c4b310da782177c7
Dec 07 00:40:35.028 INFO [stderr]      Running /target/debug/deps/deny_single_threaded_sqlite_config-82a06df4ce21229a
Dec 07 00:40:35.140 INFO [stdout] test test_error_when_singlethread_mode ... ok
Dec 07 00:40:35.140 INFO [stdout] 
Dec 07 00:40:35.140 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 00:40:35.140 INFO [stdout] 
Dec 07 00:40:35.141 INFO [stderr]      Running /target/debug/deps/vtab-9cce33e732fd9ed4
Dec 07 00:40:35.143 INFO [stderr]    Doc-tests rusqlite
Dec 07 00:40:35.143 INFO [stdout] 
Dec 07 00:40:35.143 INFO [stdout] running 0 tests
Dec 07 00:40:35.143 INFO [stdout] 
Dec 07 00:40:35.143 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 00:40:35.143 INFO [stdout] 
Dec 07 00:40:36.324 INFO [stdout] 
Dec 07 00:40:36.324 INFO [stdout] running 22 tests
Dec 07 00:40:43.588 INFO [stdout] test src/lib.rs - Connection::execute (line 312) ... ok
Dec 07 00:40:43.932 INFO [stdout] test src/lib.rs - Connection::execute_batch (line 285) ... ok
Dec 07 00:40:44.498 INFO [stdout] test src/cache.rs - cache::Connection::prepare_cached (line 15) ... ok
Dec 07 00:40:44.532 INFO [stdout] test src/lib.rs - Connection::query_row_and_then (line 429) ... ok
Dec 07 00:40:44.616 INFO [stdout] test src/lib.rs - Connection::query_row (line 374) ... ok
Dec 07 00:40:45.856 INFO [stdout] test src/lib.rs - Connection::execute_named (line 342) ... ok
Dec 07 00:40:45.894 INFO [stdout] test src/lib.rs - Connection::prepare (line 464) ... ok
Dec 07 00:40:48.518 INFO [stdout] test src/lib.rs -  (line 4) ... ok
Dec 07 00:40:50.068 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_map (line 222) ... ok
Dec 07 00:40:51.896 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::execute_named (line 107) ... ok
Dec 07 00:40:53.952 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::execute (line 70) ... ok
Dec 07 00:40:54.290 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_and_then_named (line 321) ... ok
Dec 07 00:40:54.412 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query (line 159) ... ok
Dec 07 00:40:54.925 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_map_named (line 259) ... ok
Dec 07 00:40:56.501 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_named (line 196) ... ok
Dec 07 00:40:58.092 INFO [stdout] test src/transaction.rs - transaction::Connection::savepoint (line 386) ... ok
Dec 07 00:40:58.708 INFO [stdout] test src/transaction.rs - transaction::Connection::transaction (line 343) ... ok
Dec 07 00:41:00.100 INFO [stdout] test src/transaction.rs - transaction::Transaction (line 49) ... ok
Dec 07 00:41:00.504 INFO [stdout] test src/types/mod.rs - types::Null (line 78) ... ok
Dec 07 00:41:00.640 INFO [stdout] test src/transaction.rs - transaction::Savepoint (line 77) ... ok
Dec 07 00:41:01.046 INFO [stdout] test src/transaction.rs - transaction::Transaction<'conn>::savepoint (line 126) ... ok
Dec 07 00:41:01.196 INFO [stdout] test src/types/mod.rs - types (line 21) ... ok
Dec 07 00:41:01.196 INFO [stdout] 
Dec 07 00:41:01.196 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 00:41:01.196 INFO [stdout] 
Dec 07 00:41:01.212 INFO [stderr] su: No module specific data is present
Dec 07 00:41:01.821 INFO running `"docker" "inspect" "7306d741e58348ed3f1a34c1dd5be5f9e2a9096a9b9fbc214033927181e8d7ca"`
Dec 07 00:41:02.076 INFO running `"docker" "rm" "-f" "7306d741e58348ed3f1a34c1dd5be5f9e2a9096a9b9fbc214033927181e8d7ca"`
Dec 07 00:41:02.400 INFO [stdout] 7306d741e58348ed3f1a34c1dd5be5f9e2a9096a9b9fbc214033927181e8d7ca