Uploaded image for project: 'controller'
  1. controller
  2. CONTROLLER-134

Startup exception

    XMLWordPrintable

Details

    • Improvement
    • Status: Resolved
    • Resolution: Cannot Reproduce
    • None
    • None
    • mdsal
    • None
    • Operating System: Mac OS
      Platform: PC

    Description

      On startup, I see this exception and the broker does not come up:

      java.lang.IllegalArgumentException: ModuleFactory not found with module name: binding-notification-broker
      at org.opendaylight.controller.config.manager.impl.factoriesresolver.HierarchicalConfigMBeanFactoriesHolder.findByModuleName(HierarchicalConfigMBeanFactoriesHolder.java:66) ~[na:na]
      at org.opendaylight.controller.config.manager.impl.ConfigTransactionControllerImpl.createModule(ConfigTransactionControllerImpl.java:200) ~[na:na]
      at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.7.0_45]
      at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) ~[na:1.7.0_45]
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_45]
      at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_45]
      at sun.reflect.misc.Trampoline.invoke(MethodUtil.java:75) ~[na:1.7.0_45]
      at sun.reflect.GeneratedMethodAccessor159.invoke(Unknown Source) ~[na:na]
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.7.0_45]
      at java.lang.reflect.Method.invoke(Method.java:606) ~[na:1.7.0_45]
      at sun.reflect.misc.MethodUtil.invoke(MethodUtil.java:279) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.ConvertingMethod.invokeWithOpenReturn(ConvertingMethod.java:193) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.ConvertingMethod.invokeWithOpenReturn(ConvertingMethod.java:175) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MXBeanIntrospector.invokeM2(MXBeanIntrospector.java:117) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MXBeanIntrospector.invokeM2(MXBeanIntrospector.java:54) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MBeanIntrospector.invokeM(MBeanIntrospector.java:237) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.PerInterface.invoke(PerInterface.java:138) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MBeanSupport.invoke(MBeanSupport.java:252) ~[na:1.7.0_45]
      at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.invoke(DefaultMBeanServerInterceptor.java:819) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.JmxMBeanServer.invoke(JmxMBeanServer.java:801) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MXBeanProxy$InvokeHandler.invoke(MXBeanProxy.java:150) ~[na:1.7.0_45]
      at com.sun.jmx.mbeanserver.MXBeanProxy.invoke(MXBeanProxy.java:167) ~[na:1.7.0_45]
      at javax.management.MBeanServerInvocationHandler.invoke(MBeanServerInvocationHandler.java:252) ~[na:1.7.0_45]
      at com.sun.proxy.$Proxy89.createModule(Unknown Source) ~[na:na]
      at org.opendaylight.controller.config.util.ConfigTransactionJMXClient.createModule(ConfigTransactionJMXClient.java:77) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.MissingInstanceHandlingStrategy.handleMissingInstance(MissingInstanceHandlingStrategy.java:30) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.AbstractEditConfigStrategy.executeConfiguration(AbstractEditConfigStrategy.java:34) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.EditConfig.handleMisssingInstancesOnTransaction(EditConfig.java:205) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.EditConfig.test(EditConfig.java:121) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.EditConfig.executeTests(EditConfig.java:98) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.EditConfig.getResponseInternal(EditConfig.java:69) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.editconfig.EditConfig.handle(EditConfig.java:279) ~[na:na]
      at org.opendaylight.controller.netconf.confignetconfconnector.operations.AbstractConfigNetconfOperation.handle(AbstractConfigNetconfOperation.java:42) ~[na:na]
      at org.opendaylight.controller.netconf.util.mapping.AbstractNetconfOperation.handle(AbstractNetconfOperation.java:79) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.osgi.NetconfOperationRouterImpl$NetconfOperationExecution.execute(NetconfOperationRouterImpl.java:253) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.monitoring.Get.doFilter(Get.java:46) ~[na:na]
      at org.opendaylight.controller.netconf.impl.osgi.NetconfOperationRouterImpl$1.execute(NetconfOperationRouterImpl.java:177) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.mapping.operations.DefaultCommit.doFilter(DefaultCommit.java:78) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.osgi.NetconfOperationRouterImpl$1.execute(NetconfOperationRouterImpl.java:177) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.osgi.NetconfOperationRouterImpl.executeOperationWithHighestPriority(NetconfOperationRouterImpl.java:182) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.osgi.NetconfOperationRouterImpl.onNetconfMessage(NetconfOperationRouterImpl.java:145) ~[bundlefile:na]
      at org.opendaylight.controller.netconf.impl.NetconfServerSessionListener.processDocument(NetconfServerSessionListener.java:112) [bundlefile:na]
      at org.opendaylight.controller.netconf.impl.NetconfServerSessionListener.onMessage(NetconfServerSessionListener.java:75) [bundlefile:na]
      at org.opendaylight.controller.netconf.impl.NetconfServerSessionListener.onMessage(NetconfServerSessionListener.java:30) [bundlefile:na]
      at org.opendaylight.controller.netconf.api.NetconfSession.handleMessage(NetconfSession.java:62) [bundlefile:na]
      at org.opendaylight.controller.netconf.api.NetconfSession.handleMessage(NetconfSession.java:22) [bundlefile:na]
      at org.opendaylight.protocol.framework.AbstractProtocolSession.channelRead0(AbstractProtocolSession.java:45) [bundlefile:na]
      at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:98) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.invokeChannelRead(DefaultChannelHandlerContext.java:337) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.fireChannelRead(DefaultChannelHandlerContext.java:323) [bundlefile:na]
      at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:173) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.invokeChannelRead(DefaultChannelHandlerContext.java:337) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.fireChannelRead(DefaultChannelHandlerContext.java:323) [bundlefile:na]
      at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:173) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.invokeChannelRead(DefaultChannelHandlerContext.java:337) [bundlefile:na]
      at io.netty.channel.DefaultChannelHandlerContext.fireChannelRead(DefaultChannelHandlerContext.java:323) [bundlefile:na]
      at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:785) [bundlefile:na]
      at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:100) [bundlefile:na]
      at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:480) [bundlefile:na]
      at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:447) [bundlefile:na]
      at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:341) [bundlefile:na]
      at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) [bundlefile:na]
      at java.lang.Thread.run(Thread.java:744) [na:1.7.0_45]
      2014-01-24 01:27:20.315 CST [org.opendaylight.controller.logging.bridge.internal.LogListenerImpl@374d9243] INFO o.o.c.logging.bridge.OSGI2SLF4J - Bundle:org.opendaylight.controller.sal-binding-broker-impl Message:ServiceEvent REGISTERED Exception:null
      2014-01-24 01:27:20.325 CST [org.opendaylight.controller.logging.bridge.internal.LogListenerImpl@374d9243] INFO o.o.c.logging.bridge.OSGI2SLF4J - Bundle:org.opendaylight.controller.sal-binding-broker-impl Message:BundleEvent STARTED Exception:null
      2014-01-24 01:27:21.029 CST [ConfigPersister-registrator] WARN o.o.c.netconf.util.NetconfUtil - Can not load last configuration, operation failed
      2014-01-24 01:27:21.033 CST [ConfigPersister-registrator] ERROR o.o.c.l.b.i.UncaughtExceptionPolicy - Thread Thread[ConfigPersister-registrator,5,main] died because of an uncaught exception
      java.lang.IllegalStateException: Can not load last configuration, operation failed: <rpc-reply xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" message-id="persister_edit">
      <rpc-error>
      <error-type>application</error-type>
      <error-tag>operation-failed</error-tag>
      <error-severity>error</error-severity>

      <error-info>
      <error>java.lang.IllegalArgumentException: ModuleFactory not found with module name: binding-notification-broker</error>
      </error-info>
      </rpc-error>
      </rpc-reply>

      at org.opendaylight.controller.netconf.util.NetconfUtil.checkIsMessageOk(NetconfUtil.java:74) ~[na:na]
      at org.opendaylight.controller.netconf.persist.impl.ConfigPusher.pushLastConfig(ConfigPusher.java:180) ~[na:na]
      at org.opendaylight.controller.netconf.persist.impl.ConfigPusher.pushSnapshotWithRetries(ConfigPusher.java:92) ~[na:na]
      at org.opendaylight.controller.netconf.persist.impl.ConfigPusher.pushAllConfigs(ConfigPusher.java:77) ~[na:na]
      at org.opendaylight.controller.netconf.persist.impl.ConfigPusher.init(ConfigPusher.java:71) ~[na:na]
      at org.opendaylight.controller.netconf.persist.impl.osgi.ConfigPersisterActivator$1.run(ConfigPersisterActivator.java:82) ~[na:na]
      at java.lang.Thread.run(Thread.java:744) ~[na:1.7.0_45]

      This is running the openflowplugin/distribution/base against

      Macintosh-3:opendaylight hagbard$ git log -1
      commit 4625e97da19ae082c558d9093907009a55b24a65
      Author: Michal Rehak <mirehak@cisco.com>
      Date: Thu Jan 23 09:20:48 2014 +0100

      stress test related improvement

      • propagate close upon bundle stop
      • enriched logging upon echo timeout disconnect
      • increased echo timeout from 5 to 15 sec
      • fix memory leak in OpenflowpluginTestCommandProvider.FlowEventListener

      Change-Id: I13c9bd319438f883271bd3ddc71e5952f45a351f
      Signed-off-by: Michal Rehak <mirehak@cisco.com>

      And controller:

      Macintosh-3:controller hagbard$ git log -1
      commit ed237395f6e2e4834f20c982ab1a4a1a7260b18a
      Merge: 4b24a0c 16ec838
      Author: Ed Warnicke <eaw@cisco.com>
      Date: Fri Jan 24 04:05:20 2014 +0000

      Merge "Binary masks in match types yang model changed to numbers. Plugin code review corresponding to this change: https://git.opendaylight.org/gerrit/#/c/4638/"
      Macintosh-3:controller hagbard$

      Attachments

        No reviews matched the request. Check your Options in the drop-down menu of this sections header.

        Activity

          People

            Unassigned Unassigned
            hagbard Ed Warnicke
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: