2019-04-15 12:05:37.084 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkSocketModel2Handler of thing broadlink:sp2:34-ea-34-bd-47-9b tried updating channel powerOn although the handler was already disposed.

2019-04-15 12:05:37.598 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel temperature although the handler was already disposed.

2019-04-15 12:05:37.602 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel humidity although the handler was already disposed.

2019-04-15 12:05:37.607 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel light although the handler was already disposed.

2019-04-15 12:05:37.611 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel air although the handler was already disposed.

2019-04-15 12:05:37.614 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel noise although the handler was already disposed.

2019-04-15 12:05:42.871 [ERROR] [nk.handler.BroadlinkBaseThingHandler] - IO error for device 'broadlink:sp2:b4-43-0d-ee-d6-04' during UDP command sending: Socket is closed

2019-04-15 12:05:42.872 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkStripModel1Handler of thing broadlink:mp1:34-ea-34-c9-9d-33 tried updating channel s1powerOn although the handler was already disposed.

2019-04-15 12:05:42.878 [ERROR] [nk.handler.BroadlinkBaseThingHandler] - IO error: Broadlink Device: Socket is closed

2019-04-15 12:05:42.878 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkStripModel1Handler of thing broadlink:mp1:34-ea-34-c9-9d-33 tried updating channel s2powerOn although the handler was already disposed.

2019-04-15 12:05:42.882 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkStripModel1Handler of thing broadlink:mp1:34-ea-34-c9-9d-33 tried updating channel s3powerOn although the handler was already disposed.

2019-04-15 12:05:42.882 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkSocketModel2Handler tried updating the thing status although the handler was already disposed.

2019-04-15 12:05:42.886 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkStripModel1Handler of thing broadlink:mp1:34-ea-34-c9-9d-33 tried updating channel s4powerOn although the handler was already disposed.

2019-04-15 12:05:42.998 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkSocketModel2Handler of thing broadlink:sp2:34-ea-34-f5-ae-aa tried updating channel powerOn although the handler was already disposed.

2019-04-15 12:05:44.917 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkRemoteModel2Handler of thing broadlink:rm2:34-ea-34-c7-c9-15 tried updating channel temperature although the handler was already disposed.


==> /var/log/openhab2/openhab.log <==

2019-04-15 12:09:44.938 [INFO ] [clipse.smarthome.model.script.Roomba] - Roomba phase received update: charge

2019-04-15 12:09:45.034 [INFO ] [clipse.smarthome.model.script.Roomba] - Roomba percent complete 2 of 470 calculated as 0%

2019-04-15 12:11:01.533 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: updateItemStatus; checking host availability at 192.168.1.103

2019-04-15 12:11:01.538 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: updateItemStatus; checking host availability at 192.168.1.120

2019-04-15 12:11:01.547 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: updateItemStatus; checking host availability at 192.168.1.108

2019-04-15 12:11:01.550 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: building message with count: 55578, id: 01000000, key: 3FD59D01C30F618D87A9530385FFE7F1

2019-04-15 12:11:01.554 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: Sending status for socket to 192.168.1.103:80

2019-04-15 12:11:01.556 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: Sending status for socket complete

2019-04-15 12:11:01.559 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: Receiving status for socket

2019-04-15 12:11:01.562 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: building message with count: 65275, id: 01000000, key: 35A73F8BE125AB8F4DC311A137756DDB

2019-04-15 12:11:01.565 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: Sending status for socket to 192.168.1.108:80

2019-04-15 12:11:01.569 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: Sending status for socket complete

2019-04-15 12:11:01.571 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: Receiving status for socket

2019-04-15 12:11:01.605 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-bd-47-9b[^]: Received status for socket (568 bytes)

2019-04-15 12:11:01.613 [TRACE] [dlink.handler.BroadlinkRemoteHandler] - rm3:34-ea-34-c7-76-86[^]: updateItemStatus; checking host availability at 192.168.1.102

2019-04-15 12:11:01.626 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: updateItemStatus; checking host availability at 192.168.1.105

2019-04-15 12:11:01.677 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: building message with count: 57413, id: 01000000, key: 8341E9BDF7139D19ABC5473731438BED

2019-04-15 12:11:01.682 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: Sending status for socket to 192.168.1.105:80

2019-04-15 12:11:01.686 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: Sending status for socket complete

2019-04-15 12:11:01.690 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: Receiving status for socket

2019-04-15 12:11:01.766 [TRACE] [handler.BroadlinkSocketModel2Handler] - sp2:34-ea-34-f5-ae-aa[^]: Received status for socket (568 bytes)

2019-04-15 12:11:01.775 [TRACE] [dlink.handler.BroadlinkRemoteHandler] - rm3:78-0f-77-19-fe-31[^]: updateItemStatus; checking host availability at 192.168.1.113

2019-04-15 12:11:01.784 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: updateItemStatus; checking host availability at 192.168.1.106

2019-04-15 12:11:01.796 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: building message with count: 28562, id: 01000000, key: 3B291105CF4BE5C1A34D9F0F99FB83B5

2019-04-15 12:11:01.799 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: Sending status for strip to 192.168.1.106:80

2019-04-15 12:11:01.802 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: Sending status for strip complete

2019-04-15 12:11:01.804 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: Receiving status for strip

2019-04-15 12:11:01.948 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: A1 getStatusFromDevice

2019-04-15 12:11:01.948 [TRACE] [.handler.BroadlinkStripModel1Handler] - mp1:34-ea-34-c9-9d-33[^]: Received status for strip (72 bytes)

