Attachment 573984 Details for Bug 808468 – jenkins_log

jenkins_log

jenkins.log (text/x-log), 11.92 KB, created by bzhao on 2012-03-30 13:44:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: bzhao

Created: 2012-03-30 13:44:02 UTC

Size: 11.92 KB

patch

obsolete

>Running from: /usr/lib/jenkins/jenkins.war
>webroot: System.getProperty("JENKINS_HOME")
>[Winstone 2012/03/30 09:11:52] - Beginning extraction from war file
>hudson home directory: /var/lib/stickshift/7e4c41e9ec3946b38c7285e69e11e271/jenkinstest/data found at: System.getProperty("JENKINS_HOME")
>[Winstone 2012/03/30 09:12:09] - HTTP Listener started: port=8080
>[Winstone 2012/03/30 09:12:09] - Winstone Servlet Engine v0.9.10 running: controlPort=disabled
>Mar 30, 2012 9:12:09 AM hudson.model.Hudson$5 onAttained
>INFO: Started initialization
>Mar 30, 2012 9:12:14 AM hudson.model.Hudson$5 onAttained
>INFO: Listed all plugins
>Mar 30, 2012 9:12:14 AM hudson.model.Hudson$5 onAttained
>INFO: Prepared all plugins
>Mar 30, 2012 9:12:26 AM hudson.model.Hudson$5 onAttained
>INFO: Started all plugins
>Mar 30, 2012 9:12:26 AM hudson.model.Hudson$5 onAttained
>INFO: Augmented all extensions
>Mar 30, 2012 9:12:26 AM hudson.model.Hudson$5 onAttained
>INFO: Loaded all jobs
>Mar 30, 2012 9:12:26 AM hudson.model.Hudson$5 onAttained
>INFO: Completed initialization
>Mar 30, 2012 9:12:32 AM org.springframework.context.support.AbstractApplicationContext prepareRefresh
>INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@bf9a12: display name [Root WebApplicationContext]; startup date [Fri Mar 30 09:12:32 EDT 2012]; root of context hierarchy
>Mar 30, 2012 9:12:32 AM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
>INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@bf9a12]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1826ac5
>Mar 30, 2012 9:12:32 AM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
>INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1826ac5: defining beans [authenticationManager]; root of factory hierarchy
>Mar 30, 2012 9:12:32 AM org.springframework.context.support.AbstractApplicationContext prepareRefresh
>INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@184b3b: display name [Root WebApplicationContext]; startup date [Fri Mar 30 09:12:32 EDT 2012]; root of context hierarchy
>Mar 30, 2012 9:12:32 AM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
>INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@184b3b]: org.springframework.beans.factory.support.DefaultListableBeanFactory@4b29cf
>Mar 30, 2012 9:12:32 AM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
>INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4b29cf: defining beans [filter,legacy]; root of factory hierarchy
>Mar 30, 2012 9:12:33 AM hudson.DNSMultiCast <init>
>WARNING: Failed to advertise the service to DNS multi-cast
>java.net.BindException: Permission denied
>	at java.net.PlainDatagramSocketImpl.bind0(Native Method)
>	at java.net.AbstractPlainDatagramSocketImpl.bind(AbstractPlainDatagramSocketImpl.java:85)
>	at java.net.DatagramSocket.bind(DatagramSocket.java:373)
>	at java.net.MulticastSocket.<init>(MulticastSocket.java:165)
>	at java.net.MulticastSocket.<init>(MulticastSocket.java:130)
>	at javax.jmdns.impl.JmDNSImpl.openMulticastSocket(JmDNSImpl.java:448)
>	at javax.jmdns.impl.JmDNSImpl.<init>(JmDNSImpl.java:420)
>	at javax.jmdns.JmDNS.create(JmDNS.java:60)
>	at hudson.DNSMultiCast.<init>(DNSMultiCast.java:26)
>	at hudson.model.Hudson.<init>(Hudson.java:693)
>	at hudson.model.Hudson.<init>(Hudson.java:607)
>	at hudson.WebAppMain$2.run(WebAppMain.java:215)
>[Winstone 2012/03/30 09:13:48] - Called getInputStream after getParameter ... error
>Mar 30, 2012 9:14:15 AM hudson.plugins.openshift.OpenShiftCloud provision
>INFO: Provisioning new node for workload = 1 and label = phptest-build
>Mar 30, 2012 9:14:15 AM hudson.plugins.openshift.OpenShiftCloud getOpenShiftService
>INFO: Initiating Java Client Service - Configured for OpenShift Server https://localhost
>Mar 30, 2012 9:14:17 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: Checking capacity
>Mar 30, 2012 9:14:17 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: User has consumed 2 of 3 gears.
>Mar 30, 2012 9:14:17 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: Capacity remaining - checking for existing type...
>Mar 30, 2012 9:14:17 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: No suitable builders found.
>Mar 30, 2012 9:14:17 AM hudson.plugins.openshift.OpenShiftCloud provision
>INFO: Provisioned 1 new nodes
>Mar 30, 2012 9:14:17 AM hudson.slaves.NodeProvisioner update
>INFO: Started provisioning phptest-build from OpenShift Cloud with 1 executors. Remaining excess workload:-0.9
>Mar 30, 2012 9:14:35 AM hudson.plugins.openshift.OpenShiftSlave stopApp
>INFO: Slave stopping application...
>Mar 30, 2012 9:14:37 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Connecting to slave phptestbldr...
>Mar 30, 2012 9:14:38 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Established UUID = 1b93d01235bb4899b731236b5fb38267
>Mar 30, 2012 9:14:43 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:14:43 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:14:48 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:14:48 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:14:53 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:14:54 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:14:59 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:14:59 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:04 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:05 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:10 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:10 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:15 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:15 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:20 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:21 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:26 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:26 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:31 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:32 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:37 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:37 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:42 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:42 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS not propagated yet, retrying...
>Mar 30, 2012 9:15:47 AM hudson.plugins.openshift.OpenShiftSlave connect
>WARNING: Slave DNS not propagated. Timing out.
>Mar 30, 2012 9:15:47 AM hudson.plugins.openshift.OpenShiftCloud$2 call
>WARNING: Unable to provision node java.io.IOException: Slave DNS not propagated. Timing out.
>Mar 30, 2012 9:15:47 AM hudson.slaves.NodeProvisioner update
>WARNING: Provisioned slave phptest-build failed to launch
>java.io.IOException: Slave DNS not propagated. Timing out.
>	at hudson.plugins.openshift.OpenShiftSlave.connect(OpenShiftSlave.java:198)
>	at hudson.plugins.openshift.OpenShiftSlave.provision(OpenShiftSlave.java:210)
>	at hudson.plugins.openshift.OpenShiftCloud$2.call(OpenShiftCloud.java:459)
>	at hudson.plugins.openshift.OpenShiftCloud$2.call(OpenShiftCloud.java:451)
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>	at java.lang.Thread.run(Thread.java:679)
>Mar 30, 2012 9:15:49 AM hudson.plugins.openshift.OpenShiftCloud provision
>INFO: Provisioning new node for workload = 1 and label = phptest-build
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftCloud getSlaves
>INFO: Didn't find existing slave for: phptestbldr
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftComputer <init>
>INFO: Creating Computer
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftComputerLauncher launch
>INFO: Launching slave...
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Connecting to slave phptestbldr...
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: Checking capacity
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Established UUID = 1b93d01235bb4899b731236b5fb38267
>Mar 30, 2012 9:15:50 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Checking to see if slave DNS is resolvable...
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: User has consumed 3 of 3 gears.
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftCloud shouldProvision
>INFO: No capacity remaining.  Not provisioning...
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftCloud provision
>INFO: Not provisioning new builder...
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftCloud provision
>INFO: Provisioned 0 new nodes
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftSlave _terminate
>INFO: Terminating slave phptestbldr (uuid: 1b93d01235bb4899b731236b5fb38267)
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftSlave _terminate
>INFO: Terminating OpenShift application...
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftSlave connect
>INFO: Slave DNS resolved - phptestbldr-bzhao6.dev.rhcloud.com/10.224.109.175
>Mar 30, 2012 9:15:51 AM hudson.plugins.openshift.OpenShiftComputerLauncher launch
>INFO: Checking availability of computer hudson.plugins.openshift.OpenShiftSlave@fb809362
>Mar 30, 2012 9:15:52 AM hudson.plugins.openshift.OpenShiftComputerLauncher launch
>INFO: Checking SSH access to application phptestbldr-bzhao6.dev.rhcloud.com
>Mar 30, 2012 9:15:53 AM hudson.plugins.openshift.OpenShiftComputerLauncher launch
>INFO: Connected via SSH.
>Mar 30, 2012 9:16:00 AM hudson.remoting.Channel$ReaderThread run
>SEVERE: I/O error in channel phptestbldr
>java.io.IOException: Unexpected termination of the channel
>	at hudson.remoting.Channel$ReaderThread.run(Channel.java:1015)
>Caused by: java.io.EOFException
>	at java.io.ObjectInputStream$BlockDataInputStream.peekByte(ObjectInputStream.java:2570)
>	at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1314)
>	at java.io.ObjectInputStream.readObject(ObjectInputStream.java:368)
>	at hudson.remoting.Channel$ReaderThread.run(Channel.java:1009)
>Mar 30, 2012 9:16:00 AM hudson.plugins.openshift.OpenShiftCloud cancelBuild
>WARNING: Build for phptest-build has been cancelled

Actions: View

Attachments on bug 808468: 573984