JIRA-Plugin doesn't work!

[jonath...@materna.de]

Hallo together,

 

we try to install the “scm-jira-plugin” in version 1.10.

The version of our SCM-Manager is 1.30 and the version of our JIRA-Server is 6.0.1.

 

After installation and configuration we test it with an svn commit message like this: “Modify for TES-1”.

It works fine and over the SCM-Manager we are able to jump to the jira issue “TES-1”.

 

The problem is now, that the plugin couldn’t create the jira-comment at the issue “TES-1”.

Instead, the following Exception appears:

 

------------------------------------------------

[…..]

12:16:36.967 [Hook-6] INFO  sonia.scm.jira.SoapJiraHandler - add comment to issue TES-1

12:16:37.056 [Hook-6] ERROR sonia.scm.jira.JiraIssueHandler - could not close jira issue

sonia.scm.jira.JiraException: add comment failed

        at sonia.scm.jira.SoapJiraHandler.addComment(SoapJiraHandler.java:129) ~[scm-jira-plugin-1.10.jar:na]

        at sonia.scm.jira.JiraIssueHandler.updateIssue(JiraIssueHandler.java:234) [scm-jira-plugin-1.10.jar:na]

        at sonia.scm.jira.JiraIssueHandler.handleIssue(JiraIssueHandler.java:119) [scm-jira-plugin-1.10.jar:na]

[…..]

at sonia.scm.jira.SoapJiraHandler.addComment(SoapJiraHandler.java:125) ~[scm-jira-plugin-1.10.jar:na]

        ... 11 common frames omitted

12:16:37.061 [Hook-6] INFO  sonia.scm.jira.SoapJiraHandler - logout from jira

------------------------------------------------

 

The full Exception is attached to this mail.

 

Is there any solution for this problem?

 

Best regards,

Jonathan

[Sebastian Sdorra]

Hi,

The exception does not provide many informations. Could you enable trace logging? How do you authentication from scm-manager to jira? Do you use a central user directory or do you have specified a special user in the configuration of scm-manager? Do you use global configuration or per repository?

Sebastian

2013/6/25 <jonath...@materna.de>

--
You received this message because you are subscribed to the Google Groups "scmmanager" group.
To unsubscribe from this group and stop receiving emails from it, send an email to scmmanager+...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

[jonath...@materna.de]

Hi Sebastian,

 

thanks for your email.

 

The log with enabled trace logging is attached to this email.

 

For the authentication from scm-manager to jira we specified a special user in the configuration of scm-manager (the users we use for scm-manager and jira has the same names and passwords) but tried it although with a central user directory and without any user configuration in scm-manager. When we use the user configuration in the scm-manager, we tried the global configuration and the per repository configuration but both goes wrong. The exception is always the same one.

 

Best regards,

Jonathan

[Sebastian Sdorra]

Hi,

Has the jira user enough privileges to update the issue?

Sebastian

2013/6/28 <jonath...@materna.de>

[jonath...@materna.de]

Hi Sebastian,

 

yes, the user is able to login and update the issue. The Remote API is enabled!

[Sebastian Sdorra]

I've tried to reproduce the issue, i've setup a jira 6.0.3 and connected it with scm-manager and all works as expected. Did you see any problems in the log of jira? It is possible that you enable debug logging in jira?

Sebastian

[Jonathan Simon]

Hi,

we are also experiencing this issue.

To shed some light on it: It works with a git repository, but with Subversion we get the mentioned exception.

I can see an entry in the atlassian-jira-security.log:

[...] /rpc/soap/jirasoapservice-v2 The user 'jonathan.simon' has PASSED authentication.

So authentication doesn't seem to be the problem. (We are now using Crowd SSO with JIRA, but we tried the JIRA plugin with git before when we had JIRA's standard user management switched on, and I believe it doesn't make a difference.)

Yet another issue: If a JIRA issue has already comments from the SCM-Manager plugin referring to git commits and I try to reference it in a Subversion commit, I see the following in the scm-server.out log file:

15:12:53.692 [Hook-190] INFO  sonia.scm.jira.JiraIssueHandler - comment for changeset 8 already exists at issue XXX-4

If I can provide any help with troubleshooting or fixing these issues, please let me know.

Best regards

Jonathan

2013/6/28 <jonath...@materna.de>

 

2013/6/28 <jonath...@materna.de>

To unsubscribe from this group and stop receiving emails from it, send an email to scmmanager+unsubscribe@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

 

--
You received this message because you are subscribed to the Google Groups "scmmanager" group.

To unsubscribe from this group and stop receiving emails from it, send an email to scmmanager+unsubscribe@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

--
You received this message because you are subscribed to the Google Groups "scmmanager" group.
To unsubscribe from this group and stop receiving emails from it, send an email to scmmanager+...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

--
You received this message because you are subscribed to the Google Groups "scmmanager" group.
To unsubscribe from this group and stop receiving emails from it, send an email to scmmanager+...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

[Sebastian Sdorra]

Hi Jonathan,

It is possible to enable trace logging on scm-manager, reproduce the issues and post the log output?

Sebastian

2013/7/11 Jonathan Simon <der....@gmail.com>

[Sebastian Sdorra]

I think i found the "comment for changeset x already exists at issue xxx" bug.

Possible fix:

https://bitbucket.org/sdorra/scm-jira-plugin/commits/4690b0a04b410e331c155fe2b52571f0ade2b5b8

Could you please test version 1.11 of the jira plugin?

Sebastian

2013/7/15 Sebastian Sdorra <s.sd...@gmail.com>

[Sebastian Sdorra]

It is possible to provide a step by step tutorial to reproduce this issue? Because i've tried to reproduce this issue without luck.

