poll SCM doesn't work

classic Classic list List threaded Threaded
22 messages Options
12
Reply | Threaded
Open this post in threaded view
|

poll SCM doesn't work

Arnaud LACOUR
Hi hudson users, I have one job that is configured to poll the SCM
every minute to trigger a build as soon as someone commits something
on the first available box but it is never triggered ever. the only
build I have is the first one I did manually to check that all
parameters would lead to a successful build. And it did succeed. When
I look at the polling log there's just a timestamp there, nothing
else. Any easy way to troubleshoot the polling stuff ?
cheers
-=arnaud=-

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
Arnaud LACOUR wrote:
> Hi hudson users, I have one job that is configured to poll the SCM
> every minute to trigger a build as soon as someone commits something
> on the first available box but it is never triggered ever. the only
> build I have is the first one I did manually to check that all
> parameters would lead to a successful build. And it did succeed. When
> I look at the polling log there's just a timestamp there, nothing
> else. Any easy way to troubleshoot the polling stuff ?

The normal output should look like this:

> Started on Apr 12, 2007 5:31:29 PM
> Done. Took 0 seconds
> No changes

If you are only seeing the first line, maybe it's stuck somewhere. Can
you get the stack trace dump? Does this involve master/slave?


--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Arnaud LACOUR
On 4/12/07, Kohsuke Kawaguchi <[hidden email]> wrote:

> Arnaud LACOUR wrote:
> > Hi hudson users, I have one job that is configured to poll the SCM
> > every minute to trigger a build as soon as someone commits something
> > on the first available box but it is never triggered ever. the only
> > build I have is the first one I did manually to check that all
> > parameters would lead to a successful build. And it did succeed. When
> > I look at the polling log there's just a timestamp there, nothing
> > else. Any easy way to troubleshoot the polling stuff ?
>
> The normal output should look like this:
>
> > Started on Apr 12, 2007 5:31:29 PM
> > Done. Took 0 seconds
> > No changes
>
> If you are only seeing the first line, maybe it's stuck somewhere. Can
> you get the stack trace dump?
it's not stuck, it went through and I did try this a number of time.
but I can a stack dump if you like ... if you tell me how to get it...
> Does this involve master/slave?
yes it does involve master/slave since this build is roaming only on
Unix slaves.
>
>
> --
> Kohsuke Kawaguchi
> Sun Microsystems                   [hidden email]
>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
Arnaud LACOUR wrote:
>> If you are only seeing the first line, maybe it's stuck somewhere. Can
>> you get the stack trace dump?
> it's not stuck, it went through and I did try this a number of time.
> but I can a stack dump if you like ... if you tell me how to get it...

OK, so what you are really saying that Hudson doesn't detect any changes
despite the fact that you have changes.

Can you paste the SVN polling log?

--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
Jumping on the thread, since I am experiencing similar issues as Arnaud.

Subversion polling was working for me about a week ago. Since then, I have upgraded Hudson and modified my build configurations, and it was only today that I noticed that polling was not working.

Before:
- Hudson 1.93
- Master node
- file:/// protocol

After:
- Hudson 1.102
- Slave node
- http:// protocol

The Subversion polling log has a single line:
Started on Apr 18, 2007 1:26:25 AM

The System log similarly reads:
Apr 18, 2007 1:26:25 AM hudson.triggers.SCMTrigger$Runner runPolling
INFO: Polling SCM changes of ...

but I am not seeing stack traces in these two logs. Is there any other place which I should be looking for signs of problems?

Let me know if tweaking my configuration will help debug the problem, and thanks in advance!

- Kian Win
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Arnaud LACOUR
In reply to this post by Kohsuke Kawaguchi
On 4/14/07, Kohsuke Kawaguchi <[hidden email]> wrote:

> Arnaud LACOUR wrote:
> >> If you are only seeing the first line, maybe it's stuck somewhere. Can
> >> you get the stack trace dump?
> > it's not stuck, it went through and I did try this a number of time.
> > but I can a stack dump if you like ... if you tell me how to get it...
>
> OK, so what you are really saying that Hudson doesn't detect any changes
> despite the fact that you have changes.
>
> Can you paste the SVN polling log?
there's nothing to paste I'm afraid
>
> --
> Kohsuke Kawaguchi
> Sun Microsystems                   [hidden email]
>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
In reply to this post by Kianwin Ong
Thanks. There's supposed to be another message indicating the
completion, so it looks like it's stuck somewhere. Can you get the
thread dump by going to

  http://yourhost/hudson/threadDump

