[jira] [Updated] (SLING-4014) Sling Jobs do not get picked at times

2014-10-10 Thread Amit Gupta (JIRA) 

 [ 
https://issues.apache.org/jira/browse/SLING-4014?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Amit Gupta updated SLING-4014:
--
Attachment: SLING-4014-Test.patch

 Sling Jobs do not get picked at times
 -

 Key: SLING-4014
 URL: https://issues.apache.org/jira/browse/SLING-4014
 Project: Sling
  Issue Type: Bug
  Components: Extensions
Affects Versions: Event 3.3.14, API 2.8.0
Reporter: Amit Gupta
Assignee: Amit Gupta
 Fix For: Event 3.3.16

 Attachments: SLING-4014-Test.patch, SLING-4014.patch, sling_event.log


 Sling jobs don't get picked at times. This what happened in one of the 
 scenario see attached logs for details.. but in a nutshell
 {code}
 07.10.2014 17:41:09.710 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Persisting job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 properties=com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.topics=org/apache/sling/event/job]
  into queue Granite Workflow Queue, 
 target=691ab81c-1dee-4504-bcff-962599fa602f
 07.10.2014 17:41:09.712 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Storing new job 
 {sling:resourceType=slingevent:Job, 
 slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f, 
 event.job.retries=10, 
 com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,
  event.job.retrycount=0, 
 com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,
  
 event.job.topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  slingevent:eventadmin=true, 
 slingevent:eventId=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-..at
  
 /var/eventing/jobs/assigned/691ab81c-1dee-4504-bcff-962599fa602f/slingevent:eventadmin/2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Job manager maintenance: 
 Starting #8828
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Checking for unprocessed 
 jobs...
 07.10.2014 17:41:14.748 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.MaintenanceTask Cleaning up job resource 
 tree: removing obsolete locks
 07.10.2014 17:41:30.680 *INFO* [Apache Sling Job Background Loader] 
 org.apache.sling.event Service [QueueMBean for queue Granite Workflow 
 Queue,3219] ServiceEvent REGISTERED
 07.10.2014 17:41:30.796 *INFO* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Starting job queue Granite Workflow Queue
 07.10.2014 17:41:30.796 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Configuration for job queue=Queue-Configuration(1817656049) : 
 {name=Granite Workflow Queue, type=TOPIC_ROUND_ROBIN, 
 topics=[SubPackageMatcher [packageName=com/adobe/granite/workflow/job/]], 
 maxParallelProcesses=4, retries=10, retryDelayInMs=2000, keepJobs=false, 
 preferRunOnCreationInstance=true, ownThreadPoolSize=0, serviceRanking=0, 
 pid=org.apache.sling.event.jobs.QueueConfiguration.3a3b109d-7e33-402b-972d-15eea0fc47bc,
  isValid=true}
 07.10.2014 17:41:30.800 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Discarding removed job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 id=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28,
  
 properties=slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.retries=10,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.job.retrycount=0,slingevent:created=java.util.GregorianCalendar(Tue
  Oct 07 17:41:09 IST 
 2014),sling:resourceType=slingevent:Job,com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,event.job.started.time=java.util.GregorianCalendar(Tue
  Oct 07 17:41:30 IST

[jira] [Updated] (SLING-4014) Sling Jobs do not get picked at times

2014-10-08 Thread Amit Gupta (JIRA) 

 [ 
https://issues.apache.org/jira/browse/SLING-4014?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Amit Gupta updated SLING-4014:
--
Description: 
Sling jobs don't get picked at times. This what happened in one of the scenario 
see attached logs for details.. but in a nutshell

{code}
07.10.2014 17:41:09.710 *DEBUG* [Apache Sling Job Event Bridge] 
org.apache.sling.event.impl.jobs.JobManagerImpl Persisting job Sling Job 
[topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
 
properties=com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.topics=org/apache/sling/event/job]
 into queue Granite Workflow Queue, target=691ab81c-1dee-4504-bcff-962599fa602f
07.10.2014 17:41:09.712 *DEBUG* [Apache Sling Job Event Bridge] 
org.apache.sling.event.impl.jobs.JobManagerImpl Storing new job 
{sling:resourceType=slingevent:Job, 
slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f, 
event.job.retries=10, 
com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,
 event.job.retrycount=0, 
com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,
 
event.job.topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
 slingevent:eventadmin=true, 
slingevent:eventId=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-..at
 
/var/eventing/jobs/assigned/691ab81c-1dee-4504-bcff-962599fa602f/slingevent:eventadmin/2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28
07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
org.apache.sling.event.impl.jobs.JobManagerImpl Job manager maintenance: 
Starting #8828
07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
org.apache.sling.event.impl.jobs.JobManagerImpl Checking for unprocessed jobs...
07.10.2014 17:41:14.748 *DEBUG* [pool-8-thread-1] 
org.apache.sling.event.impl.jobs.MaintenanceTask Cleaning up job resource tree: 
removing obsolete locks
07.10.2014 17:41:30.680 *INFO* [Apache Sling Job Background Loader] 
org.apache.sling.event Service [QueueMBean for queue Granite Workflow 
Queue,3219] ServiceEvent REGISTERED
07.10.2014 17:41:30.796 *INFO* [Apache Sling Job Queue Granite Workflow Queue] 
org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
Workflow Queue Starting job queue Granite Workflow Queue
07.10.2014 17:41:30.796 *DEBUG* [Apache Sling Job Queue Granite Workflow Queue] 
org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
Workflow Queue Configuration for job queue=Queue-Configuration(1817656049) : 
{name=Granite Workflow Queue, type=TOPIC_ROUND_ROBIN, topics=[SubPackageMatcher 
[packageName=com/adobe/granite/workflow/job/]], maxParallelProcesses=4, 
retries=10, retryDelayInMs=2000, keepJobs=false, 
preferRunOnCreationInstance=true, ownThreadPoolSize=0, serviceRanking=0, 
pid=org.apache.sling.event.jobs.QueueConfiguration.3a3b109d-7e33-402b-972d-15eea0fc47bc,
 isValid=true}
07.10.2014 17:41:30.800 *DEBUG* [Apache Sling Job Queue Granite Workflow Queue] 
org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
Workflow Queue Discarding removed job Sling Job 
[topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
 
id=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28,
 
properties=slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.retries=10,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.job.retrycount=0,slingevent:created=java.util.GregorianCalendar(Tue
 Oct 07 17:41:09 IST 
2014),sling:resourceType=slingevent:Job,com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,event.job.started.time=java.util.GregorianCalendar(Tue
 Oct 07 17:41:30 IST 
2014),event.job.application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.queuename=Granite
 Workflow Queue]
{code}

So a job is stored and then discarded. This was running on a single node, not 
cluster

 Sling Jobs do not get picked at times
 -

 Key: SLING-4014
 URL: https://issues.apache.org/jira/browse/SLING-4014
 Project: Sling
  Issue Type: Bug
  Components: Extensions
Reporter: Amit Gupta
Assignee: Amit Gupta

 Sling jobs don't get picked at times. This what happened in one of the 
 scenario see attached logs

[jira] [Updated] (SLING-4014) Sling Jobs do not get picked at times

2014-10-08 Thread Amit Gupta (JIRA) 

 [ 
https://issues.apache.org/jira/browse/SLING-4014?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Amit Gupta updated SLING-4014:
--
Attachment: sling_event.log

 Sling Jobs do not get picked at times
 -

 Key: SLING-4014
 URL: https://issues.apache.org/jira/browse/SLING-4014
 Project: Sling
  Issue Type: Bug
  Components: Extensions
Reporter: Amit Gupta
Assignee: Amit Gupta
 Attachments: sling_event.log


 Sling jobs don't get picked at times. This what happened in one of the 
 scenario see attached logs for details.. but in a nutshell
 {code}
 07.10.2014 17:41:09.710 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Persisting job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 properties=com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.topics=org/apache/sling/event/job]
  into queue Granite Workflow Queue, 
 target=691ab81c-1dee-4504-bcff-962599fa602f
 07.10.2014 17:41:09.712 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Storing new job 
 {sling:resourceType=slingevent:Job, 
 slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f, 
 event.job.retries=10, 
 com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,
  event.job.retrycount=0, 
 com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,
  
 event.job.topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  slingevent:eventadmin=true, 
 slingevent:eventId=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-..at
  
 /var/eventing/jobs/assigned/691ab81c-1dee-4504-bcff-962599fa602f/slingevent:eventadmin/2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Job manager maintenance: 
 Starting #8828
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Checking for unprocessed 
 jobs...
 07.10.2014 17:41:14.748 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.MaintenanceTask Cleaning up job resource 
 tree: removing obsolete locks
 07.10.2014 17:41:30.680 *INFO* [Apache Sling Job Background Loader] 
 org.apache.sling.event Service [QueueMBean for queue Granite Workflow 
 Queue,3219] ServiceEvent REGISTERED
 07.10.2014 17:41:30.796 *INFO* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Starting job queue Granite Workflow Queue
 07.10.2014 17:41:30.796 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Configuration for job queue=Queue-Configuration(1817656049) : 
 {name=Granite Workflow Queue, type=TOPIC_ROUND_ROBIN, 
 topics=[SubPackageMatcher [packageName=com/adobe/granite/workflow/job/]], 
 maxParallelProcesses=4, retries=10, retryDelayInMs=2000, keepJobs=false, 
 preferRunOnCreationInstance=true, ownThreadPoolSize=0, serviceRanking=0, 
 pid=org.apache.sling.event.jobs.QueueConfiguration.3a3b109d-7e33-402b-972d-15eea0fc47bc,
  isValid=true}
 07.10.2014 17:41:30.800 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Discarding removed job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 id=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28,
  
 properties=slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.retries=10,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.job.retrycount=0,slingevent:created=java.util.GregorianCalendar(Tue
  Oct 07 17:41:09 IST 
 2014),sling:resourceType=slingevent:Job,com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,event.job.started.time=java.util.GregorianCalendar(Tue
  Oct 07 17:41:30 IST 
 2014),event.job.application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.queuename=Granite
  Workflow Queue]
 {code}
 So a job is stored and then discarded. This was running on a

[jira] [Updated] (SLING-4014) Sling Jobs do not get picked at times

2014-10-08 Thread Amit Gupta (JIRA) 

 [ 
https://issues.apache.org/jira/browse/SLING-4014?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Amit Gupta updated SLING-4014:
--
Affects Version/s: Event 3.3.14
   API 2.8.0

 Sling Jobs do not get picked at times
 -

 Key: SLING-4014
 URL: https://issues.apache.org/jira/browse/SLING-4014
 Project: Sling
  Issue Type: Bug
  Components: Extensions
Affects Versions: Event 3.3.14, API 2.8.0
Reporter: Amit Gupta
Assignee: Amit Gupta
 Attachments: sling_event.log


 Sling jobs don't get picked at times. This what happened in one of the 
 scenario see attached logs for details.. but in a nutshell
 {code}
 07.10.2014 17:41:09.710 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Persisting job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 properties=com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.topics=org/apache/sling/event/job]
  into queue Granite Workflow Queue, 
 target=691ab81c-1dee-4504-bcff-962599fa602f
 07.10.2014 17:41:09.712 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Storing new job 
 {sling:resourceType=slingevent:Job, 
 slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f, 
 event.job.retries=10, 
 com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,
  event.job.retrycount=0, 
 com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,
  
 event.job.topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  slingevent:eventadmin=true, 
 slingevent:eventId=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-..at
  
 /var/eventing/jobs/assigned/691ab81c-1dee-4504-bcff-962599fa602f/slingevent:eventadmin/2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Job manager maintenance: 
 Starting #8828
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Checking for unprocessed 
 jobs...
 07.10.2014 17:41:14.748 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.MaintenanceTask Cleaning up job resource 
 tree: removing obsolete locks
 07.10.2014 17:41:30.680 *INFO* [Apache Sling Job Background Loader] 
 org.apache.sling.event Service [QueueMBean for queue Granite Workflow 
 Queue,3219] ServiceEvent REGISTERED
 07.10.2014 17:41:30.796 *INFO* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Starting job queue Granite Workflow Queue
 07.10.2014 17:41:30.796 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Configuration for job queue=Queue-Configuration(1817656049) : 
 {name=Granite Workflow Queue, type=TOPIC_ROUND_ROBIN, 
 topics=[SubPackageMatcher [packageName=com/adobe/granite/workflow/job/]], 
 maxParallelProcesses=4, retries=10, retryDelayInMs=2000, keepJobs=false, 
 preferRunOnCreationInstance=true, ownThreadPoolSize=0, serviceRanking=0, 
 pid=org.apache.sling.event.jobs.QueueConfiguration.3a3b109d-7e33-402b-972d-15eea0fc47bc,
  isValid=true}
 07.10.2014 17:41:30.800 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Discarding removed job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 id=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28,
  
 properties=slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.retries=10,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.job.retrycount=0,slingevent:created=java.util.GregorianCalendar(Tue
  Oct 07 17:41:09 IST 
 2014),sling:resourceType=slingevent:Job,com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,event.job.started.time=java.util.GregorianCalendar(Tue
  Oct 07 17:41:30 IST 
 2014),event.job.application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.queuename=Granite
  Workflow

[jira] [Updated] (SLING-4014) Sling Jobs do not get picked at times

2014-10-08 Thread Amit Gupta (JIRA) 

 [ 
https://issues.apache.org/jira/browse/SLING-4014?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
 ]

Amit Gupta updated SLING-4014:
--
Attachment: SLING-4014.patch

Proposed patch

 Sling Jobs do not get picked at times
 -

 Key: SLING-4014
 URL: https://issues.apache.org/jira/browse/SLING-4014
 Project: Sling
  Issue Type: Bug
  Components: Extensions
Affects Versions: Event 3.3.14, API 2.8.0
Reporter: Amit Gupta
Assignee: Amit Gupta
 Attachments: SLING-4014.patch, sling_event.log


 Sling jobs don't get picked at times. This what happened in one of the 
 scenario see attached logs for details.. but in a nutshell
 {code}
 07.10.2014 17:41:09.710 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Persisting job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 properties=com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.topics=org/apache/sling/event/job]
  into queue Granite Workflow Queue, 
 target=691ab81c-1dee-4504-bcff-962599fa602f
 07.10.2014 17:41:09.712 *DEBUG* [Apache Sling Job Event Bridge] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Storing new job 
 {sling:resourceType=slingevent:Job, 
 slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f, 
 event.job.retries=10, 
 com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,
  event.job.retrycount=0, 
 com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,
  
 event.job.topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  slingevent:eventadmin=true, 
 slingevent:eventId=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-..at
  
 /var/eventing/jobs/assigned/691ab81c-1dee-4504-bcff-962599fa602f/slingevent:eventadmin/2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Job manager maintenance: 
 Starting #8828
 07.10.2014 17:41:14.747 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.JobManagerImpl Checking for unprocessed 
 jobs...
 07.10.2014 17:41:14.748 *DEBUG* [pool-8-thread-1] 
 org.apache.sling.event.impl.jobs.MaintenanceTask Cleaning up job resource 
 tree: removing obsolete locks
 07.10.2014 17:41:30.680 *INFO* [Apache Sling Job Background Loader] 
 org.apache.sling.event Service [QueueMBean for queue Granite Workflow 
 Queue,3219] ServiceEvent REGISTERED
 07.10.2014 17:41:30.796 *INFO* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Starting job queue Granite Workflow Queue
 07.10.2014 17:41:30.796 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Configuration for job queue=Queue-Configuration(1817656049) : 
 {name=Granite Workflow Queue, type=TOPIC_ROUND_ROBIN, 
 topics=[SubPackageMatcher [packageName=com/adobe/granite/workflow/job/]], 
 maxParallelProcesses=4, retries=10, retryDelayInMs=2000, keepJobs=false, 
 preferRunOnCreationInstance=true, ownThreadPoolSize=0, serviceRanking=0, 
 pid=org.apache.sling.event.jobs.QueueConfiguration.3a3b109d-7e33-402b-972d-15eea0fc47bc,
  isValid=true}
 07.10.2014 17:41:30.800 *DEBUG* [Apache Sling Job Queue Granite Workflow 
 Queue] 
 org.apache.sling.event.impl.jobs.queues.TopicRoundRobinJobQueue.Granite 
 Workflow Queue Discarding removed job Sling Job 
 [topic=com/adobe/granite/workflow/job/etc/workflow/models/dam-ips-asset-upload/jcr_content/model,
  
 id=2014/10/7/17/41/com.adobe.granite.workflow.job.etc.workflow.models.dam-ips-asset-upload.jcr_content.model_691ab81c-1dee-4504-bcff-962599fa602f_28,
  
 properties=slingevent:application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.retries=10,slingevent:eventadmin=true,com.adobe.granite.workflow.jobid=VolatileWorkItem_node1_etc_workflow_instances_2014-10-07_model_2913172457714876,event.job.retrycount=0,slingevent:created=java.util.GregorianCalendar(Tue
  Oct 07 17:41:09 IST 
 2014),sling:resourceType=slingevent:Job,com.adobe.granite.workflow.job=com.adobe.granite.workflow.job.WorkflowJob@639d8f67,event.job.started.time=java.util.GregorianCalendar(Tue
  Oct 07 17:41:30 IST 
 2014),event.job.application=691ab81c-1dee-4504-bcff-962599fa602f,event.job.queuename=Granite
  Workflow