The configuration/test of honeycomb
Marek Gradzki -X (mgradzki - PANTHEON TECHNOLOGIES@Cisco) <mgradzki@...>
Hello,
I see two issues in the log.
1) Netconf session was not established after 20000 To initialize netconf session, client hello message needs to be provided, see:
2) HC initialization failed. During startup, HC tries to apply persisted configuration from /var/lib/honeycomb/persist. Please attach it. Please also attach HC log with TRACE level for: io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistryBuilder You can set log level in /opt/honeycomb/config/logback.xml.
Regards, Marek
From: honeycomb-dev@... [mailto:honeycomb-dev@...]
On Behalf Of Lee Via
Sent: 4 kwietnia 2018 05:37 To: honeycomb-dev@... Subject: [honeycomb-dev] The configuration/test of honeycomb
Hi there, I have installed honeycomb 18.01 and followed the test guide to initialize Honeycomb. I wanted to use honeycomb with VPP. But when I tried to test it, I got some errors and the SSH agent disconnected.
I start the test with :ssh admin@localhost -p 2831 -s netconf
The following is the honeycomb.log in /var/log ============================================
2018-04-03 23:14:10.988 EDT [main] INFO io.fd.honeycomb.infra.distro.Main - Starting honeycomb 2018-04-03 23:14:11.926 EDT [main] INFO i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution 2018-04-03 23:14:12.075 EDT [main] INFO i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers 2018-04-03 23:14:12.076 EDT [main] INFO io.fd.hc2vpp.mpls.MplsModule - Installing MPLS module 2018-04-03 23:14:12.076 EDT [main] INFO io.fd.hc2vpp.mpls.MplsModule - Injecting MPLS writers 2018-04-03 23:14:12.077 EDT [main] INFO io.fd.hc2vpp.mpls.MplsModule - MPLS module successfully configured 2018-04-03 23:14:12.092 EDT [main] INFO i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound 2018-04-03 23:14:12.504 EDT [main] INFO io.fd.hc2vpp.v3po.V3poModule - Module V3PO successfully configured 2018-04-03 23:14:12.534 EDT [main] INFO i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule 2018-04-03 23:14:12.535 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Configuring module Lisp 2018-04-03 23:14:12.536 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding Naming context[locator-set-context] 2018-04-03 23:14:12.537 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding Eid context[local-mapping-context] 2018-04-03 23:14:12.584 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding Eid context[remote-mapping-context] 2018-04-03 23:14:12.585 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding Adjacencies context 2018-04-03 23:14:12.588 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding reader factories 2018-04-03 23:14:12.618 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Reader factories binded 2018-04-03 23:14:12.618 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Binding writer factories 2018-04-03 23:14:12.620 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Writer factories binded 2018-04-03 23:14:12.621 EDT [main] INFO io.fd.hc2vpp.lisp.LispModule - Module Lisp successfully configured 2018-04-03 23:14:12.660 EDT [main] INFO io.fd.hc2vpp.nat.NatModule - Module NAT successfully configured 2018-04-03 23:14:12.700 EDT [main] INFO io.fd.hc2vpp.dhcp.DhcpModule - Installing DHCP module 2018-04-03 23:14:12.701 EDT [main] INFO io.fd.hc2vpp.dhcp.DhcpModule - Injecting writers factories 2018-04-03 23:14:12.701 EDT [main] INFO io.fd.hc2vpp.dhcp.DhcpModule - Injecting readers factories 2018-04-03 23:14:12.702 EDT [main] INFO io.fd.hc2vpp.dhcp.DhcpModule - Module DHCP successfully configured 2018-04-03 23:14:12.702 EDT [main] INFO io.fd.hc2vpp.routing.RoutingModule - Starting initialization 2018-04-03 23:14:12.705 EDT [main] INFO io.fd.hc2vpp.routing.RoutingModule - Injecting reader factories 2018-04-03 23:14:12.706 EDT [main] INFO io.fd.hc2vpp.routing.RoutingModule - Injecting writers factories 2018-04-03 23:14:12.708 EDT [main] INFO io.fd.hc2vpp.routing.RoutingModule - Started successfully 2018-04-03 23:14:12.708 EDT [main] INFO io.fd.hc2vpp.acl.AclModule - Configuring module Acl 2018-04-03 23:14:12.751 EDT [main] INFO io.fd.hc2vpp.acl.AclModule - Module Acl successfully configured 2018-04-03 23:14:12.774 EDT [main] INFO i.f.h.n.restconf.RestconfModule - Starting RESTCONF Northbound 2018-04-03 23:14:12.872 EDT [main] INFO i.f.h.i.role.InterfaceRoleModule - Configuring interface role module 2018-04-03 23:14:12.872 EDT [main] INFO i.f.h.i.role.InterfaceRoleModule - Interface role module configured 2018-04-03 23:14:13.380 EDT [main] INFO i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution 2018-04-03 23:14:13.565 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@7e094740} 2018-04-03 23:14:14.609 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@72458efc 2018-04-03 23:14:15.720 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@2ceb80a1 2018-04-03 23:14:15.828 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@732f29af 2018-04-03 23:14:15.884 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@5d05f453 2018-04-03 23:14:15.885 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@251c4280 2018-04-03 23:14:16.363 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@421d54b3 2018-04-03 23:14:16.363 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@4dad8ec0 2018-04-03 23:14:16.376 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.hc2vpp.common.integration.JVppRegistryProvider@382dc417 2018-04-03 23:14:16.462 EDT [main] INFO i.f.h.c.i.JVppRegistryProvider - JVpp connection opened successfully as: honeycomb 2018-04-03 23:14:16.462 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.hc2vpp.common.integration.JVppCoreProvider@587c5c1 2018-04-03 23:14:16.774 EDT [main] INFO i.f.h.c.i.JVppCoreProvider - Successfully loaded jvpp-core plugin 2018-04-03 23:14:16.946 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.hc2vpp.nat.jvpp.JVppNatProvider@610fbe1c 2018-04-03 23:14:16.986 EDT [main] INFO i.f.hc2vpp.nat.jvpp.JVppNatProvider - Successfully loaded jvpp-nat plugin 2018-04-03 23:14:17.028 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.hc2vpp.acl.JVppAclProvider@600bbf9e 2018-04-03 23:14:17.066 EDT [main] INFO io.fd.hc2vpp.acl.JVppAclProvider - Acl plugin successfully loaded[version 1.3] 2018-04-03 23:14:17.067 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@775f15fd 2018-04-03 23:14:17.302 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@d3957fe 2018-04-03 23:14:17.305 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@105dc04d 2018-04-03 23:14:17.326 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@29d2fa84 2018-04-03 23:14:17.328 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@299321e2 2018-04-03 23:14:17.378 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@64ba3208 2018-04-03 23:14:17.378 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@6e981e78 2018-04-03 23:14:17.406 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@57b1ec84 2018-04-03 23:14:17.416 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@15f519f7 2018-04-03 23:14:17.417 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@7d537616 2018-04-03 23:14:17.425 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.hc2vpp.common.integration.ContextsReaderFactoryProvider@252c6cdb 2018-04-03 23:14:17.426 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@365e65bb 2018-04-03 23:14:17.651 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@67e11bda 2018-04-03 23:14:17.652 EDT [main] INFO i.f.h.c.t.u.JvppReplyConsumer$JvppReplyTimeoutHolder - Jvpp reply timeout configured to 5 seconds 2018-04-03 23:14:17.653 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.restconf.JettyServerProvider@516c20de 2018-04-03 23:14:17.981 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@1d131e1b 2018-04-03 23:14:17.982 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@2fdf7da2 2018-04-03 23:14:17.983 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@304068 2018-04-03 23:14:17.983 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@5c79c5ec 2018-04-03 23:14:17.984 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@70169464 2018-04-03 23:14:17.996 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.restconf.RestconfProvider@2430cf17 2018-04-03 23:14:18.209 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.restconf.HttpConnectorProvider@2591d23a 2018-04-03 23:14:18.209 EDT [main] INFO i.f.h.n.r.HttpConnectorProvider - Starting RESTCONF HTTP 2018-04-03 23:14:18.240 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.restconf.HttpsConnectorProvider@1eb9d69a 2018-04-03 23:14:18.240 EDT [main] INFO i.f.h.n.r.HttpsConnectorProvider - Starting RESTCONF HTTPS 2018-04-03 23:14:18.261 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.restconf.JettyServerStarter@5af40e45 2018-04-03 23:14:18.261 EDT [main] INFO i.f.h.n.restconf.JettyServerStarter - Starting RESTCONF Jetty server 2018-04-03 23:14:18.416 EDT [Web socket server on port PortNumber [_value=7779]] WARN i.n.util.internal.MacAddressUtil - Failed to find a usable hardware address from the network interfaces; using random bytes: 6a:f8:c4:bc:1c:99:61:f1 2018-04-03 23:14:18.461 EDT [main] WARN o.e.jetty.security.SecurityHandler - ServletContext@o.e.j.w.WebAppContext@24068086{/restconf,file:///opt/honeycomb/config/,STARTING}{/opt/honeycomb/config/} has uncovered http methods for path: /* 2018-04-03 23:14:18.576 EDT [Web socket server on port PortNumber [_value=7779]] INFO o.o.n.s.s.w.WebSocketServer - Web socket server started at address 0.0.0.0, port 7779. 2018-04-03 23:14:19.639 EDT [main] INFO i.f.h.n.restconf.JettyServerStarter - RESTCONF Jetty server successfully started 2018-04-03 23:14:19.641 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@13ebccd 2018-04-03 23:14:19.642 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@77fb1002 2018-04-03 23:14:19.815 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@81dfdee 2018-04-03 23:14:19.833 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@63636de0 2018-04-03 23:14:19.856 EDT [main] INFO o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated. 2018-04-03 23:14:19.857 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@782dc5 2018-04-03 23:14:19.949 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@71f4aeb6 2018-04-03 23:14:19.950 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@fd0e118 2018-04-03 23:14:19.966 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@7d43f1c9 2018-04-03 23:14:19.972 EDT [main] INFO i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb 2018-04-03 23:14:19.972 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@54c3e686 2018-04-03 23:14:19.973 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@3987a1e8 2018-04-03 23:14:19.976 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@7852ab30 2018-04-03 23:14:19.979 EDT [main] INFO i.f.h.n.n.NetconfTcpServerProvider - Starting NETCONF TCP 2018-04-03 23:14:19.991 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@564d3940 2018-04-03 23:14:19.991 EDT [main] INFO i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH 2018-04-03 23:14:19.993 EDT [main] WARN io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x071207bd]' 2018-04-03 23:14:20.002 EDT [netconf-netty-0] INFO i.f.h.n.n.NetconfTcpServerProvider - Netconf TCP endpoint started successfully at /127.0.0.1:7777 2018-04-03 23:14:20.618 EDT [main] INFO io.fd.honeycomb.infra.distro.Main - Initializing configuration 2018-04-03 23:14:20.619 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@50e0b472 2018-04-03 23:14:20.620 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@747e8659 2018-04-03 23:14:20.620 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@556a6320 2018-04-03 23:14:20.620 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@64908ab9 2018-04-03 23:14:20.620 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@5af641d3 2018-04-03 23:14:20.620 EDT [main] INFO i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@744db9fb 2018-04-03 23:14:20.621 EDT [main] INFO i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started 2018-04-03 23:14:20.696 EDT [netconf-netty-1] INFO i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:2831 2018-04-03 23:14:20.708 EDT [main] INFO i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully 2018-04-03 23:14:21.438 EDT [main] INFO i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully 2018-04-03 23:14:21.461 EDT [main] WARN i.f.h.d.i.ModifiableDataTreeDelegator - Failed to apply all changes io.fd.honeycomb.translate.write.registry.UpdateFailedException: io.fd.honeycomb.translate.write.WriteFailedException: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.bulkUpdate(FlatWriterRegistry.java:223) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.processModifications(FlatWriterRegistry.java:99) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.ModifiableDataTreeDelegator$DelegatingConfigSnapshot.processCandidate(ModifiableDataTreeDelegator.java:138) ~[data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.ModifiableDataTreeManager$ConfigSnapshot.commit(ModifiableDataTreeManager.java:106) [data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.WriteTransaction.submit(WriteTransaction.java:139) [data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.init.RestoringInitializer.initialize(RestoringInitializer.java:113) [cfg-init-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapter.initialize(InitializerRegistryAdapter.java:65) [minimal-distribution-core-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.Main.init(Main.java:68) [minimal-distribution-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.Main.main(Main.java:45) [minimal-distribution-1.18.01.2-SNAPSHOT.jar:na] Caused by: io.fd.honeycomb.translate.write.WriteFailedException: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) at io.fd.hc2vpp.common.translate.util.JvppReplyConsumer.getReplyForWrite(JvppReplyConsumer.java:71) ~[vpp-translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.common.translate.util.JvppReplyConsumer.getReplyForWrite(JvppReplyConsumer.java:54) ~[vpp-translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterfaceFlags(InterfaceCustomizer.java:106) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterfaceAttributes(InterfaceCustomizer.java:85) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterface(InterfaceCustomizer.java:78) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.writeCurrentAttributes(InterfaceCustomizer.java:56) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.writeCurrentAttributes(InterfaceCustomizer.java:39) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.GenericListWriter.writeCurrentAttributes(GenericListWriter.java:52) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.util.write.AbstractGenericWriter.writeCurrent(AbstractGenericWriter.java:48) ~[translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.GenericListWriter.writeCurrent(GenericListWriter.java:86) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.util.write.AbstractGenericWriter.processModification(AbstractGenericWriter.java:84) ~[translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.bulkUpdate(FlatWriterRegistry.java:220) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] ... 8 common frames omitted Caused by: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) 2018-04-03 23:14:21.461 EDT [main] INFO i.f.h.d.i.ModifiableDataTreeDelegator - Nothing to revert 2018-04-03 23:14:21.462 EDT [main] WARN i.f.h.i.d.i.InitializerRegistryAdapter - Failed to initialize config io.fd.honeycomb.data.init.DataTreeInitializer$InitializeException: Unable to restore data from /var/lib/honeycomb/persist/config/data.json at io.fd.honeycomb.data.init.RestoringInitializer.initialize(RestoringInitializer.java:117) ~[cfg-init-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapter.initialize(InitializerRegistryAdapter.java:65) ~[minimal-distribution-core-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.Main.init(Main.java:68) [minimal-distribution-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.infra.distro.Main.main(Main.java:45) [minimal-distribution-1.18.01.2-SNAPSHOT.jar:na] Caused by: org.opendaylight.controller.md.sal.common.api.data.TransactionCommitFailedException: Failed to validate DataTreeModification at io.fd.honeycomb.data.impl.WriteTransaction.submit(WriteTransaction.java:149) ~[data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.init.RestoringInitializer.initialize(RestoringInitializer.java:113) ~[cfg-init-1.18.01.2-SNAPSHOT.jar:na] ... 3 common frames omitted Caused by: io.fd.honeycomb.translate.write.registry.UpdateFailedException: io.fd.honeycomb.translate.write.WriteFailedException: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.bulkUpdate(FlatWriterRegistry.java:223) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.processModifications(FlatWriterRegistry.java:99) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.ModifiableDataTreeDelegator$DelegatingConfigSnapshot.processCandidate(ModifiableDataTreeDelegator.java:138) ~[data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.ModifiableDataTreeManager$ConfigSnapshot.commit(ModifiableDataTreeManager.java:106) ~[data-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.data.impl.WriteTransaction.submit(WriteTransaction.java:139) ~[data-impl-1.18.01.2-SNAPSHOT.jar:na] ... 4 common frames omitted Caused by: io.fd.honeycomb.translate.write.WriteFailedException: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) at io.fd.hc2vpp.common.translate.util.JvppReplyConsumer.getReplyForWrite(JvppReplyConsumer.java:71) ~[vpp-translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.common.translate.util.JvppReplyConsumer.getReplyForWrite(JvppReplyConsumer.java:54) ~[vpp-translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterfaceFlags(InterfaceCustomizer.java:106) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterfaceAttributes(InterfaceCustomizer.java:85) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.setInterface(InterfaceCustomizer.java:78) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.writeCurrentAttributes(InterfaceCustomizer.java:56) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.hc2vpp.v3po.interfaces.InterfaceCustomizer.writeCurrentAttributes(InterfaceCustomizer.java:39) ~[v3po2vpp-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.GenericListWriter.writeCurrentAttributes(GenericListWriter.java:52) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.util.write.AbstractGenericWriter.writeCurrent(AbstractGenericWriter.java:48) ~[translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.GenericListWriter.writeCurrent(GenericListWriter.java:86) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.util.write.AbstractGenericWriter.processModification(AbstractGenericWriter.java:84) ~[translate-utils-1.18.01.2-SNAPSHOT.jar:na] at io.fd.honeycomb.translate.impl.write.registry.FlatWriterRegistry.bulkUpdate(FlatWriterRegistry.java:220) ~[translate-impl-1.18.01.2-SNAPSHOT.jar:na] ... 8 common frames omitted Caused by: io.fd.vpp.jvpp.VppCallbackException: vppApi.sw_interface_set_flags_reply failed: Invalid sw_if_index (error code: -2) 2018-04-03 23:14:21.462 EDT [main] INFO i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized 2018-04-03 23:14:21.462 EDT [main] INFO io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully 2018-04-03 23:14:21.462 EDT [main] INFO io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully! 2018-04-03 23:15:23.714 EDT [globalEventExecutor-1-2] WARN o.a.s.server.session.ServerSession - Exception caught java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcherImpl.read0(Native Method) ~[na:1.8.0_161] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) ~[na:1.8.0_161] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) ~[na:1.8.0_161] at sun.nio.ch.IOUtil.read(IOUtil.java:197) ~[na:1.8.0_161] at sun.nio.ch.UnixAsynchronousSocketChannelImpl.finishRead(UnixAsynchronousSocketChannelImpl.java:387) ~[na:1.8.0_161] at sun.nio.ch.UnixAsynchronousSocketChannelImpl.finish(UnixAsynchronousSocketChannelImpl.java:191) ~[na:1.8.0_161] at sun.nio.ch.UnixAsynchronousSocketChannelImpl.onEvent(UnixAsynchronousSocketChannelImpl.java:213) ~[na:1.8.0_161] at sun.nio.ch.EPollPort$EventHandlerTask.run(EPollPort.java:293) ~[na:1.8.0_161] at java.lang.Thread.run(Thread.java:748) [na:1.8.0_161] 2018-04-03 23:16:25.830 EDT [pool-1-thread-1] WARN o.o.n.n.AbstractNetconfSessionNegotiator - Netconf session was not established after 20000 2018-04-03 23:17:04.330 EDT [pool-1-thread-1] WARN o.o.n.n.AbstractNetconfSessionNegotiator - Netconf session was not established after 20000 =============================================== Best regards, Via Lee |
|