? I'm curious what the polling thread is doing.

2007/4/18, Kianwin Ong <[hidden email]>:

>
> Jumping on the thread, since I am experiencing similar issues as Arnaud.
>
> Subversion polling was working for me about a week ago. Since then, I have
> upgraded Hudson and modified my build configurations, and it was only today
> that I noticed that polling was not working.
>
> Before:
> - Hudson 1.93
> - Master node
> - file:/// protocol
>
> After:
> - Hudson 1.102
> - Slave node
> - http:// protocol
>
> The Subversion polling log has a single line:
> Started on Apr 18, 2007 1:26:25 AM
>
> The System log similarly reads:
> Apr 18, 2007 1:26:25 AM hudson.triggers.SCMTrigger$Runner runPolling
> INFO: Polling SCM changes of ...
>
> but I am not seeing stack traces in these two logs. Is there any other place
> which I should be looking for signs of problems?
>
> Let me know if tweaking my configuration will help debug the problem, and
> thanks in advance!
>
> - Kian Win
>
> --
> View this message in context: http://www.nabble.com/poll-SCM-doesn%27t-work-tf3568975.html#a10054582
> Sent from the Hudson users mailing list archive at Nabble.com.
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]
> For additional commands, e-mail: [hidden email]
>
>


--
Kohsuke Kawaguchi

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
In reply to this post by Arnaud LACOUR
2007/4/18, Arnaud LACOUR <[hidden email]>:
> > Can you paste the SVN polling log?
> there's nothing to paste I'm afraid

I'm confused. So you mean you don't see anything in the polling log? Not even

> Started on Apr 18, 2007 1:26:25 AM

line? That means polling hasn't even started.

But on another e-mail you said it's not stuck, it went through", so I
thought svn polling is somehow running and completing.

--
Kohsuke Kawaguchi

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Arnaud LACOUR
here's all there is in the polling log
Started on Apr 18, 2007 10:01:44 AM

On 4/18/07, Kohsuke Kawaguchi <[hidden email]> wrote:

> 2007/4/18, Arnaud LACOUR <[hidden email]>:
> > > Can you paste the SVN polling log?
> > there's nothing to paste I'm afraid
>
> I'm confused. So you mean you don't see anything in the polling log? Not even
>
> > Started on Apr 18, 2007 1:26:25 AM
>
> line? That means polling hasn't even started.
>
> But on another e-mail you said it's not stuck, it went through", so I
> thought svn polling is somehow running and completing.
>
> --
> Kohsuke Kawaguchi
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]
> For additional commands, e-mail: [hidden email]
>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
In reply to this post by Kohsuke Kawaguchi
Uh. The thread dump itself seems to be crashing. =/

Status Code: 500
Exception:
Stacktrace:

org.apache.commons.jelly.JellyTagException: jar:file:/var/lib/hudson/webapp/hudson/WEB-INF/lib/hudson-core-1.103.jar!/hudson/model/Hudson/threadDump.jelly:12:56:  java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
        at org.apache.commons.jelly.impl.TagScript.handleException(TagScript.java:712)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:282)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.tags.core.IfTag.doTag(IfTag.java:42)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
        at org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
        at org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
        at org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
        at org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
        at org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
        at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
        at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        at org.kohsuke.stapler.jelly.JellyClassTearOff.invokeScript(JellyClassTearOff.java:68)
        at org.kohsuke.stapler.MetaClass$3.dispatch(MetaClass.java:111)
        at org.kohsuke.stapler.Stapler.invoke(Stapler.java:270)
        at org.kohsuke.stapler.Stapler.invoke(Stapler.java:207)
        at org.kohsuke.stapler.Stapler.service(Stapler.java:72)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:45)
        at winstone.ServletConfiguration.execute(ServletConfiguration.java:249)
        at winstone.RequestDispatcher.forward(RequestDispatcher.java:334)
        at winstone.RequestHandlerThread.processRequest(RequestHandlerThread.java:244)
        at winstone.RequestHandlerThread.run(RequestHandlerThread.java:150)
        at java.lang.Thread.run(Thread.java:595)
