See <https://builds.apache.org/job/provisionr-master/174/>
------------------------------------------ [...truncated 3788 lines...] ------------------------------------------------------- Running org.apache.provisionr.karaf.CustomKarafDistributionTest SLF4J: Class path contains multiple SLF4J bindings. SLF4J: Found binding in [jar:file:/home/jenkins/jenkins-slave/maven-repositories/0/org/apache/karaf/org.apache.karaf.client/2.3.1/org.apache.karaf.client-2.3.1.jar!/org/slf4j/impl/StaticLoggerBinder.class] SLF4J: Found binding in [jar:file:/home/jenkins/jenkins-slave/maven-repositories/0/org/slf4j/slf4j-simple/1.6.6/slf4j-simple-1.6.6.jar!/org/slf4j/impl/StaticLoggerBinder.class] SLF4J: Found binding in [jar:file:/home/jenkins/jenkins-slave/maven-repositories/0/org/ops4j/pax/logging/pax-logging-api/1.7.0/pax-logging-api-1.7.0.jar!/org/slf4j/impl/StaticLoggerBinder.class] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. SLF4J: Actual binding is of type [org.slf4j.impl.SimpleLoggerFactory] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving [mvn:org.apache.servicemix.bundles/org.apache.servicemix.bundles.junit/4.10_1] [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [file:/home/jenkins/jenkins-slave/maven-repositories/0/,releases=true,snapshots=true] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [http://osgi.sonatype.org/content/groups/pax-runner/,releases=true,snapshots=false] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [http://repo1.maven.org/maven2/,releases=true,snapshots=false] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [http://repository.ops4j.org/maven2/,releases=true,snapshots=false] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [http://repository.springsource.com/maven/bundles/release/,releases=true,snapshots=false] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version [org.ops4j.pax.url.mvn.internal.Connection] : Collecting versions from repository [http://repository.springsource.com/maven/bundles/external/,releases=true,snapshots=false] [org.ops4j.pax.url.mvn.internal.Connection] : Resolving exact version 2013-11-11 05:27:14,891 | INFO | FelixStartLevel | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.felix.fileinstall-deploy.cfg 2013-11-11 05:27:14,897 | INFO | FelixStartLevel | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.karaf.features.obr.cfg 2013-11-11 05:27:14,899 | INFO | FelixStartLevel | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.karaf.management.cfg 2013-11-11 05:27:14,956 | INFO | FelixStartLevel | BlueprintExtender | rint.container.BlueprintExtender 138 | 7 - org.apache.aries.blueprint.core - 1.1.0 | No quiesce support is available, so blueprint components will not participate in quiesce operations 2013-11-11 05:27:15,064 | INFO | rint Extender: 3 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.console is waiting for namespace handlers [http://aries.apache.org/blueprint/xmlns/blueprint-ext/v1.0.0] 2013-11-11 05:27:15,120 | INFO | FelixStartLevel | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Starting JMX OSGi agent 2013-11-11 05:27:15,183 | INFO | FelixStartLevel | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering MBean with ObjectName [osgi.compendium:service=cm,version=1.3,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e] for service with service.id [10] 2013-11-11 05:27:15,190 | INFO | rint Extender: 3 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.packages is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,198 | INFO | rint Extender: 3 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.deployer.kar is waiting for namespace handlers [http://aries.apache.org/blueprint/xmlns/blueprint-ext/v1.0.0] 2013-11-11 05:27:15,201 | INFO | rint Extender: 3 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.diagnostic.command is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,216 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.osgi is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,243 | INFO | rint Extender: 2 | BlueprintContainerImpl | container.BlueprintContainerImpl 344 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.diagnostic.management is waiting for dependencies [(objectClass=javax.management.MBeanServer)] 2013-11-11 05:27:15,243 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.features.command is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,306 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.ssh is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,323 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.commands is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,334 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 303 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.shell.log is waiting for namespace handlers [http://karaf.apache.org/xmlns/shell/v1.0.0] 2013-11-11 05:27:15,341 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 344 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.features.management is waiting for dependencies [(objectClass=org.apache.karaf.features.FeaturesService), (objectClass=javax.management.MBeanServer)] 2013-11-11 05:27:15,347 | INFO | rint Extender: 1 | BlueprintContainerImpl | container.BlueprintContainerImpl 344 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.karaf.admin.management is waiting for dependencies [(objectClass=javax.management.MBeanServer)] 2013-11-11 05:27:15,442 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.framework.FrameworkMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.core:type=framework,version=1.7,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e 2013-11-11 05:27:15,442 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.framework.PackageStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.core:type=packageState,version=1.5,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e 2013-11-11 05:27:15,443 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.framework.wiring.BundleWiringStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.core:type=wiringState,version=1.1,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e 2013-11-11 05:27:15,444 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.framework.BundleStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.core:type=bundleState,version=1.7,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e 2013-11-11 05:27:15,445 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.service.cm.ConfigurationAdminMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.compendium:service=cm,version=1.3,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e 2013-11-11 05:27:15,445 | INFO | JMX OSGi Agent | core | ? ? | 27 - org.apache.aries.jmx.core - 1.1.1 | Registering org.osgi.jmx.framework.ServiceStateMBean to MBeanServer com.sun.jmx.mbeanserver.JmxMBeanServer@84de3c with name osgi.core:type=serviceState,version=1.7,framework=org.apache.felix.framework,uuid=95246204-baf0-4fa7-b1bd-292145faa88e _ _ /_\ _ __ __ _ ___| |__ ___ //_\\| '_ \ / _` |/ __| '_ \ / _ \ / _ \ |_) | (_| | (__| | | | __/ \_/ \_/ .__/ \__,_|\___|_| |_|\___| |_| ___ _ _ / _ \_ __ _____ _(_)___(_) ___ _ __ _ __ / /_)/ '__/ _ \ \ / / / __| |/ _ \| '_ \| '__| / ___/| | | (_) \ V /| \__ \ | (_) | | | | | \/ |_| \___/ \_/ |_|___/_|\___/|_| |_|_| Apache Provisionr (0.5.0-incubating-SNAPSHOT) http://provisionr.incubator.apache.org/ Hit '<tab>' for a list of available commands and '[cmd] --help' for help on a specific command. Hit '<ctrl-d>' to shutdown the service. Apache Provisionr [0.5.0-incubating-SNAPSHOT] $ 2013-11-11 05:27:15,936 | INFO | rint Extender: 3 | SecurityUtils | e.sshd.common.util.SecurityUtils 80 | 29 - org.apache.sshd.core - 0.8.0 | BouncyCastle not registered, using the default JCE provider 2013-11-11 05:27:16,002 | INFO | rint Extender: 1 | KarArtifactInstaller | eployer.kar.KarArtifactInstaller 73 | 20 - org.apache.karaf.deployer.kar - 2.3.1 | Karaf archives will be extracted to /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/system 2013-11-11 05:27:16,002 | INFO | rint Extender: 1 | KarArtifactInstaller | eployer.kar.KarArtifactInstaller 74 | 20 - org.apache.karaf.deployer.kar - 2.3.1 | Timestamps for Karaf archives will be extracted to /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/system/.timestamps 2013-11-11 05:27:16,011 | INFO | fbe535297/deploy | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/deploy/4c0af96e-fb35-4bd7-8872-1087daf670bb_4.10_1.jar 2013-11-11 05:27:16,039 | INFO | fbe535297/deploy | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Started bundle: file:/home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/deploy/4c0af96e-fb35-4bd7-8872-1087daf670bb_4.10_1.jar 2013-11-11 05:27:18,017 | INFO | c8cfbe535297/etc | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.felix.fileinstall-templates.cfg 2013-11-11 05:27:18,497 | INFO | c8cfbe535297/etc | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.provisionr.cfg 2013-11-11 05:27:18,923 | INFO | c8cfbe535297/etc | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/etc/org.apache.provisionr.amazon.cfg 2013-11-11 05:27:21,955 | INFO | Thread-8 | Activator | x.web.service.internal.Activator 115 | 76 - org.ops4j.pax.web.pax-web-runtime - 1.1.12 | Pax Web started 2013-11-11 05:27:22,110 | INFO | b Runtime worker | Server | org.eclipse.jetty.server.Server 266 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | jetty-7.6.8.v20121106 2013-11-11 05:27:22,146 | INFO | b Runtime worker | AbstractConnector | e.jetty.server.AbstractConnector 338 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | Started [email protected]:8181 2013-11-11 05:27:22,154 | INFO | b Runtime worker | HttpServiceFactoryImpl | .internal.HttpServiceFactoryImpl 33 | 76 - org.ops4j.pax.web.pax-web-runtime - 1.1.12 | Binding bundle: [org.apache.karaf.webconsole.console [80]] to http service 2013-11-11 05:27:22,173 | INFO | rint Extender: 3 | FeaturesPlugin | bconsole.features.FeaturesPlugin 79 | 82 - org.apache.karaf.webconsole.features - 2.3.1 | Features plugin activated 2013-11-11 05:27:22,294 | INFO | rint Extender: 2 | BlueprintContainerImpl | container.BlueprintContainerImpl 344 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.aries.transaction.blueprint is waiting for dependencies [(objectClass=javax.transaction.TransactionManager)] 2013-11-11 05:27:22,367 | INFO | b Runtime worker | ContextHandler | ty.server.handler.ContextHandler 744 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | started HttpServiceContext{httpContext=org.apache.felix.webconsole.internal.servlet.OsgiManagerHttpContext@1aefd7b} 2013-11-11 05:27:22,613 | INFO | rint Extender: 3 | BlueprintContainerImpl | container.BlueprintContainerImpl 344 | 7 - org.apache.aries.blueprint.core - 1.1.0 | Bundle org.apache.provisionr.rundeck is waiting for dependencies [(objectClass=org.activiti.engine.ProcessEngine)] 2013-11-11 05:27:22,828 | INFO | Thread-29 | Extender | org.activiti.osgi.Extender 325 | 98 - org.activiti.osgi - 5.10.0 | Found ScriptEngineFactory in groovy-all 2013-11-11 05:27:22,873 | INFO | Thread-29 | Extender | org.activiti.osgi.Extender 325 | 98 - org.activiti.osgi - 5.10.0 | Found ScriptEngineFactory in org.activiti.engine 2013-11-11 05:27:24,353 | INFO | rint Extender: 2 | DbSqlSession | viti.engine.impl.db.DbSqlSession 787 | 97 - org.activiti.engine - 5.10.0 | performing create on engine with resource org/activiti/db/create/activiti.h2.create.engine.sql 2013-11-11 05:27:24,421 | INFO | rint Extender: 2 | DbSqlSession | viti.engine.impl.db.DbSqlSession 787 | 97 - org.activiti.engine - 5.10.0 | performing create on history with resource org/activiti/db/create/activiti.h2.create.history.sql 2013-11-11 05:27:24,442 | INFO | rint Extender: 2 | DbSqlSession | viti.engine.impl.db.DbSqlSession 787 | 97 - org.activiti.engine - 5.10.0 | performing create on identity with resource org/activiti/db/create/activiti.h2.create.identity.sql 2013-11-11 05:27:24,485 | INFO | rint Extender: 2 | ProcessEngineImpl | ti.engine.impl.ProcessEngineImpl 82 | 97 - org.activiti.engine - 5.10.0 | ProcessEngine default created 2013-11-11 05:27:24,486 | INFO | rint Extender: 2 | JobExecutor | ine.impl.jobexecutor.JobExecutor 62 | 97 - org.activiti.engine - 5.10.0 | Starting up the JobExecutor[org.activiti.engine.impl.jobexecutor.DefaultJobExecutor]. 2013-11-11 05:27:24,489 | INFO | Thread-32 | AcquireJobsRunnable | .jobexecutor.AcquireJobsRunnable 46 | 97 - org.activiti.engine - 5.10.0 | JobExecutor[org.activiti.engine.impl.jobexecutor.DefaultJobExecutor] starting to acquire jobs 2013-11-11 05:27:24,514 | INFO | Thread-8 | BpmnDeployer | .impl.bpmn.deployer.BpmnDeployer 74 | 97 - org.activiti.engine - 5.10.0 | Processing resource OSGI-INF/activiti/amazonPoolManagement.bpmn20.xml 2013-11-11 05:27:24,520 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 47 | 98 - org.activiti.osgi - 5.10.0 | removed Activiti service from delegate cache null 2013-11-11 05:27:24,522 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 74 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Installing Pool template from /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/cdh4.xml 2013-11-11 05:27:24,560 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 82 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Registered new template with ID: cdh4 2013-11-11 05:27:24,561 | INFO | tall-./templates | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/cdh4.xml 2013-11-11 05:27:24,561 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 74 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Installing Pool template from /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/cdh3.xml 2013-11-11 05:27:24,574 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 82 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Registered new template with ID: cdh3 2013-11-11 05:27:24,574 | INFO | tall-./templates | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/cdh3.xml 2013-11-11 05:27:24,574 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 74 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Installing Pool template from /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/jenkins.xml 2013-11-11 05:27:24,586 | INFO | tall-./templates | PoolTemplateInstaller | .templates.PoolTemplateInstaller 82 | 106 - org.apache.provisionr.core - 0.5.0.incubating-SNAPSHOT | Registered new template with ID: jenkins 2013-11-11 05:27:24,587 | INFO | tall-./templates | fileinstall | ? ? | 6 - org.apache.felix.fileinstall - 3.2.6 | Installed /home/hudson/jenkins-slave/workspace/provisionr-master/karaf/assembly-tests/target/exam/376800f7-f33c-472c-9020-c8cfbe535297/templates/jenkins.xml 2013-11-11 05:27:24,610 | INFO | Thread-8 | BpmnParse | ngine.impl.bpmn.parser.BpmnParse 279 | 97 - org.activiti.engine - 5.10.0 | XMLSchema currently not supported as typeLanguage 2013-11-11 05:27:24,610 | INFO | Thread-8 | BpmnParse | ngine.impl.bpmn.parser.BpmnParse 285 | 97 - org.activiti.engine - 5.10.0 | XPath currently not supported as expressionLanguage 2013-11-11 05:27:25,026 | INFO | rint Extender: 2 | SecurityUtils | tyUtils$BouncyCastleRegistration 69 | 102 - net.schmizz.sshj - 0.8.1 | BouncyCastle registration succeeded 2013-11-11 05:27:25,030 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache installRepositories 2013-11-11 05:27:25,030 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache installPackages 2013-11-11 05:27:25,031 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache checkSshPortIsOpen 2013-11-11 05:27:25,031 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache downloadFiles 2013-11-11 05:27:25,560 | INFO | Thread-8 | BpmnDeployer | .impl.bpmn.deployer.BpmnDeployer 74 | 97 - org.activiti.engine - 5.10.0 | Processing resource OSGI-INF/activiti/amazonMachineSetup.bpmn20.xml 2013-11-11 05:27:25,592 | INFO | Thread-8 | BpmnParse | ngine.impl.bpmn.parser.BpmnParse 279 | 97 - org.activiti.engine - 5.10.0 | XMLSchema currently not supported as typeLanguage 2013-11-11 05:27:25,592 | INFO | Thread-8 | BpmnParse | ngine.impl.bpmn.parser.BpmnParse 285 | 97 - org.activiti.engine - 5.10.0 | XPath currently not supported as expressionLanguage 2013-11-11 05:27:25,717 | INFO | Thread-8 | HttpServiceFactoryImpl | .internal.HttpServiceFactoryImpl 33 | 76 - org.ops4j.pax.web.pax-web-runtime - 1.1.12 | Binding bundle: [org.apache.provisionr.rundeck [107]] to http service 2013-11-11 05:27:25,720 | INFO | Thread-8 | ContextHandler | ty.server.handler.ContextHandler 744 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | started HttpServiceContext{httpContext=DefaultHttpContext{bundle=org.apache.provisionr.rundeck [107]}} 2013-11-11 05:27:25,727 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_checkSetupProcessesEnded 2013-11-11 05:27:25,729 | INFO | Thread-8 | AdminPlugin | raf.webconsole.admin.AdminPlugin 61 | 81 - org.apache.karaf.webconsole.admin - 2.3.1 | Admin plugin activated 2013-11-11 05:27:25,729 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_setupAdminAccess 2013-11-11 05:27:25,730 | WARN | Thread-8 | ServletTracker | .internal.tracker.ServletTracker 96 | 114 - org.ops4j.pax.web.pax-web-extender-whiteboard - 1.1.12 | Registered servlet [org.apache.karaf.webconsole.admin.AdminPlugin@7f7d58] did not contain a valid alias or url patterns property 2013-11-11 05:27:25,734 | INFO | Thread-8 | GogoPlugin | karaf.webconsole.gogo.GogoPlugin 89 | 83 - org.apache.karaf.webconsole.gogo - 2.3.1 | Gogo plugin activated 2013-11-11 05:27:25,735 | WARN | Thread-8 | ServletTracker | .internal.tracker.ServletTracker 96 | 114 - org.ops4j.pax.web.pax-web-extender-whiteboard - 1.1.12 | Registered servlet [org.apache.karaf.webconsole.gogo.GogoPlugin@12b65bf] did not contain a valid alias or url patterns property 2013-11-11 05:27:25,735 | WARN | Thread-8 | ServletTracker | .internal.tracker.ServletTracker 96 | 114 - org.ops4j.pax.web.pax-web-extender-whiteboard - 1.1.12 | Registered servlet [org.apache.karaf.webconsole.features.FeaturesPlugin@121b1f2] did not contain a valid alias or url patterns property 2013-11-11 05:27:25,735 | WARN | Thread-8 | ServletTracker | .internal.tracker.ServletTracker 96 | 114 - org.ops4j.pax.web.pax-web-extender-whiteboard - 1.1.12 | Registered servlet [org.apache.felix.webconsole.plugins.event.internal.PluginServlet@1887119] did not contain a valid alias or url patterns property 2013-11-11 05:27:25,739 | INFO | Thread-8 | HttpServiceFactoryImpl | .internal.HttpServiceFactoryImpl 33 | 76 - org.ops4j.pax.web.pax-web-runtime - 1.1.12 | Binding bundle: [org.apache.karaf.deployer.features [37]] to http service 2013-11-11 05:27:25,740 | INFO | Thread-8 | ContextHandler | ty.server.handler.ContextHandler 744 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | started HttpServiceContext{httpContext=DefaultHttpContext{bundle=org.apache.karaf.deployer.features [37]}} 2013-11-11 05:27:25,765 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_checkNoRequestsAreOpen 2013-11-11 05:27:25,767 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_terminateInstances 2013-11-11 05:27:25,769 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_checkAllInstancesAreTerminated 2013-11-11 05:27:25,771 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_checkAllInstancesAreRunning 2013-11-11 05:27:25,775 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_runOnDemandInstances 2013-11-11 05:27:25,782 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_spawnMachineSetupProcesses 2013-11-11 05:27:25,784 | INFO | rint Extender: 1 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_checkAllRequestsAreActive 2013-11-11 05:27:25,785 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_killMachineSetUpProcesses 2013-11-11 05:27:25,788 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_ensureKeyPairExists 2013-11-11 05:27:25,790 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_cancelSpotRequests 2013-11-11 05:27:25,791 | INFO | rint Extender: 1 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_getInstanceIdsFromSpotRequests 2013-11-11 05:27:25,793 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_dumpConsoleOutput 2013-11-11 05:27:25,795 | INFO | rint Extender: 1 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_ensureSecurityGroupExists 2013-11-11 05:27:25,796 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_runSpotInstances 2013-11-11 05:27:25,798 | INFO | rint Extender: 1 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_publishListOfMachines 2013-11-11 05:27:25,799 | INFO | rint Extender: 2 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_deleteSecurityGroup 2013-11-11 05:27:25,800 | INFO | rint Extender: 1 | BlueprintELResolver | gi.blueprint.BlueprintELResolver 39 | 98 - org.activiti.osgi - 5.10.0 | added Activiti service to delegate cache amazon_deleteKeyPair 2013-11-11 05:27:25,818 | INFO | rint Extender: 3 | AmazonProvisionr | ovisionr.amazon.AmazonProvisionr 58 | 112 - org.apache.provisionr.amazon - 0.5.0.incubating-SNAPSHOT | Default provider for AmazonProvisionr is Provider{id='amazon', endpoint='', accessKey='access', options='{region=us-east-1}'} 2013-11-11 05:27:25,880 | INFO | Thread-8 | container | er.impl.PersistenceBundleManager 269 | 124 - org.apache.aries.jpa.container - 1.0.0 | The file org.apache.aries.jpa.container.properties was not found in bundle org.apache.aries.jpa.container/1.0.0. The default properties {} will be used. 2013-11-11 05:27:25,886 | WARN | rint Extender: 2 | aries | a.blueprint.aries.impl.NSHandler 288 | 123 - org.apache.aries.jpa.blueprint.aries - 1.0.1 | Managed persistence context support is no longer available for use with the Aries Blueprint container. 2013-11-11 05:27:25,888 | INFO | Thread-8 | container | er.impl.PersistenceBundleManager 633 | 124 - org.apache.aries.jpa.container - 1.0.0 | No quiesce support is available, so managed persistence units will not participate in quiesce operations. 2013-11-11 05:27:25,897 | INFO | Thread-8 | context | pl.JTAPersistenceContextRegistry 228 | 125 - org.apache.aries.jpa.container.context - 1.0.1 | A TransactionSynchronizationRegistry service is now available in the runtime. Managed persistence contexts will now integrate with JTA transactions using [javax.transaction.TransactionManager, javax.transaction.TransactionSynchronizationRegistry, javax.transaction.UserTransaction, org.apache.geronimo.transaction.manager.RecoverableTransactionManager, org.springframework.transaction.PlatformTransactionManager]. 2013-11-11 05:27:25,899 | INFO | Thread-8 | context | xt.impl.GlobalPersistenceManager 253 | 125 - org.apache.aries.jpa.container.context - 1.0.1 | No quiesce support is available, so managed persistence units will not participate in quiesce operations. 2013-11-11 05:27:25,931 | INFO | Thread-8 | ContextLoaderListener | .activator.ContextLoaderListener 354 | 135 - org.springframework.osgi.extender - 1.2.1 | Starting [org.springframework.osgi.extender] bundle v.[1.2.1] 2013-11-11 05:27:26,099 | INFO | Thread-8 | ExtenderConfiguration | al.support.ExtenderConfiguration 150 | 135 - org.springframework.osgi.extender - 1.2.1 | No custom extender configuration detected; using defaults... 2013-11-11 05:27:26,107 | INFO | Thread-8 | TimerTaskExecutor | heduling.timer.TimerTaskExecutor 106 | 91 - org.springframework.context - 3.1.4.RELEASE | Initializing Timer 2013-11-11 05:27:26,318 | INFO | Executor: 1 | WebXmlObserver | nder.war.internal.WebXmlObserver 120 | 118 - org.ops4j.pax.web.pax-web-extender-war - 1.1.12 | Using [activiti-explorer] as web application context name 2013-11-11 05:27:26,321 | INFO | Executor: 1 | WebXmlObserver | nder.war.internal.WebXmlObserver 141 | 118 - org.ops4j.pax.web.pax-web-extender-war - 1.1.12 | Using [] as web application root path 2013-11-11 05:27:26,350 | INFO | Executor: 1 | HttpServiceFactoryImpl | .internal.HttpServiceFactoryImpl 33 | 76 - org.ops4j.pax.web.pax-web-runtime - 1.1.12 | Binding bundle: [org.apache.provisionr.activiti-karaf-web-explorer [146]] to http service 2013-11-11 05:27:26,359 | INFO | Executor: 1 | ContextHandler | ty.server.handler.ContextHandler 744 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | started HttpServiceContext{httpContext=org.ops4j.pax.web.extender.war.internal.WebAppWebContainerContext@b8df14} 2013-11-11 05:27:26,363 | INFO | Executor: 1 | ContextHandler | ty.server.handler.ContextHandler 795 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | stopped HttpServiceContext{httpContext=org.ops4j.pax.web.extender.war.internal.WebAppWebContainerContext@b8df14} 2013-11-11 05:27:26,409 | INFO | Executor: 1 | /activiti-explorer | r.handler.ContextHandler$Context 1931 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | Initializing Spring root WebApplicationContext 2013-11-11 05:27:26,410 | INFO | Executor: 1 | ContextLoader | mework.web.context.ContextLoader 272 | 137 - org.springframework.web - 3.1.4.RELEASE | Root WebApplicationContext: initialization started 2013-11-11 05:27:26,466 | INFO | Executor: 1 | giBundleXmlWebApplicationContext | pport.AbstractApplicationContext 503 | 91 - org.springframework.context - 3.1.4.RELEASE | Refreshing OsgiBundleXmlWebApplicationContext(bundle=org.apache.provisionr.activiti-karaf-web-explorer, config=/WEB-INF/applicationContext.xml): startup date [Mon Nov 11 05:27:26 UTC 2013]; root of context hierarchy 2013-11-11 05:27:26,467 | INFO | Executor: 1 | giBundleXmlWebApplicationContext | ractOsgiBundleApplicationContext 365 | 91 - org.springframework.context - 3.1.4.RELEASE | Application Context service already unpublished 2013-11-11 05:27:26,520 | INFO | Executor: 1 | XmlBeanDefinitionReader | tory.xml.XmlBeanDefinitionReader 315 | 92 - org.springframework.beans - 3.1.4.RELEASE | Loading XML bean definitions from URL [bundle://146.0:0/WEB-INF/applicationContext.xml] 2013-11-11 05:27:26,808 | INFO | Executor: 1 | XmlBeanDefinitionReader | tory.xml.XmlBeanDefinitionReader 315 | 92 - org.springframework.beans - 3.1.4.RELEASE | Loading XML bean definitions from URL [bundle://146.0:0/WEB-INF/activiti-ui-context.xml] 2013-11-11 05:27:26,854 | INFO | (3)-67.195.138.9 | ContainerTestRunner | nit.internal.ContainerTestRunner 69 | 162 - org.ops4j.pax.exam.invoker.junit - 2.6.0 | running testAllFeaturesStartAsExpected in reactor 2013-11-11 05:27:26,927 | INFO | Executor: 1 | PropertyPlaceholderConfigurer | .support.PropertiesLoaderSupport 177 | 90 - org.springframework.core - 3.1.4.RELEASE | Loading properties file from OSGi resource[classpath:ui.properties|bnd.id=146|bnd.sym=org.apache.provisionr.activiti-karaf-web-explorer] 2013-11-11 05:27:26,938 | INFO | Executor: 1 | DefaultListableBeanFactory | pport.DefaultListableBeanFactory 577 | 92 - org.springframework.beans - 3.1.4.RELEASE | Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@12b644e: defining beans [processEngine,repositoryService,runtimeService,taskService,historyService,managementService,identityService,demoDataGenerator,activitiLoginHandler,activitiUiPropertyPlaceholder,navigatorManager,attachmentRendererManager,formPropertyRendererManager,variableRendererManager,componentFactories,userCache,navigationFragmentChangeListener,mainWindow,explorerApp,i18nManager,notificationManager,viewManager]; root of factory hierarchy 2013-11-11 05:27:26,945 | INFO | (3)-67.195.138.9 | CustomKarafDistributionTest | araf.CustomKarafDistributionTest 155 | 166 - PAXEXAM-PROBE-18570e90-9e7c-479e-bcfa-729baf2a5b47 - 0.0.0 | Unable to fetch http://localhost:8181/activiti-explorer/ (Server returned HTTP response code: 401 for URL: http://localhost:8181/activiti-explorer/). Trying again in 5s. 2013-11-11 05:27:26,986 | INFO | Executor: 1 | OsgiServiceProxyFactoryBean | al.aop.ServiceDynamicInterceptor 470 | 134 - org.springframework.osgi.core - 1.2.1 | Looking for mandatory OSGi service dependency for bean [processEngine] matching filter (objectClass=org.activiti.engine.ProcessEngine) 2013-11-11 05:27:26,987 | INFO | Executor: 1 | OsgiServiceProxyFactoryBean | al.aop.ServiceDynamicInterceptor 476 | 134 - org.springframework.osgi.core - 1.2.1 | Found mandatory OSGi service for bean [processEngine] 2013-11-11 05:27:27,104 | INFO | Executor: 1 | giBundleXmlWebApplicationContext | ractOsgiBundleApplicationContext 327 | 91 - org.springframework.context - 3.1.4.RELEASE | Publishing application context as OSGi service with properties {org.springframework.context.service.name=org.apache.provisionr.activiti-karaf-web-explorer, Bundle-SymbolicName=org.apache.provisionr.activiti-karaf-web-explorer, Bundle-Version=0.5.0.incubating-SNAPSHOT} 2013-11-11 05:27:27,110 | INFO | Executor: 1 | ContextLoader | mework.web.context.ContextLoader 312 | 137 - org.springframework.web - 3.1.4.RELEASE | Root WebApplicationContext: initialization completed in 700 ms 2013-11-11 05:27:27,110 | INFO | Executor: 1 | ContextHandler | ty.server.handler.ContextHandler 744 | 59 - org.eclipse.jetty.util - 7.6.8.v20121106 | started HttpServiceContext{httpContext=org.ops4j.pax.web.extender.war.internal.WebAppWebContainerContext@b8df14} 2013-11-11 05:27:31,987 | INFO | qtp23295306-69 | TrieBasedUserCache | xplorer.cache.TrieBasedUserCache 73 | 146 - org.apache.provisionr.activiti-karaf-web-explorer - 0.5.0.incubating-SNAPSHOT | Caching users 0 to 25 2013-11-11 05:27:32,090 | INFO | (3)-67.195.138.9 | TestBundleObserver | tender.intern.TestBundleObserver 101 | 159 - org.ops4j.pax.exam.extender.service - 2.6.0 | Unregistered testcase [org.ops4j.pax.exam.raw.extender.intern.Probe@1f172aa.] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.199 sec Results : Tests run: 1, Failures: 0, Errors: 0, Skipped: 0 [JENKINS] Recording test results [INFO] [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ provisionr-assembly-tests --- [INFO] Building jar: <https://builds.apache.org/job/provisionr-master/ws/karaf/assembly-tests/target/provisionr-assembly-tests-0.5.0-incubating-SNAPSHOT.jar> [INFO] [INFO] --- maven-site-plugin:3.2:attach-descriptor (attach-descriptor) @ provisionr-assembly-tests --- [WARNING] Failed to getClass for org.apache.maven.plugin.source.SourceJarMojo [INFO] [INFO] --- maven-source-plugin:2.2.1:jar (default) @ provisionr-assembly-tests --- [INFO] Building jar: <https://builds.apache.org/job/provisionr-master/ws/karaf/assembly-tests/target/provisionr-assembly-tests-0.5.0-incubating-SNAPSHOT-sources.jar> [INFO] [INFO] --- apache-rat-plugin:0.9:check (default) @ provisionr-assembly-tests --- [INFO] 51 implicit excludes (use -debug for more details). [INFO] Exclude: **/*.md [INFO] Exclude: NOTICE [INFO] Exclude: .git/** [INFO] Exclude: .repository/** [INFO] Exclude: .gitignore [INFO] Exclude: .idea/** [INFO] Exclude: **/*.iml [INFO] Exclude: **/*.project [INFO] Exclude: **/*.classpath [INFO] Exclude: **/*.prefs [INFO] Exclude: **/id_rsa_test [INFO] Exclude: **/id_rsa_test.pub [INFO] Exclude: **/*.bpmn20.xml [INFO] Exclude: **/*.activiti [INFO] Exclude: **/*.csv [INFO] Exclude: **/target/** [INFO] 3 resources included (use -debug for more details) [INFO] Rat check: Summary of files. Unapproved: 0 unknown: 0 generated: 0 approved: 3 licence. [INFO] [INFO] ------------------------------------------------------------------------ [INFO] Building Apache Provisionr :: Aggregator 0.5.0-incubating-SNAPSHOT [INFO] ------------------------------------------------------------------------ [INFO] [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ provisionr-aggregator --- [INFO] [INFO] --- maven-remote-resources-plugin:1.4:process (default) @ provisionr-aggregator --- [INFO] [INFO] --- maven-site-plugin:3.2:attach-descriptor (attach-descriptor) @ provisionr-aggregator --- [WARNING] Failed to getClass for org.apache.maven.plugin.source.SourceJarMojo [INFO] [INFO] --- maven-source-plugin:2.2.1:jar (default) @ provisionr-aggregator --- [INFO] [INFO] --- apache-rat-plugin:0.9:check (default) @ provisionr-aggregator --- [INFO] 70 implicit excludes (use -debug for more details). [INFO] Exclude: **/*.md [INFO] Exclude: NOTICE [INFO] Exclude: .git/** [INFO] Exclude: .repository/** [INFO] Exclude: .gitignore [INFO] Exclude: .idea/** [INFO] Exclude: **/*.iml [INFO] Exclude: **/*.project [INFO] Exclude: **/*.classpath [INFO] Exclude: **/*.prefs [INFO] Exclude: **/id_rsa_test [INFO] Exclude: **/id_rsa_test.pub [INFO] Exclude: **/*.bpmn20.xml [INFO] Exclude: **/*.activiti [INFO] Exclude: **/*.csv [INFO] Exclude: **/target/** [INFO] 7 resources included (use -debug for more details) [INFO] Rat check: Summary of files. Unapproved: 0 unknown: 0 generated: 0 approved: 5 licence. [INFO] [INFO] --- maven-install-plugin:2.3.1:install (default-install) @ provisionr-aggregator --- [INFO] Installing <https://builds.apache.org/job/provisionr-master/ws/pom.xml> to /home/jenkins/jenkins-slave/maven-repositories/0/org/apache/provisionr/provisionr-aggregator/0.5.0-incubating-SNAPSHOT/provisionr-aggregator-0.5.0-incubating-SNAPSHOT.pom Build timed out (after 60 minutes). Marking the build as failed. channel stopped [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] Archiving artifacts