Sebastian

2013/7/16 Sebastian Sdorra <s.sd...@gmail.com>

[Jonathan Simon]

Hi Sebastian,

I will try next week when I'm back in the office and let you know!

Best regards

Jonathan

[Alexey Kamchatkin]

Hi, i've tried to integrate jira-plugin, it doesnt work.  Could you tell me, have you decided question with plugin ?

пятница, 19 июля 2013 г., 0:11:27 UTC+4 пользователь Jonathan Simon написал:

[Alexey Kamchatkin]

i 've got log ( scm-manager 1.40 + jira-plugin 1.15)

2015-06-26 15:47:40.710 [EventBus-6] ERROR sonia.scm.jira.JiraIssueHandler - could not add comment to jira issue

sonia.scm.jira.JiraConnectException: Could not connect to jira instance at https://****.atlassian.net. Unknown error occurred.

at sonia.scm.jira.SoapJiraHandlerFactory.createJiraHandler(SoapJiraHandlerFactory.java:95) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueRequest.createJiraHandler(JiraIssueRequest.java:116) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueHandler.updateIssue(JiraIssueHandler.java:166) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueHandler.updateIssue(JiraIssueHandler.java:329) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueHandler.handleIssue(JiraIssueHandler.java:127) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraChangesetPreProcessor.process(JiraChangesetPreProcessor.java:116) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssuePostReceiveHook.handleIssueEvent(JiraIssuePostReceiveHook.java:168) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssuePostReceiveHook.onEvent(JiraIssuePostReceiveHook.java:122) [scm-jira-plugin-1.15.jar:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_65]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_65]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_65]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_65]

at com.google.common.eventbus.EventHandler.handleEvent(EventHandler.java:74) [guava-15.0.jar:na]

at com.google.common.eventbus.SynchronizedEventHandler.handleEvent(SynchronizedEventHandler.java:47) [guava-15.0.jar:na]

at com.google.common.eventbus.EventBus.dispatch(EventBus.java:314) [guava-15.0.jar:na]

at com.google.common.eventbus.AsyncEventBus.access$001(AsyncEventBus.java:34) [guava-15.0.jar:na]

at com.google.common.eventbus.AsyncEventBus$1.run(AsyncEventBus.java:100) [guava-15.0.jar:na]

at org.apache.shiro.subject.support.SubjectRunnable.doRun(SubjectRunnable.java:120) [shiro-core-1.2.3.jar:1.2.3]

at org.apache.shiro.subject.support.SubjectRunnable.run(SubjectRunnable.java:108) [shiro-core-1.2.3.jar:1.2.3]

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895) [na:1.6.0_65]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918) [na:1.6.0_65]

at java.lang.Thread.run(Thread.java:695) [na:1.6.0_65]

Caused by: org.apache.axis.AxisFault: ; nested exception is: 

javax.net.ssl.SSLException: java.lang.RuntimeException: Unexpected error: java.security.InvalidAlgorithmParameterException: the trustAnchors parameter must be non-empty

at org.apache.axis.AxisFault.makeFault(AxisFault.java:101) ~[axis-1.4.jar:na]

at org.apache.axis.transport.http.HTTPSender.invoke(HTTPSender.java:154) ~[axis-1.4.jar:na]

at org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32) ~[axis-1.4.jar:na]

at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118) ~[axis-1.4.jar:na]

at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83) ~[axis-1.4.jar:na]

at org.apache.axis.client.AxisClient.invoke(AxisClient.java:165) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invokeEngine(Call.java:2784) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2767) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2443) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2366) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:1812) ~[axis-1.4.jar:na]

at sonia.scm.jira.soap.JirasoapserviceV2SoapBindingStub.login(JirasoapserviceV2SoapBindingStub.java:5946) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.SoapJiraHandlerFactory.createJiraHandler(SoapJiraHandlerFactory.java:88) ~[scm-jira-plugin-1.15.jar:na]

... 21 common frames omitted

Caused by: javax.net.ssl.SSLException: java.lang.RuntimeException: Unexpected error: java.security.InvalidAlgorithmParameterException: the trustAnchors parameter must be non-empty

at com.sun.net.ssl.internal.ssl.Alerts.getSSLException(Alerts.java:190) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.fatal(SSLSocketImpl.java:1747) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.fatal(SSLSocketImpl.java:1708) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1691) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1222) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1199) ~[na:1.6]

at org.apache.axis.components.net.JSSESocketFactory.create(JSSESocketFactory.java:186) ~[axis-1.4.jar:na]

at org.apache.axis.transport.http.HTTPSender.getSocket(HTTPSender.java:191) ~[axis-1.4.jar:na]

at org.apache.axis.transport.http.HTTPSender.writeToSocket(HTTPSender.java:404) ~[axis-1.4.jar:na]

at org.apache.axis.transport.http.HTTPSender.invoke(HTTPSender.java:138) ~[axis-1.4.jar:na]

... 32 common frames omitted

Caused by: java.lang.RuntimeException: Unexpected error: java.security.InvalidAlgorithmParameterException: the trustAnchors parameter must be non-empty

at sun.security.validator.PKIXValidator.<init>(PKIXValidator.java:57) ~[na:1.6.0_65]

at sun.security.validator.Validator.getInstance(Validator.java:161) ~[na:1.6.0_65]