Caused by: java.lang.NoSuchMethodError: java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
        at hudson.Functions.getThreadInfos(Functions.java:451)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at org.apache.commons.jexl.util.introspection.UberspectImpl$VelMethodImpl.invoke(UberspectImpl.java:255)
        at org.apache.commons.jexl.parser.ASTMethod.execute(ASTMethod.java:104)
        at org.apache.commons.jexl.parser.ASTReference.execute(ASTReference.java:82)
        at org.apache.commons.jexl.parser.ASTReference.value(ASTReference.java:56)
        at org.apache.commons.jexl.parser.ASTReferenceExpression.value(ASTReferenceExpression.java:50)
        at org.apache.commons.jexl.ExpressionImpl.evaluate(ExpressionImpl.java:79)
        at org.apache.commons.jelly.expression.jexl.JexlExpression.evaluate(JexlExpression.java:69)
        at org.apache.commons.jelly.expression.ExpressionSupport.evaluateRecurse(ExpressionSupport.java:61)
        at org.apache.commons.jelly.expression.ExpressionSupport.evaluateAsIterator(ExpressionSupport.java:94)
        at org.apache.commons.jelly.tags.core.ForEachTag.doTag(ForEachTag.java:89)
        at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
        ... 57 more


Generated by Winstone Servlet Engine v0.9.6 at Wed Apr 18 12:45:17 PDT 2007
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator

Ouch. Mustang dependency.

But I also implemented

   http://yourhost/hudson/classicThreadDump

for cases like this. Can you try that, too?

Kianwin Ong wrote:

>
> kohsuke wrote:
>>
>>   http://yourhost/hudson/threadDump
>>
>
> Uh. The thread dump itself seems to be crashing. =/
>
> Status Code: 500
> Exception:
> Stacktrace:
>
> org.apache.commons.jelly.JellyTagException:
> jar:file:/var/lib/hudson/webapp/hudson/WEB-INF/lib/hudson-core-1.103.jar!/hudson/model/Hudson/threadDump.jelly:12:56:
> java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
> at
> org.apache.commons.jelly.impl.TagScript.handleException(TagScript.java:712)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:282)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at
> org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.tags.core.IfTag.doTag(IfTag.java:42)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at
> org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> at
> org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> at
> org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> at
> org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> at
> org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> at
> org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> at
> org.kohsuke.stapler.jelly.JellyClassTearOff.invokeScript(JellyClassTearOff.java:68)
> at org.kohsuke.stapler.MetaClass$3.dispatch(MetaClass.java:111)
> at org.kohsuke.stapler.Stapler.invoke(Stapler.java:270)
> at org.kohsuke.stapler.Stapler.invoke(Stapler.java:207)
> at org.kohsuke.stapler.Stapler.service(Stapler.java:72)
> at javax.servlet.http.HttpServlet.service(HttpServlet.java:45)
> at winstone.ServletConfiguration.execute(ServletConfiguration.java:249)
> at winstone.RequestDispatcher.forward(RequestDispatcher.java:334)
> at
> winstone.RequestHandlerThread.processRequest(RequestHandlerThread.java:244)
> at winstone.RequestHandlerThread.run(RequestHandlerThread.java:150)
> at java.lang.Thread.run(Thread.java:595)
> Caused by: java.lang.NoSuchMethodError:
> java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
> at hudson.Functions.getThreadInfos(Functions.java:451)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> at java.lang.reflect.Method.invoke(Method.java:585)
> at
> org.apache.commons.jexl.util.introspection.UberspectImpl$VelMethodImpl.invoke(UberspectImpl.java:255)
> at org.apache.commons.jexl.parser.ASTMethod.execute(ASTMethod.java:104)
> at
> org.apache.commons.jexl.parser.ASTReference.execute(ASTReference.java:82)
> at org.apache.commons.jexl.parser.ASTReference.value(ASTReference.java:56)
> at
> org.apache.commons.jexl.parser.ASTReferenceExpression.value(ASTReferenceExpression.java:50)
> at org.apache.commons.jexl.ExpressionImpl.evaluate(ExpressionImpl.java:79)
> at
> org.apache.commons.jelly.expression.jexl.JexlExpression.evaluate(JexlExpression.java:69)
> at
> org.apache.commons.jelly.expression.ExpressionSupport.evaluateRecurse(ExpressionSupport.java:61)
> at
> org.apache.commons.jelly.expression.ExpressionSupport.evaluateAsIterator(ExpressionSupport.java:94)
> at org.apache.commons.jelly.tags.core.ForEachTag.doTag(ForEachTag.java:89)
> at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> ... 57 more
>
>
> Generated by Winstone Servlet Engine v0.9.6 at Wed Apr 18 12:45:17 PDT 2007
>

--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Arnaud LACOUR
kohsuke, what revision of hudson do we need for the thread dump to work ?
I get a 404 on both threadDump and classicThreadDump ....
any idea ? I'm at 1.100
On 4/18/07, Kohsuke Kawaguchi <[hidden email]> wrote:

