· 6 years ago · Nov 02, 2019, 11:28 PM
1DEPLOY GROOVY INIT SCRIPTS
2+ '[' -L /var/jenkins_home/init.groovy.d ']'
3+ '[' -L /var/jenkins_home/license-activated-or-renewed-after-expiration.groovy.d ']'
4+ : /var/jenkins_home
5+ export -f copy_reference_file
6+ touch /var/jenkins_home/copy_reference_file.log
7+ tee -a /var/jenkins_home/copy_reference_file.log
8++ date
9+ echo '--- Copying files at Sat Nov 2 23:19:08 UTC 2019'
10--- Copying files at Sat Nov 2 23:19:08 UTC 2019
11+ find /usr/share/jenkins/ref/ -type f -exec bash -c 'copy_reference_file '\''{}'\''' ';'
12+ [[ 0 -lt 1 ]]
13+ eval 'exec java -Xbootclasspath/p:/usr/share/jenkins/alpn-boot.jar -Duser.home=/var/jenkins_home -XshowSettings:vm -Dcb.IMProp.warProfiles.cje=kubernetes.json -Dhudson.DNSMultiCast.disabled=true -Dcom.cloudbees.opscenter.analytics.reporter.JocAnalyticsReporter.PERIOD=120 -Dcom.cloudbees.opscenter.analytics.reporter.metrics.AperiodicMetricSubmitter.PERIOD=120 -Dcom.cloudbees.opscenter.analytics.FeederConfiguration.PERIOD=120 -Dcom.cloudbees.masterprovisioning.kubernetes.KubernetesMasterProvisioning.fsGroup= -Dhudson.lifecycle=hudson.lifecycle.ExitLifecycle -Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.disableAutoConfiguration=true -Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.masterImageName="CloudBees Core - Managed Master - 2.190.2.2" -Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.masterImage=cloudbees/cloudbees-core-mm:2.190.2.2 -Dcom.cloudbees.masterprovisioning.kubernetes.KubernetesMasterProvisioning.serviceAccount=jenkins -jar -Dcb.distributable.name="Docker Common CJE" -Dcb.distributable.commit_sha=4ad7f952576926f72a22876daef29be4e1d0591e /usr/share/jenkins/jenkins.war --webroot=/tmp/jenkins/war --pluginroot=/tmp/jenkins/plugins --prefix=/cjoc "$@"'
14++ exec java -Xbootclasspath/p:/usr/share/jenkins/alpn-boot.jar -Duser.home=/var/jenkins_home -XshowSettings:vm -Dcb.IMProp.warProfiles.cje=kubernetes.json -Dhudson.DNSMultiCast.disabled=true -Dcom.cloudbees.opscenter.analytics.reporter.JocAnalyticsReporter.PERIOD=120 -Dcom.cloudbees.opscenter.analytics.reporter.metrics.AperiodicMetricSubmitter.PERIOD=120 -Dcom.cloudbees.opscenter.analytics.FeederConfiguration.PERIOD=120 -Dcom.cloudbees.masterprovisioning.kubernetes.KubernetesMasterProvisioning.fsGroup= -Dhudson.lifecycle=hudson.lifecycle.ExitLifecycle -Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.disableAutoConfiguration=true '-Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.masterImageName=CloudBees Core - Managed Master - 2.190.2.2' -Dcom.cloudbees.jce.masterprovisioning.DockerImageDefinitionConfiguration.masterImage=cloudbees/cloudbees-core-mm:2.190.2.2 -Dcom.cloudbees.masterprovisioning.kubernetes.KubernetesMasterProvisioning.serviceAccount=jenkins -jar '-Dcb.distributable.name=Docker Common CJE' -Dcb.distributable.commit_sha=4ad7f952576926f72a22876daef29be4e1d0591e /usr/share/jenkins/jenkins.war --webroot=/tmp/jenkins/war --pluginroot=/tmp/jenkins/plugins --prefix=/cjoc
15VM settings:
16 Max. Heap Size (Estimated): 462.12M
17 Ergonomics Machine Class: server
18 Using VM: OpenJDK 64-Bit Server VM
19
20Running from: /usr/share/jenkins/jenkins.war
212019-11-02 23:19:09.365+0000 [id=1] INFO org.eclipse.jetty.util.log.Log#initialized: Logging initialized @745ms to org.eclipse.jetty.util.log.JavaUtilLog
222019-11-02 23:19:09.574+0000 [id=1] INFO winstone.Logger#logInternal: Beginning extraction from war file
232019-11-02 23:19:13.033+0000 [id=1] INFO org.eclipse.jetty.server.Server#doStart: jetty-9.4.z-SNAPSHOT; built: 2019-05-02T00:04:53.875Z; git: e1bc35120a6617ee3df052294e433f3a25ce7097; jvm 1.8.0_222-b10
242019-11-02 23:19:13.462+0000 [id=1] INFO o.e.j.w.StandardDescriptorProcessor#visitServlet: NO JSP Support for /cjoc, did not find org.eclipse.jetty.jsp.JettyJspServlet
252019-11-02 23:19:13.556+0000 [id=1] INFO o.e.j.s.s.DefaultSessionIdManager#doStart: DefaultSessionIdManager workerName=node0
262019-11-02 23:19:13.557+0000 [id=1] INFO o.e.j.s.s.DefaultSessionIdManager#doStart: No SessionScavenger set, using defaults
272019-11-02 23:19:13.560+0000 [id=1] INFO o.e.j.server.session.HouseKeeper#startScavenging: node0 Scavenging every 660000ms
28Jenkins home directory: /var/jenkins_home found at: EnvVars.masterEnvVars.get("JENKINS_HOME")
292019-11-02 23:19:14.640+0000 [id=1] INFO o.e.j.s.handler.ContextHandler#doStart: Started w.@4c5204af{CloudBees Core Cloud Operations Center,/cjoc,file:///tmp/jenkins/war/,AVAILABLE}{/tmp/jenkins/war}
302019-11-02 23:19:14.654+0000 [id=1] INFO o.e.j.server.AbstractConnector#doStart: Started ServerConnector@198d6542{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
312019-11-02 23:19:14.654+0000 [id=1] INFO org.eclipse.jetty.server.Server#doStart: Started @6034ms
322019-11-02 23:19:14.655+0000 [id=20] INFO winstone.Logger#logInternal: Winstone Servlet Engine v4.0 running: controlPort=disabled
332019-11-02 23:19:16.246+0000 [id=26] INFO jenkins.InitReactorRunner$1#onAttained: Started initialization
342019-11-02 23:19:24.178+0000 [id=25] WARNING hudson.ClassicPluginStrategy#createClassJarFromWebInfClasses: Created /tmp/jenkins/plugins/view-job-filters/WEB-INF/lib/classes.jar; update plugin to a version created with a newer harness
352019-11-02 23:19:25.960+0000 [id=26] INFO jenkins.InitReactorRunner$1#onAttained: Listed all plugins
362019-11-02 23:19:49.441+0000 [id=26] INFO jenkins.InitReactorRunner$1#onAttained: Prepared all plugins
372019-11-02 23:19:51.798+0000 [id=25] INFO i.f.j.o.GlobalPluginConfiguration#configChange: OpenShift Sync Plugin processing a newly supplied configuration
382019-11-02 23:19:53.061+0000 [id=29] INFO i.f.j.o.GlobalPluginConfigurationTimerTask#doRun: Confirming Jenkins is started
392019-11-02 23:19:53.751+0000 [id=25] INFO jenkins.InitReactorRunner$1#onAttained: Started all plugins
402019-11-02 23:19:53.936+0000 [id=25] INFO jenkins.InitReactorRunner$1#onAttained: Augmented all extensions
412019-11-02 23:19:56.364+0000 [id=25] INFO c.c.o.c.c.ConfigurationSnippetRefresher#<init>: ConfigurationSnippetRefresher configured with recurrence period of 60,000ms
422019-11-02 23:19:56.366+0000 [id=25] INFO c.c.o.s.c.OperationsCenterSharedConfiguration$ConfigurationRefresher#<init>: ConfigurationRefresher configured with recurrence period of 60,000ms
432019-11-02 23:19:56.760+0000 [id=26] INFO c.c.o.server.messaging.Transport#openStore: Starting Cluster Messaging
442019-11-02 23:19:59.138+0000 [id=26] INFO jenkins.InitReactorRunner$1#onAttained: Loaded all jobs
452019-11-02 23:19:59.149+0000 [id=26] INFO c.c.o.context.Messaging$Local#openStore: Starting Messaging
462019-11-02 23:19:59.636+0000 [id=69] INFO hudson.model.AsyncPeriodicWork$1#run: Started Download metadata
472019-11-02 23:19:59.640+0000 [id=69] INFO hudson.model.AsyncPeriodicWork$1#run: Finished Download metadata. 3 ms
482019-11-02 23:19:59.646+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_home/init.groovy.d/init_01_launch_configure_jenkins.groovy
492019-11-02 23:20:00.234+0000 [id=25] INFO c.c.j.p.a.CloudBeesAssurance#checkInstalledExtension: Checking the validity of the Plugin Catalog
502019-11-02 23:20:00.244+0000 [id=25] INFO c.c.j.p.a.CloudBeesAssurance#checkInstalledExtension: No Plugin Catalog installed
512019-11-02 23:20:00.352+0000 [id=72] INFO c.c.j.p.a.m.UpdateSiteDataProvider$Value#get: Beekeeper is parsing UpdateSite jenkins-operations-center
522019-11-02 23:20:02.454+0000 [id=99] INFO c.c.j.p.l.n.CloudBeesUpdateSite#updateData: Obtained the latest update center data file for UpdateSource core-oc
532019-11-02 23:20:02.839+0000 [id=26] INFO java_util_logging_Logger$info$0#call: License is valid, invoke configuration
542019-11-02 23:20:02.940+0000 [id=99] INFO h.m.DownloadService$Downloadable#load: Obtained the updated data file for hudson.tasks.Maven.MavenInstaller
552019-11-02 23:20:02.950+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_config/configure-jenkins.groovy.d/location.groovy
562019-11-02 23:20:03.054+0000 [id=25] INFO o.s.c.s.AbstractApplicationContext#prepareRefresh: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@59bd9c36: display name [Root WebApplicationContext]; startup date [Sat Nov 02 23:20:03 GMT 2019]; root of context hierarchy
572019-11-02 23:20:03.055+0000 [id=25] INFO o.s.c.s.AbstractApplicationContext#obtainFreshBeanFactory: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@59bd9c36]: org.springframework.beans.factory.support.DefaultListableBeanFactory@4b09aa4
582019-11-02 23:20:03.138+0000 [id=25] INFO o.s.b.f.s.DefaultListableBeanFactory#preInstantiateSingletons: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4b09aa4: defining beans [authenticationManager]; root of factory hierarchy
592019-11-02 23:20:04.262+0000 [id=25] INFO o.s.c.s.AbstractApplicationContext#prepareRefresh: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@78023c80: display name [Root WebApplicationContext]; startup date [Sat Nov 02 23:20:04 GMT 2019]; root of context hierarchy
602019-11-02 23:20:04.330+0000 [id=25] INFO o.s.c.s.AbstractApplicationContext#obtainFreshBeanFactory: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@78023c80]: org.springframework.beans.factory.support.DefaultListableBeanFactory@2c364aa6
612019-11-02 23:20:04.330+0000 [id=25] INFO o.s.b.f.s.DefaultListableBeanFactory#preInstantiateSingletons: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@2c364aa6: defining beans [filter,legacy]; root of factory hierarchy
622019-11-02 23:20:05.449+0000 [id=99] INFO h.m.DownloadService$Downloadable#load: Obtained the updated data file for hudson.tools.JDKInstaller
632019-11-02 23:20:05.450+0000 [id=72] INFO c.c.j.p.a.m.UpdateSiteDataProvider$Value#get: Beekeeper is parsing UpdateSite core-oc
642019-11-02 23:20:05.734+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_home/configure-jenkins.groovy.d/01-first-start-init-security.groovy
652019-11-02 23:20:07.452+0000 [id=33] INFO c.c.o.s.p.DatastoreTable#createIfMissing: Table created: CREATE TABLE IF NOT EXISTS slavelease ( lease VARCHAR(255) PRIMARY KEY, owner VARCHAR(255), tenant VARCHAR(255), status INT, resource BLOB, lastmod TIMESTAMP ); CREATE TRIGGER IF NOT EXISTS slavelease_T0 AFTER INSERT, UPDATE, DELETE ON slavelease FOR EACH ROW NOWAIT CALL "com.cloudbees.opscenter.server.persistence.SlaveLeaseTrigger"
662019-11-02 23:20:07.454+0000 [id=33] INFO c.c.o.s.p.DatastoreTable#createIfMissing: Table created: CREATE TABLE IF NOT EXISTS uids ( uid VARCHAR(255) PRIMARY KEY )
672019-11-02 23:20:10.040+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_home/configure-jenkins.groovy.d/02-initial-view.groovy
682019-11-02 23:20:12.131+0000 [id=122] INFO c.c.j.p.m.v.Alerter$Condition#checkState: Sending alert com.cloudbees.opscenter.server.metrics.health.MasterHealthCheckConditionSource$MasterHealthCheckCondition[name='Masters health checks'] status failing
692019-11-02 23:20:14.430+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_home/configure-jenkins.groovy.d/03-fixed-sshd-port.groovy
702019-11-02 23:20:14.743+0000 [id=26] INFO j.util.groovy.GroovyHookScript#execute: Executing /var/jenkins_home/init.groovy.d/init_02_administrativeMonitor.groovy
712019-11-02 23:20:14.849+0000 [id=25] INFO jenkins.InitReactorRunner$1#onAttained: Completed initialization
722019-11-02 23:20:15.132+0000 [id=29] INFO i.f.j.o.BuildConfigWatcher#start: Now handling startup build configs!!
732019-11-02 23:20:15.138+0000 [id=29] INFO i.f.j.o.ConfigMapWatcher#start: Now handling startup config maps!!
742019-11-02 23:20:15.139+0000 [id=29] INFO i.f.j.o.ImageStreamWatcher#start: Now handling startup image streams!!
752019-11-02 23:20:15.141+0000 [id=29] INFO i.f.j.o.SecretWatcher#start: Now handling startup secrets!!
762019-11-02 23:20:16.047+0000 [id=19] INFO hudson.WebAppMain$3#run: Jenkins is fully up and running
772019-11-02 23:20:17.336+0000 [id=31] INFO i.f.j.o.SecretWatcher$1#doRun: creating Secret watch for namespace cicd-pipeline-cjoc and resource version18587760
782019-11-02 23:20:17.546+0000 [id=33] INFO i.f.j.o.BuildConfigWatcher$1#doRun: creating BuildConfig watch for namespace cicd-pipeline-cjoc and resource version 18587762
792019-11-02 23:20:17.741+0000 [id=34] INFO i.f.j.o.BuildWatcher$1#doRun: creating Build watch for namespace cicd-pipeline-cjoc and resource version 18587762
802019-11-02 23:20:17.846+0000 [id=37] INFO i.f.j.o.ConfigMapWatcher$1#doRun: creating ConfigMap watch for namespace cicd-pipeline-cjoc and resource version 18587760
812019-11-02 23:20:18.030+0000 [id=34] INFO i.f.j.openshiftsync.BuildWatcher#reconcileRunsAndBuilds: Reconciling job runs and builds
822019-11-02 23:20:18.046+0000 [id=36] INFO i.f.j.o.ImageStreamWatcher$1#doRun: creating ImageStream watch for namespace cicd-pipeline-cjoc and resource version 18587762
832019-11-02 23:20:22.066+0000 [id=11] INFO o.k.s.LocaleDrivenResourceProvider#getLocaleDrivenResourceProviders: Registered LocaleDrivenResourceProvider: jenkins.MetaLocaleDrivenResourceProvider@57f41694
842019-11-02 23:20:28.435+0000 [id=199] INFO h.TcpSlaveAgentListener$ConnectionHandler#run: Accepted OperationsCenter2 connection #2 from /10.129.2.1:53686
852019-11-02 23:20:28.435+0000 [id=198] INFO h.TcpSlaveAgentListener$ConnectionHandler#run: Accepted OperationsCenter2 connection #1 from /10.128.2.1:34718
862019-11-02 23:20:28.647+0000 [id=200] INFO c.c.o.s.m.OperationsCenter$ClientListenerImpl#onHeadersReceived: Accepting connection to master Teams/workshop-team (idName: 0-workshop-team) from https://test-cjoc.ocp.fiercesw.network/teams-workshop-team/ on ip-10-129-2-1.ec2.internal/10.129.2.1:53686 with identity 87:ec:b5:af:3e:83:cb:72:ac:39:66:df:ff:e1:d6:b9
872019-11-02 23:20:28.650+0000 [id=96] INFO c.c.o.s.m.OperationsCenter$ClientListenerImpl#onHeadersReceived: Accepting connection to master Teams/test (idName: 1-test) from https://test-cjoc.ocp.fiercesw.network/teams-test/ on ip-10-128-2-1.ec2.internal/10.128.2.1:34718 with identity 66:d0:3d:df:fb:30:01:fe:32:42:88:9a:8b:90:03:d1
882019-11-02 23:20:30.435+0000 [id=98] INFO c.c.o.s.model.ConnectedMaster#updateRemoteContext: Updating context for ConnectedMaster{id=1, name='test', encodedName='test', idName='1-test', timeStamp=0, error=null, grantId='4538635f-2cad-4a8f-8306-f53366fda0a4', approved=true, localHome='null', localEndpoint=https://test-cjoc.ocp.fiercesw.network/teams-test/ , channel=hudson.remoting.Channel@6f458ae8:OperationsCenter2 connection from ip-10-128-2-1.ec2.internal/10.128.2.1:34718, identity=X.509, RSA} as it was out of date
892019-11-02 23:20:30.435+0000 [id=96] INFO c.c.o.s.model.ConnectedMaster#updateRemoteContext: Updating context for ConnectedMaster{id=0, name='workshop-team', encodedName='workshop-team', idName='0-workshop-team', timeStamp=0, error=null, grantId='370beda1-f88d-408a-9fff-2afbb2e4f911', approved=true, localHome='null', localEndpoint=https://test-cjoc.ocp.fiercesw.network/teams-workshop-team/ , channel=hudson.remoting.Channel@f908924:OperationsCenter2 connection from ip-10-129-2-1.ec2.internal/10.129.2.1:53686, identity=X.509, RSA} as it was out of date
902019-11-02 23:20:42.064+0000 [id=222] INFO c.c.j.p.m.v.Alerter$Condition#checkState: Sending alert com.cloudbees.opscenter.server.metrics.health.MasterHealthCheckConditionSource$MasterHealthCheckCondition[name='Masters health checks'] status ok
912019-11-02 23:21:09.168+0000 [id=209] WARNING c.c.o.s.b.BlueSteelRemoteServiceImpl#lambda$onConnected$0: There was an error while updating the team information on the team workshop-team
92Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to OperationsCenter2 connection from ip-10-129-2-1.ec2.internal/10.129.2.1:53686
93 at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
94 at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
95 at hudson.remoting.Channel$2.adapt(Channel.java:992)
96 at hudson.remoting.Channel$2.adapt(Channel.java:988)
97 at hudson.remoting.FutureAdapter.get(FutureAdapter.java:59)
98 at com.cloudbees.opscenter.server.bluesteel.BlueSteelMasterInformationHarvester.refreshTeamInformation(BlueSteelMasterInformationHarvester.java:153)
99 at com.cloudbees.opscenter.server.bluesteel.BlueSteelRemoteServiceImpl.lambda$onConnected$0(BlueSteelRemoteServiceImpl.java:57)
100java.lang.IllegalStateException: Can not read the team folder, time out waiting for the remote context to propagate
101 at com.cloudbees.opscenter.server.bluesteel.BlueSteelMasterInformationHarvester$BlueSteelTeamInformationMasterCallable.getTeamFolder(BlueSteelMasterInformationHarvester.java:214)
102 at com.cloudbees.opscenter.server.bluesteel.BlueSteelMasterInformationHarvester$BlueSteelTeamInformationMasterCallable.call(BlueSteelMasterInformationHarvester.java:190)
103 at com.cloudbees.opscenter.server.bluesteel.BlueSteelMasterInformationHarvester$BlueSteelTeamInformationMasterCallable.call(BlueSteelMasterInformationHarvester.java:170)
104 at hudson.remoting.UserRequest.perform(UserRequest.java:212)
105 at hudson.remoting.UserRequest.perform(UserRequest.java:54)
106 at hudson.remoting.Request$2.run(Request.java:369)
107 at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
108 at org.jenkinsci.remoting.CallableDecorator.call(CallableDecorator.java:19)
109 at hudson.remoting.CallableDecoratorList$1.call(CallableDecoratorList.java:21)
110 at jenkins.util.ContextResettingExecutorService$2.call(ContextResettingExecutorService.java:46)
111 at jenkins.security.ImpersonatingExecutorService$2.call(ImpersonatingExecutorService.java:71)
112 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
113 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
114 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
115Caused: java.util.concurrent.ExecutionException
116 at hudson.remoting.Channel$2.adapt(Channel.java:994)
117 at hudson.remoting.Channel$2.adapt(Channel.java:988)
118 at hudson.remoting.FutureAdapter.get(FutureAdapter.java:59)
119 at com.cloudbees.opscenter.server.bluesteel.BlueSteelMasterInformationHarvester.refreshTeamInformation(BlueSteelMasterInformationHarvester.java:153)
120 at com.cloudbees.opscenter.server.bluesteel.BlueSteelRemoteServiceImpl.lambda$onConnected$0(BlueSteelRemoteServiceImpl.java:57)
121 at java.lang.Thread.run(Thread.java:748)