at com.sun.net.ssl.internal.ssl.X509TrustManagerImpl.getValidator(X509TrustManagerImpl.java:108) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.X509TrustManagerImpl.checkServerTrusted(X509TrustManagerImpl.java:204) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.X509TrustManagerImpl.checkServerTrusted(X509TrustManagerImpl.java:249) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.ClientHandshaker.serverCertificate(ClientHandshaker.java:1188) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.ClientHandshaker.processMessage(ClientHandshaker.java:135) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.Handshaker.processLoop(Handshaker.java:593) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.Handshaker.process_record(Handshaker.java:529) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:943) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.performInitialHandshake(SSLSocketImpl.java:1188) ~[na:1.6]

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1215) ~[na:1.6]

... 37 common frames omitted

Caused by: java.security.InvalidAlgorithmParameterException: the trustAnchors parameter must be non-empty

at java.security.cert.PKIXParameters.setTrustAnchors(PKIXParameters.java:183) ~[na:1.6.0_65]

at java.security.cert.PKIXParameters.<init>(PKIXParameters.java:103) ~[na:1.6.0_65]

at java.security.cert.PKIXBuilderParameters.<init>(PKIXBuilderParameters.java:87) ~[na:1.6.0_65]

at sun.security.validator.PKIXValidator.<init>(PKIXValidator.java:55) ~[na:1.6.0_65]

... 48 common frames omitted

вторник, 16 июля 2013 г., 12:21:08 UTC+4 пользователь Sebastian Sdorra написал:

[Sebastian Sdorra]

The error message means that you specified a truststore which is not available or not readable by the jvm. Please have a look at the following stackowerflow thread:

* http://stackoverflow.com/questions/6784463/error-trustanchors-parameter-must-be-non-empty

Sebastian

For more options, visit https://groups.google.com/d/optout.

[Alexey Kamchatkin]

Hello, in the other hand i use scm manager on my localhost, i 've tried to auto close and got log

--
You received this message because you are subscribed to a topic in the Google Groups "scmmanager" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/scmmanager/E2fPtqKDXWs/unsubscribe.
To unsubscribe from this group and all its topics, send an email to scmmanager+...@googlegroups.com.

[Alexey Kamchatkin]

I got log , scm manager on my localhost, 

2015-06-29 20:05:51.844 [EventBus-0] DEBUG sonia.scm.jira.JiraConfigurationResolver - repository configuration is disabled, try to use global configuration

2015-06-29 20:05:51.845 [EventBus-0] DEBUG sonia.scm.jira.JiraIssueRequestFactory - create jira issue request for user ap

2015-06-29 20:05:51.845 [EventBus-0] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.url.WebUIUrlProvider@30de3c87 to event bus, async = true

2015-06-29 20:05:51.846 [EventBus-0] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.url.RestXmlUrlProvider@6c908f05 to event bus, async = true

2015-06-29 20:05:51.846 [EventBus-0] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.jira.LinkHandler@2d95bbec to event bus, async = true

2015-06-29 20:05:51.847 [EventBus-0] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.jira.DefaultCommentTemplateHandler@41649a55 to event bus, async = true

2015-06-29 20:05:51.849 [EventBus-0] DEBUG sonia.scm.jira.JiraIssueHandler - found auto close word close for issue WM-8

2015-06-29 20:05:51.849 [EventBus-0] DEBUG sonia.scm.jira.JiraIssueHandler - try to close issue WM-8 because of changeset 862c11bd8dfae7da6a05d9b344105bddf25990ce

2015-06-29 20:05:51.850 [EventBus-0] DEBUG sonia.scm.jira.SoapJiraHandlerFactory - connect to jira https://pinkappgame.atlassian.net/rpc/soap/jirasoapservice-v2 as user ap

2015-06-29 20:05:51.853 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-06-29 20:05:51.859 [EventBus-1] DEBUG sonia.scm.jira.JiraConfigurationResolver - repository configuration is disabled, try to use global configuration

2015-06-29 20:05:51.862 [EventBus-1] DEBUG sonia.scm.jira.JiraGlobalContext - mark changeset 862c11bd8dfae7da6a05d9b344105bddf25990ce of repository CEOqlOFP31 as handled

2015-06-29 20:05:51.864 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-06-29 20:05:51.864 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 145.2 ms

2015-06-29 20:05:51.867 [EventBus-1] DEBUG sonia.scm.store.JAXBDataStore - put item CEOqlOFP31 to store

2015-06-29 20:05:51.869 [EventBus-1] DEBUG sonia.scm.statistic.StatisticManager - update statistic for repository test

2015-06-29 20:05:51.870 [EventBus-1] DEBUG sonia.scm.store.JAXBDataStore - put item CEOqlOFP31 to store

2015-06-29 20:05:52.683 [qtp948074059-22] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-06-29 20:05:52.684 [qtp948074059-22] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-06-29 20:05:52.684 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@6909506a to event bus, async = true

2015-06-29 20:05:52.685 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@627e9505 to event bus, async = true

2015-06-29 20:05:52.687 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-06-29 20:05:52.804 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-06-29 20:05:52.812 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-06-29 20:05:52.813 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 126.5 ms

2015-06-29 20:05:52.815 [qtp948074059-22] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-06-29 20:05:52.815 [qtp948074059-22] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-06-29 20:05:52.816 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@32be8e12 to event bus, async = true

2015-06-29 20:05:52.816 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@7c6159c4 to event bus, async = true

2015-06-29 20:05:52.818 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-06-29 20:05:52.941 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-06-29 20:05:52.950 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-06-29 20:05:52.950 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 132.3 ms

2015-06-29 20:05:53.267 [qtp948074059-22] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-06-29 20:05:53.267 [qtp948074059-22] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-06-29 20:05:53.268 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@5aa6343d to event bus, async = true

2015-06-29 20:05:53.268 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@55afbf49 to event bus, async = true

