Duplicate key error when inserting Ping data

[404forever1]

Describe the issue

[20:54:26 ERROR] [plan]: Ran into CompletionException - logged to plugins\plan\logs\CompletionException-d081dd9f1d.txt
[20:54:26 ERROR] [plan]: (INCLUDE CONTENTS OF THE FILE IN ANY REPORTS)
[20:54:26 ERROR] [plan]: What to do: Report this, duplicate key exists in SQL.
断电后报错

Exceptions & Other Logs

d081dd9f1d - Last occurred: 2025-01-03 Occurrences: 193
---- Context 5 ----
Plan v5.6 build 2891
Velocity 3.3.0-SNAPSHOT (git-862036d4-b424)
Server v3.3.0-SNAPSHOT (git-862036d4-b424)

Report this, duplicate key exists in SQL.
Transaction: class com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction
DB State: OPEN - fatal: false
Error code: 1062
INSERT INTO plan_ping (user_id,server_id,date,min_ping,max_ping,avg_ping) VALUES ((SELECT plan_users.id FROM plan_users WHERE plan_users.uuid=? LIMIT 1),(SELECT plan_servers.id FROM plan_servers WHERE plan_servers.uuid=? LIMIT 1), ?, ?, ?, ?)
Duplicate key

---- Context 4 ----
Plan v5.6 build 2891
Velocity 3.3.0-SNAPSHOT (git-862036d4-b424)
Server v3.3.0-SNAPSHOT (git-862036d4-b424)

Report this, duplicate key exists in SQL.
Transaction: class com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction
DB State: OPEN - fatal: false
Error code: 1062
INSERT INTO plan_ping (user_id,server_id,date,min_ping,max_ping,avg_ping) VALUES ((SELECT plan_users.id FROM plan_users WHERE plan_users.uuid=? LIMIT 1),(SELECT plan_servers.id FROM plan_servers WHERE plan_servers.uuid=? LIMIT 1), ?, ?, ?, ?)
Duplicate key

---- Context 3 ----
Plan v5.6 build 2891
Velocity 3.3.0-SNAPSHOT (git-862036d4-b424)
Server v3.3.0-SNAPSHOT (git-862036d4-b424)

Report this, duplicate key exists in SQL.
Transaction: class com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction
DB State: OPEN - fatal: false
Error code: 1062
INSERT INTO plan_ping (user_id,server_id,date,min_ping,max_ping,avg_ping) VALUES ((SELECT plan_users.id FROM plan_users WHERE plan_users.uuid=? LIMIT 1),(SELECT plan_servers.id FROM plan_servers WHERE plan_servers.uuid=? LIMIT 1), ?, ?, ?, ?)
Duplicate key

---- Context 2 ----
Plan v5.6 build 2891
Velocity 3.3.0-SNAPSHOT (git-862036d4-b424)
Server v3.3.0-SNAPSHOT (git-862036d4-b424)

Report this, duplicate key exists in SQL.
Transaction: class com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction
DB State: OPEN - fatal: false
Error code: 1062
INSERT INTO plan_ping (user_id,server_id,date,min_ping,max_ping,avg_ping) VALUES ((SELECT plan_users.id FROM plan_users WHERE plan_users.uuid=? LIMIT 1),(SELECT plan_servers.id FROM plan_servers WHERE plan_servers.uuid=? LIMIT 1), ?, ?, ?, ?)
Duplicate key

---- Context 1 ----
Plan v5.6 build 2891
Velocity 3.3.0-SNAPSHOT (git-862036d4-b424)
Server v3.3.0-SNAPSHOT (git-862036d4-b424)

Report this, duplicate key exists in SQL.
Transaction: class com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction
DB State: OPEN - fatal: false
Error code: 1062
INSERT INTO plan_ping (user_id,server_id,date,min_ping,max_ping,avg_ping) VALUES ((SELECT plan_users.id FROM plan_users WHERE plan_users.uuid=? LIMIT 1),(SELECT plan_servers.id FROM plan_servers WHERE plan_servers.uuid=? LIMIT 1), ?, ?, ?, ?)
Duplicate key

---- Stacktrace ----
java.util.concurrent.CompletionException: com.djrapitops.plan.exceptions.database.DBOpException: SQL Failure: Duplicate entry '2510366' for key 'PRIMARY'
   java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:315)
   java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:320)
   java.base/java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1770)
   java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
   java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
   java.base/java.lang.Thread.run(Thread.java:1583)