>
> Ouch. Mustang dependency.
>
> But I also implemented
>
>    http://yourhost/hudson/classicThreadDump
>
> for cases like this. Can you try that, too?
>
> Kianwin Ong wrote:
> >
> > kohsuke wrote:
> >>
> >>   http://yourhost/hudson/threadDump
> >>
> >
> > Uh. The thread dump itself seems to be crashing. =/
> >
> > Status Code: 500
> > Exception:
> > Stacktrace:
> >
> > org.apache.commons.jelly.JellyTagException:
> > jar:file:/var/lib/hudson/webapp/hudson/WEB-INF/lib/hudson-core-1.103.jar!/hudson/model/Hudson/threadDump.jelly:12:56:
> > java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
> >       at
> > org.apache.commons.jelly.impl.TagScript.handleException(TagScript.java:712)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:282)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at
> > org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.tags.core.IfTag.doTag(IfTag.java:42)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at
> > org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> >       at
> > org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> >       at
> > org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> >       at
> > org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> >       at
> > org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
> >       at
> > org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
> >       at org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
> >       at org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       at
> > org.kohsuke.stapler.jelly.JellyClassTearOff.invokeScript(JellyClassTearOff.java:68)
> >       at org.kohsuke.stapler.MetaClass$3.dispatch(MetaClass.java:111)
> >       at org.kohsuke.stapler.Stapler.invoke(Stapler.java:270)
> >       at org.kohsuke.stapler.Stapler.invoke(Stapler.java:207)
> >       at org.kohsuke.stapler.Stapler.service(Stapler.java:72)
> >       at javax.servlet.http.HttpServlet.service(HttpServlet.java:45)
> >       at winstone.ServletConfiguration.execute(ServletConfiguration.java:249)
> >       at winstone.RequestDispatcher.forward(RequestDispatcher.java:334)
> >       at
> > winstone.RequestHandlerThread.processRequest(RequestHandlerThread.java:244)
> >       at winstone.RequestHandlerThread.run(RequestHandlerThread.java:150)
> >       at java.lang.Thread.run(Thread.java:595)
> > Caused by: java.lang.NoSuchMethodError:
> > java.lang.management.ThreadMXBean.isObjectMonitorUsageSupported()Z
> >       at hudson.Functions.getThreadInfos(Functions.java:451)
> >       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> >       at
> > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> >       at
> > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> >       at java.lang.reflect.Method.invoke(Method.java:585)
> >       at
> > org.apache.commons.jexl.util.introspection.UberspectImpl$VelMethodImpl.invoke(UberspectImpl.java:255)
> >       at org.apache.commons.jexl.parser.ASTMethod.execute(ASTMethod.java:104)
> >       at
> > org.apache.commons.jexl.parser.ASTReference.execute(ASTReference.java:82)
> >       at org.apache.commons.jexl.parser.ASTReference.value(ASTReference.java:56)
> >       at
> > org.apache.commons.jexl.parser.ASTReferenceExpression.value(ASTReferenceExpression.java:50)
> >       at org.apache.commons.jexl.ExpressionImpl.evaluate(ExpressionImpl.java:79)
> >       at
> > org.apache.commons.jelly.expression.jexl.JexlExpression.evaluate(JexlExpression.java:69)
> >       at
> > org.apache.commons.jelly.expression.ExpressionSupport.evaluateRecurse(ExpressionSupport.java:61)
> >       at
> > org.apache.commons.jelly.expression.ExpressionSupport.evaluateAsIterator(ExpressionSupport.java:94)
> >       at org.apache.commons.jelly.tags.core.ForEachTag.doTag(ForEachTag.java:89)
> >       at org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
> >       ... 57 more
> >
> >
> > Generated by Winstone Servlet Engine v0.9.6 at Wed Apr 18 12:45:17 PDT 2007
> >
>
>
> --
> Kohsuke Kawaguchi
> Sun Microsystems                   [hidden email]
>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
Arnaud LACOUR wrote:
> kohsuke, what revision of hudson do we need for the thread dump to work ?
> I get a 404 on both threadDump and classicThreadDump ....
> any idea ? I'm at 1.100

1.102 or later.

--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
In reply to this post by Kohsuke Kawaguchi
kohsuke wrote
But I also implemented
   http://yourhost/hudson/classicThreadDump
Oh okay. That works. Here goes:

