simple kbs startup link database prompts a timeout #66
Description
Describe the bug
The startup prompt of simple kbs indicates that the connection to the database failed. Previously, the same component started normally. The difference is that the host operating system used is different. Then, the container image was rebuilt. Whether the component versions in the container image are updated, causing incompatibility
How to reproduce
sudo docker logs simple-kbs-server-1
[2025-05-26T12:07:41Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
[2025-05-26T12:08:13Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
[2025-05-26T12:08:45Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
sudo docker logs simple-kbs-db-1
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.5.2+mariaubu2404 started.ubu2404 started.
2025-05-26 12:07:41+00:00 [Warn] [Entrypoint]: /sys/fs/cgroup/perf_event:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
12:pids:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
11:cpuset:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
10:rdma:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
9:misc:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
8:memory:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
7:freezer:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
6:cpu,cpuacct:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
5:net_cls,net_prio:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
4:blkio:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
3:devices:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
2:hugetlb:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
1:name=systemd:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa/memory.pressure not writable, functionality unavailable to MariaDB
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Switching to dedicated user 'mysql'
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.5.2+maria
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Initializing database files
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Database files initialized
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Starting temporary server
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Waiting for server startup
2025-05-26 12:08:05 0 [Note] Starting MariaDB 11.5.2-MariaDB-ubu2404 source revision ea75a0b6009b0251e83193cd38c3974e36b65e06 server_uid I8/zqvbJN3VHwCOp+HNWk+OqH0E= as process 171
2025-05-26 12:08:05 0 [Note] InnoDB: Compressed tables use zlib 1.3
2025-05-26 12:08:05 0 [Note] InnoDB: Number of transaction pools: 1
2025-05-26 12:08:05 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
2025-05-26 12:08:05 0 [Note] mariadbd: O_TMPFILE is not supported on /tmp (disabling future attempts)
2025-05-26 12:08:05 0 [Note] InnoDB: Using liburing
2025-05-26 12:08:05 0 [Note] InnoDB: Initializing buffer pool, total size = 128.000MiB, chunk size = 2.000MiB
2025-05-26 12:08:05 0 [Note] InnoDB: Completed initialization of buffer pool
2025-05-26 12:08:05 0 [Note] InnoDB: File system buffers for log disabled (block size=4096 bytes)
2025-05-26 12:08:05 0 [Note] InnoDB: End of log at LSN=47645
2025-05-26 12:08:05 0 [Note] InnoDB: Opened 3 undo tablespaces
2025-05-26 12:08:05 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
2025-05-26 12:08:05 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
2025-05-26 12:08:05 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
2025-05-26 12:08:05 0 [Note] InnoDB: log sequence number 47645; transaction id 14
2025-05-26 12:08:05 0 [Note] Plugin 'FEEDBACK' is disabled.
2025-05-26 12:08:05 0 [Note] Plugin 'wsrep-provider' is disabled.
2025-05-26 12:08:11 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
2025-05-26 12:08:11 0 [Note] mariadbd: ready for connections.
Version: '11.5.2-MariaDB-ubu2404' socket: '/run/mysqld/mysqld.sock' port: 0 mariadb.org binary distribution
2025-05-26 12:08:12+00:00 [Note] [Entrypoint]: Temporary server started.
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Creating database simple_kbs
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Creating user kbsuser
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Giving user kbsuser access to schema simple_kbs
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Securing system users (equivalent to running mysql_secure_installation)
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: /usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/10-db-mysql.sql
2025-05-26 12:08:17+00:00 [Note] [Entrypoint]: /usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/20-initial-data.sql
2025-05-26 12:08:17+00:00 [Note] [Entrypoint]: Stopping temporary server
2025-05-26 12:08:17 0 [Note] mariadbd (initiated by: unknown): Normal shutdown
2025-05-26 12:08:17 0 [Note] InnoDB: FTS optimize thread exiting.
2025-05-26 12:08:17 0 [Note] InnoDB: Starting shutdown...
2025-05-26 12:08:17 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
2025-05-26 12:08:17 0 [Note] InnoDB: Buffer pool(s) dump completed at 250526 12:08:17
2025-05-26 12:08:18 0 [Note] InnoDB: Removed temporary tablespace data file: "./ibtmp1"
2025-05-26 12:08:18 0 [Note] InnoDB: Shutdown completed; log sequence number 70580; transaction id 51
2025-05-26 12:08:18 0 [Note] mariadbd: Shutdown complete
2025-05-26 12:08:18+00:00 [Note] [Entrypoint]: Temporary server stopped
2025-05-26 12:08:18+00:00 [Note] [Entrypoint]: MariaDB init process done. Ready for start up.
2025-05-26 12:08:18 0 [Note] Starting MariaDB 11.5.2-MariaDB-ubu2404 source revision ea75a0b6009b0251e83193cd38c3974e36b65e06 server_uid I8/zqvbJN3VHwCOp+HNWk+OqH0E= as process 1
2025-05-26 12:08:18 0 [Note] InnoDB: Compressed tables use zlib 1.3
2025-05-26 12:08:18 0 [Note] InnoDB: Number of transaction pools: 1
2025-05-26 12:08:18 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
2025-05-26 12:08:18 0 [Note] mariadbd: O_TMPFILE is not supported on /tmp (disabling future attempts)
2025-05-26 12:08:18 0 [Note] InnoDB: Using liburing
2025-05-26 12:08:18 0 [Note] InnoDB: Initializing buffer pool, total size = 128.000MiB, chunk size = 2.000MiB
2025-05-26 12:08:18 0 [Note] InnoDB: Completed initialization of buffer pool
2025-05-26 12:08:18 0 [Note] InnoDB: File system buffers for log disabled (block size=4096 bytes)
2025-05-26 12:08:18 0 [Note] InnoDB: End of log at LSN=70580
2025-05-26 12:08:18 0 [Note] InnoDB: Opened 3 undo tablespaces
2025-05-26 12:08:18 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
2025-05-26 12:08:18 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
2025-05-26 12:08:18 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
2025-05-26 12:08:18 0 [Note] InnoDB: log sequence number 70580; transaction id 52
2025-05-26 12:08:18 0 [Note] Plugin 'FEEDBACK' is disabled.
2025-05-26 12:08:18 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2025-05-26 12:08:18 0 [Note] Plugin 'wsrep-provider' is disabled.
2025-05-26 12:08:18 0 [Note] Server socket created on IP: '0.0.0.0'.
2025-05-26 12:08:18 0 [Note] Server socket created on IP: '::'.
2025-05-26 12:08:18 0 [Note] InnoDB: Buffer pool(s) load completed at 250526 12:08:18
2025-05-26 12:08:18 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
2025-05-26 12:08:18 0 [Note] mariadbd: ready for connections.
Version: '11.5.2-MariaDB-ubu2404' socket: '/run/mysqld/mysqld.sock' port: 3306 mariadb.org binary distribution
[higon@kylin-server-v11 simple-kbs]$
CoCo version information
0.5.0
What TEE are you seeing the problem on
None
Failing command and relevant log output
sudo docker logs simple-kbs-server-1
[2025-05-26T12:07:41Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
[2025-05-26T12:08:13Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
[2025-05-26T12:08:45Z INFO simple_kbs] Starting gRPC Server
Error: db::get_db_pool:: Encountered error trying to create database pool: pool timed out while waiting for an open connection
sudo docker logs simple-kbs-db-1
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.5.2+maria~ubu2404 started.
2025-05-26 12:07:41+00:00 [Warn] [Entrypoint]: /sys/fs/cgroup/perf_event:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
12:pids:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
11:cpuset:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
10:rdma:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
9:misc:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
8:memory:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
7:freezer:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
6:cpu,cpuacct:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
5:net_cls,net_prio:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
4:blkio:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
3:devices:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
2:hugetlb:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa
1:name=systemd:/docker/b1febab33a9f852380369ab8a9d6e469d17630158c8a3b17863563abb0d783aa/memory.pressure not writable, functionality unavailable to MariaDB
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Switching to dedicated user 'mysql'
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Entrypoint script for MariaDB Server 1:11.5.2+maria~ubu2404 started.
2025-05-26 12:07:41+00:00 [Note] [Entrypoint]: Initializing database files
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Database files initialized
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Starting temporary server
2025-05-26 12:08:05+00:00 [Note] [Entrypoint]: Waiting for server startup
2025-05-26 12:08:05 0 [Note] Starting MariaDB 11.5.2-MariaDB-ubu2404 source revision ea75a0b6009b0251e83193cd38c3974e36b65e06 server_uid I8/zqvbJN3VHwCOp+HNWk+OqH0E= as process 171
2025-05-26 12:08:05 0 [Note] InnoDB: Compressed tables use zlib 1.3
2025-05-26 12:08:05 0 [Note] InnoDB: Number of transaction pools: 1
2025-05-26 12:08:05 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
2025-05-26 12:08:05 0 [Note] mariadbd: O_TMPFILE is not supported on /tmp (disabling future attempts)
2025-05-26 12:08:05 0 [Note] InnoDB: Using liburing
2025-05-26 12:08:05 0 [Note] InnoDB: Initializing buffer pool, total size = 128.000MiB, chunk size = 2.000MiB
2025-05-26 12:08:05 0 [Note] InnoDB: Completed initialization of buffer pool
2025-05-26 12:08:05 0 [Note] InnoDB: File system buffers for log disabled (block size=4096 bytes)
2025-05-26 12:08:05 0 [Note] InnoDB: End of log at LSN=47645
2025-05-26 12:08:05 0 [Note] InnoDB: Opened 3 undo tablespaces
2025-05-26 12:08:05 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
2025-05-26 12:08:05 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
2025-05-26 12:08:05 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
2025-05-26 12:08:05 0 [Note] InnoDB: log sequence number 47645; transaction id 14
2025-05-26 12:08:05 0 [Note] Plugin 'FEEDBACK' is disabled.
2025-05-26 12:08:05 0 [Note] Plugin 'wsrep-provider' is disabled.
2025-05-26 12:08:11 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
2025-05-26 12:08:11 0 [Note] mariadbd: ready for connections.
Version: '11.5.2-MariaDB-ubu2404' socket: '/run/mysqld/mysqld.sock' port: 0 mariadb.org binary distribution
2025-05-26 12:08:12+00:00 [Note] [Entrypoint]: Temporary server started.
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Creating database simple_kbs
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Creating user kbsuser
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Giving user kbsuser access to schema simple_kbs
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: Securing system users (equivalent to running mysql_secure_installation)
2025-05-26 12:08:15+00:00 [Note] [Entrypoint]: /usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/10-db-mysql.sql
2025-05-26 12:08:17+00:00 [Note] [Entrypoint]: /usr/local/bin/docker-entrypoint.sh: running /docker-entrypoint-initdb.d/20-initial-data.sql
2025-05-26 12:08:17+00:00 [Note] [Entrypoint]: Stopping temporary server
2025-05-26 12:08:17 0 [Note] mariadbd (initiated by: unknown): Normal shutdown
2025-05-26 12:08:17 0 [Note] InnoDB: FTS optimize thread exiting.
2025-05-26 12:08:17 0 [Note] InnoDB: Starting shutdown...
2025-05-26 12:08:17 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
2025-05-26 12:08:17 0 [Note] InnoDB: Buffer pool(s) dump completed at 250526 12:08:17
2025-05-26 12:08:18 0 [Note] InnoDB: Removed temporary tablespace data file: "./ibtmp1"
2025-05-26 12:08:18 0 [Note] InnoDB: Shutdown completed; log sequence number 70580; transaction id 51
2025-05-26 12:08:18 0 [Note] mariadbd: Shutdown complete
2025-05-26 12:08:18+00:00 [Note] [Entrypoint]: Temporary server stopped
2025-05-26 12:08:18+00:00 [Note] [Entrypoint]: MariaDB init process done. Ready for start up.
2025-05-26 12:08:18 0 [Note] Starting MariaDB 11.5.2-MariaDB-ubu2404 source revision ea75a0b6009b0251e83193cd38c3974e36b65e06 server_uid I8/zqvbJN3VHwCOp+HNWk+OqH0E= as process 1
2025-05-26 12:08:18 0 [Note] InnoDB: Compressed tables use zlib 1.3
2025-05-26 12:08:18 0 [Note] InnoDB: Number of transaction pools: 1
2025-05-26 12:08:18 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
2025-05-26 12:08:18 0 [Note] mariadbd: O_TMPFILE is not supported on /tmp (disabling future attempts)
2025-05-26 12:08:18 0 [Note] InnoDB: Using liburing
2025-05-26 12:08:18 0 [Note] InnoDB: Initializing buffer pool, total size = 128.000MiB, chunk size = 2.000MiB
2025-05-26 12:08:18 0 [Note] InnoDB: Completed initialization of buffer pool
2025-05-26 12:08:18 0 [Note] InnoDB: File system buffers for log disabled (block size=4096 bytes)
2025-05-26 12:08:18 0 [Note] InnoDB: End of log at LSN=70580
2025-05-26 12:08:18 0 [Note] InnoDB: Opened 3 undo tablespaces
2025-05-26 12:08:18 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
2025-05-26 12:08:18 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
2025-05-26 12:08:18 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
2025-05-26 12:08:18 0 [Note] InnoDB: log sequence number 70580; transaction id 52
2025-05-26 12:08:18 0 [Note] Plugin 'FEEDBACK' is disabled.
2025-05-26 12:08:18 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2025-05-26 12:08:18 0 [Note] Plugin 'wsrep-provider' is disabled.
2025-05-26 12:08:18 0 [Note] Server socket created on IP: '0.0.0.0'.
2025-05-26 12:08:18 0 [Note] Server socket created on IP: '::'.
2025-05-26 12:08:18 0 [Note] InnoDB: Buffer pool(s) load completed at 250526 12:08:18
2025-05-26 12:08:18 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
2025-05-26 12:08:18 0 [Note] mariadbd: ready for connections.
Version: '11.5.2-MariaDB-ubu2404' socket: '/run/mysqld/mysqld.sock' port: 3306 mariadb.org binary distribution