See <https://hudson.apache.org/hudson/job/sling-trunk-1.6/781/changes>
Changes: [cziegeler] We should include latest jcr resource to have a working web console plugin ------------------------------------------ [...truncated 9332 lines...] org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling SLF4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Jakarta Commons Logging API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Log4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Avalon Logger API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling JULI Logger API support. 18.02.2011 03:30:05.171 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-api BundleEvent STARTED 18.02.2011 03:30:05.175 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent RESOLVED 18.02.2011 03:30:05.177 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [20] ServiceEvent REGISTERED 18.02.2011 03:30:05.212 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [org.ops4j.pax.logging,21] ServiceEvent REGISTERED 18.02.2011 03:30:05.216 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent STARTED 18.02.2011 03:30:05.216 *INFO* [FelixDispatchQueue] org.apache.felix.framework BundleEvent STARTED 18.02.2011 03:30:05.216 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent STARTED 18.02.2011 03:30:05.283 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Install bundle from URL [file:/tmp/tb/tinybundles_a7c19b907c14559dc3b1307bbb65f6ff0dd157ef.bin] 18.02.2011 03:30:05.286 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent INSTALLED 18.02.2011 03:30:05.287 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Look up service [org.osgi.service.startlevel.StartLevel], timeout in 0 millis 18.02.2011 03:30:05.290 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent RESOLVED 18.02.2011 03:30:05.290 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.junit.extender.impl.internal.TestBundleObserver Found test: org.apache.sling.installer.it.BundleSnapshotUpdateTest.testSnapshotWithInstallerRestart 18.02.2011 03:30:05.293 *INFO* [RMI TCP Connection(1)-67.195.138.8] pax-exam-probe Service [22] ServiceEvent REGISTERED 18.02.2011 03:30:05.293 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.junit.extender.impl.internal.TestBundleObserver Registered testcase [org.apache.sling.installer.it.BundleSnapshotUpdateTest.testSnapshotWithInstallerRestart] 18.02.2011 03:30:05.293 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent STARTED 18.02.2011 03:30:05.294 *INFO* [main] org.ops4j.pax.exam.junit.internal.JUnit4TestMethod Starting test testSnapshotWithInstallerRestart [felix](org.apache.sling.installer.it.BundleSnapshotUpdateTest) 18.02.2011 03:30:05.295 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Remote call of [org.ops4j.pax.exam.junit.extender.CallableTestMethod.call] 18.02.2011 03:30:05.295 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Look up service [org.ops4j.pax.exam.junit.extender.CallableTestMethod], timeout in 0 millis 18.02.2011 03:30:05.333 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer Installed bundle osgi-installer-snapshot-test [14] from resource RegisteredResource(url=OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-snap.jar,> entity=bundle:osgi-installer-snapshot-test, state=INSTALL, digest=digest1) 18.02.2011 03:30:05.333 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent INSTALLED 18.02.2011 03:30:05.334 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent RESOLVED 18.02.2011 03:30:05.334 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent STARTED 18.02.2011 03:30:05.334 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.tasks.BundleStartTask Bundle started (retry count=0, bundle ID=14) : osgi-installer-snapshot-test 18.02.2011 03:30:05.424 *INFO* [RMI TCP Connection(1)-67.195.138.8] pax-exam-probe Restarting org.apache.sling.installer.core bundle 18.02.2011 03:30:05.585 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service stopped. 18.02.2011 03:30:05.586 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,17] ServiceEvent UNREGISTERING 18.02.2011 03:30:05.587 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,14] ServiceEvent UNREGISTERING 18.02.2011 03:30:05.587 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,15] ServiceEvent UNREGISTERING 18.02.2011 03:30:05.588 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,16] ServiceEvent UNREGISTERING 18.02.2011 03:30:05.588 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent STOPPED 18.02.2011 03:30:05.589 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,23] ServiceEvent REGISTERED 18.02.2011 03:30:05.589 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,24] ServiceEvent REGISTERED 18.02.2011 03:30:05.590 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,25] ServiceEvent REGISTERED 18.02.2011 03:30:05.593 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service started. 18.02.2011 03:30:05.593 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,26] ServiceEvent REGISTERED 18.02.2011 03:30:05.594 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent STARTED 18.02.2011 03:30:05.699 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent STOPPED 18.02.2011 03:30:05.700 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent UNRESOLVED 18.02.2011 03:30:05.700 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent UPDATED 18.02.2011 03:30:05.702 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer Updated bundle osgi-installer-snapshot-test [14] from resource RegisteredResource(url=OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-snap.jar,> entity=bundle:osgi-installer-snapshot-test, state=INSTALL, digest=digest2) 18.02.2011 03:30:05.702 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent PACKAGES REFRESHED 18.02.2011 03:30:05.703 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent PACKAGES REFRESHED 18.02.2011 03:30:05.954 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent RESOLVED 18.02.2011 03:30:05.954 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.tasks.BundleStartTask Bundle started (retry count=0, bundle ID=14) : osgi-installer-snapshot-test 18.02.2011 03:30:05.954 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent STARTED 18.02.2011 03:30:05.997 *INFO* [main] org.ops4j.pax.exam.junit.internal.JUnit4TestMethod Test testSnapshotWithInstallerRestart [felix](org.apache.sling.installer.it.BundleSnapshotUpdateTest) ended succesfully 18.02.2011 03:30:05.997 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Shutting down the test container (Pax Runner) 18.02.2011 03:30:05.997 *INFO* [FelixDispatchQueue] org.apache.felix.framework BundleEvent STOPPED 18.02.2011 03:30:05.998 *INFO* [FelixDispatchQueue] osgi-installer-snapshot-test BundleEvent STOPPED 18.02.2011 03:30:06.000 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [org.ops4j.pax.logging,21] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.001 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [20] ServiceEvent UNREGISTERING org.ops4j.pax.logging.pax-logging-api18.02.2011 03:30:06.001 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent STOPPED [org.ops4j.pax.logging.internal.Activator] : Disabling SLF4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Jakarta Commons Logging API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Log4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Avalon Logger API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling JULI Logger API support. 18.02.2011 03:30:06.002 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-api BundleEvent STOPPED 18.02.2011 03:30:06.002 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.ConfigurationAdmin,19] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.003 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.file.FilePersistenceManager,18] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.003 *INFO* [FelixDispatchQueue] org.apache.felix.configadmin BundleEvent STOPPED 18.02.2011 03:30:06.205 *INFO* [FelixStartLevel] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service stopped. 18.02.2011 03:30:06.205 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,26] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.205 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,23] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.206 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,24] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.206 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,25] ServiceEvent UNREGISTERING 18.02.2011 03:30:06.571 *INFO* [main] org.ops4j.pax.runner.platform.DefaultJavaRunner Platform has been shutdown. Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.118 sec Running org.apache.sling.installer.it.ConfigPrioritiesTest Tests run: 0, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 sec Running org.apache.sling.installer.it.RemovedResourceDetectionTest __________ ___________ \______ \_____ ___ ___ \_ _____/__ ________ _____ | ___/\__ \ \ \/ / | __)_\ \/ /\__ \ / \ | | / __ \_> < | \> < / __ \| Y Y \ |____| (____ /__/\_ \ /_______ /__/\_ \(____ /__|_| / \/ \/ \/ \/ \/ \/ Pax Exam 1.2.1 from OPS4J - http://www.ops4j.org ------------------------------------------------ 18.02.2011 03:30:06.576 *INFO* [main] org.ops4j.pax.exam.junit.internal.JUnit4TestMethod Starting test testRemoveResourceAndRestart [felix](org.apache.sling.installer.it.RemovedResourceDetectionTest) 18.02.2011 03:30:06.577 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Starting up the test container (Pax Runner 1.4.0 ) 18.02.2011 03:30:06.577 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Acquire lock for new Pax Runner instance on /tmp/paxexam_runner_hudson/paxexam.lock 18.02.2011 03:30:06.578 *INFO* [main] org.ops4j.pax.runner.ConfigurationImpl Using config [classpath:META-INF/runner.properties] 18.02.2011 03:30:06.578 *INFO* [main] org.ops4j.pax.runner.Run Using only arguments from command line 18.02.2011 03:30:06.581 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.exam/pax-exam/1.2.1@1] 18.02.2011 03:30:06.581 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.exam/pax-exam-junit-extender/1.2.1@1] 18.02.2011 03:30:06.581 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.exam/pax-exam-junit-extender-impl/1.2.1@1] 18.02.2011 03:30:06.581 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.apache.felix/org.apache.felix.scr] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.apache.felix/org.apache.felix.configadmin] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.apache.sling/org.apache.sling.commons.log] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.apache.sling/org.apache.sling.installer.core/3.0.1-SNAPSHOT@update] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.junit/com.springsource.org.junit/4.4.0@1] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.exam/pax-exam-container-rbc/1.2.1@1] 18.02.2011 03:30:06.582 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-composite:mvn:org.ops4j.pax.runner.profiles/log//composite] 18.02.2011 03:30:06.583 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.apache.felix/org.apache.felix.configadmin/1.2.4] 18.02.2011 03:30:06.583 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.logging/pax-logging-api/1.5.1] 18.02.2011 03:30:06.583 *INFO* [main] org.ops4j.pax.scanner.internal.ProvisionServiceImpl Scan bundles from [scan-bundle:mvn:org.ops4j.pax.logging/pax-logging-service/1.5.1] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.exam/pax-exam/1.2.1, at start level 1, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.exam/pax-exam-junit-extender/1.2.1, at start level 1, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.exam/pax-exam-junit-extender-impl/1.2.1, at start level 1, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.apache.felix/org.apache.felix.scr, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.apache.felix/org.apache.felix.configadmin, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.apache.sling/org.apache.sling.commons.log, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.apache.sling/org.apache.sling.installer.core/3.0.1-SNAPSHOT, at default start level, bundle will be started, bundle will be re-downloaded] 18.02.2011 03:30:06.586 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.junit/com.springsource.org.junit/4.4.0, at start level 1, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.587 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.exam/pax-exam-container-rbc/1.2.1, at start level 1, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.587 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.apache.felix/org.apache.felix.configadmin/1.2.4, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.587 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.logging/pax-logging-api/1.5.1, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.587 *INFO* [main] org.ops4j.pax.runner.Run Provision bundle [mvn:org.ops4j.pax.logging/pax-logging-service/1.5.1, at default start level, bundle will be started, bundle will be loaded from the cache] 18.02.2011 03:30:06.587 *INFO* [main] org.ops4j.pax.runner.platform.internal.PlatformImpl Preparing framework [Felix 2.0.2] 18.02.2011 03:30:06.589 *INFO* [main] org.ops4j.pax.runner.platform.internal.PlatformImpl Downloading bundles... 18.02.2011 03:30:06.599 *INFO* [main] org.ops4j.pax.runner.platform.internal.StreamUtils mvn:org.apache.sling/org.apache.sling.installer.core/3.0.1-SNAPSHOT : downloading... 18.02.2011 03:30:06.604 *INFO* [main] org.ops4j.pax.runner.platform.internal.StreamUtils mvn:org.apache.sling/org.apache.sling.installer.core/3.0.1-SNAPSHOT : 90364 bytes @ [ 45182kBps ] 18.02.2011 03:30:06.612 *INFO* [main] org.ops4j.pax.runner.platform.internal.ExecutionEnvironment Using execution environment [J2SE-1.6] 18.02.2011 03:30:06.625 *INFO* [main] org.ops4j.pax.runner.platform.DefaultJavaRunner Runner has successfully finished his job! 18.02.2011 03:30:06.625 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Test container (Pax Runner 1.4.0) started in 48 millis 18.02.2011 03:30:06.625 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Wait for test container to finish its initialization for 300000 millis Welcome to Felix ================ 18.02.2011 03:30:07.000 *INFO* [FelixStartLevel] org.ops4j.pax.exam.rbc.internal.Activator RMI registry started on port [1100] 18.02.2011 03:30:07.017 *INFO* [main] org.ops4j.pax.exam.rbc.client.RemoteBundleContextClient Remote bundle context found after 392 millis 18.02.2011 03:30:07.036 *INFO* [FelixStartLevel] org.apache.sling.commons.log.internal.LogServiceFactory LogManager: Logging set up from context 18.02.2011 03:30:07.049 *INFO* [FelixStartLevel] org.apache.sling.commons.log Service [org.apache.sling.commons.log.internal.LogServiceFactory,12] ServiceEvent REGISTERED 18.02.2011 03:30:07.051 *INFO* [FelixStartLevel] org.apache.sling.commons.log Service [org.apache.sling.commons.log.internal.LogReaderServiceFactory,13] ServiceEvent REGISTERED 18.02.2011 03:30:07.051 *INFO* [FelixDispatchQueue] org.apache.sling.commons.log BundleEvent STARTED 18.02.2011 03:30:07.053 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent RESOLVED 18.02.2011 03:30:07.058 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,14] ServiceEvent REGISTERED 18.02.2011 03:30:07.060 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,15] ServiceEvent REGISTERED 18.02.2011 03:30:07.060 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,16] ServiceEvent REGISTERED 18.02.2011 03:30:07.067 *INFO* [FelixStartLevel] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,17] ServiceEvent REGISTERED 18.02.2011 03:30:07.068 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service started. 18.02.2011 03:30:07.069 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent STARTED 18.02.2011 03:30:07.070 *INFO* [FelixDispatchQueue] org.apache.felix.configadmin BundleEvent RESOLVED 18.02.2011 03:30:07.077 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.file.FilePersistenceManager,18] ServiceEvent REGISTERED 18.02.2011 03:30:07.079 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.ConfigurationAdmin,19] ServiceEvent REGISTERED 18.02.2011 03:30:07.085 *INFO* [FelixDispatchQueue] org.apache.felix.configadmin BundleEvent STARTED 18.02.2011 03:30:07.086 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-api BundleEvent RESOLVED org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling SLF4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Jakarta Commons Logging API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Log4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling Avalon Logger API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Enabling JULI Logger API support. 18.02.2011 03:30:07.102 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-api BundleEvent STARTED 18.02.2011 03:30:07.106 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent RESOLVED 18.02.2011 03:30:07.108 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [20] ServiceEvent REGISTERED 18.02.2011 03:30:07.143 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [org.ops4j.pax.logging,21] ServiceEvent REGISTERED 18.02.2011 03:30:07.147 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent STARTED 18.02.2011 03:30:07.147 *INFO* [FelixDispatchQueue] org.apache.felix.framework BundleEvent STARTED 18.02.2011 03:30:07.147 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent STARTED 18.02.2011 03:30:07.213 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Install bundle from URL [file:/tmp/tb/tinybundles_7941615afce0d76707f3aebbe7352ae60a27eb7f.bin] 18.02.2011 03:30:07.216 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent INSTALLED 18.02.2011 03:30:07.217 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Look up service [org.osgi.service.startlevel.StartLevel], timeout in 0 millis 18.02.2011 03:30:07.219 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent RESOLVED 18.02.2011 03:30:07.220 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.junit.extender.impl.internal.TestBundleObserver Found test: org.apache.sling.installer.it.RemovedResourceDetectionTest.testRemoveResourceAndRestart 18.02.2011 03:30:07.222 *INFO* [RMI TCP Connection(1)-67.195.138.8] pax-exam-probe Service [22] ServiceEvent REGISTERED 18.02.2011 03:30:07.223 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.junit.extender.impl.internal.TestBundleObserver Registered testcase [org.apache.sling.installer.it.RemovedResourceDetectionTest.testRemoveResourceAndRestart] 18.02.2011 03:30:07.223 *INFO* [FelixDispatchQueue] pax-exam-probe BundleEvent STARTED 18.02.2011 03:30:07.223 *INFO* [main] org.ops4j.pax.exam.junit.internal.JUnit4TestMethod Starting test testRemoveResourceAndRestart [felix](org.apache.sling.installer.it.RemovedResourceDetectionTest) 18.02.2011 03:30:07.224 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Remote call of [org.ops4j.pax.exam.junit.extender.CallableTestMethod.call] 18.02.2011 03:30:07.224 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl Look up service [org.ops4j.pax.exam.junit.extender.CallableTestMethod], timeout in 0 millis 18.02.2011 03:30:07.260 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer Installed bundle osgi-installer-testbundle [14] from resource RegisteredResource(url=OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-testbundle-1.1.jar,> entity=bundle:osgi-installer-testbundle, state=INSTALL, digest=1298028575000) 18.02.2011 03:30:07.260 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent INSTALLED 18.02.2011 03:30:07.261 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent RESOLVED 18.02.2011 03:30:07.261 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent STARTED 18.02.2011 03:30:07.261 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.tasks.BundleStartTask Bundle started (retry count=0, bundle ID=14) : osgi-installer-testbundle 18.02.2011 03:30:07.516 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer Installed bundle osgi-installer-testB [15] from resource RegisteredResource(url=OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-testB-1.0.jar,> entity=bundle:osgi-installer-testB, state=INSTALL, digest=1298028575000) 18.02.2011 03:30:07.516 *INFO* [FelixDispatchQueue] osgi-installer-testB BundleEvent INSTALLED 18.02.2011 03:30:07.516 *INFO* [FelixDispatchQueue] osgi-installer-testB BundleEvent RESOLVED 18.02.2011 03:30:07.517 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.tasks.BundleStartTask Bundle started (retry count=0, bundle ID=15) : osgi-installer-testB 18.02.2011 03:30:07.517 *INFO* [FelixDispatchQueue] osgi-installer-testB BundleEvent STARTED 18.02.2011 03:30:07.553 *INFO* [RMI TCP Connection(1)-67.195.138.8] pax-exam-probe Restarting org.apache.sling.installer.core bundle 18.02.2011 03:30:07.768 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service stopped. 18.02.2011 03:30:07.768 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,17] ServiceEvent UNREGISTERING 18.02.2011 03:30:07.769 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,14] ServiceEvent UNREGISTERING 18.02.2011 03:30:07.769 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,15] ServiceEvent UNREGISTERING 18.02.2011 03:30:07.770 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,16] ServiceEvent UNREGISTERING 18.02.2011 03:30:07.770 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent STOPPED 18.02.2011 03:30:07.771 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Bundle Install Task Factory,23] ServiceEvent REGISTERED 18.02.2011 03:30:07.772 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Configuration Install Task Factory,24] ServiceEvent REGISTERED 18.02.2011 03:30:07.772 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Installer - Default Resource Transformer,25] ServiceEvent REGISTERED 18.02.2011 03:30:07.776 *INFO* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.OsgiInstallerImpl Apache Sling OSGi Installer Service started. 18.02.2011 03:30:07.776 *INFO* [RMI TCP Connection(1)-67.195.138.8] org.apache.sling.installer.core Service [Apache Sling Install Controller Service,26] ServiceEvent REGISTERED 18.02.2011 03:30:07.777 *INFO* [FelixDispatchQueue] org.apache.sling.installer.core BundleEvent STARTED 18.02.2011 03:30:07.779 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent STOPPED 18.02.2011 03:30:07.780 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent UNRESOLVED 18.02.2011 03:30:07.780 *INFO* [FelixDispatchQueue] osgi-installer-testbundle BundleEvent UNINSTALLED 18.02.2011 03:30:07.781 *INFO* [OsgiInstallerImpl] org.apache.sling.audit.osgi.installer Uninstalled bundle osgi-installer-testbundle [14] from resource RegisteredResource(url=OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-testbundle-1.1.jar,> entity=bundle:osgi-installer-testbundle, state=UNINSTALL, digest=1298028575000) 18.02.2011 03:30:07.781 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent PACKAGES REFRESHED 18.02.2011 03:30:07.781 *WARN* [OsgiInstallerImpl] org.apache.sling.installer.core.impl.FileDataStore Remove OsgiInstallerTest:<https://hudson.apache.org/hudson/job/sling-trunk-1.6/ws/trunk/installer/it/target/org.apache.sling.installer.it-3.0.1-SNAPSHOT-testbundle-1.1.jar> : 1298028575000 18.02.2011 03:30:07.783 *INFO* [FelixDispatchQueue] org.apache.felix.framework FrameworkEvent PACKAGES REFRESHED 18.02.2011 03:30:08.278 *INFO* [main] org.ops4j.pax.exam.junit.internal.JUnit4TestMethod Test testRemoveResourceAndRestart [felix](org.apache.sling.installer.it.RemovedResourceDetectionTest) ended succesfully 18.02.2011 03:30:08.278 *INFO* [main] org.ops4j.pax.exam.container.def.internal.PaxRunnerTestContainer Shutting down the test container (Pax Runner) 18.02.2011 03:30:08.279 *INFO* [FelixDispatchQueue] org.apache.felix.framework BundleEvent STOPPED 18.02.2011 03:30:08.280 *INFO* [FelixDispatchQueue] osgi-installer-testB BundleEvent STOPPED 18.02.2011 03:30:08.281 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [org.ops4j.pax.logging,21] ServiceEvent UNREGISTERING 18.02.2011 03:30:08.282 *INFO* [FelixStartLevel] org.ops4j.pax.logging.pax-logging-service Service [20] ServiceEvent UNREGISTERING org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling SLF4J API support. 18.02.2011 03:30:08.282 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-service BundleEvent STOPPED org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Jakarta Commons Logging API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Log4J API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling Avalon Logger API support. org.ops4j.pax.logging.pax-logging-api[org.ops4j.pax.logging.internal.Activator] : Disabling JULI Logger API support. 18.02.2011 03:30:08.284 *INFO* [FelixDispatchQueue] org.ops4j.pax.logging.pax-logging-api BundleEvent STOPPED 18.02.2011 03:30:08.284 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.ConfigurationAdmin,19] ServiceEvent UNREGISTERING 18.02.2011 03:30:08.285 *INFO* [FelixStartLevel] org.apache.felix.configadmin Service [org.apache.felix.cm.file.FilePersistenceManager,18] ServiceEvent UNREGISTERING 18.02.2011 03:30:08.285 *INFO* [FelixDispatchQueue] org.apache.felix.configadmin BundleEvent STOPPED Build timed out. Aborting killed. [HUDSON] Recording test results [INFO] ------------------------------------------------------------------------ [ERROR] FATAL ERROR [INFO] ------------------------------------------------------------------------ channel stopped [INFO] Unable to call isArchivingDisabled. Invalid object ID 12 [INFO] ------------------------------------------------------------------------ [INFO] Trace java.lang.IllegalStateException: Unable to call isArchivingDisabled. Invalid object ID 12 at hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:268) at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:255) at hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:215) at hudson.remoting.UserRequest.perform(UserRequest.java:114) at hudson.remoting.UserRequest.perform(UserRequest.java:48) at hudson.remoting.Request$2.run(Request.java:270) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) at java.util.concurrent.FutureTask.run(FutureTask.java:138) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:662) [INFO] ------------------------------------------------------------------------ [INFO] Total time: 63 minutes 1 second [INFO] Finished at: Fri Feb 18 04:17:33 PST 2011 [INFO] Final Memory: 178M/478M [INFO] ------------------------------------------------------------------------ [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released