Thread[ConnectorThread:[http-8203],5,main] (RUNNABLE)
java.net.PlainSocketImpl.socketAccept(Native Method)
java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
java.net.ServerSocket.implAccept(ServerSocket.java:450)
java.net.ServerSocket.accept(ServerSocket.java:421)
winstone.HttpListener.run(HttpListener.java:127)
java.lang.Thread.run(Thread.java:595)

Thread[Thread-8,5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
ch.ethz.ssh2.channel.ChannelManager.getChannelData(ChannelManager.java:910)
ch.ethz.ssh2.channel.ChannelInputStream.read(ChannelInputStream.java:58)
ch.ethz.ssh2.channel.ChannelInputStream.read(ChannelInputStream.java:70)
ch.ethz.ssh2.StreamGobbler$GobblerThread.run(StreamGobbler.java:47)

Thread[RequestHandlerThread[#0],5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
winstone.RequestHandlerThread.run(RequestHandlerThread.java:216)
java.lang.Thread.run(Thread.java:595)

Thread[WinstoneObjectPoolMgmt,5,main] (TIMED_WAITING)
java.lang.Thread.sleep(Native Method)
winstone.ObjectPool.run(ObjectPool.java:107)
java.lang.Thread.run(Thread.java:595)

Thread[Signal Dispatcher,9,system] (RUNNABLE)

Thread[stderr copier for remote agent on slave.hostname,5,main] (RUNNABLE)
java.io.FileInputStream.readBytes(Native Method)
java.io.FileInputStream.read(FileInputStream.java:177)
hudson.Util.copyStream(Util.java:211)
hudson.util.StreamCopyThread.run(StreamCopyThread.java:26)

Thread[Executor #0 for master,5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
hudson.util.OneShotEvent.block(OneShotEvent.java:28)
hudson.model.Queue.pop(Queue.java:308)
hudson.model.Executor.run(Executor.java:51)

Thread[pool-1-thread-427,5,main] (TIMED_WAITING)
sun.misc.Unsafe.park(Native Method)
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:146)
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:772)
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1087)
java.util.concurrent.SynchronousQueue$Node.waitForPut(SynchronousQueue.java:291)
java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:443)
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:475)
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:674)
java.lang.Thread.run(Thread.java:595)

