GitHub user KuasarCloud added a comment to the discussion: Recurrent errors in database on vm schedules
Hi @DaanHoogland Please take a look, this all related to `logid:c3157b08` `2025-07-17 11:29:19,360 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) VM scheduler.poll is being called at 2025-07-17T16:29:00+0000 2025-07-17 11:29:19,376 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) DB Exception on: HikariProxyPreparedStatement@1276927238 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[6379]: 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'36383332303139372d343331622d343964652d393866622d333830313831333934653730', 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-17 11:29:19,377 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Rolling back the transaction: Time = 3 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-17 11:29:19,379 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Job is already scheduled. 2025-07-17 11:29:19,383 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) DB Exception on: HikariProxyPreparedStatement@614529765 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[6379]: 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'65396330306236652d626463622d346430612d396162332d663466636136343536356130', 293, 120, NULL, 'STOP', '2025-07-18 04:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '120-2025-07-18 04:00:00' for key 'vm_schedule_id' 2025-07-17 11:29:19,383 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) 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-17 11:29:19,385 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Job is already scheduled. 2025-07-17 11:29:19,389 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) DB Exception on: HikariProxyPreparedStatement@1815803217 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[6379]: 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'30643861656165632d343234342d343835382d386363662d366161393337613734383536', 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-17 11:29:19,389 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) 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-17 11:29:19,391 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Job is already scheduled. 2025-07-17 11:29:19,395 ERROR [o.a.c.v.s.d.VMScheduledJobDaoImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) DB Exception on: HikariProxyPreparedStatement@1276268827 wrapping com.mysql.cj.jdbc.ServerPreparedStatement[6379]: 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'64313833353966382d333764322d343836632d383138632d356263663666393134303937', 654, 124, NULL, 'STOP', '2025-07-18 04:00:00') java.sql.SQLIntegrityConstraintViolationException: Duplicate entry '124-2025-07-18 04:00:00' for key 'vm_schedule_id' 2025-07-17 11:29:19,396 DEBUG [c.c.u.d.T.Transaction] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Rolling back the transaction: Time = 3 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-17 11:29:19,398 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Job is already scheduled. 2025-07-17 11:29:19,401 DEBUG [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Got 0 scheduled jobs to be executed at 2025-07-17T16:29:00+0000 2025-07-17 11:29:19,404 INFO [o.a.c.v.s.VMSchedulerImpl] (VMSchedulerPollTask:[ctx-d11d1302]) (logid:c3157b08) Cleaned up 0 VM scheduled job entries` GitHub link: https://github.com/apache/cloudstack/discussions/11238#discussioncomment-13837727 ---- This is an automatically sent email for users@cloudstack.apache.org. To unsubscribe, please send an email to: users-unsubscr...@cloudstack.apache.org