2015-06-29 20:05:53.270 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-06-29 20:05:53.486 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-06-29 20:05:53.495 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-06-29 20:05:53.495 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 225.5 ms

2015-06-29 20:05:53.497 [qtp948074059-22] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-06-29 20:05:53.497 [qtp948074059-22] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-06-29 20:05:53.498 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@552eec98 to event bus, async = true

2015-06-29 20:05:53.499 [qtp948074059-22] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@7baf1ad8 to event bus, async = true

2015-06-29 20:05:53.501 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-06-29 20:05:53.617 [qtp948074059-22] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-06-29 20:05:53.627 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-06-29 20:05:53.627 [qtp948074059-22] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 126.9 ms

2015-06-29 20:05:54.141 [EventBus-0] DEBUG sonia.scm.template.MustacheTemplate - render mustache template at sonia/scm/jira/template/autoclose.mustache

2015-06-29 20:05:54.144 [EventBus-0] INFO  sonia.scm.jira.SoapJiraHandler - try to close issue WM-8

2015-06-29 20:05:58.506 [EventBus-0] ERROR sonia.scm.jira.JiraIssueHandler - could not close jira issue

sonia.scm.jira.JiraException: Failed to close issue WM-8. Unknown error occurred.

at sonia.scm.jira.JiraExceptions.propagate(JiraExceptions.java:135) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.SoapJiraHandler.close(SoapJiraHandler.java:157) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueHandler.closeIssue(JiraIssueHandler.java:215) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssueHandler.handleIssue(JiraIssueHandler.java:118) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraChangesetPreProcessor.process(JiraChangesetPreProcessor.java:116) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssuePostReceiveHook.handleIssueEvent(JiraIssuePostReceiveHook.java:168) [scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.JiraIssuePostReceiveHook.onEvent(JiraIssuePostReceiveHook.java:122) [scm-jira-plugin-1.15.jar:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_65]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_65]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_65]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_65]

at com.google.common.eventbus.EventHandler.handleEvent(EventHandler.java:74) [guava-15.0.jar:na]

at com.google.common.eventbus.SynchronizedEventHandler.handleEvent(SynchronizedEventHandler.java:47) [guava-15.0.jar:na]

at com.google.common.eventbus.EventBus.dispatch(EventBus.java:314) [guava-15.0.jar:na]

at com.google.common.eventbus.AsyncEventBus.access$001(AsyncEventBus.java:34) [guava-15.0.jar:na]

at com.google.common.eventbus.AsyncEventBus$1.run(AsyncEventBus.java:100) [guava-15.0.jar:na]

at org.apache.shiro.subject.support.SubjectRunnable.doRun(SubjectRunnable.java:120) [shiro-core-1.2.3.jar:1.2.3]

at org.apache.shiro.subject.support.SubjectRunnable.run(SubjectRunnable.java:108) [shiro-core-1.2.3.jar:1.2.3]

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:895) [na:1.6.0_65]

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:918) [na:1.6.0_65]

at java.lang.Thread.run(Thread.java:695) [na:1.6.0_65]

Caused by: sonia.scm.jira.soap.RemoteException: null

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[na:1.6.0_65]

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39) ~[na:1.6.0_65]

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27) ~[na:1.6.0_65]

at java.lang.reflect.Constructor.newInstance(Constructor.java:513) ~[na:1.6.0_65]

at java.lang.Class.newInstance0(Class.java:357) ~[na:1.6.0_65]

at java.lang.Class.newInstance(Class.java:310) ~[na:1.6.0_65]

at org.apache.axis.encoding.ser.BeanDeserializer.<init>(BeanDeserializer.java:104) ~[axis-1.4.jar:na]

at org.apache.axis.encoding.ser.BeanDeserializer.<init>(BeanDeserializer.java:90) ~[axis-1.4.jar:na]

at sonia.scm.jira.soap.RemoteException.getDeserializer(RemoteException.java:75) ~[scm-jira-plugin-1.15.jar:na]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.6.0_65]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) ~[na:1.6.0_65]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) ~[na:1.6.0_65]

at java.lang.reflect.Method.invoke(Method.java:597) ~[na:1.6.0_65]

at org.apache.axis.encoding.ser.BaseDeserializerFactory.getSpecialized(BaseDeserializerFactory.java:154) ~[axis-1.4.jar:na]

at org.apache.axis.encoding.ser.BaseDeserializerFactory.getDeserializerAs(BaseDeserializerFactory.java:84) ~[axis-1.4.jar:na]

at org.apache.axis.encoding.DeserializationContext.getDeserializer(DeserializationContext.java:464) ~[axis-1.4.jar:na]

at org.apache.axis.encoding.DeserializationContext.getDeserializerForType(DeserializationContext.java:547) ~[axis-1.4.jar:na]

at org.apache.axis.message.SOAPFaultDetailsBuilder.onStartChild(SOAPFaultDetailsBuilder.java:157) ~[axis-1.4.jar:na]

at org.apache.axis.encoding.DeserializationContext.startElement(DeserializationContext.java:1035) ~[axis-1.4.jar:na]

at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.startElement(AbstractSAXParser.java:504) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.parsers.AbstractXMLDocumentParser.emptyElement(AbstractXMLDocumentParser.java:179) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.scanStartElement(XMLNSDocumentScannerImpl.java:378) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl$FragmentContentDriver.next(XMLDocumentFragmentScannerImpl.java:2763) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.impl.XMLDocumentScannerImpl.next(XMLDocumentScannerImpl.java:647) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.impl.XMLNSDocumentScannerImpl.next(XMLNSDocumentScannerImpl.java:140) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.impl.XMLDocumentFragmentScannerImpl.scanDocument(XMLDocumentFragmentScannerImpl.java:513) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:815) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.parsers.XML11Configuration.parse(XML11Configuration.java:744) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.parsers.XMLParser.parse(XMLParser.java:128) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.parsers.AbstractSAXParser.parse(AbstractSAXParser.java:1208) ~[na:1.6.0_65]

