Crash on RFXCOM reconnection

[egesia]

Hello,

i've found lot of crash and restart on my log and after investigating i'm pretty sure it's related to RFXCOM module: quite often FHEM lose connection with the RFXLAN device and crash on reconnection. I tried enabling only this device on a clean fhem install on a different machine with the same result. Is this a known issue and how can i help in debug and fixing ?

Thanks !

[rudolfkoenig]

AFAIK this is not a known issue, it would be helpful to show us the crash messages, and the OS where FHEM is running

[Willi]

Hello,

i've found lot of crash and restart on my log and after investigating i'm pretty sure it's related to RFXCOM module: quite often FHEM lose connection with the RFXLAN device and crash on reconnection. I tried enabling only this device on a clean fhem install on a different machine with the same result. Is this a known issue and how can i help in debug and fixing ?

Thanks !

Do you use a RFXLAN transceiver device with my old RFXCOM modules?

My RFXCOM modules probably have never been tested with an RFXLAN, so I suspect that there could be issues on reconnects using TCP on my modules. The only users I know with the RFXCOM modules (not really changed for more than one year) are using the USB based old RFXCOM receiver (I do also have on old RFXCOM receiver).
There is no support for transmits (sets) for RFXLAN using the RFXCOM modules. sets are only supported on my TRX modules for RFXtrx433.

As Rudolf asked you, please tell us more about the reconnect problems and the crash messages.

-- Willi

[egesia]

Hello,
first of all many thanks for your fast support!
Yes, i'm using an rfxcom lan device with rfxcom. I tested on three different fhem installation and every time the connection with the rfxlan reappears fhem crashes and respawn.

Last lines of log:

Code Auswählen Erweitern

2013.11.12 15:10:27 4: eventTypes: OREGON Tribuna temperature: 13.9 -> temperature: .*
2013.11.12 15:10:27 4: eventTypes: OREGON Tribuna humidity: 59 -> humidity: .*
2013.11.12 15:10:27 4: eventTypes: OREGON Tribuna battery: ok -> battery: ok
2013.11.12 15:10:27 4: eventTypes: OREGON Tribuna T: 13.9 H: 59 BAT: ok -> T: .* H: .* BAT: ok
2013.11.12 15:10:27 5: RFXCOM_Parse1 '501a2d10ab911390054247'
2013.11.12 15:10:37 1: 192.168.1.10:10001 disconnected, waiting to reappear
2013.11.12 15:10:37 5: Triggering RFXLAN (1 changes)
2013.11.12 15:10:37 5: Notify loop for RFXLAN DISCONNECTED
2013.11.12 15:10:37 4: eventTypes: RFXCOM RFXLAN DISCONNECTED -> DISCONNECTED
2013.11.12 15:11:40 1: 192.168.1.10:10001 reappeared (RFXLAN)

I can provide a link to the tcp port if you want to test on your machine.

[Willi]

Thanks for the information.

On your logs I see one TCP disconnect and a reconnect which is normal if there are any network problems or a disconnect of the device.
Does the device work normally again after the reconnect? How often do these disconnect appear?

For TCP connection I simply use the common FHEM routines that are used by nearly all modules.

Where do you see "respawn" and "crashes".? I have not seen this in the logs submitted.
Crashes implies that the FHEM mail script (fhem.pl) crashed or one of the modules crashes.

Greetings

Willi

[egesia]

Hello,

Now i'm running a fhem only for the Rfxcom on a Rpi and it's quite solid (two crashes in 24h) and connected with the main with FHEM2FHEM. On my main and installation i found respawn every 5-10 minutes. I also tested with a fhem outside the lan  (by a dyndns service) and got lot of disconnection.

On my Rpi the fhem process doesn't respawn so i have to do it manually, after that the log continues with "including fhem.cfg":

Code Auswählen Erweitern

2013.11.12 15:11:40 1: 192.168.1.10:10001 reappeared (RFXLAN)
2013.11.12 15:53:06 1: Including fhem.cfg

Same situation later:

Code Auswählen Erweitern

2013.11.12 19:34:52 1: 192.168.1.10:10001 reappeared (RFXLAN)
2013.11.12 20:53:16 1: Including fhem.cfg

Thanks

[Willi]

I am still puzzled. You have quoted again

2013.11.12 15:11:40 1: 192.168.1.10:10001 reappeared (RFXLAN)
2013.11.12 15:53:06 1: Including fhem.cfg

Does it mean that there is no other log when FHEM is crashed?
Could you set the verbose attribute to 5 for the RFXCOM device?

Regards

Willi

[egesia]

Yes, these were the last lines before fhem process stopped.

With verbose 5 on rfxcom (and respawn active):

Code Auswählen Erweitern

2013.11.13 23:24:14 5: RFXLAN dispatch 501a2d10ab461100173a92
2013.11.13 23:33:32 1: 192.168.1.10:10001 reappeared (RFXLAN)
Can't call method "status" on an undefined value at ./FHEM/40_RFXCOM.pm line 369.
2013.11.13 23:33:32 1: Including fhem.cfg

[Willi]

Thanks.

I will look into it when I have some time left. Unfortunately there is not much left before Xmas as I am currently renovating my house....

Regards

Willi