New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Infinite restarts after vanilla installation of configuration as code plugin #520

Closed
sch1itzohr opened this Issue Sep 14, 2018 · 11 comments

Comments

4 participants
@sch1itzohr

sch1itzohr commented Sep 14, 2018

Hi,
I decided to research possible cons of this plugin for spinning up the jenkins instance from scratch in minutes, but faced problem that after installation of plugins and restart by configuration plugin it goes in infinite loop.
My setup:
java version 1.8.0_181
jenkins version 2.121.1
configuration-as-code plugin: 1.0

Jenkins output. This keeps appe

Running from: /home/thisguy/.m2/repository/org/jenkins-ci/main/jenkins-war/2.121.1/jenkins-war-2.121.1.war
webroot: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 4:23:45 PM org.eclipse.jetty.util.log.Log initialized
INFO: Logging initialized @356ms to org.eclipse.jetty.util.log.JavaUtilLog
Sep 14, 2018 4:23:45 PM winstone.Logger logInternal
INFO: Beginning extraction from war file
Sep 14, 2018 4:23:45 PM org.eclipse.jetty.server.handler.ContextHandler setContextPath
WARNING: Empty contextPath
Sep 14, 2018 4:23:46 PM org.eclipse.jetty.server.Server doStart
INFO: jetty-9.4.z-SNAPSHOT, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
Sep 14, 2018 4:23:46 PM org.eclipse.jetty.webapp.StandardDescriptorProcessor visitServlet
INFO: NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
Sep 14, 2018 4:23:46 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: DefaultSessionIdManager workerName=node0
Sep 14, 2018 4:23:46 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: No SessionScavenger set, using defaults
Sep 14, 2018 4:23:46 PM org.eclipse.jetty.server.session.HouseKeeper startScavenging
INFO: Scavenging every 600000ms
Jenkins home directory: /home/thisguy/Work/jenkins_home found at: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 4:23:47 PM org.eclipse.jetty.server.handler.ContextHandler doStart
INFO: Started w.@57bc27f5{/,file:///home/thisguy/Work/jenkins_home/war/,AVAILABLE}{/home/thisguy/Work/jenkins_home/war}
Sep 14, 2018 4:23:47 PM org.eclipse.jetty.server.AbstractConnector doStart
INFO: Started ServerConnector@6f15d60e{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
Sep 14, 2018 4:23:47 PM org.eclipse.jetty.server.Server doStart
INFO: Started @1594ms
Sep 14, 2018 4:23:47 PM winstone.Logger logInternal
INFO: Winstone Servlet Engine v4.0 running: controlPort=disabled
Sep 14, 2018 4:23:48 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 4:23:48 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 4:23:51 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 4:23:51 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 4:23:51 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 4:23:52 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 4:23:52 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 4:23:52 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@94e0eb4.systemMessage = Jenkins configured automatically by Jenkins Configuration as Code Plugin
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@94e0eb4.mode = NORMAL
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@94e0eb4.numExecutors = 2
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@94e0eb4.scmCheckoutRetryCount = 2
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 4:23:56 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@94e0eb4.globalNodeProperties = [hudson.slaves.EnvironmentVariablesNodeProperty@36b0b768]
Sep 14, 2018 4:23:56 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 4:23:56 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Started Download metadata
Sep 14, 2018 4:23:56 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Finished Download metadata. 49 ms
Sep 14, 2018 4:23:57 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@61067bc3: display name [Root WebApplicationContext]; startup date [Fri Sep 14 16:23:57 MSK 2018]; root of context hierarchy
Sep 14, 2018 4:23:57 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@61067bc3]: org.springframework.beans.factory.support.DefaultListableBeanFactory@6dd81d9
Sep 14, 2018 4:23:57 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@6dd81d9: defining beans [authenticationManager]; root of factory hierarchy
Sep 14, 2018 4:23:57 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@669d05e0: display name [Root WebApplicationContext]; startup date [Fri Sep 14 16:23:57 MSK 2018]; root of context hierarchy
Sep 14, 2018 4:23:57 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@669d05e0]: org.springframework.beans.factory.support.DefaultListableBeanFactory@2dedd909
Sep 14, 2018 4:23:57 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@2dedd909: defining beans [filter,legacy]; root of factory hierarchy
Sep 14, 2018 4:23:57 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 4:23:57 PM hudson.WebAppMain$3 run
INFO: Jenkins is fully up and running
Sep 14, 2018 4:24:01 PM jenkins.model.Jenkins$23 run
SEVERE: Restarting VM as requested by SYSTEM
Sep 14, 2018 4:24:01 PM jenkins.model.Jenkins cleanUp
INFO: Stopping Jenkins
Sep 14, 2018 4:24:01 PM jenkins.model.Jenkins$19 onAttained
INFO: Started termination
Sep 14, 2018 4:24:01 PM jenkins.model.Jenkins$19 onAttained
INFO: Completed termination
Sep 14, 2018 4:24:01 PM jenkins.model.Jenkins _cleanUpDisconnectComputers
INFO: Starting node disconnection
Sep 14, 2018 4:24:02 PM jenkins.model.Jenkins _cleanUpShutdownPluginManager
INFO: Stopping plugin manager
Sep 14, 2018 4:24:02 PM jenkins.model.Jenkins _cleanUpPersistQueue
INFO: Persisting build queue
Sep 14, 2018 4:24:02 PM jenkins.model.Jenkins _cleanUpAwaitDisconnects
INFO: Waiting for node disconnection completion
Sep 14, 2018 4:24:02 PM jenkins.model.Jenkins cleanUp
INFO: Jenkins stopped

