search

souliss / bindingopenhab2

Souliss binding developed for openHAB 2
3 stars 3 forks source link

Socket error on startup but communications ok #6

Open FulvioSpelta opened 5 years ago

FulvioSpelta commented 5 years ago

During startup sequence you can find "socket error" msg in the log but in the next lines you can see a "ping ok" communications:

[2019-04-14 17:31:31.358 [INFO ] [al.discovery.SoulissGatewayDiscovery] - Starting org.openhab.binding.souliss - Version: 2019 03 12 21:31:01
2019-04-14 17:31:31.361 [INFO ] [al.discovery.SoulissGatewayDiscovery] - Starting Servers
2019-04-14 17:31:31.364 [DEBUG] [nternal.SoulissDatagramSocketFactory] - Setup socket
2019-04-14 17:31:31.367 [DEBUG] [nternal.SoulissDatagramSocketFactory] - Datagram Socket Created on port 56994
2019-04-14 17:31:31.370 [DEBUG] [al.discovery.SoulissGatewayDiscovery] - Starting UDP server on Preferred Local Port (random if it is zero)
2019-04-14 17:31:31.372 [INFO ] [.protocol.SoulissBindingUDPServerJob] - Starting UDP Server Job - Server on port 56994
2019-04-14 17:31:31.387 [DEBUG] [org.openhab.binding.souliss         ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.discovery.DiscoveryService}={service.id=351, service.bundleid=198, service.scope=bundle, component.name=org.openhab.binding.souliss.internal.discovery.SoulissGatewayDiscovery, component.id=214} - org.openhab.binding.souliss
2019-04-14 17:31:31.417 [DEBUG] [org.openhab.binding.souliss         ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={service.id=352, service.bundleid=198, service.scope=bundle, component.name=binding.souliss, component.id=215} - org.openhab.binding.souliss
2019-04-14 17:31:31.431 [DEBUG] [org.openhab.binding.souliss         ] - BundleEvent STARTED - org.openhab.binding.souliss
2019-04-14 17:31:31.543 [DEBUG] [al.discovery.SoulissGatewayDiscovery] - Create handler for Gateway 'souliss:gateway'
2019-04-14 17:31:31.559 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - initializing server handler for thing org.eclipse.smarthome.core.thing.internal.BridgeImpl@33721a01
2019-04-14 17:31:31.562 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get Preferred Local Port: 0
2019-04-14 17:31:31.563 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get Souliss Gateway Port: 230
2019-04-14 17:31:31.565 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get User Index: 72
2019-04-14 17:31:31.567 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get Node Index: 38
2019-04-14 17:31:31.568 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get Node Index: 38
2019-04-14 17:31:31.571 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get ping refresh interval: 30
2019-04-14 17:31:31.573 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get ping refresh interval: 30
2019-04-14 17:31:31.575 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Get health refresh interval: 60
2019-04-14 17:31:31.577 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Starting UDP server on Souliss Default Port for Topics (Publish&Subcribe)
2019-04-14 17:31:31.579 [DEBUG] [nternal.SoulissDatagramSocketFactory] - Setup socket
2019-04-14 17:31:31.582 [ERROR] [nternal.SoulissDatagramSocketFactory] - Error on creation of Socket
2019-04-14 17:31:31.585 [ERROR] [nternal.SoulissDatagramSocketFactory] - Address already in use (Bind failed)
2019-04-14 17:31:31.597 [DEBUG] [ernal.protocol.SoulissCommonCommands] - sendSUBSCRIPTIONframe - HEX: [21 00 00 00 06 ], IP: 192.168.0.189 - port: 56994
2019-04-14 17:31:31.600 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Sent subscription packet
2019-04-14 17:31:31.629 [DEBUG] [.protocol.SoulissBindingUDPServerJob] - Packet received (port 56994) HEX: [24 23 17 26 48 BD 00 31 00 00 00 18 5F A8 1F E0 00 00 00 00 00 23 00 00 00 FE 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ]
2019-04-14 17:31:31.631 [DEBUG] [al.protocol.SoulissBindingUDPDecoder] - Received functional code: 0x31 - Read state answer
2019-04-14 17:31:33.588 [DEBUG] [ouliss.handler.SoulissGatewayJobPing] - Sending ping packet
2019-04-14 17:31:33.591 [DEBUG] [ernal.protocol.SoulissCommonCommands] - sendPing - HEX: [08 00 00 00 00 ], IP: 192.168.0.189 to port 56994
2019-04-14 17:31:33.594 [DEBUG] [ouliss.handler.SoulissGatewayJobPing] - Sent ping packet
2019-04-14 17:31:33.648 [DEBUG] [.protocol.SoulissBindingUDPServerJob] - Packet received (port 56994) HEX: [0C 0B 17 26 48 BD 00 18 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ]
**2019-04-14 17:31:33.652 [DEBUG] [al.protocol.SoulissBindingUDPDecoder] - Received functional code: 0x18 - Ping answer**
2019-04-14 17:31:33.654 [DEBUG] [al.protocol.SoulissBindingUDPDecoder] - decodePing: putIn code: 0, 0
2019-04-14 17:31:33.657 [DEBUG] [ouliss.handler.SoulissGatewayHandler] - Setting Gateway ONLINE]