at com.sun.org.apache.xerces.internal.jaxp.SAXParserImpl$JAXPSAXParser.parse(SAXParserImpl.java:543) ~[na:1.6.0_65]

at javax.xml.parsers.SAXParser.parse(SAXParser.java:395) ~[na:1.6.0_65]

at org.apache.axis.encoding.DeserializationContext.parse(DeserializationContext.java:227) ~[axis-1.4.jar:na]

at org.apache.axis.SOAPPart.getAsSOAPEnvelope(SOAPPart.java:696) ~[axis-1.4.jar:na]

at org.apache.axis.Message.getSOAPEnvelope(Message.java:435) ~[axis-1.4.jar:na]

at org.apache.axis.handlers.soap.MustUnderstandChecker.invoke(MustUnderstandChecker.java:62) ~[axis-1.4.jar:na]

at org.apache.axis.client.AxisClient.invoke(AxisClient.java:206) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invokeEngine(Call.java:2784) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2767) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2443) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:2366) ~[axis-1.4.jar:na]

at org.apache.axis.client.Call.invoke(Call.java:1812) ~[axis-1.4.jar:na]

at sonia.scm.jira.soap.JirasoapserviceV2SoapBindingStub.progressWorkflowAction(JirasoapserviceV2SoapBindingStub.java:6913) ~[scm-jira-plugin-1.15.jar:na]

at sonia.scm.jira.SoapJiraHandler.close(SoapJiraHandler.java:152) ~[scm-jira-plugin-1.15.jar:na]

... 19 common frames omitted

2015-06-29 20:05:58.509 [EventBus-0] INFO  sonia.scm.jira.SoapJiraHandler - logout from jira

--
You received this message because you are subscribed to a topic in the Google Groups "scmmanager" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/scmmanager/E2fPtqKDXWs/unsubscribe.
To unsubscribe from this group and all its topics, send an email to scmmanager+...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

[Sebastian Sdorra]

This could be related to your jira workflow. Is the issue in a status which is closable? Could you post the available entries of your jira workflow?

Sebastian

For more options, visit https://groups.google.com/d/optout.

[Alexey Kamchatkin]

Where can i look at my jira workflow ? And what will happen if i mark commit with "close" ?

среда, 1 июля 2015 г., 22:49:26 UTC+3 пользователь Sebastian Sdorra написал:

[Alexey Kamchatkin]

we are using - Simple Issue Tracking Workflow

среда, 1 июля 2015 г., 22:49:26 UTC+3 пользователь Sebastian Sdorra написал:

This could be related to your jira workflow. Is the issue in a status which is closable? Could you post the available entries of your jira workflow?

Sebastian

...

[Alexey Kamchatkin]

so here is my full log

2015-07-07 12:58:17.948 [qtp1368348708-51] DEBUG sonia.scm.web.security.DefaultAuthenticationHandler - user ap successfully prepared for login

2015-07-07 12:58:17.949 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticator sonia.scm.web.security.DefaultAuthenticationHandler ends with result, user: ap, state: SUCCESS

2015-07-07 12:58:17.949 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - post AuthenticationEvent{user=User{name=ap, displayName=ap, mail=a...@pinkappgames.com, password=(not set), admin=false, type=xml, active=true, creationDate=1435319052818, lastModified=1435319107183, properties={}}} to event bus

2015-07-07 12:58:17.949 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:17.950 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@10ced1ac to event bus, async = true

2015-07-07 12:58:17.950 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@7a277bd2 to event bus, async = true

2015-07-07 12:58:17.952 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:18.063 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:18.072 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:18.072 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 120.7 ms

2015-07-07 12:58:18.073 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:18.074 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:18.075 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@3a6bf80b to event bus, async = true

2015-07-07 12:58:18.075 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@669b824 to event bus, async = true

2015-07-07 12:58:18.077 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:18.187 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:18.196 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:18.196 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 120.0 ms

2015-07-07 12:58:21.143 [qtp1368348708-53] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.144 [qtp1368348708-53] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.145 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@4986c47 to event bus, async = true

2015-07-07 12:58:21.145 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@32ecabac to event bus, async = true

2015-07-07 12:58:21.147 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.257 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.267 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.268 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 121.0 ms

2015-07-07 12:58:21.271 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.271 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.272 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@51d36f77 to event bus, async = true

2015-07-07 12:58:21.272 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@103b1799 to event bus, async = true

2015-07-07 12:58:21.274 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.383 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.392 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.392 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 118.6 ms

2015-07-07 12:58:21.393 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.394 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.395 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@3a765e8c to event bus, async = true

2015-07-07 12:58:21.395 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@31395750 to event bus, async = true

2015-07-07 12:58:21.397 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.505 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.513 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.514 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 117.2 ms

2015-07-07 12:58:21.515 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.515 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.516 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@19c42c4b to event bus, async = true

2015-07-07 12:58:21.516 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@1e37fd24 to event bus, async = true

2015-07-07 12:58:21.518 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.627 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.635 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.636 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 118.0 ms

2015-07-07 12:58:21.638 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.638 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.639 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@71a1644b to event bus, async = true

2015-07-07 12:58:21.640 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@1abfb235 to event bus, async = true

2015-07-07 12:58:21.642 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.750 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.757 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.758 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 116.4 ms

