OpenNetHomeServer

[Mastiff]

Done, thanks. We'll see what happens. I have already decieded to try fixed IP on the VM instead of DHCP, maybe it's a DHCP error. There has been 11 disconnects during the night... If I can't get it this way I will try to put an extra network card in the host just for this VM and see if that changes anything. I have put one new computer on the net the last days, but I'm not sure if that was before, after or during this.

[Mastiff]

No help in going to a static IP. Does this help you understand what's going on?

Code: Select all

2016-07-26 07:39:37  EVENT: RFXtrx.Type: THC238/268,THN132,THWR288,THRN122,THN122,AW129/131 id: 16897 ' temperature: +20.6 deg C signal: 4 battery: 9'
2016-07-26 07:39:39  EVENT: RFXtrx.Type: THGN122/123, THGN132, THGR122/228/238/268 id: 58881 ' temperature: +25.6 deg C humidity: 63 %RH status: normal signal: 6 battery: 9'
2016-07-26 07:39:40  EVENT: RFXtrx.Type: AC address: 008d303a unit: 08 command: off ' level: 0 signal: 6'
2016-07-26 07:39:40  EVENT: RFXtrx.Type: Viking 02811 id: 32768 ' temperature: +19.8 deg C signal: 5 battery: 9'
2016-07-26 07:39:42  INFO: ('OpenNetHome', 'Checking connection with...', 'Tellstick Duo', 499, 397)
2016-07-26 07:39:44  EVENT: RFXtrx.Type: Viking 02811 id: 43776 ' temperature: +22.2 deg C signal: 5 battery: 9'
2016-07-26 07:39:44  EVENT: RFXtrx.Type: AC address: 008d303a unit: 05 command: off ' level: 0 signal: 6'
2016-07-26 07:39:49  EVENT: RFXtrx.Type: Viking 02811 id: 6400 ' temperature: +22.2 deg C signal: 5 battery: 9'
2016-07-26 07:39:50  EVENT: RFXtrx.Type: Viking 02811 id: 2048 ' temperature: +22.0 deg C signal: 4 battery: 9'
2016-07-26 07:39:50  EVENT: RFXtrx.Type: AC address: 008d303a unit: 10 command: off ' level: 0 signal: 6'
2016-07-26 07:39:57  EVENT: RFXtrx.Type: Viking 02811 id: 3328 ' temperature: +22.7 deg C signal: 5 battery: 9'
2016-07-26 07:40:04  EVENT: RFXtrx.Type: Viking 02811 id: 60672 ' temperature: +22.3 deg C signal: 5 battery: 9'
2016-07-26 07:40:08  EVENT: RFXtrx.Type: Viking 02811 id: 28416 ' temperature: +20.7 deg C signal: 5 battery: 9'
2016-07-26 07:40:09  EVENT: RFXtrx.Type: Viking 02811 id: 51200 ' temperature: +22.1 deg C signal: 4 battery: 9'
2016-07-26 07:40:09  EVENT: RFXtrx.Type: Viking 02811 id: 6144 ' temperature: +6.9 deg C signal: 5 battery: 9'
2016-07-26 07:40:11  EVENT: RFXtrx.Type: Viking 02811 id: 7936 ' temperature: +22.8 deg C signal: 5 battery: 9'
2016-07-26 07:40:14  EVENT: RFXtrx.Type: Viking 02811 id: 9984 ' temperature: +30.7 deg C signal: 4 battery: 9'
2016-07-26 07:40:16  EVENT: RFXtrx.Type: Viking 02811 id: 41216 ' temperature: +20.4 deg C signal: 4 battery: 9'
2016-07-26 07:40:16  EVENT: RFXtrx.Type: THC238/268,THN132,THWR288,THRN122,THN122,AW129/131 id: 16897 ' temperature: +20.6 deg C signal: 4 battery: 9'
2016-07-26 07:40:17  EVENT: RFXtrx.Type: Viking 02811 id: 62976 ' temperature: -17.5 deg C signal: 4 battery: 9'
2016-07-26 07:40:17  EVENT: RFXtrx.Type: Viking 02811 id: 26368 ' temperature: +20.4 deg C signal: 5 battery: 9'
2016-07-26 07:40:18  EVENT: RFXtrx.Type: THGN122/123, THGN132, THGR122/228/238/268 id: 58881 ' temperature: +25.6 deg C humidity: 63 %RH status: normal signal: 6 battery: 9'
2016-07-26 07:40:19  EVENT: RFXtrx.Type: Viking 02811 id: 13312 ' temperature: +21.2 deg C signal: 6 battery: 9'
2016-07-26 07:40:23  EVENT: RFXtrx.Type: Viking 02811 id: 57088 ' temperature: +22.1 deg C signal: 5 battery: 9'
2016-07-26 07:40:23  INFO: socket.error 1 timed out
2016-07-26 07:40:23  ERROR: Connection with NetHomeServer TCP interface failed
2016-07-26 07:40:23  EVENT: OpenNetHome.Connection with NetHomeServer TCP interface failed

