Dec 15 04:13:28.573 INFO testing msql-srv-0.8.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 04:13:28.574 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 04:13:29.004 INFO [stdout] a962198edd6de767418e19b364d078e82291b92e328a50d69119a3df4aa2611d Dec 15 04:13:29.006 INFO running `"docker" "start" "-a" "a962198edd6de767418e19b364d078e82291b92e328a50d69119a3df4aa2611d"` Dec 15 04:13:30.287 INFO [stderr] usermod: no changes Dec 15 04:13:30.503 INFO [stderr] Compiling mysql_common v0.5.0 Dec 15 04:13:37.799 INFO [stderr] Compiling msql-srv v0.8.1 (/source) Dec 15 04:13:43.498 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 13.18s Dec 15 04:13:43.515 INFO [stderr] su: No module specific data is present Dec 15 04:13:44.651 INFO running `"docker" "inspect" "a962198edd6de767418e19b364d078e82291b92e328a50d69119a3df4aa2611d"` Dec 15 04:13:44.989 INFO running `"docker" "rm" "-f" "a962198edd6de767418e19b364d078e82291b92e328a50d69119a3df4aa2611d"` Dec 15 04:13:45.263 INFO [stdout] a962198edd6de767418e19b364d078e82291b92e328a50d69119a3df4aa2611d Dec 15 04:13:45.271 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 04:13:45.743 INFO [stdout] 082f0f4dae71937b689c802fe13efe8373510e7ce6361664b7f211cd038fbc46 Dec 15 04:13:45.745 INFO running `"docker" "start" "-a" "082f0f4dae71937b689c802fe13efe8373510e7ce6361664b7f211cd038fbc46"` Dec 15 04:13:47.204 INFO [stderr] usermod: no changes Dec 15 04:13:47.491 INFO [stderr] Compiling tokio-reactor v0.1.7 Dec 15 04:13:47.499 INFO [stderr] Compiling mysql v12.3.1 Dec 15 04:13:53.607 INFO [stderr] Compiling tokio-tcp v0.1.2 Dec 15 04:13:53.607 INFO [stderr] Compiling tokio-udp v0.1.3 Dec 15 04:13:53.607 INFO [stderr] Compiling tokio-uds v0.2.4 Dec 15 04:14:04.876 INFO [stderr] Compiling tokio v0.1.13 Dec 15 04:14:09.875 INFO [stderr] Compiling tokio-core v0.1.17 Dec 15 04:14:16.094 INFO [stderr] Compiling mysql_async v0.14.2 Dec 15 04:14:29.957 INFO [stderr] Compiling msql-srv v0.8.1 (/source) Dec 15 04:15:10.756 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 23s Dec 15 04:15:10.773 INFO [stderr] su: No module specific data is present Dec 15 04:15:11.772 INFO running `"docker" "inspect" "082f0f4dae71937b689c802fe13efe8373510e7ce6361664b7f211cd038fbc46"` Dec 15 04:15:12.185 INFO running `"docker" "rm" "-f" "082f0f4dae71937b689c802fe13efe8373510e7ce6361664b7f211cd038fbc46"` Dec 15 04:15:12.425 INFO [stdout] 082f0f4dae71937b689c802fe13efe8373510e7ce6361664b7f211cd038fbc46 Dec 15 04:15:12.429 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 04:15:12.921 INFO [stdout] 2552e171deec6825bd507f1779fe699d77e48e45e4850baea1e06f9071e83eeb Dec 15 04:15:12.923 INFO running `"docker" "start" "-a" "2552e171deec6825bd507f1779fe699d77e48e45e4850baea1e06f9071e83eeb"` Dec 15 04:15:14.458 INFO [stderr] usermod: no changes Dec 15 04:15:14.785 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.29s Dec 15 04:15:14.807 INFO [stderr] Running /target/debug/deps/msql_srv-56e9b8541834aaf1 Dec 15 04:15:14.813 INFO [stdout] Dec 15 04:15:14.813 INFO [stdout] running 103 tests Dec 15 04:15:14.844 INFO [stdout] test commands::tests::it_parses_request ... ok Dec 15 04:15:14.845 INFO [stdout] test commands::tests::it_parses_handshake ... ok Dec 15 04:15:14.845 INFO [stdout] test packet::tests::test_ping ... ok Dec 15 04:15:14.846 INFO [stdout] test packet::tests::test_one_ping ... ok Dec 15 04:15:14.855 INFO [stdout] test value::decode::tests::f64_one ... ok Dec 15 04:15:14.856 INFO [stdout] test value::decode::tests::bytes ... ok Dec 15 04:15:14.856 INFO [stdout] test value::decode::tests::datetime ... ok Dec 15 04:15:14.857 INFO [stdout] test value::decode::tests::f32_one_double ... ok Dec 15 04:15:14.863 INFO [stdout] test value::decode::tests::dur ... ok Dec 15 04:15:14.864 INFO [stdout] test value::decode::tests::i16_one_longlong ... ok Dec 15 04:15:14.867 INFO [stdout] test value::decode::tests::i16_one_long ... ok Dec 15 04:15:14.871 INFO [stdout] test value::decode::tests::i32_one_long ... ok Dec 15 04:15:14.875 INFO [stdout] test value::decode::tests::i32_one_longlong ... ok Dec 15 04:15:14.877 INFO [stdout] test value::decode::tests::i32_max ... ok Dec 15 04:15:14.877 INFO [stdout] test value::decode::tests::i8_max ... ok Dec 15 04:15:14.879 INFO [stdout] test value::decode::tests::i16_one ... ok Dec 15 04:15:14.879 INFO [stdout] test value::decode::tests::i16_max ... ok Dec 15 04:15:14.879 INFO [stdout] test value::decode::tests::i8_one ... ok Dec 15 04:15:14.880 INFO [stdout] test value::decode::tests::i8_one_long ... ok Dec 15 04:15:14.881 INFO [stdout] test value::decode::tests::time ... ok Dec 15 04:15:14.887 INFO [stdout] test value::decode::tests::string ... ok Dec 15 04:15:14.903 INFO [stdout] test value::decode::tests::i8_one_short ... ok Dec 15 04:15:14.904 INFO [stdout] test value::decode::tests::i8_one_longlong ... ok Dec 15 04:15:14.904 INFO [stdout] test value::decode::tests::u16_max ... ok Dec 15 04:15:14.904 INFO [stdout] test value::decode::tests::u32_max ... ok Dec 15 04:15:14.904 INFO [stdout] test value::decode::tests::u16_one ... ok Dec 15 04:15:14.904 INFO [stdout] test value::decode::tests::i64_one ... ok Dec 15 04:15:14.910 INFO [stdout] test value::decode::tests::u16_one_longlong ... ok Dec 15 04:15:14.910 INFO [stdout] test value::decode::tests::u64_max ... ok Dec 15 04:15:14.910 INFO [stdout] test value::decode::tests::u32_one_longlong ... ok Dec 15 04:15:14.911 INFO [stdout] test value::decode::tests::i64_max ... ok Dec 15 04:15:14.911 INFO [stdout] test value::decode::tests::u8_max ... ok Dec 15 04:15:14.911 INFO [stdout] test value::decode::tests::u64_one ... ok Dec 15 04:15:14.923 INFO [stdout] test value::decode::tests::u32_one_long ... ok Dec 15 04:15:14.923 INFO [stdout] test value::decode::tests::u16_one_long ... ok Dec 15 04:15:14.939 INFO [stdout] test value::decode::tests::u8_one_short ... ok Dec 15 04:15:14.939 INFO [stdout] test value::decode::tests::u8_one_longlong ... ok Dec 15 04:15:14.939 INFO [stdout] test value::decode::tests::u8_one ... ok Dec 15 04:15:14.939 INFO [stdout] test value::decode::tests::u8_one_long ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::datetime ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::bytes ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::dur ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::f32_one_double ... ok Dec 15 04:15:14.943 INFO [stdout] test value::encode::tests::roundtrip_bin::f64_one ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_max ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_max ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_longlong ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_long ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one_long ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_max ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i32_one_longlong ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_max ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i64_one ... ok Dec 15 04:15:14.951 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one ... ok Dec 15 04:15:14.952 INFO [stdout] test value::encode::tests::roundtrip_bin::opt_some ... ok Dec 15 04:15:14.952 INFO [stdout] test value::encode::tests::roundtrip_bin::string ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::time ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_max ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_long ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one_longlong ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_max ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_long ... ok Dec 15 04:15:14.960 INFO [stdout] test value::encode::tests::roundtrip_bin::u32_one_longlong ... ok Dec 15 04:15:14.971 INFO [stdout] test value::encode::tests::roundtrip_bin::i16_one ... ok Dec 15 04:15:14.971 INFO [stdout] test value::encode::tests::roundtrip_bin::u16_one ... ok Dec 15 04:15:14.971 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_longlong ... ok Dec 15 04:15:14.971 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_long ... ok Dec 15 04:15:14.971 INFO [stdout] test value::encode::tests::roundtrip_bin::i8_one_short ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_one ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_max ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_long ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_longlong ... ok Dec 15 04:15:14.979 INFO [stdout] test value::encode::tests::roundtrip_bin::u64_max ... ok Dec 15 04:15:14.987 INFO [stdout] test value::encode::tests::roundtrip_text::f64_one ... ok Dec 15 04:15:14.987 INFO [stdout] test value::encode::tests::roundtrip_text::i16_max ... ok Dec 15 04:15:15.003 INFO [stdout] test value::encode::tests::roundtrip_text::bytes ... ok Dec 15 04:15:15.003 INFO [stdout] test value::encode::tests::roundtrip_bin::u8_one_short ... ok Dec 15 04:15:15.003 INFO [stdout] test value::encode::tests::roundtrip_text::i32_max ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::i32_one ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::i64_max ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::i8_max ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::i64_one ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::i8_one ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::opt_none ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::opt_some ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::string ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::u16_max ... ok Dec 15 04:15:15.007 INFO [stdout] test value::encode::tests::roundtrip_text::u16_one ... ok Dec 15 04:15:15.010 INFO [stdout] test value::encode::tests::roundtrip_text::u32_max ... ok Dec 15 04:15:15.011 INFO [stdout] test value::encode::tests::roundtrip_text::u32_one ... ok Dec 15 04:15:15.011 INFO [stdout] test value::encode::tests::roundtrip_text::u64_max ... ok Dec 15 04:15:15.019 INFO [stdout] test value::encode::tests::roundtrip_text::f32_one ... ok Dec 15 04:15:15.019 INFO [stdout] test value::encode::tests::roundtrip_text::i16_one ... ok Dec 15 04:15:15.019 INFO [stdout] test value::encode::tests::roundtrip_text::u64_one ... ok Dec 15 04:15:15.031 INFO [stdout] test value::encode::tests::roundtrip_text::u8_max ... ok Dec 15 04:15:15.031 INFO [stdout] test value::encode::tests::roundtrip_text::u8_one ... ok Dec 15 04:15:15.033 INFO [stdout] test value::encode::tests::roundtrip_text::datetime ... ok Dec 15 04:15:15.056 INFO [stdout] test value::encode::tests::roundtrip_text::time ... ok Dec 15 04:15:15.070 INFO [stdout] test packet::tests::test_long_more ... ok Dec 15 04:15:15.071 INFO [stdout] test value::encode::tests::roundtrip_text::dur ... ok Dec 15 04:15:15.071 INFO [stdout] test packet::tests::test_long_exact ... ok Dec 15 04:15:15.071 INFO [stdout] Dec 15 04:15:15.071 INFO [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:15:15.071 INFO [stdout] Dec 15 04:15:15.082 INFO [stderr] Running /target/debug/deps/async-8452fa3fcded6478 Dec 15 04:15:15.089 INFO [stdout] Dec 15 04:15:15.089 INFO [stdout] running 21 tests Dec 15 04:15:15.215 INFO [stdout] test it_connects ... ok Dec 15 04:15:15.242 INFO [stdout] test empty_response ... ok Dec 15 04:15:15.258 INFO [stdout] test it_pings ... ok Dec 15 04:15:15.305 INFO [stdout] test empty_on_drop ... ok Dec 15 04:15:15.306 INFO [stdout] test insert_exec ... ok Dec 15 04:15:15.316 INFO [stdout] test it_queries ... ok Dec 15 04:15:15.356 INFO [stdout] test it_prepares_many ... ok Dec 15 04:15:15.357 INFO [stdout] test it_prepares ... ok Dec 15 04:15:15.386 INFO [stdout] test no_columns ... ok Dec 15 04:15:15.387 INFO [stdout] test no_columns_but_rows ... ok Dec 15 04:15:15.388 INFO [stdout] test it_queries_nulls ... ok Dec 15 04:15:15.427 INFO [stdout] test it_queries_many_rows ... ok Dec 15 04:15:15.457 INFO [stdout] test no_rows ... ok Dec 15 04:15:15.471 INFO [stdout] test prepared_no_cols_but_rows ... ok Dec 15 04:15:15.471 INFO [stdout] test prepared_empty ... ok Dec 15 04:15:15.472 INFO [stdout] test prepared_no_cols ... ok Dec 15 04:15:15.548 INFO [stdout] test prepared_no_rows ... ok Dec 15 04:15:15.552 INFO [stdout] test prepared_no_params ... ok Dec 15 04:15:15.607 INFO [stdout] test prepared_nulls ... ok Dec 15 04:15:15.632 INFO [stdout] test send_long ... ok Dec 15 04:15:15.699 INFO [stdout] test error_response ... ok Dec 15 04:15:15.700 INFO [stdout] Dec 15 04:15:15.700 INFO [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:15:15.700 INFO [stdout] Dec 15 04:15:15.706 INFO [stderr] Running /target/debug/deps/main-f6bfaf107e630622 Dec 15 04:15:15.709 INFO [stdout] Dec 15 04:15:15.709 INFO [stdout] running 22 tests Dec 15 04:15:15.822 INFO [stdout] test empty_response ... ok Dec 15 04:15:15.822 INFO [stdout] test error_response ... ok Dec 15 04:15:15.822 INFO [stdout] test it_connects ... ok Dec 15 04:15:15.824 INFO [stdout] test it_pings ... ok Dec 15 04:15:15.864 INFO [stdout] test empty_on_drop ... ok Dec 15 04:15:15.865 INFO [stdout] test insert_exec ... ok Dec 15 04:15:15.912 INFO [stdout] test it_prepares ... ok Dec 15 04:15:15.912 INFO [stdout] test it_prepares_many ... ok Dec 15 04:15:15.913 INFO [stdout] test it_queries_many_rows ... ok Dec 15 04:15:15.914 INFO [stdout] test it_queries_nulls ... ok Dec 15 04:15:15.914 INFO [stdout] test multi_result ... ok Dec 15 04:15:15.923 INFO [stdout] test no_columns ... ok Dec 15 04:15:15.923 INFO [stdout] test it_queries ... ok Dec 15 04:15:15.931 INFO [stdout] test no_columns_but_rows ... ok Dec 15 04:15:15.963 INFO [stdout] test prepared_no_cols_but_rows ... ok Dec 15 04:15:15.972 INFO [stdout] test prepared_no_cols ... ok Dec 15 04:15:16.007 INFO [stdout] test no_rows ... ok Dec 15 04:15:16.012 INFO [stdout] test prepared_empty ... ok Dec 15 04:15:16.055 INFO [stdout] test prepared_no_params ... ok Dec 15 04:15:16.059 INFO [stdout] test prepared_no_rows ... ok Dec 15 04:15:16.071 INFO [stdout] test prepared_nulls ... ok Dec 15 04:15:16.088 INFO [stdout] test send_long ... ok Dec 15 04:15:16.088 INFO [stdout] Dec 15 04:15:16.088 INFO [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:15:16.088 INFO [stdout] Dec 15 04:15:16.088 INFO [stderr] Doc-tests msql-srv Dec 15 04:15:21.298 INFO [stdout] Dec 15 04:15:21.299 INFO [stdout] running 1 test Dec 15 04:15:31.805 INFO [stdout] test src/lib.rs - (line 15) ... ok Dec 15 04:15:31.805 INFO [stdout] Dec 15 04:15:31.806 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 04:15:31.806 INFO [stdout] Dec 15 04:15:31.830 INFO [stderr] su: No module specific data is present Dec 15 04:15:33.187 INFO running `"docker" "inspect" "2552e171deec6825bd507f1779fe699d77e48e45e4850baea1e06f9071e83eeb"` Dec 15 04:15:33.499 INFO running `"docker" "rm" "-f" "2552e171deec6825bd507f1779fe699d77e48e45e4850baea1e06f9071e83eeb"` Dec 15 04:15:33.898 INFO [stdout] 2552e171deec6825bd507f1779fe699d77e48e45e4850baea1e06f9071e83eeb