Nov 18 00:35:58.168 INFO testing rusqlite-0.14.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 00:35:58.170 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 18 00:35:58.630 INFO [stdout] 7fa7e250869ad8459d405abbd3595b8f56ca119b092c4481305bd18d98bac8b5
Nov 18 00:35:58.632 INFO running `"docker" "start" "-a" "7fa7e250869ad8459d405abbd3595b8f56ca119b092c4481305bd18d98bac8b5"`
Nov 18 00:36:00.058 INFO [stderr] usermod: no changes
Nov 18 00:36:00.248 INFO [stderr]    Compiling rusqlite v0.14.0 (/source)
Nov 18 00:36:03.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.09s
Nov 18 00:36:03.199 INFO [stderr] su: No module specific data is present
Nov 18 00:36:03.909 INFO running `"docker" "inspect" "7fa7e250869ad8459d405abbd3595b8f56ca119b092c4481305bd18d98bac8b5"`
Nov 18 00:36:04.212 INFO running `"docker" "rm" "-f" "7fa7e250869ad8459d405abbd3595b8f56ca119b092c4481305bd18d98bac8b5"`
Nov 18 00:36:04.525 INFO [stdout] 7fa7e250869ad8459d405abbd3595b8f56ca119b092c4481305bd18d98bac8b5
Nov 18 00:36:04.527 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 18 00:36:04.927 INFO [stdout] d7cb5476828d21116ea27bb2c3f91630421407f3cacdb2eb1c7c126421b65b71
Nov 18 00:36:04.933 INFO running `"docker" "start" "-a" "d7cb5476828d21116ea27bb2c3f91630421407f3cacdb2eb1c7c126421b65b71"`
Nov 18 00:36:06.050 INFO [stderr] usermod: no changes
Nov 18 00:36:06.231 INFO [stderr]    Compiling rusqlite v0.14.0 (/source)
Nov 18 00:36:14.911 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.84s
Nov 18 00:36:14.923 INFO [stderr] su: No module specific data is present
Nov 18 00:36:15.661 INFO running `"docker" "inspect" "d7cb5476828d21116ea27bb2c3f91630421407f3cacdb2eb1c7c126421b65b71"`
Nov 18 00:36:16.107 INFO running `"docker" "rm" "-f" "d7cb5476828d21116ea27bb2c3f91630421407f3cacdb2eb1c7c126421b65b71"`
Nov 18 00:36:16.363 INFO [stdout] d7cb5476828d21116ea27bb2c3f91630421407f3cacdb2eb1c7c126421b65b71
Nov 18 00:36:16.368 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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 18 00:36:16.808 INFO [stdout] 7ae1b2791f378b9e4e81e949a7e5221464be6273c9a249184307800e6be06859
Nov 18 00:36:16.811 INFO running `"docker" "start" "-a" "7ae1b2791f378b9e4e81e949a7e5221464be6273c9a249184307800e6be06859"`
Nov 18 00:36:18.019 INFO [stderr] usermod: no changes
Nov 18 00:36:18.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Nov 18 00:36:18.211 INFO [stderr]      Running /target/debug/deps/rusqlite-f236c25db4c1dc65
Nov 18 00:36:18.215 INFO [stdout] 
Nov 18 00:36:18.219 INFO [stdout] running 65 tests
Nov 18 00:36:18.243 INFO [stdout] test busy::test::test_busy_handler ... ignored
Nov 18 00:36:18.243 INFO [stdout] test busy::test::test_busy_timeout ... ignored
Nov 18 00:36:18.244 INFO [stdout] test cache::test::test_cache ... ok
Nov 18 00:36:18.244 INFO [stdout] test cache::test::test_ddl ... ok
Nov 18 00:36:18.244 INFO [stdout] test cache::test::test_cache_key ... ok
Nov 18 00:36:18.245 INFO [stdout] test cache::test::test_discard ... ok
Nov 18 00:36:18.245 INFO [stdout] test cache::test::test_set_capacity ... ok
Nov 18 00:36:18.251 INFO [stdout] test statement::test::test_exists ... ok
Nov 18 00:36:18.251 INFO [stdout] test statement::test::test_insert ... ok
Nov 18 00:36:18.252 INFO [stdout] test statement::test::test_query_and_then_named ... ok
Nov 18 00:36:18.252 INFO [stdout] test statement::test::test_insert_different_tables ... ok
Nov 18 00:36:18.252 INFO [stdout] test statement::test::test_query_by_column_name ... ok
Nov 18 00:36:18.253 INFO [stdout] test cache::test::test_connection_close ... ok
Nov 18 00:36:18.253 INFO [stdout] test statement::test::test_execute_named ... ok
Nov 18 00:36:18.253 INFO [stdout] test statement::test::test_query_by_column_name_ignore_case ... ok
Nov 18 00:36:18.254 INFO [stdout] test statement::test::test_query_map_named ... ok
Nov 18 00:36:18.254 INFO [stdout] test statement::test::test_query_row ... ok
Nov 18 00:36:18.263 INFO [stdout] test statement::test::test_stmt_execute_named ... ok
Nov 18 00:36:18.263 INFO [stdout] test test::query_and_then_tests::test_dynamic ... ok
Nov 18 00:36:18.264 INFO [stdout] test test::query_and_then_tests::test_query_and_then_custom_error ... ok
Nov 18 00:36:18.264 INFO [stdout] test test::query_and_then_tests::test_query_and_then ... ok
Nov 18 00:36:18.265 INFO [stdout] test statement::test::test_unbound_parameters_are_null ... ok
Nov 18 00:36:18.265 INFO [stdout] test statement::test::test_query_named ... ok
Nov 18 00:36:18.265 INFO [stdout] test statement::test::test_unbound_parameters_are_reused ... ok
Nov 18 00:36:18.266 INFO [stdout] test test::query_and_then_tests::test_query_and_then_custom_error_fails ... ok
Nov 18 00:36:18.266 INFO [stdout] test test::query_and_then_tests::test_query_and_then_fails ... ok
Nov 18 00:36:18.266 INFO [stdout] test test::query_and_then_tests::test_query_row_and_then_custom_error_fails ... ok
Nov 18 00:36:18.267 INFO [stdout] test test::test_close_retry ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_last_insert_rowid ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_is_autocommit ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_execute_select ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_open ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_notnull_constraint_error ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_open_with_flags ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_prepare_column_names ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_prepare_execute ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_prepare_query ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_prepare_failures ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_execute_batch ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_execute ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::query_and_then_tests::test_query_row_and_then_custom_error ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_query_map ... ok
Nov 18 00:36:18.268 INFO [stdout] test test::test_query_row ... ok
Nov 18 00:36:18.268 INFO [stdout] test transaction::test::test_explicit_rollback_commit ... ok
Nov 18 00:36:18.274 INFO [stdout] test transaction::test::test_savepoint ... ok
Nov 18 00:36:18.275 INFO [stdout] test test::test_version_string ... ok
Nov 18 00:36:18.275 INFO [stdout] test test::test_statement_debugging ... ok
Nov 18 00:36:18.275 INFO [stdout] test transaction::test::test_ignore_drop_behavior ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::test::test_dynamic_type ... ok
Nov 18 00:36:18.275 INFO [stdout] test transaction::test::test_savepoint_names ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::from_sql::test::test_integral_ranges ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::test::test_option ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::test::test_blob ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::test::test_empty_blob ... ok
Nov 18 00:36:18.275 INFO [stdout] test types::test::test_mismatched_types ... ok
Nov 18 00:36:18.278 INFO [stdout] test types::test::test_value ... ok
Nov 18 00:36:18.278 INFO [stdout] test types::to_sql::test::test_cow_str ... ok
Nov 18 00:36:18.288 INFO [stdout] test test::test_persistence ... ok
Nov 18 00:36:18.288 INFO [stdout] test types::test::test_string ... ok
Nov 18 00:36:18.288 INFO [stdout] test transaction::test::test_drop ... ok
Nov 18 00:36:18.288 INFO [stdout] test types::time::test::test_timespec ... ok
Nov 18 00:36:18.288 INFO [stdout] test types::to_sql::test::test_integral_types ... ok
Nov 18 00:36:18.299 INFO [stdout] test types::test::test_str ... ok
Nov 18 00:36:18.299 INFO [stdout] test test::test_concurrent_transactions_busy_commit ... ok
Nov 18 00:36:23.273 INFO [stdout] test busy::test::test_default_busy ... ok
Nov 18 00:36:23.273 INFO [stdout] 
Nov 18 00:36:23.273 INFO [stdout] test result: ok. 63 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Nov 18 00:36:23.273 INFO [stdout] 
Nov 18 00:36:23.276 INFO [stderr]      Running /target/debug/deps/config_log-f180d74795e9a233
Nov 18 00:36:23.278 INFO [stderr]      Running /target/debug/deps/deny_single_threaded_sqlite_config-a70f21f80f4258ab
Nov 18 00:36:23.287 INFO [stdout] 
Nov 18 00:36:23.287 INFO [stdout] running 1 test
Nov 18 00:36:23.400 INFO [stdout] test test_error_when_singlethread_mode ... ok
Nov 18 00:36:23.400 INFO [stdout] 
Nov 18 00:36:23.400 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 00:36:23.400 INFO [stdout] 
Nov 18 00:36:23.400 INFO [stderr]      Running /target/debug/deps/vtab-4ed813d7c784b7c4
Nov 18 00:36:23.407 INFO [stdout] 
Nov 18 00:36:23.410 INFO [stderr]    Doc-tests rusqlite
Nov 18 00:36:23.411 INFO [stdout] running 0 tests
Nov 18 00:36:23.411 INFO [stdout] 
Nov 18 00:36:23.411 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 00:36:23.411 INFO [stdout] 
Nov 18 00:36:24.567 INFO [stdout] 
Nov 18 00:36:24.568 INFO [stdout] running 23 tests
Nov 18 00:36:30.567 INFO [stdout] test src/lib.rs - Connection::execute_named (line 332) ... ok
Nov 18 00:36:31.347 INFO [stdout] test src/lib.rs - Connection::query_row (line 364) ... ok
Nov 18 00:36:32.115 INFO [stdout] test src/lib.rs - Connection::execute_batch (line 279) ... ok
Nov 18 00:36:33.309 INFO [stdout] test src/lib.rs - Connection::prepare (line 486) ... ok
Nov 18 00:36:33.407 INFO [stdout] test src/lib.rs - Connection::query_row_and_then (line 417) ... ok
Nov 18 00:36:33.663 INFO [stdout] test src/lib.rs - Connection::execute (line 306) ... ok
Nov 18 00:36:33.713 INFO [stdout] test src/cache.rs - cache::Connection::prepare_cached (line 15) ... ok
Nov 18 00:36:35.915 INFO [stdout] test src/lib.rs -  (line 4) ... ok
Nov 18 00:36:38.035 INFO [stdout] test src/lib.rs - Connection::query_row_safe (line 456) ... ok
Nov 18 00:36:38.343 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_map (line 209) ... ok
Nov 18 00:36:38.634 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::execute_named (line 102) ... ok
Nov 18 00:36:38.658 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::execute (line 69) ... ok
Nov 18 00:36:39.583 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query (line 150) ... ok
Nov 18 00:36:40.482 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_and_then_named (line 304) ... ok
Nov 18 00:36:41.243 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_map_named (line 244) ... ok
Nov 18 00:36:43.491 INFO [stdout] test src/statement.rs - statement::Statement<'conn>::query_named (line 183) ... ok
Nov 18 00:36:44.864 INFO [stdout] test src/transaction.rs - transaction::Connection::savepoint (line 386) ... ok
Nov 18 00:36:44.939 INFO [stdout] test src/transaction.rs - transaction::Connection::transaction (line 343) ... ok
Nov 18 00:36:44.998 INFO [stdout] test src/transaction.rs - transaction::Transaction (line 49) ... ok
Nov 18 00:36:45.363 INFO [stdout] test src/transaction.rs - transaction::Savepoint (line 77) ... ok
Nov 18 00:36:45.731 INFO [stdout] test src/transaction.rs - transaction::Transaction<'conn>::savepoint (line 126) ... ok
Nov 18 00:36:45.871 INFO [stdout] test src/types/mod.rs - types::Null (line 78) ... ok
Nov 18 00:36:46.187 INFO [stdout] test src/types/mod.rs - types (line 21) ... ok
Nov 18 00:36:46.187 INFO [stdout] 
Nov 18 00:36:46.187 INFO [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 00:36:46.187 INFO [stdout] 
Nov 18 00:36:46.229 INFO [stderr] su: No module specific data is present
Nov 18 00:36:46.819 INFO running `"docker" "inspect" "7ae1b2791f378b9e4e81e949a7e5221464be6273c9a249184307800e6be06859"`
Nov 18 00:36:47.121 INFO running `"docker" "rm" "-f" "7ae1b2791f378b9e4e81e949a7e5221464be6273c9a249184307800e6be06859"`
Nov 18 00:36:47.424 INFO [stdout] 7ae1b2791f378b9e4e81e949a7e5221464be6273c9a249184307800e6be06859