Dec 07 06:47:35.650 INFO testing msql-srv-0.8.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 06:47:35.650 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06:47:36.044 INFO [stdout] 630235624e04c0dfeedef637474a9dcc8864d821d60425d4057212f1e205ff54
Dec 07 06:47:36.045 INFO running `"docker" "start" "-a" "630235624e04c0dfeedef637474a9dcc8864d821d60425d4057212f1e205ff54"`
Dec 07 06:47:37.175 INFO [stderr] usermod: no changes
Dec 07 06:47:37.404 INFO [stderr]    Compiling mysql_common v0.5.0
Dec 07 06:47:44.973 INFO [stderr]    Compiling msql-srv v0.8.0 (/source)
Dec 07 06:47:50.856 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.65s
Dec 07 06:47:50.882 INFO [stderr] su: No module specific data is present
Dec 07 06:47:51.438 INFO running `"docker" "inspect" "630235624e04c0dfeedef637474a9dcc8864d821d60425d4057212f1e205ff54"`
Dec 07 06:47:51.704 INFO running `"docker" "rm" "-f" "630235624e04c0dfeedef637474a9dcc8864d821d60425d4057212f1e205ff54"`
Dec 07 06:47:52.096 INFO [stdout] 630235624e04c0dfeedef637474a9dcc8864d821d60425d4057212f1e205ff54
Dec 07 06:47:52.102 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06:47:52.546 INFO [stdout] 41044a94c6931280cd3f6d1fa28ea863b658ded31f8552ce911f51725cae69b9
Dec 07 06:47:52.552 INFO running `"docker" "start" "-a" "41044a94c6931280cd3f6d1fa28ea863b658ded31f8552ce911f51725cae69b9"`
Dec 07 06:47:54.864 INFO [stderr] usermod: no changes
Dec 07 06:47:55.332 INFO [stderr]    Compiling mysql v12.3.1
Dec 07 06:47:55.364 INFO [stderr]    Compiling mysql_async v0.14.2
Dec 07 06:48:16.658 INFO [stderr]    Compiling msql-srv v0.8.0 (/source)
Dec 07 06:49:01.805 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 06s
Dec 07 06:49:01.821 INFO [stderr] su: No module specific data is present
Dec 07 06:49:03.432 INFO running `"docker" "inspect" "41044a94c6931280cd3f6d1fa28ea863b658ded31f8552ce911f51725cae69b9"`
Dec 07 06:49:03.786 INFO running `"docker" "rm" "-f" "41044a94c6931280cd3f6d1fa28ea863b658ded31f8552ce911f51725cae69b9"`
Dec 07 06:49:04.132 INFO [stdout] 41044a94c6931280cd3f6d1fa28ea863b658ded31f8552ce911f51725cae69b9
Dec 07 06:49:04.140 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 06:49:04.698 INFO [stdout] 23e6f318610a9d1e6e29094b044433b893cc648e1efcde7b9f4c041c33995c7f
Dec 07 06:49:04.700 INFO running `"docker" "start" "-a" "23e6f318610a9d1e6e29094b044433b893cc648e1efcde7b9f4c041c33995c7f"`
Dec 07 06:49:06.608 INFO [stderr] usermod: no changes
Dec 07 06:49:07.116 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.48s
Dec 07 06:49:07.139 INFO [stderr]      Running /target/debug/deps/msql_srv-f2f3ba346d674798
Dec 07 06:49:07.145 INFO [stdout] 
Dec 07 06:49:07.145 INFO [stdout] running 103 tests
Dec 07 06:49:07.188 INFO [stdout] test commands::tests::it_parses_handshake ... ok
Dec 07 06:49:07.188 INFO [stdout] test commands::tests::it_parses_request ... ok
Dec 07 06:49:07.189 INFO [stdout] test packet::tests::test_one_ping ... ok
Dec 07 06:49:07.196 INFO [stdout] test value::decode::tests::datetime ... ok
Dec 07 06:49:07.200 INFO [stdout] test value::decode::tests::dur ... ok
Dec 07 06:49:07.201 INFO [stdout] test packet::tests::test_ping ... ok
Dec 07 06:49:07.201 INFO [stdout] test value::decode::tests::f32_one_double ... ok
Dec 07 06:49:07.201 INFO [stdout] test value::decode::tests::bytes ... ok
Dec 07 06:49:07.204 INFO [stdout] test value::decode::tests::i32_max ... ok
Dec 07 06:49:07.204 INFO [stdout] test value::decode::tests::i16_one_long ... ok
Dec 07 06:49:07.205 INFO [stdout] test value::decode::tests::i16_one ... ok
Dec 07 06:49:07.205 INFO [stdout] test value::decode::tests::f64_one ... ok
Dec 07 06:49:07.208 INFO [stdout] test value::decode::tests::i64_max ... ok
Dec 07 06:49:07.212 INFO [stdout] test value::decode::tests::i64_one ... ok
Dec 07 06:49:07.213 INFO [stdout] test value::decode::tests::i8_max ... ok
Dec 07 06:49:07.213 INFO [stdout] test value::decode::tests::i32_one_longlong ... ok
Dec 07 06:49:07.213 INFO [stdout] test value::decode::tests::i32_one_long ... ok
Dec 07 06:49:07.214 INFO [stdout] test value::decode::tests::i16_one_longlong ... ok
Dec 07 06:49:07.214 INFO [stdout] test value::decode::tests::i16_max ... ok
Dec 07 06:49:07.220 INFO [stdout] test value::decode::tests::i8_one_longlong ... ok
Dec 07 06:49:07.221 INFO [stdout] test value::decode::tests::i8_one_short ... ok
Dec 07 06:49:07.221 INFO [stdout] test value::decode::tests::string ... ok
Dec 07 06:49:07.228 INFO [stdout] test value::decode::tests::u16_max ... ok
Dec 07 06:49:07.229 INFO [stdout] test value::decode::tests::u16_one_long ... ok
Dec 07 06:49:07.229 INFO [stdout] test value::decode::tests::i8_one ... ok
Dec 07 06:49:07.229 INFO [stdout] test value::decode::tests::i8_one_long ... ok
Dec 07 06:49:07.236 INFO [stdout] test value::decode::tests::time ... ok
Dec 07 06:49:07.236 INFO [stdout] test value::decode::tests::u16_one ... ok
Dec 07 06:49:07.237 INFO [stdout] test value::decode::tests::u16_one_longlong ... ok
Dec 07 06:49:07.237 INFO [stdout] test value::decode::tests::u32_max ... ok
Dec 07 06:49:07.238 INFO [stdout] test value::decode::tests::u32_one_long ... ok
Dec 07 06:49:07.248 INFO [stdout] test value::decode::tests::u8_one_long ... ok
Dec 07 06:49:07.249 INFO [stdout] test value::decode::tests::u8_one ... ok
Dec 07 06:49:07.249 INFO [stdout] test value::decode::tests::u64_max ... ok
Dec 07 06:49:07.249 INFO [stdout] test value::decode::tests::u64_one ... ok
Dec 07 06:49:07.252 INFO [stdout] test value::decode::tests::u8_max ... ok
Dec 07 06:49:07.252 INFO [stdout] test value::decode::tests::u8_one_longlong ... ok
Dec 07 06:49:07.256 INFO [stdout] test value::decode::tests::u8_one_short ... ok
Dec 07 06:49:07.264 INFO [stdout] test value::encode::tests::roundtrip_bin::datetime ... ok
Dec 07 06:49:07.264 INFO [stdout] test value::decode::tests::u32_one_longlong ... ok
Dec 07 06:49:07.264 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one ... ok
Dec 07 06:49:07.264 INFO [stdout] test value::encode::tests::roundtrip_bin::dur ... ok
Dec 07 06:49:07.264 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_long ... ok
Dec 07 06:49:07.276 INFO [stdout] test value::encode::tests::roundtrip_bin::f64_one ... ok
Dec 07 06:49:07.276 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one_double ... ok
Dec 07 06:49:07.276 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_max ... ok
Dec 07 06:49:07.276 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one ... ok
Dec 07 06:49:07.276 INFO [stdout] test value::encode::tests::roundtrip_bin::bytes ... ok
Dec 07 06:49:07.280 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_long ... ok
Dec 07 06:49:07.280 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_max ... ok
Dec 07 06:49:07.281 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_one ... ok
Dec 07 06:49:07.281 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one ... ok
Dec 07 06:49:07.281 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_long ... ok
Dec 07 06:49:07.281 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_short ... ok
Dec 07 06:49:07.281 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_longlong ... ok
Dec 07 06:49:07.312 INFO [stdout] test value::encode::tests::roundtrip_bin::opt_some ... ok
Dec 07 06:49:07.312 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_longlong ... ok
Dec 07 06:49:07.312 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_max ... ok
Dec 07 06:49:07.312 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_longlong ... ok
Dec 07 06:49:07.312 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_max ... ok
Dec 07 06:49:07.319 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one ... ok
Dec 07 06:49:07.319 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_long ... ok
Dec 07 06:49:07.319 INFO [stdout] test value::encode::tests::roundtrip_bin::time ... ok
Dec 07 06:49:07.319 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_max ... ok
Dec 07 06:49:07.319 INFO [stdout] test value::encode::tests::roundtrip_bin::string ... ok
Dec 07 06:49:07.332 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_longlong ... ok
Dec 07 06:49:07.332 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_max ... ok
Dec 07 06:49:07.332 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_max ... ok
Dec 07 06:49:07.332 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_longlong ... ok
Dec 07 06:49:07.332 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_max ... ok
Dec 07 06:49:07.364 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_long ... ok
Dec 07 06:49:07.364 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one ... ok
Dec 07 06:49:07.364 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_long ... ok
Dec 07 06:49:07.364 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_one ... ok
Dec 07 06:49:07.364 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_longlong ... ok
Dec 07 06:49:07.372 INFO [stdout] test value::encode::tests::roundtrip_text::bytes ... ok
Dec 07 06:49:07.380 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_short ... ok
Dec 07 06:49:07.383 INFO [stdout] test value::encode::tests::roundtrip_text::f64_one ... ok
Dec 07 06:49:07.383 INFO [stdout] test value::encode::tests::roundtrip_text::i16_max ... ok
Dec 07 06:49:07.383 INFO [stdout] test value::encode::tests::roundtrip_text::f32_one ... ok
Dec 07 06:49:07.400 INFO [stdout] test value::encode::tests::roundtrip_text::i16_one ... ok
Dec 07 06:49:07.400 INFO [stdout] test value::encode::tests::roundtrip_text::i32_max ... ok
Dec 07 06:49:07.400 INFO [stdout] test value::encode::tests::roundtrip_text::i32_one ... ok
Dec 07 06:49:07.401 INFO [stdout] test value::encode::tests::roundtrip_text::i64_max ... ok
Dec 07 06:49:07.404 INFO [stdout] test value::encode::tests::roundtrip_text::i8_one ... ok
Dec 07 06:49:07.404 INFO [stdout] test value::encode::tests::roundtrip_text::opt_none ... ok
Dec 07 06:49:07.404 INFO [stdout] test value::encode::tests::roundtrip_text::i8_max ... ok
Dec 07 06:49:07.407 INFO [stdout] test value::encode::tests::roundtrip_text::i64_one ... ok
Dec 07 06:49:07.407 INFO [stdout] test value::encode::tests::roundtrip_text::opt_some ... ok
Dec 07 06:49:07.407 INFO [stdout] test value::encode::tests::roundtrip_text::string ... ok
Dec 07 06:49:07.408 INFO [stdout] test value::encode::tests::roundtrip_text::u16_max ... ok
Dec 07 06:49:07.408 INFO [stdout] test value::encode::tests::roundtrip_text::u16_one ... ok
Dec 07 06:49:07.408 INFO [stdout] test value::encode::tests::roundtrip_text::u32_one ... ok
Dec 07 06:49:07.409 INFO [stdout] test value::encode::tests::roundtrip_text::u32_max ... ok
Dec 07 06:49:07.417 INFO [stdout] test value::encode::tests::roundtrip_text::u64_max ... ok
Dec 07 06:49:07.418 INFO [stdout] test value::encode::tests::roundtrip_text::u64_one ... ok
Dec 07 06:49:07.418 INFO [stdout] test value::encode::tests::roundtrip_text::u8_max ... ok
Dec 07 06:49:07.420 INFO [stdout] test value::encode::tests::roundtrip_text::u8_one ... ok
Dec 07 06:49:07.456 INFO [stdout] test value::encode::tests::roundtrip_text::datetime ... ok
Dec 07 06:49:07.474 INFO [stdout] test value::encode::tests::roundtrip_text::dur ... ok
Dec 07 06:49:07.480 INFO [stdout] test value::encode::tests::roundtrip_text::time ... ok
Dec 07 06:49:07.500 INFO [stdout] test packet::tests::test_long_exact ... ok
Dec 07 06:49:07.503 INFO [stdout] test packet::tests::test_long_more ... ok
Dec 07 06:49:07.503 INFO [stdout] 
Dec 07 06:49:07.503 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 06:49:07.503 INFO [stdout] 
Dec 07 06:49:07.505 INFO [stderr]      Running /target/debug/deps/async-25e7880bd67c3939
Dec 07 06:49:07.509 INFO [stdout] 
Dec 07 06:49:07.509 INFO [stdout] running 21 tests
Dec 07 06:49:07.746 INFO [stdout] test empty_response ... ok
Dec 07 06:49:07.782 INFO [stdout] test it_connects ... ok
Dec 07 06:49:07.783 INFO [stdout] test it_pings ... ok
Dec 07 06:49:07.802 INFO [stdout] test insert_exec ... ok
Dec 07 06:49:07.841 INFO [stdout] test empty_on_drop ... ok
Dec 07 06:49:07.849 INFO [stdout] test it_queries ... ok
Dec 07 06:49:07.880 INFO [stdout] test it_prepares ... ok
Dec 07 06:49:07.882 INFO [stdout] test it_prepares_many ... ok
Dec 07 06:49:07.900 INFO [stdout] test no_columns_but_rows ... ok
Dec 07 06:49:07.900 INFO [stdout] test no_columns ... ok
Dec 07 06:49:07.946 INFO [stdout] test it_queries_many_rows ... ok
Dec 07 06:49:07.946 INFO [stdout] test it_queries_nulls ... ok
Dec 07 06:49:07.968 INFO [stdout] test prepared_no_cols_but_rows ... ok
Dec 07 06:49:07.984 INFO [stdout] test prepared_no_cols ... ok
Dec 07 06:49:07.988 INFO [stdout] test no_rows ... ok
Dec 07 06:49:08.009 INFO [stdout] test prepared_empty ... ok
Dec 07 06:49:08.060 INFO [stdout] test prepared_no_params ... ok
Dec 07 06:49:08.133 INFO [stdout] test prepared_no_rows ... ok
Dec 07 06:49:08.182 INFO [stdout] test prepared_nulls ... ok
Dec 07 06:49:08.182 INFO [stdout] test send_long ... ok
Dec 07 06:49:08.285 INFO [stdout] test error_response ... ok
Dec 07 06:49:08.285 INFO [stdout] 
Dec 07 06:49:08.285 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 06:49:08.285 INFO [stdout] 
Dec 07 06:49:08.294 INFO [stderr]      Running /target/debug/deps/main-ba52dd67eb5d5af2
Dec 07 06:49:08.302 INFO [stdout] 
Dec 07 06:49:08.302 INFO [stdout] running 22 tests
Dec 07 06:49:08.573 INFO [stdout] test it_connects ... ok
Dec 07 06:49:08.593 INFO [stdout] test error_response ... ok
Dec 07 06:49:08.617 INFO [stdout] test empty_on_drop ... ok
Dec 07 06:49:08.632 INFO [stdout] test it_pings ... ok
Dec 07 06:49:08.637 INFO [stdout] test insert_exec ... ok
Dec 07 06:49:08.673 INFO [stdout] test empty_response ... ok
Dec 07 06:49:08.685 INFO [stdout] test no_columns ... ok
Dec 07 06:49:08.720 INFO [stdout] test it_queries ... ok
Dec 07 06:49:08.721 INFO [stdout] test it_prepares ... ok
Dec 07 06:49:08.724 INFO [stdout] test it_queries_many_rows ... ok
Dec 07 06:49:08.732 INFO [stdout] test no_columns_but_rows ... ok
Dec 07 06:49:08.736 INFO [stdout] test multi_result ... ok
Dec 07 06:49:08.744 INFO [stdout] test it_queries_nulls ... ok
Dec 07 06:49:08.776 INFO [stdout] test prepared_no_cols_but_rows ... ok
Dec 07 06:49:08.777 INFO [stdout] test no_rows ... ok
Dec 07 06:49:08.777 INFO [stdout] test it_prepares_many ... ok
Dec 07 06:49:08.778 INFO [stdout] test prepared_no_cols ... ok
Dec 07 06:49:08.812 INFO [stdout] test prepared_empty ... ok
Dec 07 06:49:08.877 INFO [stdout] test prepared_no_rows ... ok
Dec 07 06:49:08.877 INFO [stdout] test prepared_no_params ... ok
Dec 07 06:49:08.893 INFO [stdout] test prepared_nulls ... ok
Dec 07 06:49:08.909 INFO [stdout] test send_long ... ok
Dec 07 06:49:08.909 INFO [stdout] 
Dec 07 06:49:08.909 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 06:49:08.909 INFO [stdout] 
Dec 07 06:49:08.910 INFO [stderr]    Doc-tests msql-srv
Dec 07 06:49:13.713 INFO [stdout] 
Dec 07 06:49:13.713 INFO [stdout] running 1 test
Dec 07 06:49:23.167 INFO [stdout] test src/lib.rs -  (line 15) ... ok
Dec 07 06:49:23.167 INFO [stdout] 
Dec 07 06:49:23.167 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 06:49:23.167 INFO [stdout] 
Dec 07 06:49:23.205 INFO [stderr] su: No module specific data is present
Dec 07 06:49:25.022 INFO running `"docker" "inspect" "23e6f318610a9d1e6e29094b044433b893cc648e1efcde7b9f4c041c33995c7f"`
Dec 07 06:49:25.254 INFO running `"docker" "rm" "-f" "23e6f318610a9d1e6e29094b044433b893cc648e1efcde7b9f4c041c33995c7f"`
Dec 07 06:49:25.484 INFO [stdout] 23e6f318610a9d1e6e29094b044433b893cc648e1efcde7b9f4c041c33995c7f