Thread[Java2D Disposer,10,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
sun.java2d.Disposer.run(Disposer.java:107)
java.lang.Thread.run(Thread.java:595)

Thread[Thread-7,5,main] (RUNNABLE)
java.net.SocketInputStream.socketRead0(Native Method)
java.net.SocketInputStream.read(SocketInputStream.java:129)
ch.ethz.ssh2.crypto.cipher.CipherInputStream.fill_buffer(CipherInputStream.java:41)
ch.ethz.ssh2.crypto.cipher.CipherInputStream.internal_read(CipherInputStream.java:52)
ch.ethz.ssh2.crypto.cipher.CipherInputStream.getBlock(CipherInputStream.java:79)
ch.ethz.ssh2.crypto.cipher.CipherInputStream.read(CipherInputStream.java:108)
ch.ethz.ssh2.transport.TransportConnection.receiveMessage(TransportConnection.java:231)
ch.ethz.ssh2.transport.TransportManager.receiveLoop(TransportManager.java:669)
ch.ethz.ssh2.transport.TransportManager$1.run(TransportManager.java:468)
java.lang.Thread.run(Thread.java:595)

Thread[RequestHandlerThread[#2],5,main] (RUNNABLE)
java.lang.Thread.dumpThreads(Native Method)
java.lang.Thread.getAllStackTraces(Thread.java:1434)
hudson.Functions.dumpAllThreads(Functions.java:446)
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
java.lang.reflect.Method.invoke(Method.java:585)
org.apache.commons.jexl.util.introspection.UberspectImpl$VelMethodImpl.invoke(UberspectImpl.java:255)
org.apache.commons.jexl.parser.ASTMethod.execute(ASTMethod.java:104)
org.apache.commons.jexl.parser.ASTReference.execute(ASTReference.java:82)
org.apache.commons.jexl.parser.ASTReference.value(ASTReference.java:56)
org.apache.commons.jexl.parser.ASTReferenceExpression.value(ASTReferenceExpression.java:50)
org.apache.commons.jexl.ExpressionImpl.evaluate(ExpressionImpl.java:79)
org.apache.commons.jelly.expression.jexl.JexlExpression.evaluate(JexlExpression.java:69)
org.apache.commons.jelly.expression.ExpressionSupport.evaluateRecurse(ExpressionSupport.java:61)
org.apache.commons.jelly.expression.ExpressionSupport.evaluateAsIterator(ExpressionSupport.java:94)
org.apache.commons.jelly.tags.core.ForEachTag.doTag(ForEachTag.java:89)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.tags.core.IfTag.doTag(IfTag.java:42)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.tags.define.InvokeBodyTag.doTag(InvokeBodyTag.java:91)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.impl.StaticTag.doTag(StaticTag.java:65)
org.apache.commons.jelly.impl.StaticTagScript.run(StaticTagScript.java:112)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.DynamicTag.doTag(DynamicTag.java:80)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.apache.commons.jelly.impl.ScriptBlock.run(ScriptBlock.java:95)
org.apache.commons.jelly.TagSupport.invokeBody(TagSupport.java:186)
org.apache.commons.jelly.tags.core.JellyTag.doTag(JellyTag.java:45)
org.apache.commons.jelly.impl.TagScript.run(TagScript.java:262)
org.kohsuke.stapler.jelly.JellyClassTearOff.invokeScript(JellyClassTearOff.java:68)
org.kohsuke.stapler.MetaClass$3.dispatch(MetaClass.java:111)
org.kohsuke.stapler.Stapler.invoke(Stapler.java:270)
org.kohsuke.stapler.Stapler.invoke(Stapler.java:207)
org.kohsuke.stapler.Stapler.service(Stapler.java:72)
javax.servlet.http.HttpServlet.service(HttpServlet.java:45)
winstone.ServletConfiguration.execute(ServletConfiguration.java:249)
winstone.RequestDispatcher.forward(RequestDispatcher.java:334)
winstone.RequestHandlerThread.processRequest(RequestHandlerThread.java:244)
winstone.RequestHandlerThread.run(RequestHandlerThread.java:150)
java.lang.Thread.run(Thread.java:595)

Thread[RequestHandlerThread[#3],5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
winstone.RequestHandlerThread.run(RequestHandlerThread.java:216)
java.lang.Thread.run(Thread.java:595)

Thread[process reaper,5,main] (RUNNABLE)
java.lang.UNIXProcess.waitForProcessExit(Native Method)
java.lang.UNIXProcess.access$900(UNIXProcess.java:20)
java.lang.UNIXProcess$1$1.run(UNIXProcess.java:132)

Thread[DestroyJavaVM,5,main] (RUNNABLE)

Thread[Executor #0 for slave.hostname,5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
hudson.util.OneShotEvent.block(OneShotEvent.java:28)
hudson.model.Queue.pop(Queue.java:308)
hudson.model.Executor.run(Executor.java:51)

Thread[Channel reader thread: slave.hostname,5,main] (RUNNABLE)
java.io.FileInputStream.readBytes(Native Method)
java.io.FileInputStream.read(FileInputStream.java:194)
java.io.BufferedInputStream.fill(BufferedInputStream.java:218)
java.io.BufferedInputStream.read(BufferedInputStream.java:235)
java.io.ObjectInputStream$PeekInputStream.peek(ObjectInputStream.java:2196)
java.io.ObjectInputStream$BlockDataInputStream.peek(ObjectInputStream.java:2486)
java.io.ObjectInputStream$BlockDataInputStream.peekByte(ObjectInputStream.java:2496)
java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1273)
java.io.ObjectInputStream.readObject(ObjectInputStream.java:348)
hudson.remoting.Channel$ReaderThread.run(Channel.java:377)

Thread[pool-1-thread-428,5,main] (TIMED_WAITING)
sun.misc.Unsafe.park(Native Method)
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:146)
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireNanos(AbstractQueuedSynchronizer.java:772)
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireNanos(AbstractQueuedSynchronizer.java:1087)
java.util.concurrent.SynchronousQueue$Node.waitForPut(SynchronousQueue.java:291)
java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:443)
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:475)
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:674)
java.lang.Thread.run(Thread.java:595)

Thread[LauncherControlThread[ControlPort=-1],5,main] (TIMED_WAITING)
java.lang.Thread.sleep(Native Method)
winstone.Launcher.run(Launcher.java:277)
java.lang.Thread.run(Thread.java:595)

Thread[Hudson cron thread,5,main] (TIMED_WAITING)
java.lang.Object.wait(Native Method)
java.util.TimerThread.mainLoop(Timer.java:509)
java.util.TimerThread.run(Timer.java:462)

Thread[Reference Handler,10,system] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)

Thread[Thread-3,5,main] (RUNNABLE)
java.net.PlainSocketImpl.socketAccept(Native Method)
java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
java.net.ServerSocket.implAccept(ServerSocket.java:450)
java.net.ServerSocket.accept(ServerSocket.java:421)
hudson.TcpSlaveAgentListener.run(TcpSlaveAgentListener.java:95)