2015-07-07 12:58:21.759 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.759 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.760 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@1df7670d to event bus, async = true

2015-07-07 12:58:21.760 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@5d8e95d9 to event bus, async = true

2015-07-07 12:58:21.762 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.875 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:21.882 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:21.882 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 120.3 ms

2015-07-07 12:58:21.883 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:21.884 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:21.885 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@30d8f246 to event bus, async = true

2015-07-07 12:58:21.885 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@7309eab7 to event bus, async = true

2015-07-07 12:58:21.887 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:21.996 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:22.003 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:22.003 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 117.1 ms

2015-07-07 12:58:22.016 [qtp1368348708-53] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:22.016 [qtp1368348708-53] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:22.017 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@6fb9e7df to event bus, async = true

2015-07-07 12:58:22.017 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@16261ac7 to event bus, async = true

2015-07-07 12:58:22.020 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:22.138 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:22.138 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:22.139 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.HgContextProvider@5f98339a to event bus, async = true

2015-07-07 12:58:22.139 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@163f47e to event bus, async = true

2015-07-07 12:58:22.140 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@49487ce8 to event bus, async = true

2015-07-07 12:58:22.140 [qtp1368348708-51] DEBUG sonia.scm.repository.api.HookContextFactory - create new hook context for repository test

2015-07-07 12:58:22.140 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - post sonia.scm.repository.PreReceiveRepositoryHookEvent@360088ee to event bus

2015-07-07 12:58:22.145 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:22.145 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:22.146 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@6e6ca837 to event bus, async = true

2015-07-07 12:58:22.146 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@1f97eb84 to event bus, async = true

2015-07-07 12:58:22.146 [qtp1368348708-51] DEBUG sonia.scm.repository.api.HookContextFactory - create new hook context for repository test

2015-07-07 12:58:22.146 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - post sonia.scm.repository.PostReceiveRepositoryHookEvent@2ee73750 to event bus

2015-07-07 12:58:22.148 [EventBus-11] DEBUG sonia.scm.statistic.StatisticListener - update statistic of repository test

2015-07-07 12:58:22.148 [EventBus-12] DEBUG sonia.scm.jira.JiraConfigurationResolver - repository configuration is disabled, try to use global configuration

2015-07-07 12:58:22.148 [EventBus-12] DEBUG sonia.scm.repository.api.HookContext - create changeset provider for repository test

2015-07-07 12:58:22.148 [Hook-2] DEBUG sonia.scm.repository.RepositoryHookTask - execute async POST_RECEIVE hook sonia.scm.repository.api.RepositoryServiceFactory$CacheClearHook for repository test

2015-07-07 12:58:22.148 [Hook-2] DEBUG sonia.scm.repository.api.RepositoryServiceFactory - clear caches for repository id CEOqlOFP31

2015-07-07 12:58:22.157 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:22.170 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:22.170 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 150.6 ms

2015-07-07 12:58:22.171 [EventBus-10] INFO  sonia.scm.web.security.DefaultAdministrationContext - user ap executes sonia.scm.repository.LastModifiedUpdateListener$LastModifiedPrivilegedAction as admin

2015-07-07 12:58:22.171 [EventBus-10] DEBUG sonia.scm.security.AuthorizationCollector - grant admin role for user scmsystem

2015-07-07 12:58:22.172 [EventBus-10] INFO  sonia.scm.repository.LastModifiedUpdateListener - update last modified date of repository CEOqlOFP31

2015-07-07 12:58:22.172 [EventBus-10] INFO  sonia.scm.repository.DefaultRepositoryManager - modify repository test of type hg

2015-07-07 12:58:22.173 [EventBus-11] DEBUG sonia.scm.repository.api.HookContext - create changeset provider for repository test

