No logging for custom workflow from tutorial

[konart]

Hi!

I'm trying to get going with Alfresco and it's workflows and started with ecmarchitect's developer tutorials.

Right now I've managed to create a simple custom workflow and deploy it through amp installation (I can see it in workflow console and start it)
However I can see no output on catalina.out.

Here are some last lines from the log:

INFO: Deploying web application directory /home/konart/Alfresco/app/tomcat/webapps/host-manager
Jun 10, 2014 4:19:19 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /home/konart/Alfresco/app/tomcat/webapps/manager
Jun 10, 2014 4:19:19 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /home/konart/Alfresco/app/tomcat/webapps/ROOT
Jun 10, 2014 4:19:19 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8080"]
Jun 10, 2014 4:19:19 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-8009"]
Jun 10, 2014 4:19:19 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8443"]
Jun 10, 2014 4:19:19 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 136015 ms
2014-06-10 16:19:19,890  WARN  [solr.tracker.CoreTracker] [SolrTrackerScheduler_Worker-2] Tracking communication timed out.
 2014-06-10 16:19:19,898  WARN  [solr.tracker.CoreTracker] [SolrTrackerScheduler_Worker-3] Tracking communication timed out.
 2014-06-10 16:19:19,953  WARN  [scripts.solr.AlfrescoModelsDiff] [http-bio-8443-exec-2] Unable to fetch model changes from /alfresco/service/api/solr/modelsdiff
 2014-06-10 16:19:19,954  WARN  [scripts.solr.AlfrescoModelsDiff] [http-bio-8443-exec-1] Unable to fetch model changes from /alfresco/service/api/solr/modelsdiff
 2014-06-10 16:19:42,432  DEBUG [activiti.properties.ActivitiPropertyConverter] [http-bio-8080-exec-1] Task priority value (50) was invalid so it was set to the default value of 2. Task:User Task
 ‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Task priority value message is the only debug one there for user tasks

I also have this in my log4j.properties:

log4j.logger.org.alfresco.repo.workflow=DEBUG
log4j.logger.org.alfresco.repo.jscript.ScriptLogger=DEBUG
log4j.logger.org.alfresco.repo.jscript=error
‍‍‍‍‍

I've tried all kind of solutions from this forum and over the net, but still can't figure it out.
Any help here?

[kaynezhang]

Have you checked alfresco.log file ?

[konart]

Yes, I've looked through all log files just in case

[kaynezhang]

Where did you place your log4j files ?
what did you name it?
you should place and name it like below

   classpath*:alfresco/module/*/log4j.properties
   classpath*:alfresco/extension/*-log4j.properties
   classpath*:alfresco/extension/dev-log4j.properties
‍‍‍‍‍

[harikakosaraju]

Hello,

I have been getting the same error when creating a custom workflow.

12:23:08:430   DEBUG   [org.alfresco.repo.jscript.ScriptLogger] [http-bio-8080-exec-18] value of bpm:assignees size is 1
12:23:08:440   DEBUG   [org.alfresco.repo.jscript.RhinoScriptProcessor.calls] [http-bio-8080-exec-18] string script End 20 ms
12:23:08:446   DEBUG   [org.alfresco.repo.workflow.activiti.properties.ActivitiPropertyConverter] [http-bio-8080-exec-18] Task priority value (50) was invalid so it was set to the default value of 2. Task:Review Task
12:23:08:449   DEBUG   [org.alfresco.repo.jscript.ScriptLogger] [http-bio-8080-exec-18] org.alfresco.service.cmr.workflow.WorkflowException: 00210008 Failed to start workflow activiti$NASDAQParallelReview:1:7478.
12:23:08:450   DEBUG   [org.alfresco.repo.jscript.ScriptLogger] [http-bio-8080-exec-18] Returning 500 status code
12:23:08:450   DEBUG   [org.alfresco.repo.jscript.RhinoScriptProcessor.calls] [http-bio-8080-exec-18] form.post.json.js End 90 ms

[muralidharand]

Are you be able to debug the code? I believe few activiti exception are not throwing here.
If possible share the workflow details to check.

[voula_11]

Hi,
Im getting the same error with a custom workflow. When I try to start it, I get 'Failed to start workflow activiti$changeRequest:147:25207'.

2016-02-17 16:51:35,516 DEBUG [org.alfresco.repo.workflow.activiti.properties.ActivitiPropertyConverter] [http-apr-8080-exec-6] Task priority value (50) was invalid so it was set to the default value of 2. Taskelect Change Request Assignees
2016-02-17 16:51:35,525 DEBUG [org.alfresco.repo.jscript.ScriptLogger] [http-apr-8080-exec-6] org.alfresco.service.cmr.workflow.WorkflowException: 01170071 Failed to start workflow activiti$changeRequest:147:25207.
2016-02-17 16:51:35,525 DEBUG [org.alfresco.repo.jscript.ScriptLogger] [http-apr-8080-exec-6] Returning 500 status code

This workflow was working 2 days ago, and I haven't changed it, so I don't know what is going wrong. I have changed other stuff since then though (which I thought were unrelated).

Is the reason the workflow isn't starting actually because of the priority value?