Send Email option does not work at Workflow

[asato-aisi]

Hello , I'm using share of Alfresco 4.2.f.
Almost all function was working very good.

But from last week , when I create a new workflow "Send Email Notifications" option does not work
and displays an error "08290073 Failed to start workflow activiti$activitiReview:1:8.".

Without email option , workflow runs well.
Or if user is a member of ALFRESCO_ADMINISTRATORS group , it works.
(So I suspect user authority has some problem)

Here is my mail.* setting in alfresco-global.properties

mail.host=localhost
mail.port=25
mail.encoding=UTF-8
mail.smtp.auth=false

It worked well until 2 weeks ago…
Which log or config should I check for next step?

Thanks,

[mitpatoliya]

You need to check catalina.out for logs. You are referring to wrong file for email properties because with these properties it will surly does not work.
Check out alfresco-global.properties file within your extension directory.

[sscbrian]

Have you checked with your e-mail administrator for any changes?  2 things look suspicious to me (but could be just fine in your environment). 

1) Port 25 for relay is deprecated, port 587 is the new Mail Submission Agent port. 
2) Also, SMTP without AUTH is getting pretty rare these days.

[asato-aisi]

Thanks mitpatoliya-san , SSCBrian-san,

Postfix can receive SMTP from localhost by port 25.
I also checked by tcpdump and maillog , it's working.

I checked catalina.<date>.log.
Each log of failed/succeeded below

** start workflow failed (launched by user account) **

[MyIP] - [02/Oct/2014:14:09:24 +0900] "GET /share/service/components/form?htmlid=template_x002e_start-workflow_x002e_start-workflow_x0023_default-startWorkflowForm-alf-id3&itemKind=workflow&itemId=activiti%24activitiParallelReview&mode=create&submitType=json&showCaption=true&formUI=true&showCancelButton=true&destination=workspace%3A%2F%2FSpacesStore%2F3bdd9f9f-5e10-4f6f-a95d-4aedba23c1da HTTP/1.1" 200 44727

[MyIP] - [02/Oct/2014:14:09:33 +0900] "POST /share/proxy/alfresco/api/workflow/activiti$activitiParallelReview/formprocessor HTTP/1.1" 500 485

127.0.0.1 - [02/Oct/2014:14:09:33 +0900] "POST /alfresco/s/api/workflow/activiti$activitiParallelReview/formprocessor?alf_ticket=TICKET_669238f137b7311842cf14289f232a575e1d588b HTTP/1.1" 500 485


** start workflow succeeded (launched by admin) **

[MyIP] - [02/Oct/2014:14:13:02 +0900] "GET /share/service/components/form?htmlid=template_x002e_start-workflow_x002e_start-workflow_x0023_default-startWorkflowForm-alf-id3&itemKind=workflow&itemId=activiti%24activitiParallelReview&mode=create&submitType=json&showCaption=true&formUI=true&showCancelButton=true&destination=workspace%3A%2F%2FSpacesStore%2F3bdd9f9f-5e10-4f6f-a95d-4aedba23c1da&noCache=1412226781958 HTTP/1.1" 200 44727

[MyIP] - [02/Oct/2014:14:13:12 +0900] "POST /share/proxy/alfresco/api/workflow/activiti$activitiParallelReview/formprocessor?noCache=1412226790454 HTTP/1.1" 200 480

127.0.0.1 - [02/Oct/2014:14:13:12 +0900] "POST /alfresco/s/api/workflow/activiti$activitiParallelReview/formprocessor?noCache=1412226790454&alf_ticket=TICKET_d865bb59fe109f8e3c526136e9bae194ecf8a714 HTTP/1.1" 200 480

—

It seems only admin request contains "noCache" argument.
I'm not sure how this difference happens.

I backuped alf_data and tried fresh install alfresco.It's workflow runs well.
Then I stopped alfresco/DB ,  restored alf_data and restart alfresco/DB,
same problem occurred.
So I think it causes by broken repository or DB.
Someone else has similar problem ?