Thread[RequestHandlerThread[#4],5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
winstone.RequestHandlerThread.run(RequestHandlerThread.java:216)
java.lang.Thread.run(Thread.java:595)

Thread[RequestHandlerThread[#1],5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
winstone.RequestHandlerThread.run(RequestHandlerThread.java:216)
java.lang.Thread.run(Thread.java:595)

Thread[WinstoneHostConfigurationMgmt:default,5,main] (TIMED_WAITING)
java.lang.Thread.sleep(Native Method)
winstone.HostConfiguration.run(HostConfiguration.java:176)
java.lang.Thread.run(Thread.java:595)

Thread[Thread-4,5,main] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.Object.wait(Object.java:474)
hudson.model.ViewJob$ReloadThread.getNext(ViewJob.java:134)
hudson.model.ViewJob$ReloadThread.run(ViewJob.java:144)

Thread[Finalizer,8,system] (WAITING)
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator

Kianwin Ong wrote:
> Thread[Hudson cron thread,5,main] (TIMED_WAITING)
> java.lang.Object.wait(Native Method)
> java.util.TimerThread.mainLoop(Timer.java:509)
> java.util.TimerThread.run(Timer.java:462)

The thread that was supposed to be doing polling is back at its normal
wait position.

So I suspect a RuntimeException or Error happened during the polling,
and the polling was aborted. I just committed a code change so that
those would be recorded in the polling log (for 1.104.)

Such error must have left some information in the system log. Can you
find any?

--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
kohsuke wrote
So I suspect a RuntimeException or Error happened during the polling,
and the polling was aborted. I just committed a code change so that
those would be recorded in the polling log (for 1.104.)
Will the current snapshot include this change? If so, I can test it to see if the RuntimeException / Error is recorded.

kohsuke wrote
Such error must have left some information in the system log. Can you
find any?
I tried narrowing down the problem, and it seems that the polling is directly related to tying builds to slave nodes.

For reproduction steps (using Hudson 1.103 and the Subversion SCM http:// protocol)

1. tie the job to the Master node, and build manually

The Subversion polling log is normal, showing:
Started on Apr 19, 2007 3:39:25 PM
Revision:2976
Done. Took 0 seconds
No changes

2. tie the job to the Slave node (but don't build it yet)

The Subversion polling log appears normal when polling occurs again.

3. build manually on the Slave node

The Subversion polling log shows only the first line:
Started on Apr 19, 2007 3:41:25 PM

4. tie the job back to the Master node (but don't build it yet)

The Subversion polling log still shows only the first line.

5. build manually on the Master node

The Subversion polling log appears normal again.

For both normal and error cases, the system log show identical messages:
Apr 19, 2007 3:39:25 PM hudson.triggers.SCMTrigger$Runner runPolling
INFO: Polling SCM changes of debug-polling
Apr 19, 2007 3:41:25 PM hudson.triggers.SCMTrigger$Runner runPolling
INFO: Polling SCM changes of debug-polling

Furthermore, the polling failure appears to be independent of Subversion SCM protocols. For all of file:///, http:// and svn+ssh://, polling always succeeds on the Master node and always fail on the Slave node.

- Kian Win
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
Kianwin Ong wrote:

>
> kohsuke wrote:
>>
>> So I suspect a RuntimeException or Error happened during the polling,
>> and the polling was aborted. I just committed a code change so that
>> those would be recorded in the polling log (for 1.104.)
>>
>
> Will the current snapshot include this change? If so, I can test it to see
> if the RuntimeException / Error is recorded.
Yes, it should be already in the snapshot.


--
Kohsuke Kawaguchi
Sun Microsystems                   [hidden email]

smime.p7s (4K) Download Attachment
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
Just a thought: is it relevant that the master is running Unix, and the slave is running Windows (actually Cygwin, but Java likely does not care)?

Using 1.104,

The polling log still shows only a single line:
Started on Apr 24, 2007 1:16:02 PM

But, the system log now shows:
Apr 24, 2007 1:16:02 PM hudson.triggers.SCMTrigger$Runner runPolling
SEVERE: Failed to record SCM polling
java.lang.ClassCastException: hudson.remoting.RemoteOutputStream
        at hudson.util.StreamTaskListener.readObject(StreamTaskListener.java:58)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:585)
        at java.io.ObjectStreamClass.invokeReadObject(ObjectStreamClass.java:946)
        at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1809)
        at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
        at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
        at java.io.ObjectInputStream.defaultReadFields(ObjectInputStream.java:1908)
        at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1832)
        at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
        at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
        at java.io.ObjectInputStream.defaultReadFields(ObjectInputStream.java:1908)
        at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1832)
        at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
        at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
        at java.io.ObjectInputStream.readObject(ObjectInputStream.java:348)
        at hudson.remoting.UserRequest.perform(UserRequest.java:49)
        at hudson.remoting.UserRequest.perform(UserRequest.java:22)
        at hudson.remoting.Request$2.run(Request.java:178)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
        at java.lang.Thread.run(Thread.java:595)
Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kohsuke Kawaguchi
Administrator
Aha! This is exactly what we need. I think I should be able to fix
this now. Thank you for getting more info for me.

But it sounds like you are also saying that the error is still not
showing up in the polling log file?

2007/4/24, Kianwin Ong <[hidden email]>:

>
> Just a thought: is it relevant that the master is running Unix, and the slave
> is running Windows (actually Cygwin, but Java likely does not care)?
>
> Using 1.104,
>
> The polling log still shows only a single line:
> Started on Apr 24, 2007 1:16:02 PM
>
> But, the system log now shows:
> Apr 24, 2007 1:16:02 PM hudson.triggers.SCMTrigger$Runner runPolling
> SEVERE: Failed to record SCM polling
> java.lang.ClassCastException: hudson.remoting.RemoteOutputStream
>         at hudson.util.StreamTaskListener.readObject(StreamTaskListener.java:58)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>         at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
>         at java.lang.reflect.Method.invoke(Method.java:585)
>         at java.io.ObjectStreamClass.invokeReadObject(ObjectStreamClass.java:946)
>         at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1809)
>         at
> java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
>         at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
>         at java.io.ObjectInputStream.defaultReadFields(ObjectInputStream.java:1908)
>         at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1832)
>         at
> java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
>         at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
>         at java.io.ObjectInputStream.defaultReadFields(ObjectInputStream.java:1908)
>         at java.io.ObjectInputStream.readSerialData(ObjectInputStream.java:1832)
>         at
> java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1719)
>         at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1305)
>         at java.io.ObjectInputStream.readObject(ObjectInputStream.java:348)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:49)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:22)
>         at hudson.remoting.Request$2.run(Request.java:178)
>         at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
>         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
>         at java.lang.Thread.run(Thread.java:595)
>
> --
> View this message in context: http://www.nabble.com/poll-SCM-doesn%27t-work-tf3568975.html#a10168656
> Sent from the Hudson users mailing list archive at Nabble.com.
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]
> For additional commands, e-mail: [hidden email]
>
>


