Test test_shutdown_mysql_server timed out

Open evenyag opened this issue 3 years ago • 0 comments

What type of bug is this?

Other

What subsystems are affected?

Other

What happened?

The test timed out in this job

What operating system did you use?

Ubuntu

Relevant log output and stack trace

running 1 test
test mysql::mysql_server_test::test_shutdown_mysql_server ... 2022-12-08T09:48:12.450915Z  INFO common_telemetry::logging: logs dir = __unittest_logs
2022-12-08T09:48:12.454058Z  INFO servers::server: MySQL server started at 127.0.0.1:34141
2022-12-08T09:48:12.454972Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49598
2022-12-08T09:48:12.455277Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49600
2022-12-08T09:48:12.461097Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.461093Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.482529Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 21293
2022-12-08T09:48:12.482532Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 21288
2022-12-08T09:48:12.495312Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49620
2022-12-08T09:48:12.495539Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49636
2022-12-08T09:48:12.496676Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.496814Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.497986Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1212
2022-12-08T09:48:12.498065Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1144
2022-12-08T09:48:12.510511Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49638
2022-12-08T09:48:12.510734Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49650
2022-12-08T09:48:12.511777Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.511822Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.513501Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1577
2022-12-08T09:48:12.513645Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1723
2022-12-08T09:48:12.525637Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49666
2022-12-08T09:48:12.525848Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49682
2022-12-08T09:48:12.526731Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.526897Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.527944Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 943
2022-12-08T09:48:12.528015Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1168
2022-12-08T09:48:12.540302Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49696
2022-12-08T09:48:12.540563Z  INFO servers::mysql::server: MySQL connection coming from: 127.0.0.1:49708
2022-12-08T09:48:12.541793Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.542008Z DEBUG servers::mysql::handler: Start executing query: 'SELECT uint32s FROM numbers LIMIT 1'
2022-12-08T09:48:12.543646Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1492
2022-12-08T09:48:12.543646Z DEBUG servers::mysql::handler: Finished executing query: 'SELECT uint32s FROM numbers LIMIT 1', total time costs in microseconds: 1731
2022-12-08T09:48:12.555824Z  INFO servers::server: MySQL server is shutdown.

   Canceling due to test failure: 0 tests still running
        PASS [   0.041s]                       servers::mod mysql::mysql_server_test::test_start_mysql_server
------------
     Summary [ 305.857s] 710/999 tests run: 709 passed, 1 timed out, 0 skipped
     TIMEOUT [ 180.006s]                       servers::mod mysql::mysql_server_test::test_shutdown_mysql_server
error: test run failed

How can we reproduce the bug?

Looks like this test occasionally timed out

Dec 09 '22 02:12 evenyag