2019-04-15 12:11:01.953 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: building message with count: 46798, id: 02000000, key: 134590664AB80656802B7D45B79EF334

2019-04-15 12:11:01.958 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: Sending A1 device status to 192.168.1.120:80

2019-04-15 12:11:01.971 [TRACE] [dlink.handler.BroadlinkRemoteHandler] - rm3:34-ea-34-51-2d-bc[^]: updateItemStatus; checking host availability at 192.168.1.107

2019-04-15 12:11:01.988 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: Sending A1 device status complete

2019-04-15 12:11:01.991 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: Receiving A1 device status

2019-04-15 12:11:02.031 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: Received A1 device status (136 bytes)

2019-04-15 12:11:02.038 [TRACE] [broadlink.handler.BroadlinkA1Handler] - a1:34-ea-34-b2-ba-7c[^]: A1 getStatusFromDevice got temperature 22.8

2019-04-15 12:11:02.061 [TRACE] [dlink.handler.BroadlinkRemoteHandler] - rm3:78-0f-77-18-3e-c4[^]: updateItemStatus; checking host availability at 192.168.1.112

2019-04-15 12:11:02.077 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: updateItemStatus; checking host availability at 192.168.1.101

2019-04-15 12:11:02.089 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: building message with count: 13615, id: 03000000, key: D6B8D103D6B8D1030D2C4873439FBE62

2019-04-15 12:11:02.096 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: Sending RM2 device status to 192.168.1.101:80

2019-04-15 12:11:02.099 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: Sending RM2 device status complete

2019-04-15 12:11:02.101 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: Receiving RM2 device status

2019-04-15 12:11:02.134 [TRACE] [handler.BroadlinkRemoteModel2Handler] - rm2:34-ea-34-c7-c9-15[^]: Received RM2 device status (392 bytes)

2019-04-15 12:11:06.566 [DEBUG] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: No further status for socket response received for device

2019-04-15 12:11:06.569 [ERROR] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: Exception while getting status from device

java.net.ProtocolException: Incoming packet from device is null.

	at org.openhab.binding.broadlink.internal.BroadlinkProtocol.decodePacket(BroadlinkProtocol.java:193) ~[291:org.openhab.binding.broadlink:2.4.0.201904141744]

	at org.openhab.binding.broadlink.handler.BroadlinkSocketModel2Handler.getStatusFromDevice(BroadlinkSocketModel2Handler.java:47) [291:org.openhab.binding.broadlink:2.4.0.201904141744]

	at org.openhab.binding.broadlink.handler.BroadlinkBaseThingHandler.updateItemStatus(BroadlinkBaseThingHandler.java:204) [291:org.openhab.binding.broadlink:2.4.0.201904141744]

	at org.openhab.binding.broadlink.handler.BroadlinkBaseThingHandler$1.run(BroadlinkBaseThingHandler.java:77) [291:org.openhab.binding.broadlink:2.4.0.201904141744]

	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:?]

	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:?]

	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:?]

	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:?]

	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]

	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]

	at java.lang.Thread.run(Thread.java:748) [?:?]

2019-04-15 12:11:06.575 [ERROR] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: Problem getting status. Marking as offline ...

2019-04-15 12:11:06.578 [ERROR] [handler.BroadlinkSocketModel2Handler] - sp2:b4-43-0d-ee-d6-04[^]: updateItemStatus: Online -> Offline

==> /var/log/openhab2/events.log <==

2019-04-15 12:11:06.599 [me.event.ThingUpdatedEvent] - Thing 'broadlink:sp2:b4-43-0d-ee-d6-04' has been updated.

2019-04-15 12:11:06.611 [me.event.ThingUpdatedEvent] - Thing 'broadlink:sp2:b4-43-0d-ee-d6-04' has been updated.

2019-04-15 12:11:06.620 [hingStatusInfoChangedEvent] - 'broadlink:sp2:b4-43-0d-ee-d6-04' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not find device at IP address 192.168.1.103



==> /var/log/openhab2/openhab.log <==

2019-04-15 12:20:37.131 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkSocketModel2Handler of thing broadlink:sp2:34-ea-34-bd-47-9b tried updating channel powerOn although the handler was already disposed.

2019-04-15 12:20:37.656 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel temperature although the handler was already disposed.

2019-04-15 12:20:37.661 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel humidity although the handler was already disposed.

2019-04-15 12:20:37.665 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel light although the handler was already disposed.

2019-04-15 12:20:37.669 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel air although the handler was already disposed.

2019-04-15 12:20:37.673 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkA1Handler of thing broadlink:a1:34-ea-34-b2-ba-7c tried updating channel noise although the handler was already disposed.

2019-04-15 12:20:42.904 [ERROR] [nk.handler.BroadlinkBaseThingHandler] - IO error: Broadlink Device: Socket is closed

2019-04-15 12:20:42.908 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkStripModel1Handler tried updating the thing status although the handler was already disposed.

2019-04-15 12:20:43.061 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkSocketModel2Handler of thing broadlink:sp2:34-ea-34-f5-ae-aa tried updating channel powerOn although the handler was already disposed.

2019-04-15 12:20:44.960 [WARN ] [.core.thing.binding.BaseThingHandler] - Handler BroadlinkRemoteModel2Handler of thing broadlink:rm2:34-ea-34-c7-c9-15 tried updating channel temperature although the handler was already disposed.