--
Kohsuke Kawaguchi

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: poll SCM doesn't work

Kianwin Ong
kohsuke wrote
Aha! This is exactly what we need. I think I should be able to fix
this now. Thank you for getting more info for me.
No problem. Shall I file an issue for this?

kohsuke wrote
But it sounds like you are also saying that the error is still not
showing up in the polling log file?
That's right. It is on neither the Subversion polling log page, nor the hudson_data/jobs/my-job/scm-polling.log file. (I assume they must be one and the same?)

While looking through the scm-polling.log files on disk, I also rediscovered this:

Started on Apr 23, 2007 2:01:15 AM
remote file operation failed
hudson.util.IOException2: remote file operation failed
        at hudson.FilePath.act(FilePath.java:234)
        at hudson.FilePath.exists(FilePath.java:424)
        at hudson.model.AbstractProject.pollSCMChanges(AbstractProject.java:332)
        at hudson.triggers.SCMTrigger$Runner.runPolling(SCMTrigger.java:256)
        at hudson.triggers.SCMTrigger$Runner.run(SCMTrigger.java:273)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
        at java.util.concurrent.FutureTask.run(FutureTask.java:123)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
        at java.lang.Thread.run(Thread.java:595)
Caused by: java.io.IOException: Remote call failed
        at hudson.remoting.Channel.call(Channel.java:249)
        at hudson.FilePath.act(FilePath.java:231)
        ... 10 more
Caused by: java.lang.OutOfMemoryError: Java heap space
Done. Took 0 seconds

I recall that this was running on 1.103, with 3-4 poll-per-minute projects, and hudson will fail after a day or two with a java.lang.OutOfMemoryError. The issue seems to go away after I have turned off polling altogether, so my speculation is that they are related. Hope that is of some use at helping you zero down onto the problem.

- Kian Win


12