You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@servicemix.apache.org by "Gert Vanthienen (JIRA)" <ji...@apache.org> on 2010/01/29 12:56:32 UTC

[jira] Created: (SMX4NMR-181) JBI itests' ManagementTest intermittently fails on slower CI build machines

JBI itests' ManagementTest intermittently fails on slower CI build machines
---------------------------------------------------------------------------

                 Key: SMX4NMR-181
                 URL: https://issues.apache.org/activemq/browse/SMX4NMR-181
             Project: ServiceMix NMR
          Issue Type: Test
    Affects Versions: 1.0.0
            Reporter: Gert Vanthienen
            Assignee: Gert Vanthienen
             Fix For: 1.2.0


On slower CI build machines, the JBI itests' ManagementTest intermittently fails.  From the log, it looks like it's a pure timing issue - the AdminCommandsService is being registered at almost the exact same moment that the test gives up waiting for it.

{noformat}
[12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Registering service .component-4 with interfaces [org.apache.servicemix.jbi.deployer.AdminCommandsService] and properties {osgi.service.blueprint.compname=adminCommandsService}
[12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Retrieving service for bundle pax-exam-probe_0.0.0 [57] and service registration {org.apache.servicemix.jbi.deployer.AdminCommandsService}={osgi.service.blueprint.compname=adminCommandsService, service.id=80}
[12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Creating service instance
[12:18:56]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{}]
[12:18:56]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.osgi.extender.internal.support.NamespacePlugins - Trying to resolving namespace handler for http://www.springframework.org/schema/osgi
[12:18:57]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler}]
[12:18:57]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler}]
[12:18:58]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/osgi-compendium=org.springframework.osgi.compendium.config.CompendiumNamespaceHandler, http://www.springframework.org/schema/osgi=org.springframework.osgi.config.OsgiNamespaceHandler}]
[12:18:58]: [pool-2-thread-1] DEBUG org.apache.servicemix.nmr.osgi - ServiceEvent REGISTERED
[12:18:58]: [pool-2-thread-1] DEBUG org.apache.geronimo.blueprint.container.BlueprintContainerImpl - Instantiating components: [blueprintContainer, blueprintBundle, blueprintBundleContext, blueprintConverter, .component-1, servicemix, .component-2, managementStrategy, flowRegistry, endpointTracker, listenerRegistry, .component-3, endpointRegistry, internalEndpointRegistry, .component-4, wireRegistry, .component-5, authorizationService, .component-6, authorizationEntries, .component-7, authenticationService, .component-8, executorFactory, org.apache.geronimo.managedObjectManager, .cm-4]
[12:18:58]: [RMI TCP Connection(1)-10.65.4.7] DEBUG sun.rmi.server.call - RMI TCP Connection(1)-10.65.4.7: [10.65.4.7] exception: 
[12:18:58]: java.lang.reflect.InvocationTargetException
[12:18:58]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[12:18:58]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
[12:18:59]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[12:18:59]: 	at java.lang.reflect.Method.invoke(Method.java:600)
[12:18:59]: 	at org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl.remoteCall(RemoteBundleContextImpl.java:80)
[12:18:59]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[12:18:59]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
[12:18:59]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[12:18:59]: 	at java.lang.reflect.Method.invoke(Method.java:600)
[12:18:59]: 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:310)
[12:18:59]: 	at sun.rmi.transport.Transport$1.run(Transport.java:171)
[12:18:59]: 	at java.security.AccessController.doPrivileged(AccessController.java:284)
[12:18:59]: 	at sun.rmi.transport.Transport.serviceCall(Transport.java:167)
[12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:547)
[12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:802)
[12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:661)
[12:19:00]: 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
[12:19:00]: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
[12:19:00]: 	at java.lang.Thread.run(Thread.java:736)
[12:19:00]: Caused by: java.lang.reflect.InvocationTargetException
[12:19:00]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[12:19:00]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
[12:19:00]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[12:19:00]: 	at java.lang.reflect.Method.invoke(Method.java:600)
[12:19:00]: 	at org.ops4j.pax.exam.junit.extender.impl.internal.CallableTestMethodImpl.injectContextAndInvoke(CallableTestMethodImpl.java:134)
[12:19:00]: 	at org.ops4j.pax.exam.junit.extender.impl.internal.CallableTestMethodImpl.call(CallableTestMethodImpl.java:101)
[12:19:00]: 	... 19 more
[12:19:00]: Caused by: java.lang.RuntimeException: Gave up waiting for service (objectClass=org.apache.servicemix.jbi.deployer.AdminCommandsService)
[12:19:01]: 	at org.apache.servicemix.jbi.itests.AbstractIntegrationTest.getOsgiService(AbstractIntegrationTest.java:91)
[12:19:01]: 	at org.apache.servicemix.jbi.itests.AbstractIntegrationTest.getOsgiService(AbstractIntegrationTest.java:49)
[12:19:01]: 	at org.apache.servicemix.jbi.itests.ManagementTest.getAdminCommands(ManagementTest.java:137)
[12:19:01]: 	at org.apache.servicemix.jbi.itests.ManagementTest.testInstallUninstall(ManagementTest.java:64)
[12:19:01]: 	... 25 more
{noformat}

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.