Btw timeout for lost sensors is set to 120 seconds, so 20 minutes.

[Mastiff]

THANK GOD! It wasn't the new computer that I have up on the net, that would have been a problem because it's the media server, and the motherboard doesn't have room for another network card. I have been running for an hour, and it happened again. Here's the log:

Code: Select all

09:01:41   Tellstick.FineOffset|1261 'Temp|22.2'
09:01:44   RFXtrx.Type: Viking 02811 id: 28416 ' temperature: +21.2 deg C signal: 5 battery: 9'
09:01:44   RFXtrx.Type: AC address: 008d303b unit: 03 command: off ' level: 0 signal: 5'
09:01:45   RFXtrx.Type: Viking 02811 id: 6144 ' temperature: +7.2 deg C signal: 5 battery: 9'
09:01:46   Tellstick.FineOffset|1048 'Temp|7.2'
09:01:47   RFXtrx.Type: Viking 02811 id: 7936 ' temperature: +22.8 deg C signal: 5 battery: 9'
09:01:48   RFXtrx.Type: AC address: 008d303a unit: 11 command: off ' level: 0 signal: 6'
09:01:50   RFXtrx.Type: Viking 02811 id: 9984 ' temperature: +30.7 deg C signal: 4 battery: 9'
09:01:52   RFXtrx.Type: Viking 02811 id: 41216 ' temperature: +20.4 deg C signal: 4 battery: 9'
09:01:52            **********************************
09:01:52            **Ny temperatursensor i RFXtrx-en**
09:01:52            **********************************
09:01:52            ************* 41216 *************
09:01:52            **********************************
09:01:52   Tellstick.FineOffset|1185 'Temp|20.4'
09:01:52            **********************************
09:01:52            **Ny temperatursensor i Tellsticken**
09:01:52            **********************************
09:01:52            ************* 1185 *************
09:01:52            **********************************
09:01:53   RFXtrx.Type: Viking 02811 id: 26368 ' temperature: +20.4 deg C signal: 5 battery: 9'
09:01:53   RFXtrx.Type: AC address: 008d303b unit: 02 command: off ' level: 0 signal: 6'
09:01:55   RFXtrx.Type: Viking 02811 id: 32256 ' temperature: +20.3 deg C signal: 5 battery: 9'
09:01:55   RFXtrx.Type: AC address: 008d303b unit: 01 command: off ' level: 0 signal: 6'
09:01:58   RFXtrx.Type: Viking 02811 id: 57088 ' temperature: +22.1 deg C signal: 5 battery: 9'
09:01:59   RFXtrx.Type: AC address: 012e77fe unit: 01 command: off ' level: 0 signal: 6'
09:02:00   RFXtrx.Type: Viking 02811 id: 45056 ' temperature: +20.5 deg C signal: 5 battery: 9'
09:02:00   RFXtrx.Type: AC address: 008d303b unit: 04 command: off ' level: 0 signal: 5'
09:02:04   RFXtrx.Type: Viking 02811 id: 32768 ' temperature: +20.2 deg C signal: 5 battery: 9'
09:02:08   RFXtrx.Type: Viking 02811 id: 43776 ' temperature: +21.9 deg C signal: 5 battery: 9'
09:02:08   RFXtrx.Type: AC address: 008d303a unit: 05 command: off ' level: 0 signal: 6'
09:02:10   RFXtrx.Type: THC238/268,THN132,THWR288,THRN122,THN122,AW129/131 id: 16897 ' temperature: +20.7 deg C signal: 4 battery: 9'
09:02:13   RFXtrx.Type: THGN122/123, THGN132, THGR122/228/238/268 id: 58881 ' temperature: +25.9 deg C humidity: 64 %RH status: normal signal: 6 battery: 9'
09:02:13   RFXtrx.Type: Viking 02811 id: 6400 ' temperature: +22.0 deg C signal: 5 battery: 9'
09:02:14   RFXtrx.Type: Viking 02811 id: 2048 ' temperature: +22.2 deg C signal: 4 battery: 9'
09:02:18   RFXtrx.Type: Viking 02811 id: 60160 ' temperature: +17.6 deg C signal: 4 battery: 9'
09:02:22   RFXtrx.Type: Viking 02811 id: 3328 ' temperature: +22.4 deg C signal: 5 battery: 9'
09:02:22   RFXtrx.Type: AC address: 008d303a unit: 03 command: off ' level: 0 signal: 5'
09:02:28   RFXtrx.Type: Viking 02811 id: 60672 ' temperature: +22.2 deg C signal: 5 battery: 9'
09:02:31   ('OpenNetHome', 'Checking connection with...', 'Tellstick Duo', 4320, 4207)
09:02:32   RFXtrx.Type: Viking 02811 id: 28416 ' temperature: +21.2 deg C signal: 5 battery: 9'
09:02:33   RFXtrx.Type: Viking 02811 id: 51200 ' temperature: +22.0 deg C signal: 4 battery: 9'
09:02:33   RFXtrx.Type: Viking 02811 id: 6144 ' temperature: +7.2 deg C signal: 5 battery: 9'
09:02:35   RFXtrx.Type: Viking 02811 id: 7936 ' temperature: +22.8 deg C signal: 5 battery: 9'
09:02:38   RFXtrx.Type: Viking 02811 id: 9984 ' temperature: +30.7 deg C signal: 4 battery: 9'
09:02:40   RFXtrx.Type: Viking 02811 id: 41216 ' temperature: +20.4 deg C signal: 4 battery: 9'
09:02:40            **********************************
09:02:40            **Ny temperatursensor i RFXtrx-en**
09:02:40            **********************************
09:02:40            ************* 41216 *************
09:02:40            **********************************
09:02:41   RFXtrx.Type: Viking 02811 id: 62976 ' temperature: -17.4 deg C signal: 4 battery: 9'
09:02:41   RFXtrx.Type: Viking 02811 id: 26368 ' temperature: +20.4 deg C signal: 5 battery: 9'
09:02:42   RFXtrx.Type: Viking 02811 id: 32256 ' temperature: +20.4 deg C signal: 5 battery: 9'
09:02:44   RFXtrx.Type: Viking 02811 id: 13312 ' temperature: +22.1 deg C signal: 5 battery: 9'
09:02:47   RFXtrx.Type: Viking 02811 id: 57088 ' temperature: +22.2 deg C signal: 5 battery: 9'
09:02:48   RFXtrx.Type: Viking 02811 id: 45056 ' temperature: +20.5 deg C signal: 5 battery: 9'
09:02:48   RFXtrx.Type: AC address: 008d303b unit: 04 command: off ' level: 0 signal: 6'
09:02:48   RFXtrx.Type: Viking 02811 id: 59904 ' temperature: +17.0 deg C signal: 4 battery: 9'
09:02:50   RFXtrx.Type: THC238/268,THN132,THWR288,THRN122,THN122,AW129/131 id: 16897 ' temperature: +20.7 deg C signal: 4 battery: 9'
09:02:52   RFXtrx.Type: THGN122/123, THGN132, THGR122/228/238/268 id: 58881 ' temperature: +25.9 deg C humidity: 64 %RH status: normal signal: 6 battery: 9'
09:02:52   RFXtrx.Type: Viking 02811 id: 32768 ' temperature: +20.2 deg C signal: 5 battery: 9'
09:02:55   socket.error 1 timed out
09:02:56   RFXtrx.Type: Viking 02811 id: 43776 ' temperature: +21.9 deg C signal: 5 battery: 9'
09:02:56   RFXtrx.Type: AC address: 008d303a unit: 05 command: off ' level: 0 signal: 6'
09:03:01   RFXtrx.Type: Viking 02811 id: 6400 ' temperature: +22.0 deg C signal: 5 battery: 9'
09:03:01   RFXtrx.Type: AC address: 008d303a unit: 06 command: off ' level: 0 signal: 6'
09:03:02   RFXtrx.Type: Viking 02811 id: 2048 ' temperature: +22.3 deg C signal: 5 battery: 9'
09:03:06   RFXtrx.Type: Viking 02811 id: 60160 ' temperature: +17.7 deg C signal: 4 battery: 9'
09:03:10   RFXtrx.Type: Viking 02811 id: 3328 ' temperature: +22.4 deg C signal: 5 battery: 9'
09:03:16   RFXtrx.Type: Viking 02811 id: 60672 ' temperature: +22.2 deg C signal: 5 battery: 9'
09:03:20   RFXtrx.Type: Viking 02811 id: 28416 ' temperature: +21.2 deg C signal: 5 battery: 9'
09:03:21   RFXtrx.Type: Viking 02811 id: 51200 ' temperature: +22.0 deg C signal: 4 battery: 9'
09:03:21   RFXtrx.Type: AC address: 008d303a unit: 04 command: off ' level: 0 signal: 5'
09:03:23   RFXtrx.Type: Viking 02811 id: 7936 ' temperature: +22.8 deg C signal: 5 battery: 9'
09:03:26   RFXtrx.Type: Viking 02811 id: 9984 ' temperature: +30.7 deg C signal: 4 battery: 9'
09:03:26   RFXtrx.Type: AC address: 008d303b unit: 06 command: off ' level: 0 signal: 6'
09:03:28   RFXtrx.Type: Viking 02811 id: 41216 ' temperature: +20.4 deg C signal: 4 battery: 9'
09:03:28            **********************************
09:03:28            **Ny temperatursensor i RFXtrx-en**
09:03:28            **********************************
09:03:28            ************* 41216 *************
09:03:28            **********************************
09:03:28   RFXtrx.Type: THC238/268,THN132,THWR288,THRN122,THN122,AW129/131 id: 16897 ' temperature: +20.7 deg C signal: 4 battery: 9'
09:03:29   RFXtrx.Type: Viking 02811 id: 26368 ' temperature: +20.4 deg C signal: 5 battery: 9'
09:03:29   RFXtrx.Type: Viking 02811 id: 62976 ' temperature: -17.4 deg C signal: 4 battery: 9'
09:03:31   RFXtrx.Type: THGN122/123, THGN132, THGR122/228/238/268 id: 58881 ' temperature: +25.9 deg C humidity: 64 %RH status: normal signal: 6 battery: 9'
09:03:32   ('OpenNetHome', 'Checking connection with...', 'Tellstick Duo', 4393, 4320)
09:03:32   RFXtrx.Type: Viking 02811 id: 13312 ' temperature: +22.1 deg C signal: 5 battery: 9'
09:03:32   RFXtrx.Type: AC address: 008d303a unit: 07 command: off ' level: 0 signal: 6'
09:03:35   RFXtrx.Type: Viking 02811 id: 57088 ' temperature: +22.2 deg C signal: 5 battery: 9'
09:03:36   RFXtrx.Type: Viking 02811 id: 45056 ' temperature: +20.5 deg C signal: 5 battery: 9'
09:03:40   RFXtrx.Type: Viking 02811 id: 32768 ' temperature: +20.2 deg C signal: 5 battery: 9'
09:03:41   RFXtrx.Type: AC address: 008d303b unit: 05 command: off ' level: 0 signal: 6'
09:03:44   RFXtrx.Type: Viking 02811 id: 43776 ' temperature: +21.9 deg C signal: 5 battery: 9'
09:03:49   RFXtrx.Type: Viking 02811 id: 6400 ' temperature: +22.0 deg C signal: 5 battery: 9'
09:03:49   RFXtrx.Type: AC address: 008d303a unit: 06 command: off ' level: 0 signal: 6'
09:03:50   RFXtrx.Type: Viking 02811 id: 2048 ' temperature: +22.3 deg C signal: 5 battery: 9'
09:03:54   RFXtrx.Type: Viking 02811 id: 60160 ' temperature: +17.7 deg C signal: 4 battery: 9'
09:03:58   RFXtrx.Type: Viking 02811 id: 3328 ' temperature: +22.4 deg C signal: 5 battery: 9'
09:04:00   socket.error 1 timed out
09:04:00   Connection with NetHomeServer TCP interface failed
09:04:00   OpenNetHome.Connection with NetHomeServer TCP interface failed
09:04:00   Keep Alive thread ended
09:04:04   RFXtrx.Type: Viking 02811 id: 60672 ' temperature: +22.2 deg C signal: 5 battery: 9'
09:04:05   OpenNetHome.Connection with NetHomeServer TCP interface established