Environment:
CASC_JENKINS_CONFIG=~/Work/jenkins_cac/configs/jenkins.yaml
Configuration as code configuration file content:

plugins:
  sites:
  - id: "default"
    url: "https://ftp-nyc.osuosl.org/pub/jenkins/updates/current/update-center.json"
  required:
    ldap: "latest"
    git: "3.9.0"
jenkins:
  systemMessage: "Jenkins configured automatically by Jenkins Configuration as Code Plugin\n\n"
  numExecutors: 2
  scmCheckoutRetryCount: 2
  mode: NORMAL
  globalNodeProperties:
    - envVars:
        env:
          - key: WHAT
            value: EVER

Jenkins running command:
java -Duser.home=~/Work/jenkins_home -Dhudson.model.DownloadService.noSignatureCheck=true -jar /home/ashageev/Work/jenkins.war

@ndeloof

This comment has been minimized.

Member

ndeloof commented Sep 14, 2018

Can you check JENKINS_HOME/plugins to confirm requested plugins (and dependencies) get downloaded ?
I'm surprised the log don't tell anything about plugin installation (which is the most probable cause for restart)

@sch1itzohr

This comment has been minimized.

sch1itzohr commented Sep 14, 2018

Hi @ndeloof,
Plugins are in place, they were downloaded in previous restart. Sorry, I attached little part of log. This is the complete log:

ning from: /home/thisguy/.m2/repository/org/jenkins-ci/main/jenkins-war/2.121.1/jenkins-war-2.121.1.war
webroot: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 2:31:58 PM org.eclipse.jetty.util.log.Log initialized
INFO: Logging initialized @558ms to org.eclipse.jetty.util.log.JavaUtilLog
Sep 14, 2018 2:31:58 PM winstone.Logger logInternal
INFO: Beginning extraction from war file
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.server.handler.ContextHandler setContextPath
WARNING: Empty contextPath
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.server.Server doStart
INFO: jetty-9.4.z-SNAPSHOT, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.webapp.StandardDescriptorProcessor visitServlet
INFO: NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: DefaultSessionIdManager workerName=node0
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: No SessionScavenger set, using defaults
Sep 14, 2018 2:32:00 PM org.eclipse.jetty.server.session.HouseKeeper startScavenging
INFO: Scavenging every 600000ms
Jenkins home directory: /home/thisguy/Work/jenkins_home found at: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 2:32:01 PM org.eclipse.jetty.server.handler.ContextHandler doStart
INFO: Started w.@7f811d00{/,file:///home/thisguy/Work/jenkins_home/war/,AVAILABLE}{/home/thisguy/Work/jenkins_home/war}
Sep 14, 2018 2:32:01 PM org.eclipse.jetty.server.AbstractConnector doStart
INFO: Started ServerConnector@724f138e{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
Sep 14, 2018 2:32:01 PM org.eclipse.jetty.server.Server doStart
INFO: Started @3215ms
Sep 14, 2018 2:32:01 PM winstone.Logger logInternal
INFO: Winstone Servlet Engine v4.0 running: controlPort=disabled
Sep 14, 2018 2:32:02 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:32:02 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:32:04 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:32:04 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:32:04 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:32:05 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:32:05 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Started Download metadata
Sep 14, 2018 2:32:06 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@72b8ed1e: display name [Root WebApplicationContext]; startup date [Fri Sep 14 14:32:06 MSK 2018]; root of context hierarchy
Sep 14, 2018 2:32:06 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@72b8ed1e]: org.springframework.beans.factory.support.DefaultListableBeanFactory@448eafb6
Sep 14, 2018 2:32:06 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@448eafb6: defining beans [authenticationManager]; root of factory hierarchy
Sep 14, 2018 2:32:06 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@d9937a5: display name [Root WebApplicationContext]; startup date [Fri Sep 14 14:32:06 MSK 2018]; root of context hierarchy
Sep 14, 2018 2:32:06 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@d9937a5]: org.springframework.beans.factory.support.DefaultListableBeanFactory@3c3a33dd
Sep 14, 2018 2:32:06 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@3c3a33dd: defining beans [filter,legacy]; root of factory hierarchy
Sep 14, 2018 2:32:07 PM jenkins.install.SetupWizard init
INFO: 

*************************************************************
*************************************************************
*************************************************************

Jenkins initial setup is required. An admin user has been created and a password generated.
Please use the following password to proceed to installation:

6425af0bd8d24c06ae472271cf77ac8e

This may also be found at: /home/thisguy/Work/jenkins_home/secrets/initialAdminPassword

*************************************************************
*************************************************************
*************************************************************