[jira] Resolved: (SMX4NMR-181) JBI itests' ManagementTest intermittently fails on slower CI build machines

Posted by "Gert Vanthienen (JIRA)" <ji...@apache.org>.
     [ https://issues.apache.org/activemq/browse/SMX4NMR-181?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Gert Vanthienen resolved SMX4NMR-181.
-------------------------------------

    Resolution: Fixed

Increased the time-out on the ServiceTracker in http://svn.apache.org/viewvc?view=revision&revision=904478

> JBI itests' ManagementTest intermittently fails on slower CI build machines
> ---------------------------------------------------------------------------
>
>                 Key: SMX4NMR-181
>                 URL: https://issues.apache.org/activemq/browse/SMX4NMR-181
>             Project: ServiceMix NMR
>          Issue Type: Test
>    Affects Versions: 1.0.0
>            Reporter: Gert Vanthienen
>            Assignee: Gert Vanthienen
>             Fix For: 1.2.0
>
>
> On slower CI build machines, the JBI itests' ManagementTest intermittently fails.  From the log, it looks like it's a pure timing issue - the AdminCommandsService is being registered at almost the exact same moment that the test gives up waiting for it.
> {noformat}
> [12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Registering service .component-4 with interfaces [org.apache.servicemix.jbi.deployer.AdminCommandsService] and properties {osgi.service.blueprint.compname=adminCommandsService}
> [12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Retrieving service for bundle pax-exam-probe_0.0.0 [57] and service registration {org.apache.servicemix.jbi.deployer.AdminCommandsService}={osgi.service.blueprint.compname=adminCommandsService, service.id=80}
> [12:18:56]: [pool-2-thread-3] DEBUG org.apache.geronimo.blueprint.container.ServiceRecipe - Creating service instance
> [12:18:56]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{}]
> [12:18:56]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.osgi.extender.internal.support.NamespacePlugins - Trying to resolving namespace handler for http://www.springframework.org/schema/osgi
> [12:18:57]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/lang=org.springframework.scripting.config.LangNamespaceHandler, http://www.springframework.org/schema/jee=org.springframework.ejb.config.JeeNamespaceHandler, http://www.springframework.org/schema/context=org.springframework.context.config.ContextNamespaceHandler}]
> [12:18:57]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/p=org.springframework.beans.factory.xml.SimplePropertyNamespaceHandler, http://www.springframework.org/schema/util=org.springframework.beans.factory.xml.UtilNamespaceHandler}]
> [12:18:58]: [SpringOsgiExtenderThread-1] DEBUG org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver - Loaded mappings [{http://www.springframework.org/schema/osgi-compendium=org.springframework.osgi.compendium.config.CompendiumNamespaceHandler, http://www.springframework.org/schema/osgi=org.springframework.osgi.config.OsgiNamespaceHandler}]
> [12:18:58]: [pool-2-thread-1] DEBUG org.apache.servicemix.nmr.osgi - ServiceEvent REGISTERED
> [12:18:58]: [pool-2-thread-1] DEBUG org.apache.geronimo.blueprint.container.BlueprintContainerImpl - Instantiating components: [blueprintContainer, blueprintBundle, blueprintBundleContext, blueprintConverter, .component-1, servicemix, .component-2, managementStrategy, flowRegistry, endpointTracker, listenerRegistry, .component-3, endpointRegistry, internalEndpointRegistry, .component-4, wireRegistry, .component-5, authorizationService, .component-6, authorizationEntries, .component-7, authenticationService, .component-8, executorFactory, org.apache.geronimo.managedObjectManager, .cm-4]
> [12:18:58]: [RMI TCP Connection(1)-10.65.4.7] DEBUG sun.rmi.server.call - RMI TCP Connection(1)-10.65.4.7: [10.65.4.7] exception: 
> [12:18:58]: java.lang.reflect.InvocationTargetException
> [12:18:58]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [12:18:58]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
> [12:18:59]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
> [12:18:59]: 	at java.lang.reflect.Method.invoke(Method.java:600)
> [12:18:59]: 	at org.ops4j.pax.exam.rbc.internal.RemoteBundleContextImpl.remoteCall(RemoteBundleContextImpl.java:80)
> [12:18:59]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [12:18:59]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
> [12:18:59]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
> [12:18:59]: 	at java.lang.reflect.Method.invoke(Method.java:600)
> [12:18:59]: 	at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:310)
> [12:18:59]: 	at sun.rmi.transport.Transport$1.run(Transport.java:171)
> [12:18:59]: 	at java.security.AccessController.doPrivileged(AccessController.java:284)
> [12:18:59]: 	at sun.rmi.transport.Transport.serviceCall(Transport.java:167)
> [12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:547)
> [12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:802)
> [12:18:59]: 	at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:661)
> [12:19:00]: 	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
> [12:19:00]: 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
> [12:19:00]: 	at java.lang.Thread.run(Thread.java:736)
> [12:19:00]: Caused by: java.lang.reflect.InvocationTargetException
> [12:19:00]: 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> [12:19:00]: 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:48)
> [12:19:00]: 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
> [12:19:00]: 	at java.lang.reflect.Method.invoke(Method.java:600)
> [12:19:00]: 	at org.ops4j.pax.exam.junit.extender.impl.internal.CallableTestMethodImpl.injectContextAndInvoke(CallableTestMethodImpl.java:134)
> [12:19:00]: 	at org.ops4j.pax.exam.junit.extender.impl.internal.CallableTestMethodImpl.call(CallableTestMethodImpl.java:101)
> [12:19:00]: 	... 19 more
> [12:19:00]: Caused by: java.lang.RuntimeException: Gave up waiting for service (objectClass=org.apache.servicemix.jbi.deployer.AdminCommandsService)
> [12:19:01]: 	at org.apache.servicemix.jbi.itests.AbstractIntegrationTest.getOsgiService(AbstractIntegrationTest.java:91)
> [12:19:01]: 	at org.apache.servicemix.jbi.itests.AbstractIntegrationTest.getOsgiService(AbstractIntegrationTest.java:49)
> [12:19:01]: 	at org.apache.servicemix.jbi.itests.ManagementTest.getAdminCommands(ManagementTest.java:137)
> [12:19:01]: 	at org.apache.servicemix.jbi.itests.ManagementTest.testInstallUninstall(ManagementTest.java:64)
> [12:19:01]: 	... 25 more
> {noformat}

-- 
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.