Uploaded image for project: 'Jenkins'
  1. Jenkins
  2. JENKINS-36035

Bouncycastle stacktrace at jenkins start after an upgrade

    Details

    • Similar Issues:

      Description

      In some occasions this exception appears in the jenkins logs during the start

      INFO] java -Djava.security.egd=file:/dev/./urandom -Xdebug -Xrunjdwp:transport=dt_socket,suspend=n,server=y,address=127.0.0.1:8000 -XX:MaxPermSize=512m -Xms256m -Dhudson.DNSMultiCast.disabled=true -jar /Users/dvilladiego/.m2/repository/com/cloudbees/jenkins/main/jenkins-enterprise-war/1.651.3.1-beta-2/jenkins-enterprise-war-1.651.3.1-beta-2.war --httpPort=8080 --httpListenAddress=127.0.0.1
      Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0
      Listening for transport dt_socket at address: 8000
      Running from: /Users/dvilladiego/.m2/repository/com/cloudbees/jenkins/main/jenkins-enterprise-war/1.651.3.1-beta-2/jenkins-enterprise-war-1.651.3.1-beta-2.war
      webroot: EnvVars.masterEnvVars.get("JENKINS_HOME")
      jun 16, 2016 3:58:07 PM winstone.Logger logInternal
      INFO: Beginning extraction from war file
      jun 16, 2016 3:58:09 PM org.eclipse.jetty.util.log.JavaUtilLog info
      INFO: jetty-winstone-2.9
      jun 16, 2016 3:58:11 PM org.eclipse.jetty.util.log.JavaUtilLog info
      INFO: NO JSP Support for , did not find org.apache.jasper.servlet.JspServlet
      jun 16, 2016 3:58:11 PM com.cloudbees.jenkins.ha.AbstractJenkinsSingleton contextInitialized
      INFO: Jenkins home directory: /Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home found at: EnvVars.masterEnvVars.get("JENKINS_HOME")
      jun 16, 2016 3:58:11 PM com.cloudbees.jenkins.ha.singleton.HASingleton createChannel
      INFO: Loading default configuration from jar:file:/Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home/war/WEB-INF/lib/jenkins-ha-4.7.jar!/com/cloudbees/jenkins/ha/singleton/jgroups-jenkins.xml using HA_JGROUPS_DIR=/Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home/jgroups
      jun 16, 2016 3:58:11 PM org.jgroups.stack.DiagnosticsHandler bindToInterfaces
      WARNING: failed to join /224.0.75.75:7500 on awdl0: java.net.SocketException: Can't assign requested address
      
      -------------------------------------------------------------------
      GMS: address=MacBook-Pro-de-Dario-7421 (318 bytes), cluster=Jenkins, physical address=192.168.0.1:53507
      -------------------------------------------------------------------
      jun 16, 2016 3:58:14 PM com.cloudbees.jenkins.ha.singleton.HASingleton$3 viewAccepted
      INFO: Cluster membership has changed to: [MacBook-Pro-de-Dario-7421 (318 bytes)|0] [MacBook-Pro-de-Dario-7421 (318 bytes)]
      jun 16, 2016 3:58:14 PM com.cloudbees.jenkins.ha.singleton.HASingleton$3 viewAccepted
      INFO: New primary node is JenkinsClusterMemberIdentity[member=MacBook-Pro-de-Dario-7421 (318 bytes),weight=0,min=0]
      jun 16, 2016 3:58:14 PM com.cloudbees.jenkins.ha.singleton.HASingleton reactToPrimarySwitch
      INFO: Elected as the primary node
      Jenkins home directory: /Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home found at: System.getProperty("JENKINS_HOME")
      jun 16, 2016 3:58:15 PM org.eclipse.jetty.util.log.JavaUtilLog info
      INFO: Started SelectChannelConnector@127.0.0.1:8080
      jun 16, 2016 3:58:15 PM winstone.Logger logInternal
      INFO: Winstone Servlet Engine v2.0 running: controlPort=disabled
      jun 16, 2016 3:58:16 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Started initialization
      jun 16, 2016 3:58:21 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Listed all plugins
      jun 16, 2016 3:58:22 PM org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration run
      INFO: Trying to register BouncyCastle as a JCE provider
      java.lang.ClassCastException: org.bouncycastle.jcajce.provider.symmetric.IDEA$Mappings cannot be cast to org.bouncycastle.jcajce.provider.util.AlgorithmProvider
      	at org.bouncycastle.jce.provider.BouncyCastleProvider.loadAlgorithms(Unknown Source)
      	at org.bouncycastle.jce.provider.BouncyCastleProvider.setup(Unknown Source)
      	at org.bouncycastle.jce.provider.BouncyCastleProvider.access$000(Unknown Source)
      	at org.bouncycastle.jce.provider.BouncyCastleProvider$1.run(Unknown Source)
      	at java.security.AccessController.doPrivileged(Native Method)
      	at org.bouncycastle.jce.provider.BouncyCastleProvider.<init>(Unknown Source)
      	at org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration.run(SecurityUtils.java:114)
      	at org.apache.sshd.common.util.SecurityUtils.register(SecurityUtils.java:96)
      	at org.apache.sshd.common.util.SecurityUtils.isBouncyCastleRegistered(SecurityUtils.java:81)
      	at org.apache.sshd.SshBuilder$BaseBuilder.fillWithDefaultValues(SshBuilder.java:107)
      	at org.apache.sshd.SshBuilder$ClientBuilder.fillWithDefaultValues(SshBuilder.java:306)
      	at org.apache.sshd.SshBuilder$ClientBuilder.fillWithDefaultValues(SshBuilder.java:295)
      	at org.apache.sshd.SshBuilder$BaseBuilder.build(SshBuilder.java:234)
      	at org.apache.sshd.SshBuilder$ClientBuilder.build(SshBuilder.java:352)
      	at org.apache.sshd.SshBuilder$ClientBuilder.build(SshBuilder.java:295)
      	at org.apache.sshd.SshBuilder$BaseBuilder.build(SshBuilder.java:254)
      	at com.cloudbees.jenkins.plugins.sshslaves.PluginImpl.start(PluginImpl.java:26)
      	at hudson.ClassicPluginStrategy.startPlugin(ClassicPluginStrategy.java:450)
      	at hudson.ClassicPluginStrategy.load(ClassicPluginStrategy.java:439)
      	at hudson.PluginManager$2$1$1.run(PluginManager.java:406)
      	at org.jvnet.hudson.reactor.TaskGraphBuilder$TaskImpl.run(TaskGraphBuilder.java:169)
      	at org.jvnet.hudson.reactor.Reactor.runTask(Reactor.java:282)
      	at jenkins.model.Jenkins$8.runTask(Jenkins.java:926)
      	at org.jvnet.hudson.reactor.Reactor$2.run(Reactor.java:210)
      	at org.jvnet.hudson.reactor.Reactor$Node.run(Reactor.java:117)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
      	at java.lang.Thread.run(Thread.java:745)
      jun 16, 2016 3:58:22 PM org.apache.sshd.common.util.SecurityUtils register
      INFO: BouncyCastle not registered, using the default JCE provider
      jun 16, 2016 3:58:22 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Prepared all plugins
      jun 16, 2016 3:58:36 PM hudson.ExtensionFinder$GuiceFinder$FaultTolerantScope$1 error
      WARNING: Failed to instantiate optional component org.jenkinsci.plugins.workflow.steps.scm.SubversionStep$DescriptorImpl; skipping
      jun 16, 2016 3:58:36 PM org.openid4java.server.RealmVerifier setEnforceRpId
      WARNING: RP discovery / realm validation disabled;
      jun 16, 2016 3:58:37 PM jenkins.bouncycastle.api.SecurityProviderInitializer addSecurityProvider
      INFO: Initializing Bouncy Castle security provider.
      jun 16, 2016 3:58:37 PM jenkins.bouncycastle.api.SecurityProviderInitializer addSecurityProvider
      INFO: Bouncy Castle security provider initialized.
      jun 16, 2016 3:58:37 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Started all plugins
      jun 16, 2016 3:58:37 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Augmented all extensions
      jun 16, 2016 3:58:38 PM com.cloudbees.opscenter.context.config.ConfigurationSnippetRefresher <init>
      INFO: ConfigurationSnippetRefresher configured with recurrence period of 60.000ms
      jun 16, 2016 3:58:38 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Loaded all jobs
      jun 16, 2016 3:58:38 PM hudson.model.AsyncPeriodicWork$1 run
      INFO: Started Download metadata
      jun 16, 2016 3:58:38 PM hudson.model.AsyncPeriodicWork createListener
      INFO: Moved /Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home/Download metadata.log to /Users/dvilladiego/workspaces/test/CJE_1.651.3.1-beta-2/T3304/jenkins-home/logs/tasks/Download metadata.log.1
      jun 16, 2016 3:58:38 PM com.cloudbees.opscenter.context.Messaging$Local open
      INFO: Starting Messaging
      jun 16, 2016 3:58:38 PM hudson.model.AsyncPeriodicWork$1 run
      INFO: Finished Download metadata. 9 ms
      jun 16, 2016 3:58:38 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Completed initialization
      jun 16, 2016 3:58:38 PM hudson.UDPBroadcastThread run
      INFO: Cannot listen to UDP port 33.848, skipping: java.net.SocketException: Can't assign requested address
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because cloudbees-workflow-template is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because cloudbees-template is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because cloudbees-aborted-builds is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because cloudbees-ssh-slaves is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because workflow-cps-checkpoint is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Cannot dynamically load optional plugins because cloudbees-long-running-build is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin cloudbees-workflow-template is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin cloudbees-template is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin cloudbees-aborted-builds is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin cloudbees-ssh-slaves is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin workflow-cps-checkpoint is already installed
      jun 16, 2016 3:58:39 PM org.jenkinsci.modules.optpluginhelper.PluginHelper refresh
      INFO: Restart required as plugin cloudbees-long-running-build is already installed
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Started initialization
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Listed all plugins
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Prepared all plugins
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Started all plugins
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Augmented all extensions
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Loaded all jobs
      jun 16, 2016 3:58:39 PM jenkins.InitReactorRunner$1 onAttained
      INFO: Completed initialization
      jun 16, 2016 3:58:39 PM hudson.WebAppMain$3 run
      INFO: Jenkins is fully up and running
      
      

        Attachments

          Issue Links

            Activity

            Hide
            alobato Alvaro Lobato added a comment -

            Created PR-12 to solve the issue

            Show
            alobato Alvaro Lobato added a comment - Created PR-12 to solve the issue
            Hide
            alobato Alvaro Lobato added a comment -

            Released as 1.648.3 / 1.0.3

            Show
            alobato Alvaro Lobato added a comment - Released as 1.648.3 / 1.0.3

              People

              • Assignee:
                alobato Alvaro Lobato
                Reporter:
                alobato Alvaro Lobato
              • Votes:
                0 Vote for this issue
                Watchers:
                1 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: