New openHab2 EnOcean binding

kdmsch · August 19, 2019, 6:22pm

Hello,

since I have updated to 2.5.0M2 the enocean binding stops receiving telegrams after appr. 1-2 hours working properly. The log-file reports at that time:

2019-08-19 19:36:13.641 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: java.lang.NumberFormatException: For input string: ""
at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[?:?]
at java.lang.Long.parseLong(Long.java:601) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.informListeners(EnOceanTransceiver.java:422) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.processMessage(EnOceanTransceiver.java:313) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.receivePackets(EnOceanTransceiver.java:200) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.access$3(EnOceanTransceiver.java:192) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver$1.run(EnOceanTransceiver.java:163) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:?]
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) [?:?]

I have tried a lot to get it working again, e.g. re-installing enocean bridge, different seriel port setups, deleting tmp and chache, installing latest system updates and snapshots etc. but without success. Maybe someone can see anything what could went wrong in the part of the log-file.
But sending telegrams via enocean is always working and things are reacting properly, only receiving telegrams is not working.
Thank you for feedback in advance
KDMSch

fruggy83 · August 22, 2019, 7:36am

Hi Klaus @kdmsch,

as far as I know, exception handling in function informListeners was changed a little bit. So whenever an exception occures the receiving thread is aborted now. Could you please enable the debug log and post the last received message before this error? It seems that the received message has no senderId or at least it cannot be parsed.

Best regards
Daniel

fruggy83 · August 22, 2019, 7:52am

Hi Basti @Bastyd,

the discovered thing is just a simple RockerSwitch. So you are just able to receive state changes with this thing, However you cannot control your device within openhab with this thing. Could you please FIRST start the discovery in openhab and set your Opus Bridge AFTERWARDS into teach-in-mode? Whenever you set a device with a D2-EEP into teach-in-mode it normally sends a special UTE teach in message which has to be answered within 800ms.

Best regards
Daniel

kdmsch · August 23, 2019, 6:03am

Hi Daniel,

following the debug log most probably at that time when the enocean stops receiving telegrams. But I’m not hundred percent sure that this is the right hint. But it is obviously the first time since reboot that this block of logmessages appears. After enocean receiving has stopped this block (beginning with twice “2019-08-22 22:51:32.443 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels” is appering very often.

DEBUG log:
==> /var/log/openhab2/events.log <==
2019-08-22 22:50:29.953 [vent.ItemStateChangedEvent] - OU_WS_Aussentemperatur changed from 15.4 to 15.3
2019-08-22 22:50:58.096 [vent.ItemStateChangedEvent] - Azimut changed from 319.7365098285878 to 320.91874797244526
2019-08-22 22:50:58.104 [vent.ItemStateChangedEvent] - Sonnenhoehe changed from -20.20568759803006 to -20.726404837462493
==> /var/log/openhab2/openhab.log <==
2019-08-22 22:51:32.443 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-22 22:51:32.444 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-22 22:51:32.448 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-22 22:51:32.449 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-22 22:51:32.451 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-22 22:51:32.479 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-22 22:51:32.479 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-22 22:51:32.728 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-22 22:51:33.008 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-22 22:51:33.288 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00

Just as an example this morning (appr. 10 h after enocean stopped) following log ihas appeared:

2019-08-23 07:56:26.660 [vent.ItemStateChangedEvent] - OU_WS_Helligkeit changed from 23993.0 to 24034.0

==> /var/log/openhab2/openhab.log <==
2019-08-23 07:56:37.292 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-23 07:56:37.297 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-23 07:56:37.297 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-23 07:56:37.300 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-23 07:56:37.303 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-23 07:56:37.329 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-23 07:56:37.329 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-23 07:56:37.578 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-23 07:56:37.858 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-23 07:56:38.138 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00

==> /var/log/openhab2/events.log <==
2019-08-23 07:58:26.494 [vent.ItemStateChangedEvent] - OU_WS_Aussentemperatur changed from 13.9 to 14.1

Hopefully you can discover anything helping to find a solution.
If not I have to switch back to 2.5M1 but the Nodon PIR does not work properly there.

Thank you very much for your support
Klaus

kdmsch · August 24, 2019, 1:22pm

additional information:
When enocean has stopped receiving telegrams, the following block of log messages is repeating every 5 min exactly!:

2019-08-24 15:15:33.643 [DEBUG] [rnal..EnOceanBaseThingHandler] - polling channels
2019-08-24 15:15:33.643 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-24 15:15:33.649 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:15:33.653 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:15:33.654 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-24 15:15:33.690 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:15:33.694 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:15:33.939 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-24 15:15:34.219 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-24 15:15:34.499 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00

When I swith on and of a plug from theBasic UI or iOS-App the following log messages appers:

2019-08-24 15:14:36.270 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command ON
2019-08-24 15:14:36.349 [vent.ItemStateChangedEvent] - Plug_01 changed from OFF to ON

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

2019-08-24 15:14:36.350 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:14:36.354 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E01FF9B468200010509786FFF00

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

2019-08-24 15:14:37.085 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command OFF
2019-08-24 15:14:37.130 [vent.ItemStateChangedEvent] - Plug_01 changed from ON to OFF

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

2019-08-24 15:14:37.149 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-24 15:14:37.158 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E00FF9B468200010509786FFF00

That is also with the latest snapshot 1668 installed.

best regards
Klaus

wbr · August 27, 2019, 5:02pm

Hey,

somebody mentioned in my topic to ask in this thread for an answer. Don’t want to duplicate content, so here the Link to my question regarding the enocean binding…
Security Teach in causes Null Pointer in EnOcean binding

Thank you very much.

fruggy83 · August 28, 2019, 9:17am

Hi Klaus @kdmsch,

thanks for the logs. However I need the log entry with the received entry just before the NumberFomatException. @wbr posted an example of such an problematic message, maybe one of your devices is secured too?

Best regards
Daniel

Bastyd · August 29, 2019, 6:33am

Hey good morning!

Did as you said with the same result. Automatically just finds the rocker switch even though the log shows D2 EEP entrys. Here is the complete log from the moment I start the EnOcean discovery:

08:21:19.949 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - Starting EnOcean discovery and accepting teach in requests
08:21:21.273 [INFO ] [smarthome.event.ItemStateChangedEvent] - SensorIlluminance_HWR_EG changed from 62.60369976726429 to 65.35818751130745
08:21:24.042 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F630050BF8873001FFFFFFFF3D00 received
08:21:24.047 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:24.050 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F630050BF88730, additional 01FFFFFFFF3D00
08:21:24.064 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'enocean:rockerSwitch:A600I0TU:050BF887' has been added.
08:21:24.063 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'enocean:rockerSwitch:A600I0TU:050BF887' to inbox.
08:21:24.074 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F670050BF8873001FFFFFFFF3D00 received
08:21:24.077 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:24.080 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F670050BF88730, additional 01FFFFFFFF3D00
08:21:24.169 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for 050BF887 payload D200000004050BF8870001FFFFFFFF3C00 received
08:21:24.171 [DEBUG] [ernal.handler.EnOceanBaseThingHandler] - ESP Packet payload D200000004050BF88700 for 050BF887 received
08:21:34.421 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F620050BF8873001FFFFFFFF3C00 received
08:21:34.424 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:34.425 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F620050BF88730, additional 01FFFFFFFF3C00
08:21:34.536 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F660050BF8873001FFFFFFFF3C00 received
08:21:34.539 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:34.541 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F660050BF88730, additional 01FFFFFFFF3C00
08:21:36.867 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F610050BF8873001FFFFFFFF3700 received
08:21:36.869 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:36.871 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F610050BF88730, additional 01FFFFFFFF3700
08:21:36.885 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG RPS for 050BF887 payload F630050BF8873001FFFFFFFF3700 received
08:21:36.886 [INFO ] [ternal.transceiver.EnOceanTransceiver] - Received teach in message from 050BF887
08:21:36.889 [INFO ] [scovery.EnOceanDeviceDiscoveryService] - EnOcean Package discovered, RORG RPS, payload F630050BF88730, additional 01FFFFFFFF3700
08:21:58.540 [DEBUG] [ernal.handler.EnOceanBaseThingHandler] - polling channels
08:21:58.551 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
08:21:58.554 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D203FFD8650C0001050BF887FF00
08:21:58.577 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
08:21:58.608 [DEBUG] [ternal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FFD8650C payload D203FFD8650C8102050BF8873600 received

Thank you everyone

kdmsch · August 31, 2019, 12:51pm

Thank you Daniel,

I have also checked transmission protocol of the enocean devices installen and noone is set to secure transmission. Following a excerpt of the log with all enocean events before stopping receiving messages indicated by the identical blocks starting with “polling Channels”.
I have tried a lot to get it working properly including removing a lot of devices and reinstalling but without success. I assume I will go back to 2.5M1 because this is at least running reliable with most of my devices.

2019-08-31 12:54:26.577 [vent.ItemStateChangedEvent] - BWM_Bewegung changed from OFF to ON
2019-08-31 12:54:26.626 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command OFF
2019-08-31 12:54:26.643 [vent.ItemStateChangedEvent] - Plug_01 changed from ON to OFF
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:54:26.645 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:54:26.648 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E00FF9B468200010509786FFF00
2019-08-31 12:54:26.659 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:54:26.701 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2011E00FF9B468281000509786F5900 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:54:43.932 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0580DC1A payload A59904C0880580DC1A0000FFFFFFFF2D00 received
2019-08-31 12:54:43.935 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A59904C0880580DC1A00 for 0580DC1A received
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:26.240 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0588041F payload A5970440080588041F0000FFFFFFFF3100 received
2019-08-31 12:55:26.244 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A5970440080588041F00 for 0588041F received
==> /var/log/openhab2/events.log <==
2019-08-31 12:55:26.257 [vent.ItemStateChangedEvent] - BWM_Bewegung changed from ON to OFF
2019-08-31 12:55:26.300 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command ON
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:26.337 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:55:26.344 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E01FF9B468200010509786FFF00
==> /var/log/openhab2/events.log <==
2019-08-31 12:55:26.346 [vent.ItemStateChangedEvent] - Plug_01 changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:26.360 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:55:26.388 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2011E01FF9B468281000509786F5500 received
2019-08-31 12:55:30.095 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0580DC1A payload A59904C0080580DC1A0000FFFFFFFF2D00 received
2019-08-31 12:55:30.101 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A59904C0080580DC1A00 for 0580DC1A received
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:30.913 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0588041F payload A5970440880588041F0000FFFFFFFF3100 received
2019-08-31 12:55:30.915 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A5970440880588041F00 for 0588041F received
==> /var/log/openhab2/events.log <==
2019-08-31 12:55:30.923 [vent.ItemStateChangedEvent] - BWM_Bewegung changed from OFF to ON
2019-08-31 12:55:30.944 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command OFF
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:30.960 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
==> /var/log/openhab2/events.log <==
2019-08-31 12:55:30.960 [vent.ItemStateChangedEvent] - Plug_01 changed from ON to OFF
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:30.966 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E00FF9B468200010509786FFF00
2019-08-31 12:55:30.977 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:55:31.008 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2011E00FF9B468281000509786F5500 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:55:45.831 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0580DC1A payload A59904C0880580DC1A0000FFFFFFFF2D00 received
2019-08-31 12:55:45.835 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A59904C0880580DC1A00 for 0580DC1A received
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:56:30.203 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 12:56:30.208 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:56:30.212 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 12:56:30.214 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 12:56:30.221 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:56:30.225 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:56:30.242 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:56:30.246 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:56:30.491 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 12:56:30.501 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:56:30.525 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2063EFF9B468281000509786F5800 received
2019-08-31 12:56:30.771 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 12:56:30.782 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:56:30.812 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2031EFF9B468181000509786E5800 received
2019-08-31 12:56:31.052 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 12:56:31.064 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:56:31.092 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2063EFF9B468181000509786E5800 received
2019-08-31 12:57:08.847 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0588041F payload A5970480080588041F0000FFFFFFFF3100 received
2019-08-31 12:57:08.850 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A5970480080588041F00 for 0588041F received
==> /var/log/openhab2/events.log <==
2019-08-31 12:57:08.865 [vent.ItemStateChangedEvent] - BWM_Bewegung changed from ON to OFF
2019-08-31 12:57:08.931 [ome.event.ItemCommandEvent] - Item 'Plug_01' received command ON
2019-08-31 12:57:08.952 [vent.ItemStateChangedEvent] - Plug_01 changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2019-08-31 12:57:08.964 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 12:57:08.966 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2011E01FF9B468200010509786FFF00
2019-08-31 12:57:08.979 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 12:57:09.007 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 0580DC1A payload A5990500080580DC1A0000FFFFFFFF2D00 received
2019-08-31 12:57:09.009 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A5990500080580DC1A00 for 0580DC1A received
2019-08-31 12:57:09.019 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2011E01FF9B468281000509786F5300 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:01:30.245 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:01:30.249 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:01:30.250 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:01:30.251 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:01:30.255 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:01:30.267 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:01:30.282 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:01:30.282 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:01:30.292 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2031EFF9B468181000509786E5600 received
2019-08-31 13:01:30.531 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:01:30.543 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:01:30.572 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2031EFF9B468281000509786F5600 received
2019-08-31 13:01:30.811 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:01:30.824 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:01:30.849 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2063EFF9B468281000509786F5600 received
2019-08-31 13:01:31.092 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:01:31.104 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:01:31.133 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2063EFF9B468181000509786E5900 received
2019-08-31 13:02:17.887 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 05820B1D payload A56A02B60805820B1D0000FFFFFFFF5500 received
2019-08-31 13:02:17.892 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A56A02B60805820B1D00 for 05820B1D received
==> /var/log/openhab2/events.log <==
2019-08-31 13:02:17.916 [vent.ItemStateChangedEvent] - Temp_Gartenhaus changed from 33.95894428152493 to 34.271749755620725
2019-08-31 13:02:17.932 [vent.ItemStateChangedEvent] - Temp_Gartenhaus_01 changed from 33.95894428152493 to 34.271749755620725
2019-08-31 13:02:17.936 [vent.ItemStateChangedEvent] - Humi_Gartenhaus changed from 42.35294117647059 to 41.568627450980394
2019-08-31 13:02:17.940 [vent.ItemStateChangedEvent] - Humi_Gartenhaus_01 changed from 42.35294117647059 to 41.568627450980394
2019-08-31 13:03:18.506 [vent.ItemStateChangedEvent] - OU_WS_Helligkeit changed from 27779.0 to 27799.0
2019-08-31 13:03:18.661 [vent.ItemStateChangedEvent] - OU_WS_Aussentemperatur changed from 33.1 to 33.0
2019-08-31 13:04:08.419 [vent.ItemStateChangedEvent] - OU_WS_Windgeschwindigkeit changed from 0.0 to 2.52
2019-08-31 13:04:10.415 [vent.ItemStateChangedEvent] - OU_WS_Windgeschwindigkeit changed from 2.52 to 3.6
2019-08-31 13:04:21.425 [vent.ItemStateChangedEvent] - OU_WS_Windgeschwindigkeit changed from 3.6 to 2.52
2019-08-31 13:05:18.503 [vent.ItemStateChangedEvent] - OU_WS_Helligkeit changed from 27799.0 to 27823.0
2019-08-31 13:05:18.550 [vent.ItemStateChangedEvent] - OU_WS_Aussentemperatur changed from 33.0 to 32.8
2019-08-31 13:05:18.660 [vent.ItemStateChangedEvent] - OU_WS_Windgeschwindigkeit changed from 2.52 to 1.44
2019-08-31 13:05:22.425 [vent.ItemStateChangedEvent] - OU_WS_Windgeschwindigkeit changed from 1.44 to 2.52
2019-08-31 13:05:55.880 [vent.ItemStateChangedEvent] - Azimut changed from 174.03200515496195 to 175.92438182591746
2019-08-31 13:05:55.883 [vent.ItemStateChangedEvent] - Sonnenhoehe changed from 49.273568794047414 to 49.343590096270255
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:06:30.286 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:06:30.286 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:06:30.291 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:06:30.295 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:06:30.301 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:06:30.307 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:06:30.322 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:06:30.326 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:06:30.571 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:06:30.583 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:06:30.604 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2063EFF9B468181000509786E5600 received
2019-08-31 13:06:30.851 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:06:30.867 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:06:30.892 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2031EFF9B468281000509786F5300 received
2019-08-31 13:06:31.132 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:06:31.145 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:06:31.168 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2063EFF9B468281000509786F5900 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:11:30.326 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:11:30.328 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:11:30.330 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:11:30.331 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:11:30.337 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:11:30.342 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:11:30.362 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:11:30.366 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2031EFF9B468181000509786E5300 received
2019-08-31 13:11:30.362 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:11:30.611 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:11:30.623 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:11:30.648 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2031EFF9B468281000509786F5300 received
2019-08-31 13:11:30.892 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:11:30.907 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:11:30.933 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2063EFF9B468181000509786E5500 received
2019-08-31 13:11:31.172 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:11:31.187 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:11:31.209 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2063EFF9B468281000509786F5200 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:13:46.248 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _4BS for 05820A68 payload A5AE023F0805820A680000FFFFFFFF5600 received
2019-08-31 13:13:46.254 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload A5AE023F0805820A6800 for 05820A68 received
==> /var/log/openhab2/events.log <==
2019-08-31 13:13:46.272 [vent.ItemStateChangedEvent] - Temp_Irgendwo changed from 24.809384164222877 to 24.965786901270775
2019-08-31 13:13:46.289 [vent.ItemStateChangedEvent] - Status_Irgendwo changed from -83 to -86
2019-08-31 13:13:46.293 [vent.ItemStateChangedEvent] - Humi_Irgendwo changed from 68.62745098039215 to 68.23529411764706
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:16:14.367 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _1BS for 0517E5DB payload D5080517E5DB0100FFFFFFFF5300 received
2019-08-31 13:16:14.372 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload D5080517E5DB01 for 0517E5DB received
==> /var/log/openhab2/events.log <==
2019-08-31 13:16:14.390 [vent.ItemStateChangedEvent] - Stat_GartenhausTuer changed from -89 to -83
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:16:21.264 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG _1BS for 0517F8BC payload D5090517F8BC0000FFFFFFFF5900 received
2019-08-31 13:16:21.267 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - ESP Packet payload D5090517F8BC00 for 0517F8BC received
2019-08-31 13:16:30.364 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:16:30.367 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:16:30.369 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:16:30.372 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:16:30.368 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:16:30.384 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:16:30.402 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:16:30.403 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:16:30.415 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2031EFF9B468181000509786E5300 received
2019-08-31 13:16:30.651 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:16:30.673 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:16:30.709 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2031EFF9B468281000509786F5600 received
2019-08-31 13:16:30.942 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:16:30.954 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:16:30.983 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4682 payload D2063EFF9B468281000509786F5600 received
2019-08-31 13:16:31.222 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:16:31.235 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RESPONSE with code RET_OK payload 00 received
2019-08-31 13:16:31.259 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG VLD for FF9B4681 payload D2063EFF9B468181000509786E5300 received
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:18:47.572 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG Unknown for payload D0066405820A680000FFFFFFFF5200 received
2019-08-31 13:18:47.576 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.NumberFormatException: For input string: ""
at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[?:?]
at java.lang.Long.parseLong(Long.java:601) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.informListeners(EnOceanTransce iver.java:422) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.processMessage(EnOceanTransceiver.java:313) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.receivePackets(EnOceanTransceiver.java:200) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver.access$3(EnOceanTransceiver.java:192) ~[?:?]
at org.openhab.binding.enocean.internal.transceiver.EnOceanTransceiver$1.run(EnOceanTransceiver.java:163) ~[?:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:?]
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) [?:?]
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:21:30.406 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:21:30.411 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:21:30.414 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:21:30.418 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:21:30.422 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:21:30.442 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:21:30.446 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:21:30.694 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:21:30.971 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:21:31.251 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:26:30.445 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:26:30.448 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:26:30.450 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:26:30.450 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:26:30.456 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:26:30.482 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:26:30.482 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:26:30.731 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:26:31.011 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:26:31.291 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:31:30.484 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:31:30.488 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:31:30.486 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:31:30.494 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:31:30.497 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:31:30.532 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:31:30.534 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:31:30.781 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:31:31.061 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:36:30.536 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:36:30.538 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:36:30.540 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:36:30.543 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:36:30.544 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:36:30.572 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:36:30.574 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:36:30.821 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:36:31.101 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:36:31.381 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:41:30.574 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:41:30.578 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:41:30.577 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:41:30.580 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:41:30.580 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:41:30.612 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:41:30.612 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:41:30.862 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:41:31.142 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:41:31.422 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:46:30.614 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:46:30.614 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:46:30.617 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:46:30.617 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:46:30.619 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:46:30.652 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:46:30.652 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:46:30.902 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:46:31.182 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
2019-08-31 13:46:31.462 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:51:30.654 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:51:30.654 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:51:30.657 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:51:30.657 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:51:30.659 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:51:30.692 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:51:30.692 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:51:30.941 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:51:31.221 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:51:31.501 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 13:56:30.694 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:56:30.694 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 13:56:30.696 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:56:30.697 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:56:30.698 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 13:56:30.722 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:56:30.722 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 13:56:30.971 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 13:56:31.251 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 13:56:31.531 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 14:01:30.724 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 14:01:30.725 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 14:01:30.726 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:01:30.728 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:01:30.728 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 14:01:30.771 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:01:30.771 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:01:31.022 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 14:01:31.302 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 14:01:31.582 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00
==> /var/log/openhab2/openhab.log <==
2019-08-31 14:06:30.773 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 14:06:30.774 [DEBUG] [rnal.handler.EnOceanBaseThingHandler] - polling channels
2019-08-31 14:06:30.776 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:06:30.776 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:06:30.779 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468200010509786FFF00
2019-08-31 14:06:30.812 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:06:30.812 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Enqueue new send request with ESP3 type RADIO_ERP1 without callback
2019-08-31 14:06:31.062 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2031EFF9B468100010509786EFF00
2019-08-31 14:06:31.342 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468100010509786EFF00
2019-08-31 14:06:31.622 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - Sending data, type RADIO_ERP1, payload D2063EFF9B468200010509786FFF00

It would be of great help if you would see anything in the log which could hint for a solution.
Thanks for your great suppuort.

best regards
Klaus

raphael · August 31, 2019, 3:42pm

Hi Daniel @fruggy83

Is the retry policy included in the 2.5.0.M2 release of the Enocean binding? I wasn’t able to find the thing parameters retryInterval and retryTries for my rollershuter things D2-05-00 and for my switch things D2-01-12.

Best regards Raphael

sttts · September 1, 2019, 10:28am

@AlexKid have you gotten the A5_14_0A profile to work? Would you mind sharing your things and items definition? I also see the debug messages as you did, but fail to connect those with a manually defined thing (not talking about any transformation yet).

sttts · September 1, 2019, 12:54pm

Got it working finally, not with D5_00_01,A5_14_01_ELTAKO, but with A5_14_09 as written above.

fruggy83 · September 2, 2019, 2:01pm

Hi Klaus,

2019-08-31 13:18:47.572 [DEBUG] [ernal.transceiver.EnOceanTransceiver] - RADIO_ERP1 with RORG Unknown for payload D0066405820A680000FFFFFFFF5200 received

your _Irgendwo thing seems to send smart ack messages. In this case it sends a message with RORG D0, which is currently not supported by the binding. I am working on these smack messages. However currently I do not see any workaround for you. Sorry.

Best regards
Daniel

fruggy83 · September 2, 2019, 2:06pm

Hi Raphael,

sorry I had not found time to merge this feature back to the official binding yet. I have got a new project at work and was quite busy the last weeks before my vacation. However it is getting better…

Best regards
Daniel

fruggy83 · September 2, 2019, 2:13pm

Hi Basti,

thanks for the log. I cannot find an UTE teach in message from your device. Therefore it cannot be recognized by the binding. Can you post the link from the fhem where they got it to work?

As @dominikkv already mentioned

While the search indicator is on screen, activate teach in mode on your device (UTE). Sometimes there is a button you have to press / long press, sometimes there is a spot where you have to hold a magnet, look it up in the manual.

Maybe you just have not activated the teach in mode of your device correctly.

Best regards
Daniel

fruggy83 · September 2, 2019, 2:21pm

Hi Basti,

sorry, I have to read first and answer later I found your link to the fhem forum. However there is no hope for a quick solution

Am Schalter selber lässt sich kein learn Telegramm auslösen. Ich konnte auch nicht herausfinden ob dieser so etwas überhaupt kann.
Anlernen von FHEM ging deshalb nur über Remote Management und “remoteLinkTable in ”.

RemoteMan is currently not supported by the binding.Do you have an Opus tool where you can trigger an UTE teach in message?

Best regards
Daniel

raphael · September 2, 2019, 6:07pm

Hi Daniel

No problem and thanks for your feedback.

Best regards
Raphael

kdmsch · September 3, 2019, 6:07am

Hi Daniel,

the sensor you have identified possibly causes the malfunction is an Easyfit ETHS wireless temperature & humidity sensor. In the available manuals I couldn’t find anything about smart ack support. I assume the sensor (I have 2 in service) is not able to do that or it is not reported!??
On the other hand if the sensors are cousing this issue, why is that not present in version 2.5M1?? (I have just switched back to M1 and this sensors are working properly without stopping receiving messages). If the binding has not changed where it should caused by? Could that be an issue from the sitemap/items files also?
My installed sensors/actors in total (maybe you can find any other incompatibility with the current binding version):

Thanks a lot again for you great support
Klaus

fruggy83 · September 9, 2019, 9:23am

Hi Klaus,

I found the following in the ETHS manual

In addition, ETHS reports its internal energy level using a SIGNAL telegram of type 0x06

This is the problematic telegram which causes the receiving interruption. These signal telegrams are used during smack teach in too. So I thought it would support this.

On the other hand if the sensors are cousing this issue, why is that not present in version 2.5M1?

They (not me) removed a try/catch block in the receiving part of the binding, which I implemented to catch all problems with unknown telegram types silently.

My installed sensors/actors in total (maybe you can find any other incompatibility with the current binding version)

The Permundo and Nodon sensor should not do any problems, as they are working at my setup flawlessly. Maybe the other EasyFit sensor also send this signal telegram. I am thinking about implementing this type of telegram. I will keep you informed.

Best regards
Daniel

dominikkv · September 9, 2019, 5:15pm

Sorry, that was me :-/
Did a PR to revert this: https://github.com/openhab/openhab2-addons/pull/6050