search

ioBroker / ioBroker.rflink

Support of RFLink
MIT License
11 stars 11 forks source link

random disconnect #16

Closed cimba007 closed 5 years ago

cimba007 commented 5 years ago

image image

What can I do about this? Why does this even happen?

iobroker.rflink: Version 1.2.0

Apollon77 commented 5 years ago

Please enable debug log for the instance and send this log

cimba007 commented 5 years ago

Ignore the javascript debug output .. I think the disconnect happens after the determined timeout in the adapter .. but what is the purpose of this? The messages seem to be still received. The skipped frames can be ignored too as I interpret the RAW attribute of the rflink adapter.


javascript.0 | 2018-12-03 23:02:53.402 | info | script.js.common.rflink.process_raw: raw: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON; array_len: 7
-- | -- | -- | --
rflink.0 | 2018-12-03 23:02:53.393 | debug | Skip frame: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON;
rflink.0 | 2018-12-03 23:02:53.391 | debug | Serial data received: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON;
rflink.0 | 2018-12-03 23:02:53.389 | info | Connected
rflink.0 | 2018-12-03 22:46:57.110 | debug | redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":false,"ack":true,"ts":1543873617107,"q":0,"from":"system.adapter.rflink.0","lc":1543873617107}
rflink.0 | 2018-12-03 22:46:57.105 | info | disconnected
javascript.0 | 2018-12-03 22:36:57.127 | info | script.js.common.rflink.process_raw: raw: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON; array_len: 7
rflink.0 | 2018-12-03 22:36:57.121 | debug | redis pmessage io.rflink.0.* io.rflink.0.rawData {"val":"20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;\r","ack":true,"ts":1543873017111,"q":0,"from":"system.adapter.rflink.0","lc":1543873017111}
rflink.0 | 2018-12-03 22:36:57.119 | debug | redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":true,"ack":true,"ts":1543873017110,"q":0,"from":"system.adapter.rflink.0","lc":1543873017110}
rflink.0 | 2018-12-03 22:36:57.106 | debug | Skip frame: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;
rflink.0 | 2018-12-03 22:36:57.104 | debug | Serial data received: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;
rflink.0 | 2018-12-03 22:36:57.097 | info | Connected
rflink.0 | 2018-12-03 22:04:51.225 | debug | redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":false,"ack":true,"ts":1543871091223,"q":0,"from":"system.adapter.rflink.0","lc":1543871091223}
rflink.0 | 2018-12-03 22:04:51.221 | info | disconnected
rflink.0 | 2018-12-03 22:04:21.656 | debug | system.adapter.admin.0: logging false
GermanBluefox commented 5 years ago

Possible that if no messages received in last 10 minutes, adapter thinks => disconnect

cimba007 notifications@github.com schrieb am Mo., 3. Dez. 2018, 23:09:

Ignore the javascript debug output .. I think the disconnect happens after the determined timeout in the adapter .. but what is the purpose of this? The messages seem to be still received.

javascript.0 2018-12-03 23:02:53.402 info script.js.common.rflink.process_raw: raw: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON; array_len: 7
rflink.0 2018-12-03 23:02:53.393 debug Skip frame: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON;
rflink.0 2018-12-03 23:02:53.391 debug Serial data received: 20;09;EV1527;ID=004515;SWITCH=04;CMD=ON;
rflink.0 2018-12-03 23:02:53.389 info Connected
rflink.0 2018-12-03 22:46:57.110 debug redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":false,"ack":true,"ts":1543873617107,"q":0,"from":"system.adapter.rflink.0","lc":1543873617107}
rflink.0 2018-12-03 22:46:57.105 info disconnected
javascript.0 2018-12-03 22:36:57.127 info script.js.common.rflink.process_raw: raw: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON; array_len: 7
rflink.0 2018-12-03 22:36:57.121 debug redis pmessage io.rflink.0.* io.rflink.0.rawData {"val":"20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;\r","ack":true,"ts":1543873017111,"q":0,"from":"system.adapter.rflink.0","lc":1543873017111}
rflink.0 2018-12-03 22:36:57.119 debug redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":true,"ack":true,"ts":1543873017110,"q":0,"from":"system.adapter.rflink.0","lc":1543873017110}
rflink.0 2018-12-03 22:36:57.106 debug Skip frame: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;
rflink.0 2018-12-03 22:36:57.104 debug Serial data received: 20;08;EV1527;ID=004401;SWITCH=05;CMD=ON;
rflink.0 2018-12-03 22:36:57.097 info Connected
rflink.0 2018-12-03 22:04:51.225 debug redis pmessage io.rflink.0.* io.rflink.0.info.connection {"val":false,"ack":true,"ts":1543871091223,"q":0,"from":"system.adapter.rflink.0","lc":1543871091223}
rflink.0 2018-12-03 22:04:51.221 info disconnected
rflink.0 2018-12-03 22:04:21.656 debug system.adapter.admin.0: logging false

— You are receiving this because you are subscribed to this thread. Reply to this email directly, view it on GitHub https://github.com/ioBroker/ioBroker.rflink/issues/16#issuecomment-443889362, or mute the thread https://github.com/notifications/unsubscribe-auth/AEXqgNLR3QB4mJ5hiIIUl8bqen9FH3_Gks5u1aEfgaJpZM4Y_Jq0 .

GermanBluefox commented 5 years ago

https://github.com/ioBroker/ioBroker.rflink/blob/master/lib/serial.js#L49

Set connection timeout to something bigger. 120000 or more.

cimba007 commented 5 years ago

@GermanBluefox @Apollon77 Thank you for your helpful tips. If the disconnect is no real issue but only cosmetic than this is totally fine for me.