Sep 14, 2018 2:32:11 PM hudson.model.UpdateSite updateData
INFO: Obtained the latest update center data file for UpdateSource default
Sep 14, 2018 2:32:12 PM hudson.model.UpdateSite updateData
INFO: Obtained the latest update center data file for UpdateSource default
Sep 14, 2018 2:32:12 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:32:12 PM hudson.WebAppMain$3 run
INFO: Jenkins is fully up and running
Sep 14, 2018 2:32:12 PM hudson.model.DownloadService$Downloadable load
INFO: Obtained the updated data file for hudson.tasks.Maven.MavenInstaller
Sep 14, 2018 2:32:12 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Finished Download metadata. 7,475 ms
Sep 14, 2018 2:33:12 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of jdk-tool for plugin configuration-as-code
Sep 14, 2018 2:33:12 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of command-launcher for plugin configuration-as-code
Sep 14, 2018 2:33:12 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of jdk-tool for plugin command-launcher already added, skipping
Sep 14, 2018 2:33:12 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of script-security for plugin command-launcher
Sep 14, 2018 2:33:12 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of jdk-tool for plugin script-security already added, skipping
Sep 14, 2018 2:33:12 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of JDK Tool on behalf of admin
Sep 14, 2018 2:33:13 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading JDK Tool
Sep 14, 2018 2:33:14 PM hudson.PluginManager dynamicLoad
INFO: Attempting to dynamic load /home/thisguy/Work/jenkins_home/plugins/jdk-tool.jpi
Sep 14, 2018 2:33:15 PM hudson.model.UpdateSite updateData
INFO: Obtained the latest update center data file for UpdateSource default
Sep 14, 2018 2:33:15 PM hudson.model.DownloadService$Downloadable load
INFO: Obtained the updated data file for hudson.tools.JDKInstaller
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:33:15 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:33:15 PM hudson.PluginManager dynamicLoad
INFO: Plugin jdk-tool:1.1 dynamically installed
Sep 14, 2018 2:33:15 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: JDK Tool
Sep 14, 2018 2:33:15 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Script Security on behalf of admin
Sep 14, 2018 2:33:15 PM hudson.model.DownloadService$Downloadable load
INFO: Obtained the updated data file for hudson.tasks.Maven.MavenInstaller
Sep 14, 2018 2:33:16 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Script Security
Sep 14, 2018 2:33:16 PM hudson.model.DownloadService$Downloadable load
INFO: Obtained the updated data file for hudson.tools.JDKInstaller
Sep 14, 2018 2:33:16 PM hudson.PluginManager dynamicLoad
INFO: Attempting to dynamic load /home/thisguy/Work/jenkins_home/plugins/script-security.jpi
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:33:16 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:33:16 PM hudson.PluginManager dynamicLoad
INFO: Plugin script-security:1.46 dynamically installed
Sep 14, 2018 2:33:16 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Script Security
Sep 14, 2018 2:33:16 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Command Agent Launcher on behalf of admin
Sep 14, 2018 2:33:17 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Command Agent Launcher
Sep 14, 2018 2:33:17 PM hudson.PluginManager dynamicLoad
INFO: Attempting to dynamic load /home/thisguy/Work/jenkins_home/plugins/command-launcher.jpi
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:33:17 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:33:17 PM hudson.PluginManager dynamicLoad
INFO: Plugin command-launcher:1.2 dynamically installed
Sep 14, 2018 2:33:17 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Command Agent Launcher
Sep 14, 2018 2:33:17 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Configuration as Code on behalf of admin
Sep 14, 2018 2:33:19 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Configuration as Code
Sep 14, 2018 2:33:20 PM hudson.PluginManager dynamicLoad
INFO: Attempting to dynamic load /home/thisguy/Work/jenkins_home/plugins/configuration-as-code.jpi
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.systemMessage = Jenkins configured automatically by Jenkins Configuration as Code Plugin


Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.mode = NORMAL
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.numExecutors = 2
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.scmCheckoutRetryCount = 2
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:33:20 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.globalNodeProperties = [hudson.slaves.EnvironmentVariablesNodeProperty@f73723]
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:33:20 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:33:20 PM hudson.PluginManager dynamicLoad
INFO: Plugin configuration-as-code:1.0 dynamically installed
Sep 14, 2018 2:33:20 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Configuration as Code
Sep 14, 2018 2:34:45 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:34:45 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:34:45 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of workflow-scm-step for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of workflow-step-api for plugin workflow-scm-step
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of structs for plugin workflow-step-api
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of bouncycastle-api for plugin structs
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin workflow-step-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of credentials for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin credentials already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin credentials already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin git already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of git-client for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of apache-httpcomponents-client-4-api for plugin git-client
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of credentials for plugin git-client already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin git-client already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of ssh-credentials for plugin git-client
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of credentials for plugin ssh-credentials already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin ssh-credentials already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of jsch for plugin git-client
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of ssh-credentials for plugin jsch already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin jsch already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin git-client already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of ssh-credentials for plugin git already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of scm-api for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin scm-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin scm-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin git already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of mailer for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of display-url-api for plugin mailer
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin display-url-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin mailer already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of matrix-project for plugin git
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of junit for plugin matrix-project
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin junit already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin junit already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Adding dependent install of workflow-api for plugin junit
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin workflow-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of scm-api for plugin workflow-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin workflow-api already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin junit already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin matrix-project already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin git already added, skipping
Sep 14, 2018 2:34:47 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of bouncycastle API on behalf of admin
Sep 14, 2018 2:34:49 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading bouncycastle API
Sep 14, 2018 2:34:51 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Structs on behalf of admin
Sep 14, 2018 2:34:51 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Structs
Sep 14, 2018 2:34:51 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: Step API on behalf of admin
Sep 14, 2018 2:34:52 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Pipeline: Step API
Sep 14, 2018 2:34:53 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: SCM Step on behalf of admin
Sep 14, 2018 2:34:54 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Pipeline: SCM Step
Sep 14, 2018 2:34:54 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Credentials on behalf of admin
Sep 14, 2018 2:34:55 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Credentials
Sep 14, 2018 2:34:56 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Apache HttpComponents Client 4.x API on behalf of admin
Sep 14, 2018 2:34:57 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Apache HttpComponents Client 4.x API
Sep 14, 2018 2:34:58 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of SSH Credentials on behalf of admin
Sep 14, 2018 2:34:59 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading SSH Credentials
Sep 14, 2018 2:34:59 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of JSch dependency on behalf of admin
Sep 14, 2018 2:35:00 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading JSch dependency
Sep 14, 2018 2:35:01 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Git client on behalf of admin
Sep 14, 2018 2:35:02 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Git client
Sep 14, 2018 2:35:04 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of SCM API on behalf of admin
Sep 14, 2018 2:35:05 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading SCM API
Sep 14, 2018 2:35:05 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Display URL API on behalf of admin
Sep 14, 2018 2:35:06 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Display URL API
Sep 14, 2018 2:35:06 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Mailer on behalf of admin
Sep 14, 2018 2:35:07 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Mailer
Sep 14, 2018 2:35:07 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: API on behalf of admin
Sep 14, 2018 2:35:09 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Pipeline: API
Sep 14, 2018 2:35:09 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of JUnit on behalf of admin
Sep 14, 2018 2:35:10 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading JUnit
Sep 14, 2018 2:35:11 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Matrix Project on behalf of admin
Sep 14, 2018 2:35:12 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Matrix Project
Sep 14, 2018 2:35:12 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Git on behalf of admin
Sep 14, 2018 2:35:13 PM hudson.model.UpdateCenter$UpdateCenterConfiguration download
INFO: Downloading Git
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin workflow-scm-step already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: SCM Step on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Pipeline: SCM Step@2.6
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Pipeline: SCM Step
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin workflow-step-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin workflow-step-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: Step API on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Pipeline: Step API@2.16
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Pipeline: Step API
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin credentials already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin credentials already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Credentials on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Credentials@2.1.18
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Credentials
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of apache-httpcomponents-client-4-api for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of credentials for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of ssh-credentials for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of jsch for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin git-client already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Git client on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Git client@2.7.3
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Git client
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of display-url-api for plugin mailer already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin mailer already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Mailer on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Mailer@1.21
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Mailer
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of junit for plugin matrix-project already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin matrix-project already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Matrix Project on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Matrix Project@1.13
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Matrix Project
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin scm-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin scm-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of SCM API on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: SCM API@2.2.7
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: SCM API
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of credentials for plugin ssh-credentials already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin ssh-credentials already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of SSH Credentials on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: SSH Credentials@1.14
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: SSH Credentials
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin structs already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Structs on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Structs@1.14
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Structs
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Apache HttpComponents Client 4.x API on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Apache HttpComponents Client 4.x API@4.5.5-3.0
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Apache HttpComponents Client 4.x API
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of ssh-credentials for plugin jsch already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin jsch already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of JSch dependency on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: JSch dependency@0.1.54.2
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: JSch dependency
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin display-url-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Display URL API on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Display URL API@2.2.0
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Display URL API
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin junit already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin junit already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-api for plugin junit already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of bouncycastle-api for plugin junit already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of JUnit on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: JUnit@1.25
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: JUnit
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of structs for plugin workflow-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of scm-api for plugin workflow-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateSite$Plugin deploy
INFO: Dependent install of workflow-step-api for plugin workflow-api already added, skipping
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Starting the installation of Pipeline: API on behalf of admin
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$InstallationJob _run
INFO: Skipping duplicate install of: Pipeline: API@2.29
Sep 14, 2018 2:35:14 PM hudson.model.UpdateCenter$DownloadJob run
INFO: Installation successful: Pipeline: API
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.systemMessage = Jenkins configured automatically by Jenkins Configuration as Code Plugin


Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.mode = NORMAL
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.numExecutors = 2
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.scmCheckoutRetryCount = 2
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:35:14 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@51e368d8.globalNodeProperties = [hudson.slaves.EnvironmentVariablesNodeProperty@78d76419]
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins$23 run
SEVERE: Restarting VM as requested by admin
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins cleanUp
INFO: Stopping Jenkins
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins$19 onAttained
INFO: Started termination
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins$19 onAttained
INFO: Completed termination
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins _cleanUpDisconnectComputers
INFO: Starting node disconnection
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins _cleanUpShutdownPluginManager
INFO: Stopping plugin manager
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins _cleanUpPersistQueue
INFO: Persisting build queue
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins _cleanUpAwaitDisconnects
INFO: Waiting for node disconnection completion
Sep 14, 2018 2:35:19 PM jenkins.model.Jenkins cleanUp
INFO: Jenkins stopped
Running from: /home/thisguy/.m2/repository/org/jenkins-ci/main/jenkins-war/2.121.1/jenkins-war-2.121.1.war
webroot: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 2:35:21 PM org.eclipse.jetty.util.log.Log initialized
INFO: Logging initialized @459ms to org.eclipse.jetty.util.log.JavaUtilLog
Sep 14, 2018 2:35:22 PM winstone.Logger logInternal
INFO: Beginning extraction from war file
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.server.handler.ContextHandler setContextPath
WARNING: Empty contextPath
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.server.Server doStart
INFO: jetty-9.4.z-SNAPSHOT, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.webapp.StandardDescriptorProcessor visitServlet
INFO: NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: DefaultSessionIdManager workerName=node0
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.server.session.DefaultSessionIdManager doStart
INFO: No SessionScavenger set, using defaults
Sep 14, 2018 2:35:22 PM org.eclipse.jetty.server.session.HouseKeeper startScavenging
INFO: Scavenging every 660000ms
Jenkins home directory: /home/thisguy/Work/jenkins_home found at: EnvVars.masterEnvVars.get("JENKINS_HOME")
Sep 14, 2018 2:35:23 PM org.eclipse.jetty.server.handler.ContextHandler doStart
INFO: Started w.@57bc27f5{/,file:///home/thisguy/Work/jenkins_home/war/,AVAILABLE}{/home/thisguy/Work/jenkins_home/war}
Sep 14, 2018 2:35:23 PM org.eclipse.jetty.server.AbstractConnector doStart
INFO: Started ServerConnector@6f15d60e{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
Sep 14, 2018 2:35:23 PM org.eclipse.jetty.server.Server doStart
INFO: Started @1832ms
Sep 14, 2018 2:35:23 PM winstone.Logger logInternal
INFO: Winstone Servlet Engine v4.0 running: controlPort=disabled
Sep 14, 2018 2:35:24 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started initialization
Sep 14, 2018 2:35:25 PM jenkins.InitReactorRunner$1 onAttained
INFO: Listed all plugins
Sep 14, 2018 2:35:28 PM jenkins.InitReactorRunner$1 onAttained
INFO: Prepared all plugins
Sep 14, 2018 2:35:28 PM jenkins.InitReactorRunner$1 onAttained
INFO: Started all plugins
Sep 14, 2018 2:35:28 PM jenkins.InitReactorRunner$1 onAttained
INFO: Augmented all extensions
Sep 14, 2018 2:35:29 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:35:29 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:35:29 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@3c519b47.systemMessage = Jenkins configured automatically by Jenkins Configuration as Code Plugin


Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@3c519b47.mode = NORMAL
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@3c519b47.numExecutors = 2
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@3c519b47.scmCheckoutRetryCount = 2
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.key = FOO
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty$Entry.value = BAR
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.impl.configurators.DataBoundConfigurator tryConstructor
INFO: Setting class hudson.slaves.EnvironmentVariablesNodeProperty.env = [{}]
Sep 14, 2018 2:35:33 PM io.jenkins.plugins.casc.BaseConfigurator configure
INFO: Setting hudson.model.Hudson@3c519b47.globalNodeProperties = [hudson.slaves.EnvironmentVariablesNodeProperty@bb8e9b8]
Sep 14, 2018 2:35:33 PM jenkins.InitReactorRunner$1 onAttained
INFO: Loaded all jobs
Sep 14, 2018 2:35:33 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Started Download metadata
Sep 14, 2018 2:35:33 PM hudson.model.AsyncPeriodicWork$1 run
INFO: Finished Download metadata. 8 ms
Sep 14, 2018 2:35:34 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@6294eebc: display name [Root WebApplicationContext]; startup date [Fri Sep 14 14:35:34 MSK 2018]; root of context hierarchy
Sep 14, 2018 2:35:34 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@6294eebc]: org.springframework.beans.factory.support.DefaultListableBeanFactory@d89cdc7
Sep 14, 2018 2:35:34 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@d89cdc7: defining beans [authenticationManager]; root of factory hierarchy
Sep 14, 2018 2:35:34 PM org.springframework.context.support.AbstractApplicationContext prepareRefresh
INFO: Refreshing org.springframework.web.context.support.StaticWebApplicationContext@4cea6ea6: display name [Root WebApplicationContext]; startup date [Fri Sep 14 14:35:34 MSK 2018]; root of context hierarchy
Sep 14, 2018 2:35:34 PM org.springframework.context.support.AbstractApplicationContext obtainFreshBeanFactory
INFO: Bean factory for application context [org.springframework.web.context.support.StaticWebApplicationContext@4cea6ea6]: org.springframework.beans.factory.support.DefaultListableBeanFactory@5388ef8f
Sep 14, 2018 2:35:34 PM org.springframework.beans.factory.support.DefaultListableBeanFactory preInstantiateSingletons
INFO: Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@5388ef8f: defining beans [filter,legacy]; root of factory hierarchy
Sep 14, 2018 2:35:34 PM jenkins.InitReactorRunner$1 onAttained
INFO: Completed initialization
Sep 14, 2018 2:35:34 PM hudson.WebAppMain$3 run
INFO: Jenkins is fully up and running
Sep 14, 2018 2:35:38 PM jenkins.model.Jenkins$23 run
SEVERE: Restarting VM as requested by SYSTEM
Sep 14, 2018 2:35:38 PM jenkins.model.Jenkins cleanUp
INFO: Stopping Jenkins
Sep 14, 2018 2:35:38 PM jenkins.model.Jenkins$19 onAttained
INFO: Started termination
Sep 14, 2018 2:35:38 PM hudson.util.ExceptionCatchingThreadFactory uncaughtException
WARNING: Thread Computer.threadPoolForRemoting [#2] terminated unexpectedly
java.nio.channels.ClosedSelectorException
  at sun.nio.ch.SelectorImpl.keys(SelectorImpl.java:68)
  at org.jenkinsci.remoting.protocol.IOHub.getThreadNameBase(IOHub.java:426)
  at org.jenkinsci.remoting.protocol.IOHub.access$200(IOHub.java:69)
  at org.jenkinsci.remoting.protocol.IOHub$IOHubSelectorWatcher.run(IOHub.java:536)
  at jenkins.util.ContextResettingExecutorService$1.run(ContextResettingExecutorService.java:28)
  at jenkins.security.ImpersonatingExecutorService$1.run(ImpersonatingExecutorService.java:59)
  at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
  at java.lang.Thread.run(Thread.java:748)

Sep 14, 2018 2:35:38 PM jenkins.model.Jenkins$19 onAttained
INFO: Completed termination
Sep 14, 2018 2:35:38 PM jenkins.model.Jenkins _cleanUpDisconnectComputers
INFO: Starting node disconnection
Sep 14, 2018 2:35:39 PM jenkins.model.Jenkins _cleanUpShutdownPluginManager
INFO: Stopping plugin manager
Sep 14, 2018 2:35:39 PM jenkins.model.Jenkins _cleanUpPersistQueue
INFO: Persisting build queue
Sep 14, 2018 2:35:39 PM jenkins.model.Jenkins _cleanUpAwaitDisconnects
INFO: Waiting for node disconnection completion
Sep 14, 2018 2:35:39 PM jenkins.model.Jenkins cleanUp
INFO: Jenkins stopped
@thePhilGuy

This comment has been minimized.

thePhilGuy commented Sep 15, 2018

I have experienced the same issue. Upon removing the

plugins:
  required:
    git: "latest" 

part of the configuration and restarting jenkins the plugins were succesfully installed and the restart loop stopped.

@ndeloof

This comment has been minimized.

Member

ndeloof commented Sep 15, 2018

ok, I'll setup a test instance and try to reproduce this issue

@ndeloof ndeloof self-assigned this Sep 15, 2018

@ndeloof ndeloof added the bug label Sep 15, 2018

@ndeloof

This comment has been minimized.

Member

ndeloof commented Sep 26, 2018

I can reproduce this issue, investigating

ndeloof added a commit to ndeloof/configuration-as-code-plugin that referenced this issue Sep 26, 2018

handle "latest" by comparing installed version with update center met…
…adata

fix jenkinsci#520

Signed-off-by: Nicolas De Loof <nicolas.deloof@gmail.com>
@ndeloof

This comment has been minimized.

Member

ndeloof commented Sep 26, 2018

see #558

@gbonnefille

This comment has been minimized.

gbonnefille commented Sep 27, 2018

While deploying (many) jenkins, I encountered the same issue. But, related to that, I still don't understand a sort of Chicken&Egg problem related to plugins: management. So, I fear I didn't understand the role of plugins: section.

During my tests, starting from a clean installation (only Jenkins RPM and CasC plugin) it appeared that CasC is unable to process the jenkins.yaml because it starts to interpret this file before processing the plugins: section. As a consequence, it try to interpret ldap: without installing the ldap plugin (even if this plugin is listed in plugins:). In order to have a succesful full-automatic deploy, I use a multi-stage installation:

  1. install Jenkins + CasC + minimal jenkins.yaml (only plugins: section) and start Jenkins -> it downloads the plugins
  2. when ready, deploy the effective jenkins.yaml (without the plugins: section, due to this bug) and restart -> Jenkins terminates its configuration.

While automatic deploy is probably the goal of CasC, I imagined I missed something fundamental. Can you explain me how plugins: section is expected to work, please?

@ndeloof

This comment has been minimized.

Member

ndeloof commented Sep 27, 2018

@gbonnefille plugins should be processed first, to support other elements of the yaml file
I wonder this is a regression introduced by the "check then apply" logic, as "check" won't install plugins.

Anyway plugin support is pretty experimental, I'd recommend to rely on another approach until proven stable.

@sch1itzohr

This comment has been minimized.

sch1itzohr commented Oct 2, 2018

Closing issue, everything works fine.

@sch1itzohr sch1itzohr closed this Oct 2, 2018

@sch1itzohr sch1itzohr reopened this Oct 2, 2018

@sch1itzohr

This comment has been minimized.

sch1itzohr commented Oct 2, 2018

Sorry, this commit is not in master yet.

@ndeloof

This comment has been minimized.

Member

ndeloof commented Oct 2, 2018

@sch1itzohr have you been able to test the PR ? Does it fix this issue ?

@ndeloof ndeloof closed this in 20ee208 Oct 5, 2018

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment