GitHub user KuasarCloud added a comment to the discussion: Recurrent errors in database on vm schedules
Hi @DaanHoogland this would be with the same ctx `2025-07-18 03:49:19,360 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) VM scheduler.poll is being called at 2025-07-18T08:49:00+0000 2025-07-18 03:49:19,370 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) DB Exception on: HikariProxyPreparedStatement@898825417 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[18104]: INSERT INTO vm_scheduled_job (vm_scheduled_job.uuid, vm_scheduled_job.vm_id, vm_scheduled_job.vm_schedule_id, vm_scheduled_job.async_job_id, vm_scheduled_job.action, vm_scheduled_job.scheduled_timestamp) VALUES (x'34643731356134372d646163622d346534392d613466312d626663383834343337663336', 293, 118, NULL, 'START', '2025-07-18 11:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '118-2025-07-18 11:00:00' for key 'vm_schedule_id' 2025-07-18 03:49:19,371 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Rolling back the transaction: Time = 4 Name = VMSchedulerPollTask; called by -TransactionLegacy.rollback:896-TransactionLegacy.removeUpTo:839-TransactionLegacy.close:663-TransactionContextInterceptor.invoke:36-ReflectiveMethodInvocation.proceed:175-ExposeInvocationInterceptor.invoke:97-ReflectiveMethodInvocation.proceed:186-JdkDynamicAopProxy.invoke:215-$Proxy373.persist:-1-VMSchedulerImpl.scheduleNextJob:167-VMSchedulerImpl.scheduleNextJobs:248-VMSchedulerImpl.poll:216 2025-07-18 03:49:19,373 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Job is already scheduled. 2025-07-18 03:49:19,376 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) DB Exception on: HikariProxyPreparedStatement@2098749760 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[18104]: INSERT INTO vm_scheduled_job (vm_scheduled_job.uuid, vm_scheduled_job.vm_id, vm_scheduled_job.vm_schedule_id, vm_scheduled_job.async_job_id, vm_scheduled_job.action, vm_scheduled_job.scheduled_timestamp) VALUES (x'62343238303963342d326435342d346432612d386662342d623138666632616661646465', 293, 120, NULL, 'STOP', '2025-07-19 04:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '120-2025-07-19 04:00:00' for key 'vm_schedule_id' 2025-07-18 03:49:19,377 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Rolling back the transaction: Time = 2 Name = VMSchedulerPollTask; called by -TransactionLegacy.rollback:896-TransactionLegacy.removeUpTo:839-TransactionLegacy.close:663-TransactionContextInterceptor.invoke:36-ReflectiveMethodInvocation.proceed:175-ExposeInvocationInterceptor.invoke:97-ReflectiveMethodInvocation.proceed:186-JdkDynamicAopProxy.invoke:215-$Proxy373.persist:-1-VMSchedulerImpl.scheduleNextJob:167-VMSchedulerImpl.scheduleNextJobs:248-VMSchedulerImpl.poll:216 2025-07-18 03:49:19,379 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Job is already scheduled. 2025-07-18 03:49:19,382 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) DB Exception on: HikariProxyPreparedStatement@1545630670 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[18104]: INSERT INTO vm_scheduled_job (vm_scheduled_job.uuid, vm_scheduled_job.vm_id, vm_scheduled_job.vm_schedule_id, vm_scheduled_job.async_job_id, vm_scheduled_job.action, vm_scheduled_job.scheduled_timestamp) VALUES (x'39613738316263302d653064612d343639352d383639622d333434666338633134343861', 654, 122, NULL, 'START', '2025-07-18 11:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '122-2025-07-18 11:00:00' for key 'vm_schedule_id' 2025-07-18 03:49:19,383 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Rolling back the transaction: Time = 2 Name = VMSchedulerPollTask; called by -TransactionLegacy.rollback:896-TransactionLegacy.removeUpTo:839-TransactionLegacy.close:663-TransactionContextInterceptor.invoke:36-ReflectiveMethodInvocation.proceed:175-ExposeInvocationInterceptor.invoke:97-ReflectiveMethodInvocation.proceed:186-JdkDynamicAopProxy.invoke:215-$Proxy373.persist:-1-VMSchedulerImpl.scheduleNextJob:167-VMSchedulerImpl.scheduleNextJobs:248-VMSchedulerImpl.poll:216 2025-07-18 03:49:19,384 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Job is already scheduled. 2025-07-18 03:49:19,387 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) DB Exception on: HikariProxyPreparedStatement@1736698952 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[18104]: INSERT INTO vm_scheduled_job (vm_scheduled_job.uuid, vm_scheduled_job.vm_id, vm_scheduled_job.vm_schedule_id, vm_scheduled_job.async_job_id, vm_scheduled_job.action, vm_scheduled_job.scheduled_timestamp) VALUES (x'34393765363563662d616635302d346666362d383736662d653239386563366164376330', 654, 124, NULL, 'STOP', '2025-07-19 04:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '124-2025-07-19 04:00:00' for key 'vm_schedule_id' 2025-07-18 03:49:19,390 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Rolling back the transaction: Time = 4 Name = VMSchedulerPollTask; called by -TransactionLegacy.rollback:896-TransactionLegacy.removeUpTo:839-TransactionLegacy.close:663-TransactionContextInterceptor.invoke:36-ReflectiveMethodInvocation.proceed:175-ExposeInvocationInterceptor.invoke:97-ReflectiveMethodInvocation.proceed:186-JdkDynamicAopProxy.invoke:215-$Proxy373.persist:-1-VMSchedulerImpl.scheduleNextJob:167-VMSchedulerImpl.scheduleNextJobs:248-VMSchedulerImpl.poll:216 2025-07-18 03:49:19,392 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Job is already scheduled. 2025-07-18 03:49:19,395 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Got 0 scheduled jobs to be executed at 2025-07-18T08:49:00+0000 2025-07-18 03:49:19,399 INFO [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-baef661e]) (logid:6169a69c) Cleaned up 0 VM scheduled job entries` GitHub link: https://github.com/apache/cloudstack/discussions/11238#discussioncomment-13808623 ---- This is an automatically sent email for users@cloudstack.apache.org. To unsubscribe, please send an email to: users-unsubscr...@cloudstack.apache.org
