Nov 18 05:50:01.131 INFO testing msql-srv-0.8.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982 Nov 18 05:50:01.132 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 18 05:50:01.364 INFO [stdout] 7352c5de17ae5a026999cd29106c11b56b98fed7086c1c408284937a275f98ab Nov 18 05:50:01.366 INFO running `"docker" "start" "-a" "7352c5de17ae5a026999cd29106c11b56b98fed7086c1c408284937a275f98ab"` Nov 18 05:50:01.861 INFO [stderr] usermod: no changes Nov 18 05:50:02.223 INFO [stderr] Compiling twox-hash v1.1.1 Nov 18 05:50:03.259 INFO [stderr] Compiling mysql_common v0.5.0 Nov 18 05:50:09.910 INFO [stderr] Compiling msql-srv v0.8.0 (/source) Nov 18 05:50:15.579 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 13.67s Nov 18 05:50:15.599 INFO [stderr] su: No module specific data is present Nov 18 05:50:16.143 INFO running `"docker" "inspect" "7352c5de17ae5a026999cd29106c11b56b98fed7086c1c408284937a275f98ab"` Nov 18 05:50:16.400 INFO running `"docker" "rm" "-f" "7352c5de17ae5a026999cd29106c11b56b98fed7086c1c408284937a275f98ab"` Nov 18 05:50:16.750 INFO [stdout] 7352c5de17ae5a026999cd29106c11b56b98fed7086c1c408284937a275f98ab Nov 18 05:50:16.754 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 18 05:50:17.071 INFO [stdout] 6f365c7cd65bb66b8497750dc11b7888be8ceee81e67c997e900930275639932 Nov 18 05:50:17.079 INFO running `"docker" "start" "-a" "6f365c7cd65bb66b8497750dc11b7888be8ceee81e67c997e900930275639932"` Nov 18 05:50:18.311 INFO [stderr] usermod: no changes Nov 18 05:50:18.621 INFO [stderr] Compiling nix v0.9.0 Nov 18 05:50:18.671 INFO [stderr] Compiling tokio-core v0.1.17 Nov 18 05:50:26.575 INFO [stderr] Compiling mysql_async v0.14.2 Nov 18 05:50:28.583 INFO [stderr] Compiling mysql v12.3.1 Nov 18 05:50:44.924 INFO [stderr] Compiling msql-srv v0.8.0 (/source) Nov 18 05:51:38.587 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 20s Nov 18 05:51:38.602 INFO [stderr] su: No module specific data is present Nov 18 05:51:39.511 INFO running `"docker" "inspect" "6f365c7cd65bb66b8497750dc11b7888be8ceee81e67c997e900930275639932"` Nov 18 05:51:39.895 INFO running `"docker" "rm" "-f" "6f365c7cd65bb66b8497750dc11b7888be8ceee81e67c997e900930275639932"` Nov 18 05:51:40.255 INFO [stdout] 6f365c7cd65bb66b8497750dc11b7888be8ceee81e67c997e900930275639932 Nov 18 05:51:40.257 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 18 05:51:40.807 INFO [stdout] a077cd68acb29fd7734ffaf83c46ef45f94dc6acf6bb06d2bd9ca1de3b7438e2 Nov 18 05:51:40.809 INFO running `"docker" "start" "-a" "a077cd68acb29fd7734ffaf83c46ef45f94dc6acf6bb06d2bd9ca1de3b7438e2"` Nov 18 05:51:42.631 INFO [stderr] usermod: no changes Nov 18 05:51:42.883 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.26s Nov 18 05:51:42.894 INFO [stderr] Running /target/debug/deps/msql_srv-b823ca84ad9a79a8 Nov 18 05:51:42.905 INFO [stdout] Nov 18 05:51:42.905 INFO [stdout] running 103 tests Nov 18 05:51:42.915 INFO [stdout] test commands::tests::it_parses_handshake ... ok Nov 18 05:51:42.916 INFO [stdout] test commands::tests::it_parses_request ... ok Nov 18 05:51:42.919 INFO [stdout] test value::decode::tests::datetime ... ok Nov 18 05:51:42.919 INFO [stdout] test value::decode::tests::dur ... ok Nov 18 05:51:42.927 INFO [stdout] test value::decode::tests::f32_one_double ... ok Nov 18 05:51:42.928 INFO [stdout] test value::decode::tests::f64_one ... ok Nov 18 05:51:42.930 INFO [stdout] test value::decode::tests::i16_one_long ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i16_one_longlong ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i32_max ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i32_one_long ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i32_one_longlong ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i64_max ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i64_one ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i8_max ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i8_one ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i8_one_long ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i8_one_longlong ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i8_one_short ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::string ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i16_max ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::i16_one ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::time ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::u16_max ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::u16_one ... ok Nov 18 05:51:42.931 INFO [stdout] test value::decode::tests::u16_one_long ... ok Nov 18 05:51:42.943 INFO [stdout] test value::decode::tests::u16_one_longlong ... ok Nov 18 05:51:42.943 INFO [stdout] test value::decode::tests::u32_one_long ... ok Nov 18 05:51:42.943 INFO [stdout] test value::decode::tests::u32_max ... ok Nov 18 05:51:42.943 INFO [stdout] test value::decode::tests::bytes ... ok Nov 18 05:51:42.943 INFO [stdout] test packet::tests::test_ping ... ok Nov 18 05:51:42.955 INFO [stdout] test packet::tests::test_one_ping ... ok Nov 18 05:51:42.979 INFO [stdout] test value::decode::tests::u64_max ... ok Nov 18 05:51:42.979 INFO [stdout] test value::decode::tests::u64_one ... ok Nov 18 05:51:43.003 INFO [stdout] test value::decode::tests::u8_one_long ... ok Nov 18 05:51:43.003 INFO [stdout] test value::decode::tests::u8_max ... ok Nov 18 05:51:43.003 INFO [stdout] test value::decode::tests::u32_one_longlong ... ok Nov 18 05:51:43.003 INFO [stdout] test value::decode::tests::u8_one_longlong ... ok Nov 18 05:51:43.003 INFO [stdout] test value::decode::tests::u8_one ... ok Nov 18 05:51:43.003 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one_double ... ok Nov 18 05:51:43.003 INFO [stdout] test value::encode::tests::roundtrip_bin::f64_one ... ok Nov 18 05:51:43.003 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_max ... ok Nov 18 05:51:43.015 INFO [stdout] test value::encode::tests::roundtrip_bin::datetime ... ok Nov 18 05:51:43.015 INFO [stdout] test value::encode::tests::roundtrip_bin::dur ... ok Nov 18 05:51:43.016 INFO [stdout] test value::decode::tests::u8_one_short ... ok Nov 18 05:51:43.016 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_long ... ok Nov 18 05:51:43.017 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one ... ok Nov 18 05:51:43.027 INFO [stdout] test value::encode::tests::roundtrip_bin::bytes ... ok Nov 18 05:51:43.031 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_max ... ok Nov 18 05:51:43.035 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_longlong ... ok Nov 18 05:51:43.035 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_one ... ok Nov 18 05:51:43.035 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one ... ok Nov 18 05:51:43.035 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_long ... ok Nov 18 05:51:43.036 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one ... ok Nov 18 05:51:43.036 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_longlong ... ok Nov 18 05:51:43.036 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_long ... ok Nov 18 05:51:43.036 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_max ... ok Nov 18 05:51:43.037 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_max ... ok Nov 18 05:51:43.037 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_max ... ok Nov 18 05:51:43.037 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_short ... ok Nov 18 05:51:43.037 INFO [stdout] test value::encode::tests::roundtrip_bin::time ... ok Nov 18 05:51:43.038 INFO [stdout] test value::encode::tests::roundtrip_bin::string ... ok Nov 18 05:51:43.038 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_longlong ... ok Nov 18 05:51:43.038 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one ... ok Nov 18 05:51:43.038 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_long ... ok Nov 18 05:51:43.038 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_longlong ... ok Nov 18 05:51:43.051 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_max ... ok Nov 18 05:51:43.051 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_longlong ... ok Nov 18 05:51:43.051 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_long ... ok Nov 18 05:51:43.063 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_max ... ok Nov 18 05:51:43.063 INFO [stdout] test value::encode::tests::roundtrip_bin::opt_some ... ok Nov 18 05:51:43.063 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_one ... ok Nov 18 05:51:43.063 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one ... ok Nov 18 05:51:43.063 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_max ... ok Nov 18 05:51:43.067 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_long ... ok Nov 18 05:51:43.067 INFO [stdout] test value::encode::tests::roundtrip_text::bytes ... ok Nov 18 05:51:43.067 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_longlong ... ok Nov 18 05:51:43.067 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_short ... ok Nov 18 05:51:43.075 INFO [stdout] test value::encode::tests::roundtrip_text::i16_one ... ok Nov 18 05:51:43.075 INFO [stdout] test value::encode::tests::roundtrip_text::i32_max ... ok Nov 18 05:51:43.079 INFO [stdout] test value::encode::tests::roundtrip_text::f32_one ... ok Nov 18 05:51:43.079 INFO [stdout] test value::encode::tests::roundtrip_text::i16_max ... ok Nov 18 05:51:43.079 INFO [stdout] test value::encode::tests::roundtrip_text::f64_one ... ok Nov 18 05:51:43.101 INFO [stdout] test value::encode::tests::roundtrip_text::i32_one ... ok Nov 18 05:51:43.107 INFO [stdout] test value::encode::tests::roundtrip_text::i64_max ... ok Nov 18 05:51:43.107 INFO [stdout] test value::encode::tests::roundtrip_text::i64_one ... ok Nov 18 05:51:43.108 INFO [stdout] test value::encode::tests::roundtrip_text::i8_max ... ok Nov 18 05:51:43.112 INFO [stdout] test value::encode::tests::roundtrip_text::opt_some ... ok Nov 18 05:51:43.121 INFO [stdout] test value::encode::tests::roundtrip_text::i8_one ... ok Nov 18 05:51:43.127 INFO [stdout] test value::encode::tests::roundtrip_text::opt_none ... ok Nov 18 05:51:43.127 INFO [stdout] test value::encode::tests::roundtrip_text::string ... ok Nov 18 05:51:43.127 INFO [stdout] test value::encode::tests::roundtrip_text::u16_max ... ok Nov 18 05:51:43.135 INFO [stdout] test value::encode::tests::roundtrip_text::u32_one ... ok Nov 18 05:51:43.135 INFO [stdout] test value::encode::tests::roundtrip_text::u16_one ... ok Nov 18 05:51:43.135 INFO [stdout] test value::encode::tests::roundtrip_text::u32_max ... ok Nov 18 05:51:43.137 INFO [stdout] test value::encode::tests::roundtrip_text::datetime ... ok Nov 18 05:51:43.147 INFO [stdout] test value::encode::tests::roundtrip_text::u64_max ... ok Nov 18 05:51:43.147 INFO [stdout] test value::encode::tests::roundtrip_text::u64_one ... ok Nov 18 05:51:43.147 INFO [stdout] test value::encode::tests::roundtrip_text::u8_max ... ok Nov 18 05:51:43.147 INFO [stdout] test value::encode::tests::roundtrip_text::u8_one ... ok Nov 18 05:51:43.159 INFO [stdout] test value::encode::tests::roundtrip_text::time ... ok Nov 18 05:51:43.171 INFO [stdout] test value::encode::tests::roundtrip_text::dur ... ok Nov 18 05:51:43.185 INFO [stdout] test packet::tests::test_long_exact ... ok Nov 18 05:51:43.187 INFO [stdout] test packet::tests::test_long_more ... ok Nov 18 05:51:43.187 INFO [stdout] Nov 18 05:51:43.187 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:43.187 INFO [stdout] Nov 18 05:51:43.189 INFO [stderr] Running /target/debug/deps/async-fca527a84bea9cd4 Nov 18 05:51:43.192 INFO [stdout] Nov 18 05:51:43.192 INFO [stdout] running 21 tests Nov 18 05:51:43.311 INFO [stdout] test it_pings ... ok Nov 18 05:51:43.319 INFO [stdout] test it_connects ... ok Nov 18 05:51:43.327 INFO [stdout] test empty_response ... ok Nov 18 05:51:43.363 INFO [stdout] test insert_exec ... ok Nov 18 05:51:43.375 INFO [stdout] test empty_on_drop ... ok Nov 18 05:51:43.412 INFO [stdout] test it_queries ... ok Nov 18 05:51:43.412 INFO [stdout] test it_queries_many_rows ... ok Nov 18 05:51:43.423 INFO [stdout] test it_prepares ... ok Nov 18 05:51:43.423 INFO [stdout] test no_columns ... ok Nov 18 05:51:43.423 INFO [stdout] test no_columns_but_rows ... ok Nov 18 05:51:43.438 INFO [stdout] test it_prepares_many ... ok Nov 18 05:51:43.443 INFO [stdout] test it_queries_nulls ... ok Nov 18 05:51:43.507 INFO [stdout] test prepared_no_cols_but_rows ... ok Nov 18 05:51:43.511 INFO [stdout] test prepared_empty ... ok Nov 18 05:51:43.516 INFO [stdout] test no_rows ... ok Nov 18 05:51:43.547 INFO [stdout] test prepared_no_cols ... ok Nov 18 05:51:43.572 INFO [stdout] test prepared_no_params ... ok Nov 18 05:51:43.576 INFO [stdout] test prepared_no_rows ... ok Nov 18 05:51:43.580 INFO [stdout] test prepared_nulls ... ok Nov 18 05:51:43.659 INFO [stdout] test send_long ... ok Nov 18 05:51:43.960 INFO [stdout] test error_response ... ok Nov 18 05:51:43.960 INFO [stdout] Nov 18 05:51:43.960 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:43.961 INFO [stdout] Nov 18 05:51:43.967 INFO [stderr] Running /target/debug/deps/main-82e2e1c513a55741 Nov 18 05:51:43.971 INFO [stdout] Nov 18 05:51:43.971 INFO [stdout] running 22 tests Nov 18 05:51:44.067 INFO [stdout] test empty_response ... ok Nov 18 05:51:44.079 INFO [stdout] test it_connects ... ok Nov 18 05:51:44.087 INFO [stdout] test error_response ... ok Nov 18 05:51:44.087 INFO [stdout] test it_pings ... ok Nov 18 05:51:44.127 INFO [stdout] test insert_exec ... ok Nov 18 05:51:44.127 INFO [stdout] test empty_on_drop ... ok Nov 18 05:51:44.167 INFO [stdout] test it_queries_many_rows ... ok Nov 18 05:51:44.167 INFO [stdout] test it_queries ... ok Nov 18 05:51:44.167 INFO [stdout] test it_prepares_many ... ok Nov 18 05:51:44.173 INFO [stdout] test no_columns_but_rows ... ok Nov 18 05:51:44.179 INFO [stdout] test no_columns ... ok Nov 18 05:51:44.179 INFO [stdout] test it_prepares ... ok Nov 18 05:51:44.179 INFO [stdout] test it_queries_nulls ... ok Nov 18 05:51:44.187 INFO [stdout] test multi_result ... ok Nov 18 05:51:44.223 INFO [stdout] test prepared_no_cols ... ok Nov 18 05:51:44.223 INFO [stdout] test prepared_no_cols_but_rows ... ok Nov 18 05:51:44.275 INFO [stdout] test no_rows ... ok Nov 18 05:51:44.275 INFO [stdout] test prepared_empty ... ok Nov 18 05:51:44.311 INFO [stdout] test prepared_no_params ... ok Nov 18 05:51:44.311 INFO [stdout] test prepared_no_rows ... ok Nov 18 05:51:44.319 INFO [stdout] test prepared_nulls ... ok Nov 18 05:51:44.320 INFO [stdout] test send_long ... ok Nov 18 05:51:44.320 INFO [stdout] Nov 18 05:51:44.320 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:44.320 INFO [stdout] Nov 18 05:51:44.322 INFO [stderr] Doc-tests msql-srv Nov 18 05:51:48.475 INFO [stdout] Nov 18 05:51:48.475 INFO [stdout] running 1 test Nov 18 05:51:56.806 INFO [stdout] test src/lib.rs - (line 15) ... ok Nov 18 05:51:56.806 INFO [stdout] Nov 18 05:51:56.806 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:56.806 INFO [stdout] Nov 18 05:51:56.831 INFO [stderr] su: No module specific data is present Nov 18 05:51:57.575 INFO running `"docker" "inspect" "a077cd68acb29fd7734ffaf83c46ef45f94dc6acf6bb06d2bd9ca1de3b7438e2"` Nov 18 05:51:57.857 INFO running `"docker" "rm" "-f" "a077cd68acb29fd7734ffaf83c46ef45f94dc6acf6bb06d2bd9ca1de3b7438e2"` Nov 18 05:51:58.207 INFO [stdout] a077cd68acb29fd7734ffaf83c46ef45f94dc6acf6bb06d2bd9ca1de3b7438e2