HoneyNode Java 11 support for 221 devices

[transportpce.git] / tests / honeynode / 2.2.1 / log / honeycomb / honeycomb.log

diff --git a/tests/honeynode/2.2.1/log/honeycomb/honeycomb.log b/tests/honeynode/2.2.1/log/honeycomb/honeycomb.log
deleted file mode 100644 (file)
index 7ad36b9..0000000
--- a/tests/honeynode/2.2.1/log/honeycomb/honeycomb.log
+++ /dev/null
@@ -1,903 +0,0 @@
-2019-05-20 16:18:33.915 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:18:34.220 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:18:34.248 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:18:34.259 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:18:34.262 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:18:34.264 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:18:34.264 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:18:34.266 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:18:34.268 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:18:34.269 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:18:34.289 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:18:34.292 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:18:34.302 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:18:34.305 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:18:34.434 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:18:34.625 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:18:34.654 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:18:35.190 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:18:35.913 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:18:35.934 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:18:35.934 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:18:35.935 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:18:36.211 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@1e7ab390
-2019-05-20 16:18:36.211 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@77774571
-2019-05-20 16:18:36.212 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:18:36.223 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:18:36.224 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@4745e9c
-2019-05-20 16:18:36.233 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@715b886f
-2019-05-20 16:18:36.236 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@4228bf58
-2019-05-20 16:18:36.278 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:18:36.279 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@4bc33720
-2019-05-20 16:18:36.279 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@67064bdc
-2019-05-20 16:18:36.280 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:18:36.280 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:18:36.280 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@1b7a52dd
-2019-05-20 16:18:36.280 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@5ad5be4a
-2019-05-20 16:18:36.280 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@737d100a
-2019-05-20 16:18:36.281 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@3af7d855
-2019-05-20 16:18:42.454 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@1768c0b3
-2019-05-20 16:18:42.458 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:18:42.458 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:18:42.569 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:18:42.569 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'XPDR-A1'
-2019-05-20 16:18:42.646 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:18:43.468 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'XPDR-A1' writed to oper datastore
-2019-05-20 16:18:43.468 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:18:43.469 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:18:43.469 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:18:43.771 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:18:43.827 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:18:43.827 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:18:43.827 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:18:43.846 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:18:43.847 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:18:43.848 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:18:43.857 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:18:43.857 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:18:43.857 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:18:43.860 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:18:43.972 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:18:43.988 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:18:43.988 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:18:43.989 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:18:43.997 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:18:44.002 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:18:44.003 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:18:44.003 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:18:44.013 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:18:44.014 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:18:44.014 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:18:44.014 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:18:44.015 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/3-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=4, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=baseXPDR, getCircuitPackType=Base_transponder_circuit_pack, getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=2, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=1, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=3, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getNodeId=NodeIdType [_value=XPDR-A1], getNodeNumber=1, getNodeType=Xpdr, getPrefixLength=24, getTemplate=template_TRANSPONDER, augmentations={}}, getShelves=[Shelves{getAdministrativeState=InService, getDueDate=DateAndTime [_value=2017-02-24T15:56:10+00:00], getRack=XYZ, getShelfName=1, getShelfPosition=1, getShelfType=pizza, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:18:44.025 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:18:44.025 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:18:44.031 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:18:44.031 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:18:44.032 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:18:44.033 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'XPDR-A1' merged to device oper datastore
-2019-05-20 16:18:44.083 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:18:44.090 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@121bacf4
-2019-05-20 16:18:44.093 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@258cb16c
-2019-05-20 16:18:44.094 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@2966695e
-2019-05-20 16:18:44.102 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@1d8264c9
-2019-05-20 16:18:44.103 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@59e182
-2019-05-20 16:18:44.104 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@138c53c7
-2019-05-20 16:18:44.105 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@1eee885c
-2019-05-20 16:18:44.116 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@61ed43ae
-2019-05-20 16:18:44.128 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:18:44.129 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@655936bd
-2019-05-20 16:18:44.150 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@647407b4
-2019-05-20 16:18:44.162 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@b6b8632
-2019-05-20 16:18:44.165 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@52b31599
-2019-05-20 16:18:44.172 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:18:44.172 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:18:44.173 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@66df0533
-2019-05-20 16:18:44.174 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:18:44.174 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:18:44.174 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:18:44.175 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:18:44.175 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:18:44.175 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:18:44.175 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:18:44.208 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@519910b2
-2019-05-20 16:18:44.212 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@273b3afc
-2019-05-20 16:18:44.213 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@444dac3f
-2019-05-20 16:18:44.213 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:18:44.273 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x17b553fd]'
-2019-05-20 16:18:44.545 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:18:44.545 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@226ddb69
-2019-05-20 16:18:44.547 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@5f666412
-2019-05-20 16:18:44.547 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:18:44.547 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@1ce6c617
-2019-05-20 16:18:44.548 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@2d1e3187
-2019-05-20 16:18:44.548 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@26308d2a
-2019-05-20 16:18:44.548 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@38f3ef54
-2019-05-20 16:18:44.549 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@f32397
-2019-05-20 16:18:44.549 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:18:44.549 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:18:44.550 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:18:44.550 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:18:44.550 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:18:44.550 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:18:44.550 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:18:44.629 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17840
-2019-05-20 16:19:32.923 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:19:33.233 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:19:33.261 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:19:33.272 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:19:33.275 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:19:33.278 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:19:33.278 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:19:33.280 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:19:33.281 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:19:33.283 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:19:33.304 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:19:33.306 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:19:33.316 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:19:33.319 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:19:33.450 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:19:33.647 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:19:33.677 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:19:34.212 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:19:34.946 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:19:34.967 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:19:34.967 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:19:34.968 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:19:35.255 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:19:35.255 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:19:35.256 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:19:35.268 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:19:35.268 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:19:35.277 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:19:35.281 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:19:35.322 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:19:35.322 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:19:35.322 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:19:35.323 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:19:35.323 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:19:35.324 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:19:35.324 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:19:35.324 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:19:35.324 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:19:40.467 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@287213ac
-2019-05-20 16:19:40.471 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:19:40.471 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:19:40.568 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:19:40.568 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'XPDR-A1'
-2019-05-20 16:19:40.621 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:19:40.967 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'XPDR-A1' writed to oper datastore
-2019-05-20 16:19:40.967 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:19:40.967 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:19:40.968 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:19:41.211 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:19:41.271 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:19:41.271 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:19:41.272 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:19:41.299 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:19:41.302 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:19:41.303 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:19:41.313 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:19:41.313 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:19:41.313 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:19:41.316 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:19:41.435 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:19:41.451 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:19:41.452 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:19:41.452 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:19:41.462 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:19:41.467 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:19:41.467 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:19:41.467 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:19:41.477 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:19:41.477 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:19:41.477 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:19:41.478 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:19:41.480 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/3-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=4, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=baseXPDR, getCircuitPackType=Base_transponder_circuit_pack, getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=2, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=1, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=3, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getNodeId=NodeIdType [_value=XPDR-A1], getNodeNumber=1, getNodeType=Xpdr, getPrefixLength=24, getTemplate=template_TRANSPONDER, augmentations={}}, getShelves=[Shelves{getAdministrativeState=InService, getDueDate=DateAndTime [_value=2017-02-24T15:56:10+00:00], getRack=XYZ, getShelfName=1, getShelfPosition=1, getShelfType=pizza, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:19:41.491 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:19:41.491 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:19:41.495 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:19:41.495 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:19:41.495 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:19:41.500 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'XPDR-A1' merged to device oper datastore
-2019-05-20 16:19:41.533 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:19:41.542 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@2954dc9e
-2019-05-20 16:19:41.545 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@52b44e58
-2019-05-20 16:19:41.547 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@21ae26b0
-2019-05-20 16:19:41.555 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@300f2857
-2019-05-20 16:19:41.556 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@5f1495c6
-2019-05-20 16:19:41.557 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@4b0d7cb5
-2019-05-20 16:19:41.558 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@dda90c0
-2019-05-20 16:19:41.570 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@71993eba
-2019-05-20 16:19:41.583 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:19:41.584 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@2298e5d2
-2019-05-20 16:19:41.607 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@2596ae35
-2019-05-20 16:19:41.620 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@6a7488a0
-2019-05-20 16:19:41.627 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@b6b8632
-2019-05-20 16:19:41.634 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:19:41.634 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:19:41.635 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@4bfe0b18
-2019-05-20 16:19:41.637 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:19:41.637 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:19:41.637 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:19:41.638 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:19:41.638 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:19:41.638 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:19:41.638 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:19:41.701 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@4b74fb68
-2019-05-20 16:19:41.707 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@4f251b32
-2019-05-20 16:19:41.709 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@52565dea
-2019-05-20 16:19:41.709 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:19:41.797 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0xf039ef03]'
-2019-05-20 16:19:42.113 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:19:42.113 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@150658c8
-2019-05-20 16:19:42.115 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@5dadf7d9
-2019-05-20 16:19:42.115 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:19:42.115 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@5a1f3003
-2019-05-20 16:19:42.116 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@649b0336
-2019-05-20 16:19:42.116 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@3e666559
-2019-05-20 16:19:42.116 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@461e3e5
-2019-05-20 16:19:42.117 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@6179761f
-2019-05-20 16:19:42.117 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:19:42.117 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:19:42.118 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:19:42.118 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:19:42.118 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:19:42.118 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:19:42.118 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:19:42.208 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17841
-2019-05-20 16:22:00.815 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:22:00.816 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:22:03.327 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:22:03.626 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:22:03.654 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:22:03.664 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:22:03.667 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:22:03.670 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:22:03.670 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:22:03.672 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:22:03.673 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:22:03.675 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:22:03.695 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:22:03.697 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:22:03.707 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:22:03.710 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:22:03.835 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:22:04.025 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:22:04.055 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:22:04.610 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:22:05.378 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:22:05.400 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:22:05.401 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:22:05.401 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:22:05.705 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:22:05.705 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:22:05.706 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:22:05.717 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:22:05.718 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:22:05.727 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:22:05.730 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:22:05.771 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:22:05.771 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:22:05.771 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:22:05.772 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:22:05.773 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:22:05.773 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:22:05.773 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:22:05.773 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:22:05.773 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:22:10.983 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@380d3099
-2019-05-20 16:22:10.988 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:22:10.988 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:22:11.085 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:22:11.085 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'XPDR-A1'
-2019-05-20 16:22:11.138 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:22:11.524 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'XPDR-A1' writed to oper datastore
-2019-05-20 16:22:11.524 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:22:11.525 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:22:11.525 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:22:11.739 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:22:11.794 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:22:11.794 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:22:11.795 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:22:11.823 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:22:11.825 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:22:11.826 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:22:11.835 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:22:11.835 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:22:11.835 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:22:11.838 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:22:11.957 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:22:11.974 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:22:11.974 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:22:11.975 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:22:11.984 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:22:11.990 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:22:11.990 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:22:11.990 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:22:12.000 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:22:12.001 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:22:12.001 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:22:12.001 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:22:12.002 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/3-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=4, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=baseXPDR, getCircuitPackType=Base_transponder_circuit_pack, getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=2, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=1, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=3, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getNodeId=NodeIdType [_value=XPDR-A1], getNodeNumber=1, getNodeType=Xpdr, getPrefixLength=24, getTemplate=template_TRANSPONDER, augmentations={}}, getShelves=[Shelves{getAdministrativeState=InService, getDueDate=DateAndTime [_value=2017-02-24T15:56:10+00:00], getRack=XYZ, getShelfName=1, getShelfPosition=1, getShelfType=pizza, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:22:12.013 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:22:12.013 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:22:12.021 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'XPDR-A1' merged to device oper datastore
-2019-05-20 16:22:12.023 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:22:12.023 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:22:12.024 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:22:12.056 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:22:12.063 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@58e13c25
-2019-05-20 16:22:12.066 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@1538ca32
-2019-05-20 16:22:12.067 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@40fc9cce
-2019-05-20 16:22:12.075 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@4b0d7cb5
-2019-05-20 16:22:12.076 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@2066f0d3
-2019-05-20 16:22:12.077 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@dda90c0
-2019-05-20 16:22:12.078 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@1db078d9
-2019-05-20 16:22:12.089 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@4341ce99
-2019-05-20 16:22:12.101 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:22:12.102 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@335755a0
-2019-05-20 16:22:12.152 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@6efd96c7
-2019-05-20 16:22:12.165 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@56c9fb47
-2019-05-20 16:22:12.170 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@694396a
-2019-05-20 16:22:12.176 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:22:12.176 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:22:12.176 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@5a25183b
-2019-05-20 16:22:12.186 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:22:12.186 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:22:12.186 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:22:12.188 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:22:12.188 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:22:12.188 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:22:12.188 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:22:12.212 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@25becefb
-2019-05-20 16:22:12.216 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@2720786f
-2019-05-20 16:22:12.217 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@7c4d896a
-2019-05-20 16:22:12.217 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:22:12.293 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0xb727ebc7]'
-2019-05-20 16:22:12.585 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:22:12.586 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@2f8a57c2
-2019-05-20 16:22:12.588 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@4727acf9
-2019-05-20 16:22:12.588 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:22:12.588 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@9201335
-2019-05-20 16:22:12.589 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@684968be
-2019-05-20 16:22:12.589 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@7c49fbd8
-2019-05-20 16:22:12.589 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@7a4a91f8
-2019-05-20 16:22:12.590 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@249199a7
-2019-05-20 16:22:12.590 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:22:12.590 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:22:12.591 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:22:12.591 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:22:12.591 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:22:12.591 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:22:12.591 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:22:12.693 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17840
-2019-05-20 16:25:09.142 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:25:09.144 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:26:16.144 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:26:16.451 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:26:16.479 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:26:16.489 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:26:16.492 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:26:16.495 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:26:16.495 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:26:16.497 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:26:16.498 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:26:16.500 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:26:16.520 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:26:16.522 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:26:16.532 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:26:16.536 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:26:16.665 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:26:16.862 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:26:16.891 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:26:17.423 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:26:18.169 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:26:18.191 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:26:18.191 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:26:18.192 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:26:18.471 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:26:18.472 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:26:18.473 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:26:18.484 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:26:18.484 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:26:18.493 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:26:18.497 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:26:18.538 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:26:18.539 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:26:18.539 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:26:18.540 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:26:18.540 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:26:18.540 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:26:18.540 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:26:18.540 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:26:18.541 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:26:24.204 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@287213ac
-2019-05-20 16:26:24.208 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:26:24.208 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:26:24.298 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:26:24.299 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'XPDR-A1'
-2019-05-20 16:26:24.349 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:26:24.892 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'XPDR-A1' writed to oper datastore
-2019-05-20 16:26:24.892 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:26:24.892 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:26:24.892 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:26:25.102 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:26:25.148 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:26:25.148 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:26:25.148 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:26:25.166 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:26:25.167 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:26:25.168 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:26:25.177 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:26:25.177 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:26:25.177 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:26:25.179 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:26:25.292 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:26:25.309 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:26:25.309 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:26:25.310 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:26:25.318 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:26:25.324 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:26:25.324 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=XPDR-A1]
-2019-05-20 16:26:25.324 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:26:25.333 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:26:25.334 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:26:25.334 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-XPDRA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRA.xml
-2019-05-20 16:26:25.334 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:26:25.335 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=baseXPDR, getCircuitPackType=Base_transponder_circuit_pack, getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=2, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=4, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=1, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=3, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getNodeId=NodeIdType [_value=XPDR-A1], getNodeNumber=1, getNodeType=Xpdr, getPrefixLength=24, getTemplate=template_TRANSPONDER, augmentations={}}, getShelves=[Shelves{getAdministrativeState=InService, getDueDate=DateAndTime [_value=2017-02-24T15:56:10+00:00], getRack=XYZ, getShelfName=1, getShelfPosition=1, getShelfType=pizza, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:26:25.343 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:26:25.343 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:26:25.350 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'XPDR-A1' merged to device oper datastore
-2019-05-20 16:26:25.355 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:26:25.355 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:26:25.356 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:26:25.387 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:26:25.394 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@6354fb91
-2019-05-20 16:26:25.396 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@78493c50
-2019-05-20 16:26:25.398 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@121bacf4
-2019-05-20 16:26:25.406 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@583ae407
-2019-05-20 16:26:25.407 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@3782d1e6
-2019-05-20 16:26:25.407 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@ebfe707
-2019-05-20 16:26:25.408 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@12bbf99e
-2019-05-20 16:26:25.419 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@2b7af38e
-2019-05-20 16:26:25.431 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:26:25.431 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@18456783
-2019-05-20 16:26:25.452 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@7506e95a
-2019-05-20 16:26:25.464 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@41d72593
-2019-05-20 16:26:25.482 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@56c9fb47
-2019-05-20 16:26:25.491 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:26:25.491 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:26:25.492 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@4bddc770
-2019-05-20 16:26:25.493 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:26:25.493 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:26:25.493 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:26:25.494 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:26:25.494 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:26:25.494 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:26:25.494 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:26:25.517 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@5b0c28d0
-2019-05-20 16:26:25.522 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@354ec60
-2019-05-20 16:26:25.523 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@3c1d5349
-2019-05-20 16:26:25.523 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:26:25.582 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x21015074]'
-2019-05-20 16:26:25.875 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:26:25.876 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@7499df3a
-2019-05-20 16:26:25.877 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@1eb9713
-2019-05-20 16:26:25.877 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:26:25.877 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@3a949bec
-2019-05-20 16:26:25.878 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@3eeee97f
-2019-05-20 16:26:25.878 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@14415802
-2019-05-20 16:26:25.878 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@1c1b9f36
-2019-05-20 16:26:25.879 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@7a8a2cd8
-2019-05-20 16:26:25.879 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:26:25.879 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:26:25.880 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:26:25.880 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:26:25.880 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:26:25.880 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:26:25.880 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:26:25.962 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17840
-2019-05-20 16:27:18.230 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:27:18.232 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:28:04.541 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:28:04.851 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:28:04.880 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:28:04.892 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:28:04.895 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:28:04.897 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:28:04.897 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:28:04.899 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:28:04.901 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:28:04.902 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:28:04.923 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:28:04.926 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:28:04.936 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:28:04.939 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:28:05.071 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:28:05.266 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:28:05.296 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:28:05.832 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:28:06.617 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:28:06.639 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:28:06.640 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:28:06.640 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:28:06.923 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:28:06.924 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:28:06.925 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:28:06.935 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:28:06.936 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:28:06.945 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:28:06.949 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:28:06.991 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:28:06.992 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:28:06.992 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:28:06.993 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:28:06.994 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:28:06.994 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:28:06.994 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:28:06.994 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:28:06.994 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:28:12.118 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@380d3099
-2019-05-20 16:28:12.123 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:28:12.123 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-ROADMA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMA.xml
-2019-05-20 16:28:12.237 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=ROADM-A1]
-2019-05-20 16:28:12.238 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'ROADM-A1'
-2019-05-20 16:28:12.504 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:28:12.911 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'ROADM-A1' writed to oper datastore
-2019-05-20 16:28:12.911 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:28:12.912 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:28:12.912 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:28:13.126 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:28:13.175 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:28:13.175 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:28:13.175 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-ROADMA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMA.xml
-2019-05-20 16:28:13.200 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:28:13.201 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:28:13.203 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:28:13.212 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:28:13.212 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:28:13.212 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:28:13.215 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:28:13.360 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:28:13.387 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:28:13.387 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:28:13.388 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:28:13.400 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:28:13.410 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=ROADM-A1]
-2019-05-20 16:28:13.410 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=ROADM-A1]
-2019-05-20 16:28:13.410 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:28:13.425 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:28:13.426 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:28:13.426 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-ROADMA.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMA.xml
-2019-05-20 16:28:13.427 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:28:13.428 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=5/0, getCircuitPackProductCode=SRGRx, getCircuitPackType=ADDDROP, getEquipmentState=ReservedForFacilityAvailable, getPorts=[Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG3-CP-TXRX, getPortName=AD-DEG1, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG3-PP1, getPortName=C1, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG3-PP2, getPortName=C2, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG3-CP-TXRX, getPortName=AD-DEG2, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG3-PP3, getPortName=C3, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG3-PP4, getPortName=C4, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=2, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=2/0, getCircuitPackProductCode=DegE, getCircuitPackType=WSSDEG, getEquipmentState=NotReservedAvailable, getPorts=[Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG2-TTP-TXRX, getPortName=L1, getPortQual=RoadmExternal, getPortType=LINE, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG2-CTP-TXRX, getPortName=C1, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG2-CTP-TXRX, getPortName=C2, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG2-CTP-TXRX, getPortName=C3, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG2-CTP-TXRX, getPortName=C4, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=DegW, getCircuitPackType=WSSDEG, getEquipmentState=NotReservedAvailable, getPorts=[Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-TTP-TXRX, getPortName=L1, getPortQual=RoadmExternal, getPortType=LINE, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C1, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C2, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C3, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C4, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=NORMAL, getCircuitPackName=2/0/OSC-PLUG, getCircuitPackType=port, getEquipmentState=NotReservedAvailable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=2/0, getCpSlotName=OSC-SLOT, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=OSC-PORT, getPortQual=RoadmInternal, getPortType=1GEX, augmentations={}}], getShelf=2, getSlot=0, getSubSlot=0, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=3/0, getCircuitPackProductCode=SRGRx, getCircuitPackType=ADDDROP, getEquipmentState=ReservedForFacilityAvailable, getPorts=[Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG1-CP-TXRX, getPortName=AD-DEG1, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP1, getPortName=C1, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP2, getPortName=C2, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG1-CP-TXRX, getPortName=AD-DEG2, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP3, getPortName=C3, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP4, getPortName=C4, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=2, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=2/0/ETH-PLUG, getCircuitPackProductCode=Eth, getCircuitPackType=port, getEquipmentState=NotReservedAvailable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=2/0, getCpSlotName=ETH-PLUG, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=ETH-PLUG, getPortQual=RoadmInternal, getPortType=1GEX, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=0, augmentations={}}], getDegree=[Degree{getCircuitPacks=[CircuitPacks{getCircuitPackName=2/0/ETH-PLUG, getIndex=1, augmentations={}}], getConnectionPorts=[ConnectionPorts{getCircuitPackName=2/0, getIndex=1, getPortName=L1, augmentations={}}], getDegreeNumber=2, augmentations={}}, Degree{getCircuitPacks=[CircuitPacks{getCircuitPackName=1/0/ETH-PLUG, getIndex=1, augmentations={}}], getConnectionPorts=[ConnectionPorts{getCircuitPackName=1/0, getIndex=1, getPortName=L1, augmentations={}}], getDegreeNumber=1, augmentations={}}], getInfo=Info{getClli=NodeA, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=127.0.0.20]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=127.0.0.11]], getNodeId=NodeIdType [_value=ROADM-A1], getNodeNumber=2, getNodeType=Rdm, getPrefixLength=28, getTemplate=template_1, augmentations={}}, getInterface=[Interface{getAdministrativeState=InService, getName=1GE-interface-2, getSupportingCircuitPackName=2/0/ETH-PLUG, getSupportingPort=ETH-PLUG, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.EthernetCsmacd, augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.ethernet.interfaces.rev181019.Interface1=Interface1{getEthernet=Ethernet{getAutoNegotiation=Enabled, getDuplex=Full, getMtu=1800, getSpeed=1000, augmentations={}}}}}, Interface{getAdministrativeState=InService, getName=OTS-DEG2-TTP-TXRX, getSupportingCircuitPackName=2/0, getSupportingPort=L1, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.OpticalTransport, augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.optical.transport.interfaces.rev181019.Interface1=Interface1{getOts=Ots{getFiberType=Smf, getIngressSpanLossAgingMargin=RatioDB [_value=0, _UNITS=dB], getSpanLossReceive=RatioDB [_value=15, _UNITS=dB], getSpanLossTransmit=RatioDB [_value=6, _UNITS=dB], augmentations={}}}}}, Interface{getAdministrativeState=InService, getCircuitId=TBD, getDescription=TBD, getName=OMS-DEG2-TTP-TXRX, getSupportingCircuitPackName=2/0, getSupportingInterface=OTS-DEG2-TTP-TXRX, getSupportingPort=L1, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.OpenROADMOpticalMultiplex, augmentations={}}], getProtocols=Protocols{augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.lldp.rev181019.Protocols1=Protocols1{getLldp=Lldp{getGlobalConfig=GlobalConfig{getAdminStatus=Enable, getMsgTxHoldMultiplier=4, getMsgTxInterval=20, augmentations={}}, getPortConfig=[PortConfig{getAdminStatus=Txandrx, getIfName=1GE-interface-2, augmentations={}}], augmentations={}}}}}, getSharedRiskGroup=[SharedRiskGroup{getCircuitPacks=[CircuitPacks{getCircuitPackName=3/0, getIndex=1, augmentations={}}], getSrgNumber=1, augmentations={}}, SharedRiskGroup{getCircuitPacks=[CircuitPacks{getCircuitPackName=5/0, getIndex=1, augmentations={}}], getSrgNumber=3, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:28:13.441 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:28:13.441 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:28:13.451 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:28:13.452 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:28:13.452 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:28:13.456 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'ROADM-A1' merged to device oper datastore
-2019-05-20 16:28:13.487 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:28:13.494 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@3fe900b0
-2019-05-20 16:28:13.497 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@44e0a1ce
-2019-05-20 16:28:13.498 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@5058da5a
-2019-05-20 16:28:13.506 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@172d9bb2
-2019-05-20 16:28:13.507 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@485ca712
-2019-05-20 16:28:13.508 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@23277696
-2019-05-20 16:28:13.509 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@3f7fbfba
-2019-05-20 16:28:13.520 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@2596ae35
-2019-05-20 16:28:13.533 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:28:13.533 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@255a1fdb
-2019-05-20 16:28:13.553 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@19f7107e
-2019-05-20 16:28:13.565 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@336e1bcf
-2019-05-20 16:28:13.569 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@4054397
-2019-05-20 16:28:13.575 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:28:13.575 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:28:13.575 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@26d6399e
-2019-05-20 16:28:13.577 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:28:13.577 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:28:13.577 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:28:13.578 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:28:13.578 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:28:13.578 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:28:13.578 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:28:13.605 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@f2d9eb
-2019-05-20 16:28:13.609 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@70b95f05
-2019-05-20 16:28:13.610 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@1ad13538
-2019-05-20 16:28:13.610 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:28:13.671 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x6f6e448c]'
-2019-05-20 16:28:13.949 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:28:13.949 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@720574a4
-2019-05-20 16:28:13.951 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@2348e36
-2019-05-20 16:28:13.951 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:28:13.951 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@5c6d1a38
-2019-05-20 16:28:13.951 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@27c5fba0
-2019-05-20 16:28:13.952 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@de3b06a
-2019-05-20 16:28:13.952 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@205d8cfa
-2019-05-20 16:28:13.952 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@698ba673
-2019-05-20 16:28:13.953 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:28:13.953 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:28:13.954 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:28:13.954 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:28:13.954 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:28:13.954 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:28:13.954 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:28:14.046 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17841
-2019-05-20 16:33:25.548 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:33:25.854 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:33:25.883 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:33:25.893 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:33:25.896 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:33:25.899 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:33:25.899 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:33:25.901 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:33:25.902 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:33:25.904 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:33:25.924 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:33:25.927 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:33:25.937 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:33:25.940 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:33:26.070 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:33:26.263 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:33:26.292 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:33:26.834 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:33:27.595 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:33:27.617 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:33:27.617 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:33:27.618 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:33:27.896 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:33:27.897 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:33:27.898 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:33:27.909 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:33:27.909 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:33:27.918 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:33:27.922 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:33:27.962 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:33:27.963 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:33:27.963 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:33:27.964 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:33:27.964 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:33:27.965 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:33:27.965 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:33:27.965 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:33:27.965 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:33:33.544 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@380d3099
-2019-05-20 16:33:33.548 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:33:33.548 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-ROADMC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMC.xml
-2019-05-20 16:33:33.652 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=ROADM-C1]
-2019-05-20 16:33:33.652 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'ROADM-C1'
-2019-05-20 16:33:33.704 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:33:34.301 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'ROADM-C1' writed to oper datastore
-2019-05-20 16:33:34.301 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:33:34.301 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:33:34.301 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:33:34.509 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:33:34.555 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:33:34.556 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:33:34.556 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-ROADMC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMC.xml
-2019-05-20 16:33:34.577 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:33:34.579 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:33:34.580 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:33:34.588 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:33:34.588 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:33:34.588 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:33:34.591 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:33:34.707 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:33:34.729 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:33:34.729 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:33:34.730 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:33:34.739 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:33:34.747 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=ROADM-C1]
-2019-05-20 16:33:34.747 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=ROADM-C1]
-2019-05-20 16:33:34.747 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:33:34.759 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:33:34.760 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:33:34.760 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-ROADMC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-ROADMC.xml
-2019-05-20 16:33:34.760 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:33:34.762 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=NORMAL, getCircuitPackName=1/0/OSC-PLUG, getCircuitPackType=port, getEquipmentState=NotReservedAvailable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=OSC-SLOT, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=OSC-PORT, getPortQual=RoadmInternal, getPortType=1GEX, augmentations={}}], getShelf=2, getSlot=0, getSubSlot=0, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0/ETH-PLUG, getCircuitPackProductCode=Eth, getCircuitPackType=port, getEquipmentState=NotReservedAvailable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=ETH-PLUG, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=ETH-PLUG, getPortQual=RoadmInternal, getPortType=1GEX, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=0, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=DegW, getCircuitPackType=WSSDEG, getEquipmentState=NotReservedAvailable, getPorts=[Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-TTP-TXRX, getPortName=L1, getPortQual=RoadmExternal, getPortType=LINE, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C1, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C2, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C3, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=1, getLogicalConnectionPoint=DEG1-CTP-TXRX, getPortName=C4, getPortQual=RoadmInternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=3/0, getCircuitPackProductCode=SRGRx, getCircuitPackType=ADDDROP, getEquipmentState=ReservedForFacilityAvailable, getPorts=[Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG1-CP-TXRX, getPortName=AD-DEG1, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP1, getPortName=C1, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP2, getPortName=C2, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getLogicalConnectionPoint=SRG1-CP-TXRX, getPortName=AD-DEG2, getPortQual=RoadmInternal, getPortType=Edge, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP3, getPortName=C3, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}, Ports{getAdministrativeState=InService, getCircuitId=SRG1, getLogicalConnectionPoint=SRG1-PP4, getPortName=C4, getPortQual=RoadmExternal, getPortType=Client, augmentations={}}], getShelf=1, getSlot=2, augmentations={}}], getDegree=[Degree{getCircuitPacks=[CircuitPacks{getCircuitPackName=1/0/ETH-PLUG, getIndex=1, augmentations={}}], getConnectionPorts=[ConnectionPorts{getCircuitPackName=1/0, getIndex=1, getPortName=L1, augmentations={}}], getDegreeNumber=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=127.0.0.20]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=127.0.0.11]], getNodeId=NodeIdType [_value=ROADM-C1], getNodeNumber=4, getNodeType=Rdm, getPrefixLength=28, getTemplate=template_1, augmentations={}}, getInterface=[Interface{getAdministrativeState=InService, getCircuitId=TBD, getDescription=TBD, getName=OMS-DEG1-TTP-TXRX, getSupportingCircuitPackName=1/0, getSupportingInterface=OTS-DEG1-TTP-TXRX, getSupportingPort=L1, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.OpenROADMOpticalMultiplex, augmentations={}}, Interface{getAdministrativeState=InService, getName=1GE-interface-1, getSupportingCircuitPackName=1/0/ETH-PLUG, getSupportingPort=ETH-PLUG, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.EthernetCsmacd, augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.ethernet.interfaces.rev181019.Interface1=Interface1{getEthernet=Ethernet{getAutoNegotiation=Enabled, getDuplex=Full, getMtu=1800, getSpeed=1000, augmentations={}}}}}, Interface{getAdministrativeState=InService, getName=OTS-DEG1-TTP-TXRX, getSupportingCircuitPackName=1/0, getSupportingPort=L1, getType=class org.opendaylight.yang.gen.v1.http.org.openroadm.interfaces.rev170626.OpticalTransport, augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.optical.transport.interfaces.rev181019.Interface1=Interface1{getOts=Ots{getFiberType=Smf, getIngressSpanLossAgingMargin=RatioDB [_value=0, _UNITS=dB], getSpanLossReceive=RatioDB [_value=15, _UNITS=dB], getSpanLossTransmit=RatioDB [_value=6, _UNITS=dB], augmentations={}}}}}], getProtocols=Protocols{augmentations={interface org.opendaylight.yang.gen.v1.http.org.openroadm.lldp.rev181019.Protocols1=Protocols1{getLldp=Lldp{getGlobalConfig=GlobalConfig{getAdminStatus=Enable, getMsgTxHoldMultiplier=4, getMsgTxInterval=20, augmentations={}}, getPortConfig=[PortConfig{getAdminStatus=Txandrx, getIfName=1GE-interface-1, augmentations={}}], augmentations={}}}}}, getSharedRiskGroup=[SharedRiskGroup{getCircuitPacks=[CircuitPacks{getCircuitPackName=3/0, getIndex=1, augmentations={}}], getSrgNumber=1, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:33:34.773 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:33:34.774 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:33:34.784 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:33:34.784 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:33:34.785 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:33:34.789 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'ROADM-C1' merged to device oper datastore
-2019-05-20 16:33:34.816 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:33:34.822 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@474e0ab4
-2019-05-20 16:33:34.824 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@600c5acf
-2019-05-20 16:33:34.826 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@56677422
-2019-05-20 16:33:34.833 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@501c3ef6
-2019-05-20 16:33:34.834 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@40ba320f
-2019-05-20 16:33:34.835 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@6981107f
-2019-05-20 16:33:34.836 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@77f3e77a
-2019-05-20 16:33:34.863 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@34b1bc0f
-2019-05-20 16:33:34.876 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:33:34.876 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@49b257f9
-2019-05-20 16:33:34.895 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@21622e1
-2019-05-20 16:33:34.907 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@58ef7429
-2019-05-20 16:33:34.911 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@541c9054
-2019-05-20 16:33:34.918 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:33:34.918 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:33:34.918 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@34f8cee0
-2019-05-20 16:33:34.920 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:33:34.920 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:33:34.920 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:33:34.921 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:33:34.921 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:33:34.921 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:33:34.921 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:33:34.948 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@5555eb81
-2019-05-20 16:33:34.954 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@169d11c5
-2019-05-20 16:33:34.955 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@1894b733
-2019-05-20 16:33:34.955 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:33:35.020 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x42478495]'
-2019-05-20 16:33:35.290 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:33:35.291 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@59d768de
-2019-05-20 16:33:35.292 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@1bac51bd
-2019-05-20 16:33:35.292 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:33:35.293 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@6150e1c5
-2019-05-20 16:33:35.293 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@7ab8af34
-2019-05-20 16:33:35.293 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@2fdd5baa
-2019-05-20 16:33:35.294 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@698ba673
-2019-05-20 16:33:35.294 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@7d5b1786
-2019-05-20 16:33:35.294 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:33:35.294 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:33:35.295 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:33:35.295 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:33:35.295 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:33:35.295 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:33:35.295 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:33:35.375 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17843
-2019-05-20 16:34:57.288 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Starting honeycomb
-2019-05-20 16:34:57.594 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:34:57.622 CEST [main] INFO  i.f.h.t.device.DeviceModule - Initializing Device Module
-2019-05-20 16:34:57.633 CEST [main] INFO  i.f.h.t.d.c.DeviceConfigurationModule - Initializing Device Readers Module
-2019-05-20 16:34:57.636 CEST [main] INFO  i.f.h.t.d.c.PmConfigurationModule - Initializing Pm Readers Module
-2019-05-20 16:34:57.638 CEST [main] INFO  i.f.h.t.device.DeviceModule - Device Module intitailized !
-2019-05-20 16:34:57.638 CEST [main] INFO  i.f.h.t.d.c.NetconfConfigurationModule - Initializing Netconf Readers Module
-2019-05-20 16:34:57.640 CEST [main] INFO  i.f.h.t.device.DeviceModule - Netconf Module intitailized !
-2019-05-20 16:34:57.642 CEST [main] INFO  i.f.h.i.d.s.YangBindingProviderModule - Configuring YangBindingProviderModule
-2019-05-20 16:34:57.643 CEST [main] INFO  i.f.h.t.device.DeviceRpcModule - Initializing Device Rpcs Module
-2019-05-20 16:34:57.664 CEST [main] INFO  i.f.h.t.d.DeviceNotificationModule - Initializing Device Notification Module
-2019-05-20 16:34:57.667 CEST [main] INFO  i.f.h.t.device.DeviceWriterModule - Initializing Device Writers Module
-2019-05-20 16:34:57.677 CEST [main] INFO  i.f.h.n.n.NetconfReadersModule - Initializing NETCONF Northbound readers
-2019-05-20 16:34:57.680 CEST [main] INFO  i.f.h.n.netconf.NetconfModule - Starting NETCONF Northbound
-2019-05-20 16:34:57.809 CEST [main] INFO  i.f.h.t.device.DeviceReaderModule - Initializing Device, PM and Netconf Readers Module
-2019-05-20 16:34:58.002 CEST [main] INFO  i.f.h.i.d.a.ActiveModuleProvider - Reading active modules configuration for distribution
-2019-05-20 16:34:58.031 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: ModuleInfoBackedCtxProvider{writerFactories=io.fd.honeycomb.infra.distro.schema.YangModulesProvider$YangModules@447a020}
-2019-05-20 16:34:58.565 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SchemaServiceProvider@4c36250e
-2019-05-20 16:34:59.273 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ContextDataTreeProvider@6cc558c6
-2019-05-20 16:34:59.294 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ContextPersistingDataTreeProvider@63f259c3
-2019-05-20 16:34:59.295 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.ModifiableDTMgrProvider@3db972d2
-2019-05-20 16:34:59.295 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.schema.SerializerProvider@158f4cfe
-2019-05-20 16:34:59.575 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataTreeProvider$ConfigDataTreeProvider@77774571
-2019-05-20 16:34:59.575 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistingDataTreeProvider$ConfigPersistingDataTreeProvider@6cd64ee8
-2019-05-20 16:34:59.576 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@62010f5c
-2019-05-20 16:34:59.588 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@132e0cc
-2019-05-20 16:34:59.588 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@75de29c0
-2019-05-20 16:34:59.597 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.transportpce.device.DeviceBindingDataBrokerProvider@1e253c9d
-2019-05-20 16:34:59.600 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.config.WriterRegistryProvider@20b9d5d5
-2019-05-20 16:34:59.641 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.HoneycombContextDOMDataBrokerProvider@26ceffa8
-2019-05-20 16:34:59.642 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@67064bdc
-2019-05-20 16:34:59.642 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.ModifiableDTDelegProvider@18578491
-2019-05-20 16:34:59.643 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@7c8c9a05
-2019-05-20 16:34:59.643 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DataStoreProvider@5c2375a9
-2019-05-20 16:34:59.643 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.InmemoryDOMDataBrokerProvider@5ad5be4a
-2019-05-20 16:34:59.643 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@737d100a
-2019-05-20 16:34:59.644 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringReaderFactoryProvider@6535117e
-2019-05-20 16:34:59.644 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationsReaderFactoryProvider@f88bfbe
-2019-05-20 16:35:04.847 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReaderRegistryProvider@380d3099
-2019-05-20 16:35:04.851 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting xml file data to oper datastore
-2019-05-20 16:35:04.851 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - file 'oper-XPDRC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRC.xml
-2019-05-20 16:35:04.951 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-C1]
-2019-05-20 16:35:04.951 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Getting device info from xml file for device 'XPDR-C1'
-2019-05-20 16:35:05.009 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:35:05.574 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device 'XPDR-C1' writed to oper datastore
-2019-05-20 16:35:05.574 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf state to oper datastore
-2019-05-20 16:35:05.574 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Loading models from directory.
-2019-05-20 16:35:05.575 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - folder '/home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/yang' exists !
-2019-05-20 16:35:05.788 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to oper datastore
-2019-05-20 16:35:05.833 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf state writed to oper datastore
-2019-05-20 16:35:05.833 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - writting netconf stream to oper datastore
-2019-05-20 16:35:05.833 CEST [main] INFO  i.f.h.t.d.t.DefaultNetconfFactory - file 'oper-XPDRC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRC.xml
-2019-05-20 16:35:05.851 CEST [main] INFO  i.f.h.t.d.c.NetconfConfiguration - netconf streams result : Streams{getStream=[Stream{getName=StreamNameType [_value=OPENROADM], augmentations={}}], augmentations={}}
-2019-05-20 16:35:05.853 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - Netconf Data gets from xml file is present
-2019-05-20 16:35:05.854 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok
-2019-05-20 16:35:05.862 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - netconf stream writed to oper datastore
-2019-05-20 16:35:05.862 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - loading device configuration info from xml file...
-2019-05-20 16:35:05.862 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file 
-2019-05-20 16:35:05.865 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml data to config device ...
-2019-05-20 16:35:05.975 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - removing namespace ...
-2019-05-20 16:35:05.992 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device info gets from xml file !
-2019-05-20 16:35:05.992 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - process to transform xml file to config data
-2019-05-20 16:35:05.993 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - transforming xml string to config device ...
-2019-05-20 16:35:06.001 CEST [main] INFO  i.f.h.t.d.t.DefaultDeviceFactory - device data config string is ok 
-2019-05-20 16:35:06.007 CEST [main] INFO  i.f.h.t.d.c.DeviceConfiguration - result info : NodeIdType [_value=XPDR-C1]
-2019-05-20 16:35:06.007 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - OrgOpenroadmDevice info gets : NodeIdType [_value=XPDR-C1]
-2019-05-20 16:35:06.007 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - WriteTransaction is ok, copy device info to config datastore
-2019-05-20 16:35:06.017 CEST [main] INFO  i.f.h.t.d.read.DeviceReaderFactory - device writed to config datastore
-2019-05-20 16:35:06.017 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - writting xml pm file data to oper datastore
-2019-05-20 16:35:06.017 CEST [main] INFO  i.f.h.t.d.t.DefaultPmListFactory - file with pm 'oper-XPDRC.xml' exists at location : /home/gilles/git/01/transportpce/tests/honeynode221/honeynode-distribution/target/honeynode-distribution-1.18.01-hc/honeynode-distribution-1.18.01/config/device/oper-XPDRC.xml
-2019-05-20 16:35:06.017 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - onDataTreeChanged
-2019-05-20 16:35:06.018 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - Received Device change(WRITE):
- before=null 
- after=OrgOpenroadmDevice{getCircuitPacks=[CircuitPacks{getAdministrativeState=InService, getCircuitPackMode=inServiceMode, getCircuitPackName=1/0, getCircuitPackProductCode=baseXPDR, getCircuitPackType=Base_transponder_circuit_pack, getShelf=1, getSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=2, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-CLIENT, getCircuitPackProductCode=Client_P, getCircuitPackType=client_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=4, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=C1, getPortQual=XpdrClient, getPortType=QSFP28, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/1-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=1, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}, CircuitPacks{getCircuitPackMode=NORMAL, getCircuitPackName=1/0/2-PLUG-NET, getCircuitPackProductCode=Line_NW_P, getCircuitPackType=line_pluggable, getParentCircuitPack=ParentCircuitPack{getCircuitPackName=1/0, getCpSlotName=3, augmentations={}}, getPorts=[Ports{getAdministrativeState=InService, getPortName=1, getPortQual=XpdrNetwork, getPortType=CFP2, augmentations={}}], getShelf=1, getSlot=1, getSubSlot=1, augmentations={}}], getInfo=Info{getClli=NodeC, getDefaultGateway=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getGeoLocation=GeoLocation{getLatitude=1.0000, getLongitude=2.0000, augmentations={}}, getIpAddress=IpAddress [_ipv4Address=Ipv4Address [_value=1.2.3.4]], getNodeId=NodeIdType [_value=XPDR-C1], getNodeNumber=1, getNodeType=Xpdr, getPrefixLength=24, getTemplate=template_TRANSPONDER, augmentations={}}, getShelves=[Shelves{getAdministrativeState=InService, getDueDate=DateAndTime [_value=2017-02-24T15:56:10+00:00], getRack=XYZ, getShelfName=1, getShelfPosition=1, getShelfType=pizza, augmentations={}}], getUsers=Users{getUser=[User{getGroup=Sudo, getName=UsernameType [_value=openroadm], getPassword=PasswordType [_value=openroadm], augmentations={}}], augmentations={}}, augmentations={}}
-2019-05-20 16:35:06.026 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - processing change ...
-2019-05-20 16:35:06.026 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - WriteTransactions are ok, merge device info to datastores
-2019-05-20 16:35:06.033 CEST [config-DCL-0] INFO  i.f.h.t.d.w.DeviceChangeListener - device 'XPDR-C1' merged to device oper datastore
-2019-05-20 16:35:06.034 CEST [main] INFO  i.f.h.t.d.c.PmConfiguration - result pm list size : 1
-2019-05-20 16:35:06.034 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - Getting pm info from xml file for device 
-2019-05-20 16:35:06.035 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - WriteTransaction is ok, copy currentPmList to oper datastore
-2019-05-20 16:35:06.067 CEST [main] INFO  i.f.h.t.device.read.PmReaderFactory - currentPmList writed to oper datastore
-2019-05-20 16:35:06.074 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.oper.ReadableDTDelegProvider@571ae6d5
-2019-05-20 16:35:06.076 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfBindingBrokerProvider@59ef35de
-2019-05-20 16:35:06.078 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfNotificationMapperProvider@2eaafa39
-2019-05-20 16:35:06.087 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryBuilderProvider@48e48923
-2019-05-20 16:35:06.087 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.RpcRegistryProvider@6c1f6481
-2019-05-20 16:35:06.088 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMRpcServiceProvider@2a428e3b
-2019-05-20 16:35:06.089 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMdsalMapperProvider@4473d830
-2019-05-20 16:35:06.100 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringServiceProvider@73e15280
-2019-05-20 16:35:06.131 CEST [main] INFO  o.o.n.i.o.NetconfSessionMonitoringService - Scheduling thread pool is present = false, update interval 0: /netconf-state/sessions won't be updated.
-2019-05-20 16:35:06.132 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfMonitoringMapperProvider@47a20fab
-2019-05-20 16:35:06.152 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.DOMNotificationServiceProvider@719edb69
-2019-05-20 16:35:06.166 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombNotificationManagerProvider@71e21f48
-2019-05-20 16:35:06.170 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.HoneycombNotification2NetconfProvider@18f557ce
-2019-05-20 16:35:06.176 CEST [main] INFO  i.f.h.n.n.HoneycombNotification2NetconfProvider - Exposing HONEYCOMB_NETCONF notification stream: honeycomb
-2019-05-20 16:35:06.176 CEST [pool-18-thread-3] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Starting notification stream for OrgOpenroadmDevice
-2019-05-20 16:35:06.176 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NettyThreadGroupProvider@53600a30
-2019-05-20 16:35:06.178 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - onDataTreeChanged
-2019-05-20 16:35:06.178 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - transforming changes to notification...
-2019-05-20 16:35:06.178 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Received Device change :
-WRITE
-2019-05-20 16:35:06.179 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - device operational datastore is created !
-2019-05-20 16:35:06.179 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - edit List is empty !
-2019-05-20 16:35:06.179 CEST [operational-DCL-0] INFO  i.f.h.t.d.n.DeviceNotificationProducer - Emitting notification : null
-2019-05-20 16:35:06.179 CEST [operational-DCL-0] WARN  i.f.h.t.d.n.DeviceNotificationProducer - Failed to emit notification
-2019-05-20 16:35:06.203 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfServerDispatcherProvider@3fd762bb
-2019-05-20 16:35:06.207 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfTcpServerProvider@2b6cd876
-2019-05-20 16:35:06.208 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.northbound.netconf.NetconfSshServerProvider@70207dcb
-2019-05-20 16:35:06.208 CEST [main] INFO  i.f.h.n.n.NetconfSshServerProvider - Starting NETCONF SSH
-2019-05-20 16:35:06.269 CEST [main] WARN  io.netty.bootstrap.ServerBootstrap - Unknown channel option 'SO_BACKLOG' for channel '[id: 0x85cde949]'
-2019-05-20 16:35:06.542 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Initializing configuration
-2019-05-20 16:35:06.543 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.PersistedContextInitializerProvider@1eb9713
-2019-05-20 16:35:06.544 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.PersistedConfigInitializerProvider@514753f4
-2019-05-20 16:35:06.544 CEST [main] INFO  i.f.h.i.d.d.PersistedConfigInitializerProvider - RestoringInitializer ...
-2019-05-20 16:35:06.544 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.ModifiableDTDelegInitProvider@2f8a57c2
-2019-05-20 16:35:06.545 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.HoneycombDOMDataBrokerProvider@61d2ff30
-2019-05-20 16:35:06.545 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.BindingDataBrokerProvider@6c60a141
-2019-05-20 16:35:06.545 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.data.context.RealtimeMappingContextProvider@78626f16
-2019-05-20 16:35:06.546 CEST [main] INFO  i.f.h.binding.init.ProviderTrait - Providing: io.fd.honeycomb.infra.distro.initializer.InitializerRegistryAdapterProvider@3e666559
-2019-05-20 16:35:06.546 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Config initialization started
-2019-05-20 16:35:06.546 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted context restored successfully
-2019-05-20 16:35:06.547 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Configuration initialized successfully
-2019-05-20 16:35:06.547 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Persisted configuration restored successfully
-2019-05-20 16:35:06.547 CEST [main] INFO  i.f.h.i.d.i.InitializerRegistryAdapter - Honeycomb initialized
-2019-05-20 16:35:06.547 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Configuration initialized successfully
-2019-05-20 16:35:06.547 CEST [main] INFO  io.fd.honeycomb.infra.distro.Main - Honeycomb started successfully!
-2019-05-20 16:35:06.629 CEST [netconf-netty-0] INFO  i.f.h.n.n.NetconfSshServerProvider - Netconf SSH endpoint started successfully at /0.0.0.0:17844
-2019-05-20 16:39:00.502 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:39:00.503 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:39:03.270 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:39:03.271 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:39:04.292 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:39:04.293 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory
-2019-05-20 16:39:05.317 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component notification-to-mdsal-writer
-2019-05-20 16:39:05.319 CEST [Thread-5] INFO  i.f.h.impl.ShutdownHandlerImpl - Closing component netconf-notification-service-factory