Exception in thread Thread-15 com.mendix.core.CoreRuntimeException:

0
Hi, I got an error which I have a hard time identifying the culprit to. Can "Thread-15" be translated to the queue number that is shown in the queue configuration? Does "Exception occurred in action '{"xpath":"//ProcessQueue.QueuedAction[ID=16044073672697653]","amount":-1,"variables":{},"depth":0,"offset":-1,"sort":{},"type":"RetrieveXPathAction"}" mean that it failed to fetch the QueueAction or a association to a specific QueueAction? 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Exception in thread "Thread-15" com.mendix.core.CoreRuntimeException: com.mendix.systemwideinterfaces.MendixRuntimeException: com.mendix.core.CoreRuntimeException: Exception occurred in action '{"xpath":"//ProcessQueue.QueuedAction[ID=16044073672697653]","amount":-1,"variables":{},"depth":0,"offset":-1,"sort":{},"type":"RetrieveXPathAction"}', all database changes executed by this action were rolled back 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.ActionManagerBase.executeSync(ActionManagerBase.java:159) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: com.mendix.systemwideinterfaces.MendixRuntimeException: com.mendix.core.CoreRuntimeException: Exception occurred in action '{"xpath":"//ProcessQueue.QueuedAction[ID=16044073672697653]","amount":-1,"variables":{},"depth":0,"offset":-1,"sort":{},"type":"RetrieveXPathAction"}', all database changes executed by this action were rolled back 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.util.classloading.Runner.doRunUsingClassLoaderOf(Runner.java:36) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: com.mendix.core.CoreRuntimeException: Exception occurred in action '{"xpath":"//ProcessQueue.QueuedAction[ID=16044073672697653]","amount":-1,"variables":{},"depth":0,"offset":-1,"sort":{},"type":"RetrieveXPathAction"}', all database changes executed by this action were rolled back 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CoreActionHandlerImpl.processErrorState(CoreActionHandlerImpl.scala:160) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: java.util.concurrent.ExecutionException: com.mendix.basis.connectionbus.ConnectionBusException: Exception occurred while retrieving data. (SQL State: null, Error Code: 0) Detail Message: java.util.NoSuchElementException: Timeout waiting for idle object 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.util.concurrent.FutureTask.report(FutureTask.java:122) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.util.concurrent.FutureTask.get(FutureTask.java:192) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.QueryExecution.$anonfun$getDataSet$3(QueryExecution.scala:20) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at scala.collection.immutable.List.map(List.scala:283) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.QueryExecution.getAndMergeResult$1(QueryExecution.scala:20) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.QueryExecution.getDataSet(QueryExecution.scala:29) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.AssociationDataRetriever.handleQueryResult(AssociationDataRetriever.java:164) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.AssociationDataRetriever.executeQueriesPerQueryInstance(AssociationDataRetriever.java:117) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.AssociationDataRetriever.executeQueryInstances(AssociationDataRetriever.java:91) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.AssociationDataRetriever.executeQueries(AssociationDataRetriever.java:64) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.AssociationDataRetriever.executeAssociationQueries(AssociationDataRetriever.java:38) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.dependentexecutions.DependentDataRetriever.executeQueries(DependentDataRetriever.java:47) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.DataStoreCaller.executeQueries(DataStoreCaller.java:160) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.DataStoreCaller.executeQueries(DataStoreCaller.java:121) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.DataStoreCaller.getResult(DataStoreCaller.java:54) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.retrieve.GetRequestHandler.doRequest(GetRequestHandler.java:77) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.RequestAnalyzer.doRequest(RequestAnalyzer.java:48) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.ConnectionBusImpl.doRequest(ConnectionBusImpl.java:450) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.action.user.RetrieveXPathRawAction.retrieveXPathRaw(RetrieveXPathRawAction.java:132) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.action.user.RetrieveXPathRawAction.executeAction(RetrieveXPathRawAction.java:72) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.action.user.RetrieveXPathAction.executeAction(RetrieveXPathAction.java:58) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.action.user.RetrieveXPathAction.executeAction(RetrieveXPathAction.java:16) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.systemwideinterfaces.core.UserAction.execute(UserAction.java:46) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CoreActionHandlerImpl.doCall(CoreActionHandlerImpl.scala:79) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CoreActionHandlerImpl.call(CoreActionHandlerImpl.scala:57) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.core.actionmanagement.CoreAction.call(CoreAction.java:55) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.DefaultActionMonitor$.$anonfun$run$1(CustomMonitoredAction.scala:18) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.IMonitoredAction$$anon$1.execute(IMonitoredAction.scala:47) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.util.classloading.Runner.doRunUsingClassLoaderOf(Runner.java:32) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.IMonitoredAction.monitor(IMonitoredAction.scala:49) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.IMonitoredAction.monitor$(IMonitoredAction.scala:25) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CustomMonitoredAction.monitor(CustomMonitoredAction.scala:22) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.DefaultActionMonitor$.run(CustomMonitoredAction.scala:18) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CoreActionHandlerImpl.runMonitoredAction(CoreActionHandlerImpl.scala:68) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.CoreActionHandlerImpl.call(CoreActionHandlerImpl.scala:55) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.core.actionmanagement.CoreAction.call(CoreAction.java:55) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.ActionManagerBase$1.execute(ActionManagerBase.java:150) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.util.classloading.Runner.doRunUsingClassLoaderOf(Runner.java:32) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.actionmanagement.ActionManagerBase.executeSync(ActionManagerBase.java:155) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.component.InternalCoreBase.retrieveXPathQuery(InternalCoreBase.java:1240) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.basis.component.InternalCoreBase.retrieveXPathQuery(InternalCoreBase.java:1205) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.core.Core.retrieveXPathQuery(Core.java:757) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.core.Core.retrieveXPathQuery(Core.java:799) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at processqueue.queuehandler.QueueHandler$QueueAppender.run(QueueHandler.java:276) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: com.mendix.basis.connectionbus.ConnectionBusException: Exception occurred while retrieving data. (SQL State: null, Error Code: 0) Detail Message: java.util.NoSuchElementException: Timeout waiting for idle object 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.getCorrectException(JdbcDataStore.java:736) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: java.sql.SQLException: Cannot get a connection, pool error Timeout waiting for idle object 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at org.apache.commons.dbcp2.PoolingDataSource.getConnection(PoolingDataSource.java:142) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.ConnectionManager.getConnection(ConnectionManager.java:49) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.getConnection(JdbcDataStore.java:565) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.getConnection(JdbcDataStore.java:538) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.execRetrieveQueries(JdbcDataStore.java:180) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.retrieve(JdbcDataStore.java:155) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.jdbc.JdbcDataStore.retrieve(JdbcDataStore.java:150) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.DataStoreRetrieveAction.call(DataStoreRetrieveAction.java:29) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at com.mendix.connectionbus.connections.DataStoreRetrieveAction.call(DataStoreRetrieveAction.java:11) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.util.concurrent.FutureTask.run(FutureTask.java:266) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at java.lang.Thread.run(Thread.java:748) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]    Caused by: java.util.NoSuchElementException: Timeout waiting for idle object 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:449) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:363) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        at org.apache.commons.dbcp2.PoolingDataSource.getConnection(PoolingDataSource.java:134) 2018-11-15T08:20:15.85+0000 [APP/PROC/WEB/0]        ... 12 more 2018-11-15T08:21:06.22+0000 [APP/PROC/WEB/0]    2018/11/15 08:21:06 [error] 200#0: *3199 upstream prematurely closed connection while reading response header from upstream, client: 83.209.12.193, server: _, request: "POST /xas/ HTTP/1.1", upstream: "http://127.0.0.1:8081/xas/", host: "enqlare100-accp.mendixcloud.com", referrer: "https://enqlare100-accp.mendixcloud.com/" 2018-11-15T08:21:06.94+0000 [APP/PROC/WEB/0]    2018/11/15 08:21:06 [error] 200#0: *3201 upstream prematurely closed connection while reading response header from upstream, client: 83.209.12.193, server: _, request: "POST /xas/ HTTP/1.1", upstream: "http://127.0.0.1:8081/xas/", host: "enqlare100-accp.mendixcloud.com", referrer: "https://enqlare100-accp.mendixcloud.com/"  
asked
1 answers
0

Hello Johan,

It looks like a retrieve has failed - however this looks to be a retrieve by ID not by association. 

Perhaps the object was deleted at some point before that retrieve was actioned.

Hope this helps

answered