Building remotely on slave1 in workspace /home/fayyaz/workspace/gate-example-pep8
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games
The ZUUL_URL must be provided.
INFO: ---- Worker slave1_exec-0 scheduling gate-example-pep8 build #1 on slave1 with UUID 4b1ef21774ee4a0aac1aeba63a19dfd6 and build params [(TextParameterValue) BASE_LOG_PATH='1/1/1', (TextParameterValue) ZUUL_PIPELINE='check', (TextParameterValue) ZUUL_UUID='4b1ef21774ee4a0aac1aeba63a19dfd6', (TextParameterValue) LOG_PATH='1/1/1/check/gate-example-pep8/4b1ef21', (TextParameterValue) ZUUL_VOTING='1', (TextParameterValue) ZUUL_CHANGE_IDS='1,1', (TextParameterValue) ZUUL_PATCHSET='1', (TextParameterValue) ZUUL_BRANCH='master', (TextParameterValue) ZUUL_REF='refs/zuul/master/Zc620d30e6bd94d1c80d1712e0805e62c', (TextParameterValue) ZUUL_COMMIT='c0bad8e5c9df4c245367e3d877ec09b28dd287f7', (TextParameterValue) ZUUL_URL='ci.corvit.lab:/git', (TextParameterValue) ZUUL_CHANGE='1', (TextParameterValue) ZUUL_CHANGES='example:master:refs/changes/01/1/1', (TextParameterValue) ZUUL_PROJECT='example']
Jul 18, 2016 5:40:39 AM org.gearman.common.GearmanJobServerSession submitTask
INFO: Session GearmanJobServerSession:285:GearmanNIOJobServerConnection:ci.corvit.lab/
192.168.100.19:4730 is now handling the task GearmanTask:null:d25d1ddf-58ad-41ab-b6ab-0be627012713
Jul 18, 2016 5:40:39 AM org.gearman.common.GearmanJobServerSession handleReqSessionEvent
INFO: Session GearmanJobServerSession:285:GearmanNIOJobServerConnection:ci.corvit.lab/
192.168.100.19:4730 handling a REQ/WORK_DATA event
Jul 18, 2016 5:40:39 AM org.gearman.common.GearmanJobServerSession submitTask
INFO: Session GearmanJobServerSession:285:GearmanNIOJobServerConnection:ci.corvit.lab/
192.168.100.19:4730 is now handling the task GearmanTask:null:587df4f9-9586-44e0-861b-756cc32600be
Jul 18, 2016 5:40:39 AM org.gearman.common.GearmanJobServerSession handleReqSessionEvent
INFO: Session GearmanJobServerSession:285:GearmanNIOJobServerConnection:ci.corvit.lab/
192.168.100.19:4730 handling a REQ/WORK_STATUS event
Jul 18, 2016 5:40:39 AM hudson.model.ParametersAction filter
WARNING: Skipped parameter `BASE_LOG_PATH` as it is undefined on `gate-example-pep8`. Set `-Dhudson.model.ParametersAction.keepUndefinedParameters`=true to allow undefined parameters to be injected as environment variables or `-Dhudson.model.ParametersAction.safeParameters=[comma-separated list]` to whitelist specific parameter names, even though it represents a security breach
Jul 18, 2016 5:40:39 AM hudson.model.ParametersAction filter
WARNING: Skipped parameter `ZUUL_PIPELINE` as it is undefined on `gate-example-pep8`. Set `-Dhudson.model.ParametersAction.keepUndefinedParameters`=true to allow undefined parameters to be injected as environment variables or `-Dhudson.model.ParametersAction.safeParameters=[comma-separated list]` to whitelist specific parameter names, even though it represents a security breach
Jul 18, 2016 5:40:39 AM hudson.model.ParametersAction filter
WARNING: Skipped parameter `ZUUL_UUID` as it is undefined on `gate-example-pep8`. Set `-Dhudson.model.ParametersAction.keepUndefinedParameters`=true to allow undefined parameters to be injected as environment variables or `-Dhudson.model.ParametersAction.safeParameters=[comma-separated list]` to whitelist specific parameter names, even though it represents a security breach
Jul 18, 2016 5:40:39 AM hudson.model.ParametersAction filter
WARNING: Skipped parameter `LOG_PATH` as it is undefined on `gate-example-pep8`. Set `-Dhudson.model.ParametersAction.keepUndefinedParameters`=true to allow undefined parameters to be injected as environment variables or `-Dhudson.model.ParametersAction.safeParameters=[comma-separated list]` to whitelist specific parameter names, even though it represents a security breach
Jul 18, 2016 5:40:39 AM hudson.model.ParametersAction filter
WARNING: Skipped parameter `ZUUL_VOTING` as it is undefined on `gate-example-pep8`. Set `-Dhudson.model.ParametersAction.keepUndefinedParameters`=true to allow undefined parameters to be injected as environment variables or `-Dhudson.model.ParametersAction.safeParameters=[comma-separated list]` to whitelist specific parameter names, even though it represents a security breach
fayyaz@jenkins:~$ java -Dhudson.model.ParametersAction.keepUndefinedParameters=true -jar /usr/share/jenkins/jenkins.war
Running from: /usr/share/jenkins/jenkins.war
webroot: $user.home/.jenkins
Jul 18, 2016 5:33:37 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: Logging initialized @290ms
Jul 18, 2016 5:33:37 AM winstone.Logger logInternal
INFO: Beginning extraction from war file
Jul 18, 2016 5:33:38 AM org.eclipse.jetty.util.log.JavaUtilLog warn
WARNING: Empty contextPath
Jul 18, 2016 5:33:38 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: jetty-9.2.z-SNAPSHOT
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
Jenkins home directory: /home/fayyaz/.jenkins found at: $user.home/.jenkins
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: Started w.@738cc466{/,file:/home/fayyaz/.jenkins/war/,AVAILABLE}{/home/fayyaz/.jenkins/war}
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog warn
WARNING: FAILED ServerConnector@588c6ea1{HTTP/1.1}{
0.0.0.0:8080}: java.net.BindException: Address already in use
java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:463)
at sun.nio.ch.Net.bind(Net.java:455)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:321)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.eclipse.jetty.server.Server.doStart(Server.java:366)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at winstone.Launcher.<init>(Launcher.java:152)
at winstone.Launcher.main(Launcher.java:352)
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 Main._main(Main.java:246)
at Main.main(Main.java:91)
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog warn
WARNING: FAILED org.eclipse.jetty.server.Server@1a6bfc15: java.net.BindException: Address already in use
java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:463)
at sun.nio.ch.Net.bind(Net.java:455)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:321)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.eclipse.jetty.server.Server.doStart(Server.java:366)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at winstone.Launcher.<init>(Launcher.java:152)
at winstone.Launcher.main(Launcher.java:352)
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 Main._main(Main.java:246)
at Main.main(Main.java:91)
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: Stopped ServerConnector@588c6ea1{HTTP/1.1}{
0.0.0.0:8080}
Jul 18, 2016 5:33:39 AM hudson.WebAppMain$4 run
INFO: Shutting down a Jenkins instance that was still starting up
java.lang.Throwable: reason
at hudson.WebAppMain$4.run(WebAppMain.java:383)
at hudson.security.ACL.impersonate(ACL.java:198)
at hudson.WebAppMain.contextDestroyed(WebAppMain.java:374)
at org.eclipse.jetty.server.handler.ContextHandler.callContextDestroyed(ContextHandler.java:808)
at org.eclipse.jetty.servlet.ServletContextHandler.callContextDestroyed(ServletContextHandler.java:457)
at org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:842)
at org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:215)
at org.eclipse.jetty.webapp.WebAppContext.doStop(WebAppContext.java:529)
at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:143)
at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:162)
at org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
at org.eclipse.jetty.server.Server.doStop(Server.java:456)
at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
at winstone.Launcher.shutdown(Launcher.java:305)
at winstone.Launcher.<init>(Launcher.java:165)
at winstone.Launcher.main(Launcher.java:352)
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 Main._main(Main.java:246)
at Main.main(Main.java:91)
Jul 18, 2016 5:33:39 AM org.eclipse.jetty.util.log.JavaUtilLog info
INFO: Stopped w.@738cc466{/,file:/home/fayyaz/.jenkins/war/,UNAVAILABLE}{/home/fayyaz/.jenkins/war}
Jul 18, 2016 5:33:39 AM winstone.Logger logInternal
INFO: Winstone shutdown successfully
Jul 18, 2016 5:33:39 AM winstone.Logger logInternal
SEVERE: Container startup failed
java.io.IOException: Failed to start Jetty
at winstone.Launcher.<init>(Launcher.java:154)
at winstone.Launcher.main(Launcher.java:352)
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 Main._main(Main.java:246)
at Main.main(Main.java:91)
Caused by: java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:463)
at sun.nio.ch.Net.bind(Net.java:455)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:321)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.eclipse.jetty.server.Server.doStart(Server.java:366)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at winstone.Launcher.<init>(Launcher.java:152)
... 7 more
Jul 18, 2016 5:33:40 AM hudson.util.BootFailure publish
SEVERE: Failed to initialize Jenkins
hudson.util.HudsonFailedToLoad: java.lang.InterruptedException
at hudson.WebAppMain$3.run(WebAppMain.java:244)
Caused by: java.lang.InterruptedException
at java.lang.Object.wait(Native Method)
at java.lang.Object.wait(Object.java:503)
at org.jvnet.hudson.reactor.Reactor.execute(Reactor.java:267)
at jenkins.InitReactorRunner.run(InitReactorRunner.java:45)
at jenkins.model.Jenkins.executeReactor(Jenkins.java:1010)
at jenkins.model.Jenkins.<init>(Jenkins.java:878)
at hudson.model.Hudson.<init>(Hudson.java:85)
at hudson.model.Hudson.<init>(Hudson.java:81)
at hudson.WebAppMain$3.run(WebAppMain.java:227)
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins cleanUp
INFO: Stopping Jenkins
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins$21 onAttained
INFO: Started termination
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins$21 onTaskFailed
SEVERE: Failed NioChannelSelector.cleanUp
java.lang.IllegalArgumentException: Unable to inject class jenkins.slaves.NioChannelSelector
at hudson.init.TaskMethodFinder.lookUp(TaskMethodFinder.java:125)
at hudson.init.TaskMethodFinder.invoke(TaskMethodFinder.java:104)
at hudson.init.TaskMethodFinder$TaskImpl.run(TaskMethodFinder.java:175)
at org.jvnet.hudson.reactor.Reactor.runTask(Reactor.java:282)
at org.jvnet.hudson.reactor.Reactor$2.run(Reactor.java:210)
at org.jvnet.hudson.reactor.Reactor$Node.run(Reactor.java:117)
at jenkins.model.Jenkins$20.execute(Jenkins.java:3020)
at org.jvnet.hudson.reactor.Reactor$Node.runIfPossible(Reactor.java:139)
at org.jvnet.hudson.reactor.Reactor$Node.run(Reactor.java:128)
at jenkins.model.Jenkins$20.execute(Jenkins.java:3020)
at org.jvnet.hudson.reactor.Reactor$Node.runIfPossible(Reactor.java:139)
at org.jvnet.hudson.reactor.Reactor.execute(Reactor.java:263)
at jenkins.model.Jenkins._cleanUpRunTerminators(Jenkins.java:3017)
at jenkins.model.Jenkins.cleanUp(Jenkins.java:2939)
at hudson.WebAppMain$3.run(WebAppMain.java:248)
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins$21 onAttained
INFO: Completed termination
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins _cleanUpDisconnectComputers
INFO: Starting node disconnection
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins _cleanUpShutdownPluginManager
INFO: Stopping plugin manager
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins _cleanUpPersistQueue
INFO: Persisting build queue
Jul 18, 2016 5:33:40 AM jenkins.model.Jenkins cleanUp
INFO: Jenkins stopped