2015-07-07 12:58:22.173 [EventBus-10] DEBUG sonia.scm.event.GuavaScmEventBus - post RepositoryEvent{eventType=BEFORE_MODIFY, repository=Repository{id=CEOqlOFP31, name=test, contact=, description=test http, publicReadable=true, archived=false, permissions=[Permission{name=ap, type=WRITE, groupPermission=false}, Permission{name=asp, type=WRITE, groupPermission=false}], type=hg, url=null, lastModified=1436259502172, creationDate=1411544828157, properties={jira.url=https://pinkappgame.atlassian.net, jira.username=, jira.comment-prefix=[SCM], jira.mail-error-address=, jira.auto-close=true, jira.role-level=, jira.update-issues=true, jira.resubmission=false, jira.password=, jira.auto-close-words=fixed, fix, closed, close, resolved, resolve}, healthCheckFailures=null}} to event bus

2015-07-07 12:58:22.173 [qtp1368348708-53] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:22.174 [qtp1368348708-53] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:22.174 [EventBus-10] DEBUG sonia.scm.store.JAXBStore - store sonia.scm.repository.xml.XmlRepositoryDatabase to /Users/asp/.scm/config/repositories.xml

2015-07-07 12:58:22.175 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@401419d0 to event bus, async = true

2015-07-07 12:58:22.175 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@3b9c5ce6 to event bus, async = true

2015-07-07 12:58:22.175 [EventBus-10] DEBUG sonia.scm.event.GuavaScmEventBus - post RepositoryEvent{eventType=MODIFY, repository=Repository{id=CEOqlOFP31, name=test, contact=, description=test http, publicReadable=true, archived=false, permissions=[Permission{name=ap, type=WRITE, groupPermission=false}, Permission{name=asp, type=WRITE, groupPermission=false}], type=hg, url=null, lastModified=1436259502174, creationDate=1411544828157, properties={jira.url=https://pinkappgame.atlassian.net, jira.username=, jira.comment-prefix=[SCM], jira.mail-error-address=, jira.auto-close=true, jira.role-level=, jira.update-issues=true, jira.resubmission=false, jira.password=, jira.auto-close-words=fixed, fix, closed, close, resolved, resolve}, healthCheckFailures=null}} to event bus

2015-07-07 12:58:22.175 [EventBus-14] DEBUG sonia.scm.security.AuthorizationCollector - clear cache, because repository test has changed

2015-07-07 12:58:22.176 [EventBus-10] DEBUG sonia.scm.web.security.DefaultAdministrationContext - release administration context for user ap/ap

2015-07-07 12:58:22.176 [EventBus-14] DEBUG sonia.scm.cache.GuavaCache - clear cache sonia.cache.authorizing

2015-07-07 12:58:22.177 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:22.228 [EventBus-12] DEBUG sonia.scm.jira.JiraConfigurationResolver - repository configuration is disabled, try to use global configuration

2015-07-07 12:58:22.229 [EventBus-12] DEBUG sonia.scm.jira.JiraIssueRequestFactory - create jira issue request for user ap

2015-07-07 12:58:22.229 [EventBus-12] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.url.WebUIUrlProvider@4ba0bb6f to event bus, async = true

2015-07-07 12:58:22.229 [EventBus-12] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.url.RestXmlUrlProvider@25719b33 to event bus, async = true

2015-07-07 12:58:22.229 [EventBus-12] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.jira.LinkHandler@45844479 to event bus, async = true

2015-07-07 12:58:22.229 [EventBus-12] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.jira.DefaultCommentTemplateHandler@6eab8796 to event bus, async = true

2015-07-07 12:58:22.229 [EventBus-11] DEBUG sonia.scm.jira.JiraConfigurationResolver - repository configuration is disabled, try to use global configuration

2015-07-07 12:58:22.231 [EventBus-11] DEBUG sonia.scm.jira.JiraGlobalContext - mark changeset a17de68abb6ce297e7469eff5a216ed60ab322cd of repository CEOqlOFP31 as handled

2015-07-07 12:58:22.231 [EventBus-12] DEBUG sonia.scm.jira.JiraIssueHandler - found auto close word fix for issue WM-38

2015-07-07 12:58:22.231 [EventBus-12] DEBUG sonia.scm.jira.JiraIssueHandler - try to close issue WM-38 because of changeset a17de68abb6ce297e7469eff5a216ed60ab322cd

2015-07-07 12:58:22.232 [EventBus-12] DEBUG sonia.scm.jira.SoapJiraHandlerFactory - connect to jira https://pinkappgame.atlassian.net/rpc/soap/jirasoapservice-v2 as user ap

2015-07-07 12:58:22.233 [EventBus-11] DEBUG sonia.scm.store.JAXBDataStore - put item CEOqlOFP31 to store

2015-07-07 12:58:22.233 [EventBus-11] DEBUG sonia.scm.statistic.StatisticManager - update statistic for repository test

2015-07-07 12:58:22.234 [EventBus-11] DEBUG sonia.scm.store.JAXBDataStore - put item CEOqlOFP31 to store

2015-07-07 12:58:22.287 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:22.295 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:22.296 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 119.5 ms

2015-07-07 12:58:23.166 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:23.166 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:23.167 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@2d35dd72 to event bus, async = true

2015-07-07 12:58:23.167 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@2b5d7e8e to event bus, async = true

2015-07-07 12:58:23.169 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:23.282 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:23.294 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:23.294 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 125.9 ms

2015-07-07 12:58:23.295 [qtp1368348708-51] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:23.296 [qtp1368348708-51] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:23.297 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@613500b1 to event bus, async = true

2015-07-07 12:58:23.297 [qtp1368348708-51] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@5ec09652 to event bus, async = true

2015-07-07 12:58:23.299 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:23.411 [qtp1368348708-51] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:23.419 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:23.419 [qtp1368348708-51] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 120.3 ms

2015-07-07 12:58:23.727 [qtp1368348708-53] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:23.727 [qtp1368348708-53] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:23.728 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@79e0123b to event bus, async = true

2015-07-07 12:58:23.728 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@65cd1dff to event bus, async = true

2015-07-07 12:58:23.730 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:23.843 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:23.852 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:23.853 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 123.0 ms

2015-07-07 12:58:23.854 [qtp1368348708-53] DEBUG sonia.scm.web.security.ChainAuthenticatonManager - authenticate ap via cache

2015-07-07 12:58:23.855 [qtp1368348708-53] DEBUG sonia.scm.security.ScmRealm - user ap is member of _authenticated

2015-07-07 12:58:23.856 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.RepositoryManagerProvider@7ed69cff to event bus, async = true

2015-07-07 12:58:23.856 [qtp1368348708-53] DEBUG sonia.scm.event.GuavaScmEventBus - register sonia.scm.repository.DefaultRepositoryProvider@3bdd6a67 to event bus, async = true

2015-07-07 12:58:23.860 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - execute cgi: python /Users/asp/.scm/lib/python/hgweb.py

2015-07-07 12:58:23.971 [qtp1368348708-53] DEBUG sonia.scm.web.cgi.DefaultCGIExecutor - CGI returned with status 200

2015-07-07 12:58:23.979 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIExceptionHandler - mercurial process ends successfully

2015-07-07 12:58:23.980 [qtp1368348708-53] DEBUG sonia.scm.web.HgCGIServlet - mercurial request finished in 120.9 ms

2015-07-07 12:58:24.105 [EventBus-12] DEBUG sonia.scm.template.MustacheTemplate - render mustache template at sonia/scm/jira/template/autoclose.mustache

2015-07-07 12:58:24.106 [EventBus-12] INFO  sonia.scm.jira.SoapJiraHandler - try to close issue WM-38

2015-07-07 12:58:26.006 [EventBus-12] ERROR sonia.scm.jira.JiraIssueHandler - could not close jira issue

sonia.scm.jira.JiraException: Failed to close issue WM-38. Unknown error occurred.

2015-07-07 12:58:26.008 [EventBus-12] INFO  sonia.scm.jira.SoapJiraHandler - logout from jira

 

use 1.46 scmamnager & jira plugin 1.15.

My jira workflow (see pic at attachment)

среда, 1 июля 2015 г., 22:49:26 UTC+3 пользователь Sebastian Sdorra написал:

This could be related to your jira workflow. Is the issue in a status which is closable? Could you post the available entries of your jira workflow?

Sebastian

[Sebastian Sdorra]

It looks like you are not able to access the close state from the current state of your issue. Could you try to use the resolved keyword in your commit message? The resolved state should be accessible from the current state (in progress) of your issue.

Sebastian

[Alexey Kamchatkin]

Thanks a lot Sebastian, i've used - "resolve" keyword and this works!

вторник, 7 июля 2015 г., 22:26:03 UTC+3 пользователь Sebastian Sdorra написал:

...

[Jan-Erik Hansen]

Hi Sebastian,

I tried to connect my SCM Manager to a JIRA instance and it seems to be working. But when I try to update an issue based on a commit, I get the following exception:
2017-02-24 12:41:14.787 [EventBus-5] ERROR sonia.scm.jira.JiraIssueHandler - could not add comment to jira issue
sonia.scm.jira.JiraException: failed to transform response from CT-3. Unknown error occurred.
    at sonia.scm.jira.JiraExceptions.propagate(JiraExceptions.java:135)
    at sonia.scm.jira.rest.RestJiraHandler.isCommentAlreadyExists(RestJiraHandler.java:218)
    at sonia.scm.jira.JiraIssueHandler.updateIssue(JiraIssueHandler.java:169)
    at sonia.scm.jira.JiraIssueHandler.updateIssue(JiraIssueHandler.java:317)
    at sonia.scm.jira.JiraIssueHandler.handleIssue(JiraIssueHandler.java:133)
    at sonia.scm.jira.JiraHookProcessor.process(JiraHookProcessor.java:82)
    at sonia.scm.jira.JiraIssuePostReceiveHook.handleIssueEvent(JiraIssuePostReceiveHook.java:165)
    at sonia.scm.jira.JiraIssuePostReceiveHook.onEvent(JiraIssuePostReceiveHook.java:123)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at com.google.common.eventbus.EventHandler.handleEvent(EventHandler.java:74)
    at com.google.common.eventbus.SynchronizedEventHandler.handleEvent(SynchronizedEventHandler.java:47)
    at com.google.common.eventbus.EventBus.dispatch(EventBus.java:314)
    at com.google.common.eventbus.AsyncEventBus.access$001(AsyncEventBus.java:34)
    at com.google.common.eventbus.AsyncEventBus$1.run(AsyncEventBus.java:100)
    at org.apache.shiro.subject.support.SubjectRunnable.doRun(SubjectRunnable.java:120)
    at org.apache.shiro.subject.support.SubjectRunnable.run(SubjectRunnable.java:108)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
    at java.lang.Thread.run(Thread.java:745)
Caused by: sonia.scm.net.ahc.ContentTransformerException: could not unmarshall content
    at sonia.scm.net.ahc.JsonContentTransformer.unmarshall(JsonContentTransformer.java:124)
    at sonia.scm.net.ahc.AdvancedHttpResponse.contentTransformed(AdvancedHttpResponse.java:279)
    at sonia.scm.net.ahc.AdvancedHttpResponse.contentFromJson(AdvancedHttpResponse.java:207)
    at sonia.scm.jira.rest.RestJiraHandler.isCommentAlreadyExists(RestJiraHandler.java:203)
    ... 20 common frames omitted
Caused by: org.codehaus.jackson.JsonParseException: Unexpected character ('<' (code 60)): expected a valid value (number, String, array, object, 'true', 'false' or 'null')
 at [Source: java.io.BufferedInputStream@7daa5563; line: 1, column: 2]
    at org.codehaus.jackson.JsonParser._constructError(JsonParser.java:1432)
    at org.codehaus.jackson.impl.JsonParserMinimalBase._reportError(JsonParserMinimalBase.java:385)
    at org.codehaus.jackson.impl.JsonParserMinimalBase._reportUnexpectedChar(JsonParserMinimalBase.java:306)
    at org.codehaus.jackson.impl.Utf8StreamParser._handleUnexpectedValue(Utf8StreamParser.java:2084)
    at org.codehaus.jackson.impl.Utf8StreamParser._nextTokenNotInObject(Utf8StreamParser.java:600)
    at org.codehaus.jackson.impl.Utf8StreamParser.nextToken(Utf8StreamParser.java:486)
    at org.codehaus.jackson.map.ObjectMapper._initForReading(ObjectMapper.java:2761)
    at org.codehaus.jackson.map.ObjectMapper._readMapAndClose(ObjectMapper.java:2709)
    at org.codehaus.jackson.map.ObjectMapper.readValue(ObjectMapper.java:1900)
    at sonia.scm.net.ahc.JsonContentTransformer.unmarshall(JsonContentTransformer.java:120)
    ... 23 common frames omitted

There seems to be a problem when parsing the JSON for sending it to the JIRA instance.

Do you have any idea what the exact problem could be?

Kind Regards

Jan-Erik Hansen