| [virgo-build] [Hudson] Build failed in Hudson: virgo.packaging.snapshot #244 |
See <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/244/> ------------------------------------------ [...truncated 2803 lines...] [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] [2012-11-19 22:17:24.006] startup-tracker <KE0001I> Kernel starting. [junit] [2012-11-19 22:17:24.267] startup-tracker <KE0002I> Kernel started. [junit] [2012-11-19 22:17:24.313] startup-readiness <KE0007I> Virgo ready. Started for 5.862s. [junit] [2012-11-19 22:17:46.456] fs-watcher <HD0001I> Hot deployer processing 'CREATED' event for file 'java.profile.tester_1.0.0.jar'. [junit] [2012-11-19 22:17:46.478] fs-watcher <DE0001I> Installing from '<https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/nano/org.eclipse.virgo.nano.smoketest/target/test-expanded/virgo-nano-full-3.6.0.CI-2012-11-19_21-59-14/pickup/java.profile.tester_1.0.0.jar'.> [junit] [2012-11-19 22:17:46.544] fs-watcher <DE0002I> Installed bundle 'java.profile.tester' version '1.0.0'. [junit] [2012-11-19 22:17:46.545] fs-watcher <DE0003I> Starting bundle 'java.profile.tester' version '1.0.0'. [junit] [2012-11-19 22:17:46.663] System Bundle Shutdown <KE0010I> Shutdown initiated. [junit] ------------- ---------------- --------------- [subant] Leaving directory: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/nano/org.eclipse.virgo.nano.smoketest> test.post: [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results/html> [junitreport] Processing <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/TESTS-TestSuites.xml> to /tmp/null1731637017 [junitreport] Loading stylesheet jar:file:/shared/common/apache-ant-1.8.2/lib/ant-junit.jar!/org/apache/tools/ant/taskdefs/optional/junit/xsl/junit-frames.xsl [junitreport] Transform time: 162ms [junitreport] Deleting: /tmp/null1731637017 [delete] Deleting: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/TESTS-TestSuites.xml> smoke-test-kernel: test.pre: [delete] Deleting directory <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results> [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results/xml> [subant] Entering directory: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest> test-package: unzip.package: [delete] Deleting directory <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-expanded> [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-expanded> [unzip] Expanding: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/artifacts/virgo-kernel-3.6.0.CI-2012-11-19_21-59-14.zip> into <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-expanded> ivy.init: ivy.file.exists: resolve.init: resolve.compile: [ivy:cachepath] :: Ivy 2.1.0 - 20090925235825 :: http://ant.apache.org/ivy/ :: [ivy:cachepath] :: loading settings :: file = <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/virgo-build/common/ivysettings.xml> resolve.test: compile.init: test.init: [delete] Deleting: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/user-ivy.properties> [delete] Deleting: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/tmp.file> test.do: [delete] Deleting directory <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-classes> [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-classes> [s:javac] Compiling 2 source files to <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest/target/test-classes> [junit] Testsuite: org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests [junit] Tests run: 2, Failures: 0, Errors: 2, Time elapsed: 108.97 sec [junit] [junit] ------------- Standard Output --------------- [junit] SLF4J: The following loggers will not work because they were created [junit] SLF4J: during the default configuration phase of the underlying logging system. [junit] SLF4J: See also http://www.slf4j.org/codes.html#substituteLogger [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.server.call [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.server.call [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.server.call [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.server.call [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.transport.tcp [junit] SLF4J: sun.rmi.loader [junit] SLF4J: sun.rmi.server.call [junit] [2012-11-19 22:18:04.376] startup-tracker <KE0001I> Kernel starting. [junit] [2012-11-19 22:18:09.413] service-monitor-thread-1 <KE0100W> Reference '&globalRegion' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.eclipse.equinox.region.Region)(org.eclipse.virgo.kernel.region.name=global))'. [junit] [2012-11-19 22:18:09.448] service-monitor-thread-1 <KE0100W> Reference '&userRegion' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.eclipse.equinox.region.Region)(org.eclipse.virgo.kernel.region.name=org.eclipse.virgo.region.user))'. [junit] [2012-11-19 22:18:09.450] service-monitor-thread-1 <KE0100W> Reference '&runtimeArtifactModel' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(objectClass=org.eclipse.virgo.kernel.deployer.model.RuntimeArtifactModel)'. [junit] [2012-11-19 22:18:09.451] service-monitor-thread-1 <KE0100W> Reference '&repository' in bundle 'org.eclipse.virgo.kernel.osgi' version '3.6.0.D-20121105122315' is waiting for service with filter '(objectClass=org.eclipse.virgo.repository.Repository)'. [junit] [2012-11-19 22:18:09.783] service-monitor-thread-1 <KE0100W> Reference '&userBundleContext' in bundle 'org.eclipse.virgo.kernel.deployer' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.osgi.framework.BundleContext)(org.eclipse.virgo.kernel.regionContext=true))'. [junit] [2012-11-19 22:18:12.286] kernel-dm-4 <ME0003I> Dump 'serviceability/dump/2012-11-19-22-18-932' generated [junit] [2012-11-19 22:18:12.342] kernel-dm-4 <KE0101I> Reference '&repository' in bundle 'org.eclipse.virgo.kernel.osgi' version '3.6.0.D-20121105122315' was satisfied by service with filter '(objectClass=org.eclipse.virgo.repository.Repository)'. [junit] [2012-11-19 22:18:12.403] kernel-dm-5 <KE0101I> Reference '&globalRegion' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' was satisfied by service with filter '(&(objectClass=org.eclipse.equinox.region.Region)(org.eclipse.virgo.kernel.region.name=global))'. [junit] [2012-11-19 22:18:24.453] service-monitor-thread-1 <KE0100W> Reference '&userRegion' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.eclipse.equinox.region.Region)(org.eclipse.virgo.kernel.region.name=org.eclipse.virgo.region.user))'. [junit] [2012-11-19 22:18:24.454] service-monitor-thread-1 <KE0100W> Reference '&runtimeArtifactModel' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(objectClass=org.eclipse.virgo.kernel.deployer.model.RuntimeArtifactModel)'. [junit] [2012-11-19 22:18:24.785] service-monitor-thread-1 <KE0100W> Reference '&userBundleContext' in bundle 'org.eclipse.virgo.kernel.deployer' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.osgi.framework.BundleContext)(org.eclipse.virgo.kernel.regionContext=true))'. [junit] [2012-11-19 22:19:09.517] service-monitor-thread-1 <KE0100W> Reference '&userRegion' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.eclipse.equinox.region.Region)(org.eclipse.virgo.kernel.region.name=org.eclipse.virgo.region.user))'. [junit] [2012-11-19 22:19:09.518] service-monitor-thread-1 <KE0100W> Reference '&runtimeArtifactModel' in bundle 'org.eclipse.virgo.kernel.model' version '3.6.0.D-20121105122315' is waiting for service with filter '(objectClass=org.eclipse.virgo.kernel.deployer.model.RuntimeArtifactModel)'. [junit] [2012-11-19 22:19:09.795] service-monitor-thread-1 <KE0100W> Reference '&userBundleContext' in bundle 'org.eclipse.virgo.kernel.deployer' version '3.6.0.D-20121105122315' is waiting for service with filter '(&(objectClass=org.osgi.framework.BundleContext)(org.eclipse.virgo.kernel.regionContext=true))'. [junit] ------------- ---------------- --------------- [junit] Testcase: testKernelStartUpStatus(org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests): Caused an ERROR [junit] unable to create new native thread [junit] java.lang.OutOfMemoryError: unable to create new native thread [junit] at java.lang.Thread.start0(Native Method) [junit] at java.lang.Thread.start(Thread.java:597) [junit] at com.sun.jmx.remote.internal.ClientCommunicatorAdmin.<init>(ClientCommunicatorAdmin.java:25) [junit] at javax.management.remote.rmi.RMIConnector$RMIClientCommunicatorAdmin.<init>(RMIConnector.java:1431) [junit] at javax.management.remote.rmi.RMIConnector.connect(RMIConnector.java:308) [junit] at javax.management.remote.JMXConnectorFactory.connect(JMXConnectorFactory.java:248) [junit] at org.eclipse.virgo.kernel.smoketest.AbstractKernelTests.getMBeanServerConnection(AbstractKernelTests.java:83) [junit] at org.eclipse.virgo.kernel.smoketest.AbstractKernelTests.getKernelStartUpStatus(AbstractKernelTests.java:89) [junit] at org.eclipse.virgo.kernel.smoketest.AbstractKernelTests.waitForKernelStartFully(AbstractKernelTests.java:190) [junit] at org.eclipse.virgo.kernel.smoketest.AbstractKernelTests.waitForKernelStartFully(AbstractKernelTests.java:179) [junit] at org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests.testKernelStartUpStatus(KernelStartupAndShutdownTests.java:22) [junit] [junit] [junit] Testcase: testKernelShutdownStatus(org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests): Caused an ERROR [junit] unable to create new native thread [junit] java.lang.OutOfMemoryError: unable to create new native thread [junit] at java.lang.Thread.start0(Native Method) [junit] at java.lang.Thread.start(Thread.java:597) [junit] at org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests.testKernelShutdownStatus(KernelStartupAndShutdownTests.java:29) [junit] [junit] [junit] Test org.eclipse.virgo.kernel.smoketest.KernelStartupAndShutdownTests FAILED [subant] Leaving directory: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/kernel/org.eclipse.virgo.kernel.smoketest> test.post: [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results/html> [junitreport] Processing <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/TESTS-TestSuites.xml> to /tmp/null655625985 [junitreport] Loading stylesheet jar:file:/shared/common/apache-ant-1.8.2/lib/ant-junit.jar!/org/apache/tools/ant/taskdefs/optional/junit/xsl/junit-frames.xsl [junitreport] Transform time: 179ms [junitreport] Deleting: /tmp/null655625985 [delete] Deleting: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/TESTS-TestSuites.xml> smoke-test-tomcat-server: test.pre: [delete] Deleting directory <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results> [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/test-results/xml> [subant] Entering directory: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest> test-package: unzip.package: [delete] Deleting directory <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest/target/test-expanded> [mkdir] Created dir: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest/target/test-expanded> [unzip] Expanding: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/target/artifacts/virgo-tomcat-server-3.6.0.CI-2012-11-19_21-59-14.zip> into <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest/target/test-expanded> startup.pre: [copy] Copying 1 file to <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest/target/test-expanded/virgo-tomcat-server-3.6.0.CI-2012-11-19_21-59-14/pickup> shutdown: [echo] Shutting Down Virgo Server [echo] Shutting Down Virgo Server - waiting for http://localhost:8080/ to stop responding [echo] Shutting Down Virgo Server - shutdown startup: [echo] Starting Up Virgo Server [echo] Starting Up Virgo Server - script run and waiting for response from http://localhost:8080/ [subant] Leaving directory: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/org.eclipse.virgo.server.smoketest> BUILD FAILED <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/build.xml>:36: The following error occurred while executing this line: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/build-tomcat-server.xml>:187: The following error occurred while executing this line: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/virgo-build/multi-bundle/common.xml>:168: The following error occurred while executing this line: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/test-package.xml>:12: The following error occurred while executing this line: <https://hudson.eclipse.org/hudson/job/virgo.packaging.snapshot/ws/build-packaging/tomcat-server/test-package.xml>:52: Virgo Server start up failed Total time: 21 minutes 2 seconds [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Archiving artifacts -- This message is automatically generated by Hudson. For more information on Hudson, see: http://hudson-ci.org/