Caused by:
com.djrapitops.plan.exceptions.database.DBOpException: SQL Failure: Duplicate entry '2510366' for key 'PRIMARY'
   com.djrapitops.plan.exceptions.database.DBOpException.forCause(DBOpException.java:153)
   com.djrapitops.plan.storage.database.transactions.ExecStatement.execute(ExecStatement.java:57)
   com.djrapitops.plan.storage.database.transactions.Transaction.execute(Transaction.java:223)
   com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction.performOperations(PingStoreTransaction.java:60)
   com.djrapitops.plan.storage.database.transactions.Transaction.executeTransaction(Transaction.java:89)
   com.djrapitops.plan.storage.database.SQLDB.lambda$executeTransaction$4(SQLDB.java:373)
   com.djrapitops.plan.storage.database.DBAccessLock.performDatabaseOperation(DBAccessLock.java:78)
   com.djrapitops.plan.storage.database.DBAccessLock.performDatabaseOperation(DBAccessLock.java:56)
   com.djrapitops.plan.storage.database.SQLDB.lambda$executeTransaction$5(SQLDB.java:372)
   java.base/java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1768)
   java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
   java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
   java.base/java.lang.Thread.run(Thread.java:1583)
Caused by:
java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '2510366' for key 'PRIMARY'
   com.mysql.cj.jdbc.exceptions.SQLError.createSQLException(SQLError.java:109)
   com.mysql.cj.jdbc.exceptions.SQLExceptionsMapping.translateException(SQLExceptionsMapping.java:113)
   com.mysql.cj.jdbc.ClientPreparedStatement.executeInternal(ClientPreparedStatement.java:938)
   com.mysql.cj.jdbc.ClientPreparedStatement.executeUpdateInternal(ClientPreparedStatement.java:1106)
   com.mysql.cj.jdbc.ClientPreparedStatement.executeUpdateInternal(ClientPreparedStatement.java:1045)
   com.mysql.cj.jdbc.ClientPreparedStatement.executeLargeUpdate(ClientPreparedStatement.java:1369)
   com.mysql.cj.jdbc.ClientPreparedStatement.executeUpdate(ClientPreparedStatement.java:1030)
   plan.com.zaxxer.hikari.pool.ProxyPreparedStatement.executeUpdate(ProxyPreparedStatement.java:61)
   plan.com.zaxxer.hikari.pool.HikariProxyPreparedStatement.executeUpdate(HikariProxyPreparedStatement.java)
   com.djrapitops.plan.storage.database.transactions.ExecStatement.callExecute(ExecStatement.java:70)
   com.djrapitops.plan.storage.database.transactions.ExecStatement.execute(ExecStatement.java:64)
   com.djrapitops.plan.storage.database.transactions.ExecStatement.execute(ExecStatement.java:55)
   com.djrapitops.plan.storage.database.transactions.Transaction.execute(Transaction.java:223)
   com.djrapitops.plan.storage.database.transactions.events.PingStoreTransaction.performOperations(PingStoreTransaction.java:60)
   com.djrapitops.plan.storage.database.transactions.Transaction.executeTransaction(Transaction.java:89)
   com.djrapitops.plan.storage.database.SQLDB.lambda$executeTransaction$4(SQLDB.java:373)
   com.djrapitops.plan.storage.database.DBAccessLock.performDatabaseOperation(DBAccessLock.java:78)
   com.djrapitops.plan.storage.database.DBAccessLock.performDatabaseOperation(DBAccessLock.java:56)
   com.djrapitops.plan.storage.database.SQLDB.lambda$executeTransaction$5(SQLDB.java:372)
   java.base/java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1768)
   java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
   java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
   java.base/java.lang.Thread.run(Thread.java:1583)
   java.base/java.lang.Thread.getStackTrace(Thread.java:2450)
   com.djrapitops.plan.storage.database.SQLDB.executeTransaction(SQLDB.java:359)
   com.djrapitops.plan.gathering.timed.VelocityPingCounter.run(VelocityPingCounter.java:113)
   com.velocitypowered.proxy.scheduler.VelocityScheduler$VelocityTask.lambda$run$0(VelocityScheduler.java:285)
   java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
   java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
   java.base/java.lang.Thread.run(Thread.java:1583)

Plugin versions

[20:55:24 INFO]: > Player Analytics
[20:55:24 INFO]:
[20:55:24 INFO]: Version: 5.6 build 2891
[20:55:24 INFO]: Update Available: No
[20:55:24 INFO]: Current Database: MySQL (OPEN)
[20:55:24 INFO]: Connected to Proxy: Yes
[20:55:24 INFO]: Server UUID: 0bd60aa3-55a9-4e09-a6fe-70d5fec5965c
[20:55:24 INFO]:
[20:55:24 INFO]: >

Additional information

[404forever1]

使用了最新的版本，还是报错

[404forever1]

[404forever1]

CompletionException-6d8a658bd3.txt

[AuroraLS3]

I think the issue is due to transaction isolation level in mysql settings - Both proxy server and game server are inserting at the same time, leading to an issue where one of them fails as the other one was faster.

The implemented fix was to retry, but this new error log seems to have tried twice