Nov 18 05:50:01.130 INFO testing msql-srv-0.8.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982 Nov 18 05:50:01.131 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 18 05:50:01.314 INFO [stdout] c09655a7b01e29228eaa3d5635bfc47c5cfcf808812d8dc32f3f1e9483bb4209 Nov 18 05:50:01.314 INFO running `"docker" "start" "-a" "c09655a7b01e29228eaa3d5635bfc47c5cfcf808812d8dc32f3f1e9483bb4209"` Nov 18 05:50:01.861 INFO [stderr] usermod: no changes Nov 18 05:50:02.187 INFO [stderr] Compiling mysql_common v0.5.0 Nov 18 05:50:09.143 INFO [stderr] Compiling msql-srv v0.8.0 (/source) Nov 18 05:50:14.718 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 12.81s Nov 18 05:50:14.743 INFO [stderr] su: No module specific data is present Nov 18 05:50:15.787 INFO running `"docker" "inspect" "c09655a7b01e29228eaa3d5635bfc47c5cfcf808812d8dc32f3f1e9483bb4209"` Nov 18 05:50:16.109 INFO running `"docker" "rm" "-f" "c09655a7b01e29228eaa3d5635bfc47c5cfcf808812d8dc32f3f1e9483bb4209"` Nov 18 05:50:16.524 INFO [stdout] c09655a7b01e29228eaa3d5635bfc47c5cfcf808812d8dc32f3f1e9483bb4209 Nov 18 05:50:16.538 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 18 05:50:16.927 INFO [stdout] b988097a3f2e0c4c351ea20e137cec6a2131e8f1edd04f5616f332782ae804dc Nov 18 05:50:16.930 INFO running `"docker" "start" "-a" "b988097a3f2e0c4c351ea20e137cec6a2131e8f1edd04f5616f332782ae804dc"` Nov 18 05:50:18.214 INFO [stderr] usermod: no changes Nov 18 05:50:18.567 INFO [stderr] Compiling nix v0.9.0 Nov 18 05:50:18.575 INFO [stderr] Compiling mysql_async v0.14.2 Nov 18 05:50:30.099 INFO [stderr] Compiling mysql v12.3.1 Nov 18 05:50:42.045 INFO [stderr] Compiling msql-srv v0.8.0 (/source) Nov 18 05:51:35.892 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 17s Nov 18 05:51:35.915 INFO [stderr] su: No module specific data is present Nov 18 05:51:37.635 INFO running `"docker" "inspect" "b988097a3f2e0c4c351ea20e137cec6a2131e8f1edd04f5616f332782ae804dc"` Nov 18 05:51:38.020 INFO running `"docker" "rm" "-f" "b988097a3f2e0c4c351ea20e137cec6a2131e8f1edd04f5616f332782ae804dc"` Nov 18 05:51:38.395 INFO [stdout] b988097a3f2e0c4c351ea20e137cec6a2131e8f1edd04f5616f332782ae804dc Nov 18 05:51:38.398 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 18 05:51:39.108 INFO [stdout] f66ffb0a151ae043d19212e24ea0c8c0d64aa1ed3701bbcc0657661417e242a6 Nov 18 05:51:39.111 INFO running `"docker" "start" "-a" "f66ffb0a151ae043d19212e24ea0c8c0d64aa1ed3701bbcc0657661417e242a6"` Nov 18 05:51:40.683 INFO [stderr] usermod: no changes Nov 18 05:51:40.987 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.26s Nov 18 05:51:41.003 INFO [stderr] Running /target/debug/deps/msql_srv-4f4833139cfcb61f Nov 18 05:51:41.015 INFO [stdout] Nov 18 05:51:41.016 INFO [stdout] running 103 tests Nov 18 05:51:41.016 INFO [stdout] test commands::tests::it_parses_request ... ok Nov 18 05:51:41.016 INFO [stdout] test commands::tests::it_parses_handshake ... ok Nov 18 05:51:41.017 INFO [stdout] test value::decode::tests::bytes ... ok Nov 18 05:51:41.017 INFO [stdout] test value::decode::tests::f64_one ... ok Nov 18 05:51:41.017 INFO [stdout] test value::decode::tests::datetime ... ok Nov 18 05:51:41.017 INFO [stdout] test packet::tests::test_one_ping ... ok Nov 18 05:51:41.023 INFO [stdout] test value::decode::tests::f32_one_double ... ok Nov 18 05:51:41.023 INFO [stdout] test packet::tests::test_ping ... ok Nov 18 05:51:41.023 INFO [stdout] test value::decode::tests::i16_max ... ok Nov 18 05:51:41.024 INFO [stdout] test value::decode::tests::dur ... ok Nov 18 05:51:41.043 INFO [stdout] test value::decode::tests::i32_one_longlong ... ok Nov 18 05:51:41.043 INFO [stdout] test value::decode::tests::i64_max ... ok Nov 18 05:51:41.043 INFO [stdout] test value::decode::tests::i32_max ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i16_one ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i16_one_longlong ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i64_one ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i32_one_long ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i16_one_long ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i8_one ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i8_one_long ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i8_one_longlong ... ok Nov 18 05:51:41.047 INFO [stdout] test value::decode::tests::i8_max ... ok Nov 18 05:51:41.055 INFO [stdout] test value::decode::tests::i8_one_short ... ok Nov 18 05:51:41.055 INFO [stdout] test value::decode::tests::u16_one ... ok Nov 18 05:51:41.055 INFO [stdout] test value::decode::tests::string ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::u16_one_long ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::u32_one_long ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::u32_max ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::u16_one_longlong ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::time ... ok Nov 18 05:51:41.071 INFO [stdout] test value::decode::tests::u16_max ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u32_one_longlong ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u64_max ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u64_one ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u8_max ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u8_one_long ... ok Nov 18 05:51:41.094 INFO [stdout] test value::decode::tests::u8_one ... ok Nov 18 05:51:41.107 INFO [stdout] test value::decode::tests::u8_one_short ... ok Nov 18 05:51:41.107 INFO [stdout] test value::encode::tests::roundtrip_bin::datetime ... ok Nov 18 05:51:41.107 INFO [stdout] test value::decode::tests::u8_one_longlong ... ok Nov 18 05:51:41.107 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one ... ok Nov 18 05:51:41.108 INFO [stdout] test value::encode::tests::roundtrip_bin::dur ... ok Nov 18 05:51:41.115 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one_double ... ok Nov 18 05:51:41.115 INFO [stdout] test value::encode::tests::roundtrip_bin::f64_one ... ok Nov 18 05:51:41.115 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_max ... ok Nov 18 05:51:41.123 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one ... ok Nov 18 05:51:41.123 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_long ... ok Nov 18 05:51:41.123 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_max ... ok Nov 18 05:51:41.124 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_longlong ... ok Nov 18 05:51:41.124 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_longlong ... ok Nov 18 05:51:41.124 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_max ... ok Nov 18 05:51:41.124 INFO [stdout] test value::encode::tests::roundtrip_bin::bytes ... ok Nov 18 05:51:41.131 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_one ... ok Nov 18 05:51:41.131 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_max ... ok Nov 18 05:51:41.132 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_long ... ok Nov 18 05:51:41.132 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one ... ok Nov 18 05:51:41.132 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_longlong ... ok Nov 18 05:51:41.132 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_long ... ok Nov 18 05:51:41.135 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_short ... ok Nov 18 05:51:41.143 INFO [stdout] test value::encode::tests::roundtrip_bin::string ... ok Nov 18 05:51:41.143 INFO [stdout] test value::encode::tests::roundtrip_bin::opt_some ... ok Nov 18 05:51:41.144 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_max ... ok Nov 18 05:51:41.144 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one ... ok Nov 18 05:51:41.144 INFO [stdout] test value::encode::tests::roundtrip_bin::time ... ok Nov 18 05:51:41.155 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_max ... ok Nov 18 05:51:41.163 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_longlong ... ok Nov 18 05:51:41.163 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_max ... ok Nov 18 05:51:41.163 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_long ... ok Nov 18 05:51:41.164 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_longlong ... ok Nov 18 05:51:41.164 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_long ... ok Nov 18 05:51:41.164 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one ... ok Nov 18 05:51:41.183 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_one ... ok Nov 18 05:51:41.183 INFO [stdout] test value::encode::tests::roundtrip_text::bytes ... ok Nov 18 05:51:41.184 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_max ... ok Nov 18 05:51:41.184 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_long ... ok Nov 18 05:51:41.184 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_longlong ... ok Nov 18 05:51:41.184 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_short ... ok Nov 18 05:51:41.219 INFO [stdout] test value::encode::tests::roundtrip_text::f64_one ... ok Nov 18 05:51:41.219 INFO [stdout] test value::encode::tests::roundtrip_text::i16_max ... ok Nov 18 05:51:41.219 INFO [stdout] test value::encode::tests::roundtrip_text::i16_one ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::f32_one ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::i32_one ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::i64_max ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::i64_one ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::i32_max ... ok Nov 18 05:51:41.220 INFO [stdout] test value::encode::tests::roundtrip_text::i8_max ... ok Nov 18 05:51:41.221 INFO [stdout] test value::encode::tests::roundtrip_text::i8_one ... ok Nov 18 05:51:41.221 INFO [stdout] test value::encode::tests::roundtrip_text::opt_none ... ok Nov 18 05:51:41.227 INFO [stdout] test value::encode::tests::roundtrip_text::opt_some ... ok Nov 18 05:51:41.227 INFO [stdout] test value::encode::tests::roundtrip_text::u16_one ... ok Nov 18 05:51:41.227 INFO [stdout] test value::encode::tests::roundtrip_text::datetime ... ok Nov 18 05:51:41.235 INFO [stdout] test value::encode::tests::roundtrip_text::u16_max ... ok Nov 18 05:51:41.235 INFO [stdout] test value::encode::tests::roundtrip_text::u32_one ... ok Nov 18 05:51:41.236 INFO [stdout] test value::encode::tests::roundtrip_text::u64_max ... ok Nov 18 05:51:41.247 INFO [stdout] test value::encode::tests::roundtrip_text::string ... ok Nov 18 05:51:41.248 INFO [stdout] test value::encode::tests::roundtrip_text::u64_one ... ok Nov 18 05:51:41.248 INFO [stdout] test value::encode::tests::roundtrip_text::u32_max ... ok Nov 18 05:51:41.248 INFO [stdout] test value::encode::tests::roundtrip_text::u8_max ... ok Nov 18 05:51:41.249 INFO [stdout] test value::encode::tests::roundtrip_text::dur ... ok Nov 18 05:51:41.249 INFO [stdout] test value::encode::tests::roundtrip_text::u8_one ... ok Nov 18 05:51:41.291 INFO [stdout] test value::encode::tests::roundtrip_text::time ... ok Nov 18 05:51:41.299 INFO [stdout] test packet::tests::test_long_exact ... ok Nov 18 05:51:41.323 INFO [stdout] test packet::tests::test_long_more ... ok Nov 18 05:51:41.323 INFO [stdout] Nov 18 05:51:41.323 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:41.323 INFO [stdout] Nov 18 05:51:41.331 INFO [stderr] Running /target/debug/deps/async-038bf74ea631f99d Nov 18 05:51:41.331 INFO [stdout] Nov 18 05:51:41.331 INFO [stdout] running 21 tests Nov 18 05:51:41.447 INFO [stdout] test empty_response ... ok Nov 18 05:51:41.447 INFO [stdout] test it_connects ... ok Nov 18 05:51:41.472 INFO [stdout] test it_pings ... ok Nov 18 05:51:41.476 INFO [stdout] test empty_on_drop ... ok Nov 18 05:51:41.505 INFO [stdout] test insert_exec ... ok Nov 18 05:51:41.544 INFO [stdout] test it_prepares ... ok Nov 18 05:51:41.547 INFO [stdout] test it_prepares_many ... ok Nov 18 05:51:41.547 INFO [stdout] test no_columns ... ok Nov 18 05:51:41.547 INFO [stdout] test it_queries_many_rows ... ok Nov 18 05:51:41.555 INFO [stdout] test it_queries ... ok Nov 18 05:51:41.563 INFO [stdout] test no_columns_but_rows ... ok Nov 18 05:51:41.612 INFO [stdout] test prepared_no_cols ... ok Nov 18 05:51:41.612 INFO [stdout] test it_queries_nulls ... ok Nov 18 05:51:41.619 INFO [stdout] test prepared_no_cols_but_rows ... ok Nov 18 05:51:41.651 INFO [stdout] test no_rows ... ok Nov 18 05:51:41.666 INFO [stdout] test prepared_empty ... ok Nov 18 05:51:41.695 INFO [stdout] test prepared_no_params ... ok Nov 18 05:51:41.715 INFO [stdout] test prepared_no_rows ... ok Nov 18 05:51:41.763 INFO [stdout] test send_long ... ok Nov 18 05:51:41.775 INFO [stdout] test prepared_nulls ... ok Nov 18 05:51:42.011 INFO [stdout] test error_response ... ok Nov 18 05:51:42.012 INFO [stdout] Nov 18 05:51:42.012 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:42.012 INFO [stdout] Nov 18 05:51:42.017 INFO [stderr] Running /target/debug/deps/main-c902fb3c23416524 Nov 18 05:51:42.039 INFO [stdout] Nov 18 05:51:42.040 INFO [stdout] running 22 tests Nov 18 05:51:42.099 INFO [stdout] test error_response ... ok Nov 18 05:51:42.103 INFO [stdout] test empty_response ... ok Nov 18 05:51:42.104 INFO [stdout] test it_connects ... ok Nov 18 05:51:42.104 INFO [stdout] test it_pings ... ok Nov 18 05:51:42.142 INFO [stdout] test insert_exec ... ok Nov 18 05:51:42.142 INFO [stdout] test empty_on_drop ... ok Nov 18 05:51:42.187 INFO [stdout] test it_queries ... ok Nov 18 05:51:42.188 INFO [stdout] test it_prepares ... ok Nov 18 05:51:42.188 INFO [stdout] test it_prepares_many ... ok Nov 18 05:51:42.196 INFO [stdout] test it_queries_many_rows ... ok Nov 18 05:51:42.196 INFO [stdout] test no_columns ... ok Nov 18 05:51:42.196 INFO [stdout] test no_columns_but_rows ... ok Nov 18 05:51:42.207 INFO [stdout] test it_queries_nulls ... ok Nov 18 05:51:42.208 INFO [stdout] test multi_result ... ok Nov 18 05:51:42.235 INFO [stdout] test prepared_no_cols ... ok Nov 18 05:51:42.247 INFO [stdout] test prepared_no_cols_but_rows ... ok Nov 18 05:51:42.279 INFO [stdout] test prepared_empty ... ok Nov 18 05:51:42.279 INFO [stdout] test no_rows ... ok Nov 18 05:51:42.338 INFO [stdout] test prepared_no_params ... ok Nov 18 05:51:42.338 INFO [stdout] test prepared_no_rows ... ok Nov 18 05:51:42.339 INFO [stdout] test send_long ... ok Nov 18 05:51:42.339 INFO [stdout] test prepared_nulls ... ok Nov 18 05:51:42.339 INFO [stderr] Doc-tests msql-srv Nov 18 05:51:42.340 INFO [stdout] Nov 18 05:51:42.340 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:42.340 INFO [stdout] Nov 18 05:51:46.514 INFO [stdout] Nov 18 05:51:46.514 INFO [stdout] running 1 test Nov 18 05:51:54.323 INFO [stdout] test src/lib.rs - (line 15) ... ok Nov 18 05:51:54.323 INFO [stdout] Nov 18 05:51:54.323 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Nov 18 05:51:54.324 INFO [stdout] Nov 18 05:51:54.351 INFO [stderr] su: No module specific data is present Nov 18 05:51:55.181 INFO running `"docker" "inspect" "f66ffb0a151ae043d19212e24ea0c8c0d64aa1ed3701bbcc0657661417e242a6"` Nov 18 05:51:55.479 INFO running `"docker" "rm" "-f" "f66ffb0a151ae043d19212e24ea0c8c0d64aa1ed3701bbcc0657661417e242a6"` Nov 18 05:51:55.819 INFO [stdout] f66ffb0a151ae043d19212e24ea0c8c0d64aa1ed3701bbcc0657661417e242a6