I had pings going on at the same time in Visual Ping with 100 ms wait, both to 127.0.0.1 and 192.168.0.1, and there was no dropped packages at all when this happened or any other time. Also I have the USB devices on an USB over TCPIP connection, and if the network falls as much as a second (probably much less) they will all disconnect, which doesn't happen here. I think I will stop the server and put an extra network card for this VM into it, a special network card that's not shared with anything.

[Mastiff]

Wow, it happened again while I was writing the previous message. So something is very funky here.

Edit: And again. It resets the plug-in, but it doesn't fix it.

[Mastiff]

I obviuosly need to have my head examined... I had a separate card in the server/host already, dedicated to the automation VM. I just wasn't using it. So now I have started using it, and we'll see if that changes anything. Stay tuned for more episodes of "L├Âdder"! (I do remember correctly that you're at an age where you remember that series, right? Or "Soap", as it was originally called. In Norway it was "Forviklingar", which would be something like "Entanglements".)

[krambriw]

INFO: socket.error 1 timed out

This indicates that there is a time out problem in the socket connection. I have right now no clue if there is a possibility to have a longer such, needs to be investigated

Also, sensor time outs are handled separately and has nothing to do with this problem

[Mastiff]

I thought so about the sensors. In the meantime after an hour or so without problems it started to happen again with the separate network card, so it's not that.

[krambriw]

The time out is already set to a generous value. When the plugin starts it is 5 seconds to start and then re-configures itself to the value you have configured (in your case 60 seconds). Googling a bit shows that this problem is a network issue and cannot be solved by the client software. We are already trying re-connections etc if this happens.

You mentioned that the software has worked for long time without any problems. Software (like the plugin python software) does not suddenly break down. Something must have happened in the operating system affecting the network connectivity. When this happens, have you looked in the log of ONH?

[Mastiff]

Not before I haven't, but I have now! Here's something:

Code: Select all

2016.07.26 11:25:44.855 INFO:Connection from 127.0.0.1 (TCPCommandPort 6377, TID22) 
2016.07.26 11:25:44.859 INFO:Disconnected from 127.0.0.1 (TCPCommandPort$Session 6378, TID419) 
2016.07.26 11:25:44.876 INFO:Connection from 127.0.0.1 (TCPCommandPort 6379, TID22) 
2016.07.26 11:25:44.878 INFO:Disconnected from 127.0.0.1 (TCPCommandPort$Session 6380, TID420) 

Code: Select all

2016.07.26 11:25:20.569 INFO:Connection from 127.0.0.1 (TCPCommandPort 6325, TID22) 
2016.07.26 11:25:20.577 WARNING:Failed reading from socket in TCPCommandPort java.net.SocketException: Software caused connection abort: recv failed (TCPCommandPort$Session 6326, TID415) 
2016.07.26 11:25:20.580 INFO:Disconnected from 127.0.0.1 (TCPCommandPort$Session 6327, TID415) 
2016.07.26 11:25:20.605 FINE:Received from Tellstick: +Wclass:sensor;protocol:fineoffset;data:4AB0D9FF58; (Tellstick 6328, TID24) 
2016.07.26 11:25:21.6 FINE:Received from Tellstick: +T (Tellstick 6329, TID24) 

It seems to have a lot of disconnects here. Some are messing up the plug-in, but not all. Should I try to update Java and see if something happens?

And would you like the full log file?

Edit: Not much info about that error on Google, I found this:

http://stackoverflow.com/questions/1359 ... ecv-failed

But I'm not really getting much help from it.

Oh, and you're right, stuff doesn't just go bad. I have no idea why this happened, but I have been working a lot on the attic lately, getting the technical stuff ready for the buyer. Maybe I should go up there and pull and reseat all the network cables? Mind you, the server is in a different room, and that hasn't been touched, so the problem isn't directly on the server.

[Mastiff]

I'm updating Java now, even though I know it hasn't "gone bad". I will also restart my Asus wifi router, just in case that har anything to do with it, I moved the cable and some stuff, but I can't remember if that was before or after this started.

[krambriw]

I think the problem is with ONH, it looks similar to the problems I reported long time ago

I do think you should post the log sections + eventually complete log to Stefan at the ONH forum

[Mastiff]

OK, thanks! I will. I'll just copy some of the observations from this thread.

[Mastiff]

Two hours without problems after the Java update and the restart of the Wifi router. Crossing my fingers...

Edit: Nope, back again. I should have held my big mouth shut.

[krambriw]

If you are running both EG and ONH in the same machine (virtual or non-virtual) try to see if it works with localhost as host name (On most platforms, this will take a shortcut around a couple of layers of network code and be quite a bit faster).

[Mastiff]

Thanks a lot, I'll try that! I did a full restart of the host, with half a minute without power (instead of just a reboot), and it has behaved for half an hour now. But of course that's happened before. I won't change anything else right now, but in a couple of hours I'll use the localhost trick.