HM-LGW Peer Unreachable after update from 0.6.7-1545 to 0.6.13-864

goebelmeier · January 13, 2017, 10:03pm

I’ve just updated homegear on my RPi running Debian jessie. Afterwards i can’t control my Bidcos rollershutters using openHAB anymore. Any ideas?

01/13/17 22:17:05.987 Starting Homegear...
01/13/17 22:17:05.987 Homegear version 0.6.13-864
01/13/17 22:17:05.987 Git commit SHA of libhomegear-base: -
01/13/17 22:17:05.987 Git branch of libhomegear-base:     -
01/13/17 22:17:05.987 Git commit SHA of Homegear:         -
01/13/17 22:17:05.987 Git branch of Homegear:             -
01/13/17 22:17:05.988 Info: Setting allowed core file size to "4294967295" for user with id 0 and group with id 0.
01/13/17 22:17:05.988 Info: Core file size now is "4294967295".
01/13/17 22:17:05.988 Info: Setting maximum thread priority to "4294967295" for user with id 0 and group with id 0.
01/13/17 22:17:05.988 Info: Maximum thread priority now is "4294967295".
01/13/17 22:17:06.010 Info: Backing up database...
01/13/17 22:17:16.320 Initializing database...
01/13/17 22:17:16.327 Info: Loading family module mod_homematicwired.so
01/13/17 22:17:16.350 Critical: Could not open module "/var/lib/homegear/modules/mod_homematicwired.so". Symbol "getFamilyId" not found.
01/13/17 22:17:16.351 Info: Disposing family module mod_homematicwired.so
01/13/17 22:17:16.351 Info: Loading family module mod_insteon.so
01/13/17 22:17:16.376 Critical: Could not open module "/var/lib/homegear/modules/mod_insteon.so". Symbol "getFamilyId" not found.
01/13/17 22:17:16.377 Info: Disposing family module mod_insteon.so
01/13/17 22:17:16.377 Info: Loading family module mod_max.so
01/13/17 22:17:16.402 Critical: Could not open module "/var/lib/homegear/modules/mod_max.so". Symbol "getFamilyId" not found.
01/13/17 22:17:16.403 Info: Disposing family module mod_max.so
01/13/17 22:17:16.403 Info: Loading family module mod_miscellaneous.so
01/13/17 22:17:16.409 /etc/homegear/families/miscellaneous.conf
01/13/17 22:17:16.410 Info: Loading family module mod_philipshue.so
01/13/17 22:17:16.432 Critical: Could not open module "/var/lib/homegear/modules/mod_philipshue.so". Symbol "getFamilyId" not found.
01/13/17 22:17:16.433 Info: Disposing family module mod_philipshue.so
01/13/17 22:17:16.433 Info: Loading family module mod_homematicbidcos.so
01/13/17 22:17:16.455 /etc/homegear/families/homematicbidcos.conf
01/13/17 22:17:16.458 Info: Setting up physical interfaces and GPIOs...
01/13/17 22:17:16.458 Info: Dropping privileges to user homegear (107) and group homegear (110)
01/13/17 22:17:16.459 Info: Homegear is (now) running as user with id 107 and group with id 110.
01/13/17 22:17:16.460 Starting script engine server...
01/13/17 22:17:16.489 Initializing licensing controller...
01/13/17 22:17:16.490 Loading licensing controller data...
01/13/17 22:17:16.490 Loading devices...
01/13/17 22:17:16.490 Loading XML RPC devices...
01/13/17 22:17:17.029 Info: Skipping file rf_central.xml: File is not needed.
01/13/17 22:17:17.400 Loading device 4
01/13/17 22:17:17.401 Module HomeMatic BidCoS: Info: Central address set to 0xFD24BE.
01/13/17 22:17:17.402 Module HomeMatic BidCoS: Loading peer 1
01/13/17 22:17:17.411 Module HomeMatic BidCoS: Loading peer 2
01/13/17 22:17:17.420 Module HomeMatic BidCoS: Loading peer 3
01/13/17 22:17:17.429 Module HomeMatic BidCoS: Loading peer 4
01/13/17 22:17:17.438 Module HomeMatic BidCoS: Loading peer 5
01/13/17 22:17:17.447 Module HomeMatic BidCoS: Loading peer 6
01/13/17 22:17:17.456 Module HomeMatic BidCoS: Loading peer 7
01/13/17 22:17:17.465 Module HomeMatic BidCoS: Loading peer 8
01/13/17 22:17:17.473 Module HomeMatic BidCoS: Loading peer 9
01/13/17 22:17:17.482 Module HomeMatic BidCoS: Loading peer 10
01/13/17 22:17:17.491 Module HomeMatic BidCoS: Loading peer 11
01/13/17 22:17:17.501 Module HomeMatic BidCoS: Loading peer 12
01/13/17 22:17:17.510 Module HomeMatic BidCoS: Loading peer 13
01/13/17 22:17:17.519 Loading XML RPC devices...
01/13/17 22:17:17.521 Loading device 3
01/13/17 22:17:17.521 Start listening for packets...
01/13/17 22:17:17.522 Initializing RPC client...
01/13/17 22:17:17.522 Starting XML RPC server RPCServer1 listening on 0.0.0.0:2001...
01/13/17 22:17:17.525 Starting XML RPC server RPCServer2 listening on 0.0.0.0:2002, SSL enabled...
01/13/17 22:17:17.525 RPC Server (Port 2001): Info: RPC Server started listening on address 0.0.0.0 and port 2001
01/13/17 22:17:17.552 Starting XML RPC server RPCServer3 listening on 0.0.0.0:2003, SSL enabled, authentication enabled...
01/13/17 22:17:17.552 RPC Server (Port 2002): Info: RPC Server started listening on address 0.0.0.0 and port 2002
01/13/17 22:17:17.577 Starting CLI server...
01/13/17 22:17:17.577 RPC Server (Port 2003): Info: RPC Server started listening on address 0.0.0.0 and port 2003
01/13/17 22:17:17.577 Initializing event handler...
01/13/17 22:17:17.578 Loading events...
01/13/17 22:17:17.578 Starting flows server...
01/13/17 22:17:17.580 Startup complete. Waiting for physical interfaces to connect.
01/13/17 22:17:17.580 All physical interfaces are connected now.
01/13/17 22:17:17.580 Starting UPnP server...
01/13/17 22:17:17.581 Info: UPnP server: Binding to address: 192.168.4.4
01/13/17 22:17:17.582 UPnP Server: Info: Started listening.
01/13/17 22:17:24.119 RPC Server (Port 2001): Info: Connection from 192.168.4.4:42628 accepted. Client number: 8
01/13/17 22:17:24.120 RPC Server (Port 2001): Info: RPC server client id for client number 8 is: 0
01/13/17 22:17:24.121 RPC Server (Port 2001): Info: Client number 8 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:17:24.125 RPC Server (Port 2001): Info: Connection to client number 8 closed (3).
01/13/17 22:17:24.180 RPC Server (Port 2001): Info: Connection from 192.168.4.4:42630 accepted. Client number: 9
01/13/17 22:17:24.181 RPC Server (Port 2001): Info: RPC server client id for client number 9 is: 1
01/13/17 22:17:24.182 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280882:0
(String) MASTER
01/13/17 22:17:24.186 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280882:0
(String) VALUES
01/13/17 22:17:24.195 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280882:1
(String) MASTER
01/13/17 22:17:24.199 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280882:1
(String) VALUES
01/13/17 22:17:24.280 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199221:0
(String) MASTER
01/13/17 22:17:24.306 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199221:0
(String) VALUES
01/13/17 22:17:24.311 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199221:1
(String) MASTER
01/13/17 22:17:24.314 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199221:1
(String) VALUES
01/13/17 22:17:24.362 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0279855:0
(String) MASTER
01/13/17 22:17:24.365 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0279855:0
(String) VALUES
01/13/17 22:17:24.370 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0279855:1
(String) MASTER
01/13/17 22:17:24.373 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0279855:1
(String) VALUES
01/13/17 22:17:24.428 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280143:0
(String) MASTER
01/13/17 22:17:24.447 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280143:0
(String) VALUES
01/13/17 22:17:24.452 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280143:1
(String) MASTER
01/13/17 22:17:24.455 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280143:1
(String) VALUES
01/13/17 22:17:24.505 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198828:0
(String) MASTER
01/13/17 22:17:24.508 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198828:0
(String) VALUES
01/13/17 22:17:24.513 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198828:1
(String) MASTER
01/13/17 22:17:24.516 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198828:1
(String) VALUES
01/13/17 22:17:24.580 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280307:0
(String) MASTER
01/13/17 22:17:24.583 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280307:0
(String) VALUES
01/13/17 22:17:24.589 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280307:1
(String) MASTER
01/13/17 22:17:24.592 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0280307:1
(String) VALUES
01/13/17 22:17:24.643 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199194:0
(String) MASTER
01/13/17 22:17:24.646 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199194:0
(String) VALUES
01/13/17 22:17:24.652 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199194:1
(String) MASTER
01/13/17 22:17:24.655 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199194:1
(String) VALUES
01/13/17 22:17:24.704 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199852:0
(String) MASTER
01/13/17 22:17:24.707 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199852:0
(String) VALUES
01/13/17 22:17:24.712 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199852:1
(String) MASTER
01/13/17 22:17:24.716 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199852:1
(String) VALUES
01/13/17 22:17:24.765 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199735:0
(String) MASTER
01/13/17 22:17:24.768 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199735:0
(String) VALUES
01/13/17 22:17:24.773 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199735:1
(String) MASTER
01/13/17 22:17:24.776 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199735:1
(String) VALUES
01/13/17 22:17:24.826 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199025:0
(String) MASTER
01/13/17 22:17:24.829 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199025:0
(String) VALUES
01/13/17 22:17:24.834 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199025:1
(String) MASTER
01/13/17 22:17:24.837 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199025:1
(String) VALUES
01/13/17 22:17:24.890 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198967:0
(String) MASTER
01/13/17 22:17:24.893 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198967:0
(String) VALUES
01/13/17 22:17:24.898 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198967:1
(String) MASTER
01/13/17 22:17:24.901 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198967:1
(String) VALUES
01/13/17 22:17:24.968 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199850:0
(String) MASTER
01/13/17 22:17:24.971 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199850:0
(String) VALUES
01/13/17 22:17:24.976 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199850:1
(String) MASTER
01/13/17 22:17:24.979 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0199850:1
(String) VALUES
01/13/17 22:17:25.029 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198865:0
(String) MASTER
01/13/17 22:17:25.032 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198865:0
(String) VALUES
01/13/17 22:17:25.037 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198865:1
(String) MASTER
01/13/17 22:17:25.040 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: getParamset (2) Parameters:
(String) LEQ0198865:1
(String) VALUES
01/13/17 22:17:26.575 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Init queue completed. Sending peers...
01/13/17 22:17:27.011 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Peer sending completed.
01/13/17 22:17:40.107 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:17:55.110 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:17:55.390 RPC Server (Port 2001): Info: Client number 9 is calling RPC method: setValue (2) Parameters:
(String) LEQ0199852:1
(String) LEVEL
(Float) 0
01/13/17 22:17:55.392 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Sending (My-HM-LGW): 0CCDA011FD24BE277D3E020100
01/13/17 22:17:57.949 RPC Server (Port 2001): Info: Connection to client number 9 closed (3).
01/13/17 22:17:57.950 RPC Server (Port 2001): Info: Connection from 192.168.4.4:42636 accepted. Client number: 10
01/13/17 22:17:57.951 RPC Server (Port 2001): Info: RPC server client id for client number 10 is: 2
01/13/17 22:17:57.952 RPC Server (Port 2001): Info: Client number 10 is calling RPC method: setValue (2) Parameters:
(String) LEQ0199852:1
(String) LEVEL
(Float) 0
01/13/17 22:18:00.600 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
01/13/17 22:18:00.760 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 1). Retrying...
01/13/17 22:18:00.761 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Sending (My-HM-LGW): 0CCEA011FD24BE277D3E020100
01/13/17 22:18:02.763 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
01/13/17 22:18:02.764 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 1). Retrying...
01/13/17 22:18:02.864 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Sending (My-HM-LGW): 0CCEA011FD24BE277D3E020100
01/13/17 22:18:04.866 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
01/13/17 22:18:04.866 Module HomeMatic BidCoS: Info: Queue is not finished (peer: 1). Retrying...
01/13/17 22:18:04.966 Module HomeMatic BidCoS: HM-LGW "My-HM-LGW": Info: Sending (My-HM-LGW): 0CCEA011FD24BE277D3E020100
01/13/17 22:18:06.969 Module HomeMatic BidCoS: Info: Setting peer to unreachable, because the queue processing was interrupted.
01/13/17 22:18:06.969 Info: Peer 1 is unreachable.
01/13/17 22:18:07.076 Info: Peer 1 is reachable again.
01/13/17 22:18:10.116 RPC Server (Port 2001): Info: Client number 10 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:18:25.119 RPC Server (Port 2001): Info: Client number 10 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:18:40.123 RPC Server (Port 2001): Info: Client number 10 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:18:55.127 RPC Server (Port 2001): Info: Client number 10 is calling RPC method: listBidcosInterfaces (2) Parameters:
01/13/17 22:19:07.065 (Shutdown) => Stopping Homegear (Signal: 15)
01/13/17 22:19:07.065 (Shutdown) => Stopping CLI server

goebelmeier · January 13, 2017, 10:10pm

Just did a cross check… After downgrading back to 0.6.7-1545 it’s working again like a charm

sathya · January 13, 2017, 10:11pm

Hi @goebelmeier,

do you have “sendFix = true” in “homematicbidcos.conf”? If yes, try to set it to “false”. Does it work again then? I changed the meaning of sendFix, because most gateways needed it.

Cheers,

Sathya

goebelmeier · January 14, 2017, 10:57am

Hi @sathya,

initially it was commented out. I removed the comment and tried it with sendFix = false without success. Same errors as above. Afterwards i switched it to true and now it’s working.

Brgds,

Timo

sathya · January 14, 2017, 7:51pm

Hey @goebelmeier,

great it works again !

Cheers,

Sathya

sathya · January 14, 2017, 7:51pm