3.61.5.20211113 - Transport error on xmlrpc call listBidcosInterfaces. Error in XmlRpcClient::writeRequest: write error

[aksnet]

Hallo,
seit dem update auf RaspberryMatic-3.61.5.20211113-rpi0.zip verhält sich mein Pi Zero als LAN-Gateway sehr seltsam.
Ich habe die SD-Karte nochmal neugeflasht und keine Veränderungen vorgenommen.

Direkt nach dem ersten booten funktioniert das HM Modul noch:

root@homematic-raspi:~# echo "load tclrpc.so; puts [xmlrpc http://127.0.0.1:32001/ listBidcosInterfaces]" | tclsh
{ADDRESS OEQ0304789 CONNECTED 1 DEFAULT 1 DESCRIPTION {} DUTY_CYCLE 1 FIRMWARE_VERSION 2.8.6 TYPE CCU2}

sobald man auf Gateway Mode umschaltet via "touch /usr/local/HMLGW" und einen reboot macht geht nichts mehr. Also kein Gerät kann mehr erreicht werden weil das HM Modul nicht mehr erkannt wird. So teils zumindest:

root@hmlgw:~# echo "load tclrpc.so; puts [xmlrpc http://127.0.0.1:32001/ listBidcosInterfaces]" | tclsh
Transport error on xmlrpc call listBidcosInterfaces to http://127.0.0.1:32001/: Error in XmlRpcClient::writeRequest: write error (error 111).

Achtet mal auf die Firmware!

Hier mehr Details dazu, als "normale" CCU:

root@homematic-raspi:~# cat /var/hm_mode
HM_HMIP_ADDRESS='0xBF55C2'
HM_HMIP_ADDRESS_ACTIVE='0xBF55C2'
HM_HMIP_DEV='HM-MOD-RPI-PCB'
HM_HMIP_DEVNODE='/dev/raw-uart'
HM_HMIP_DEVTYPE='GPIO'
HM_HMIP_SERIAL='OEQ0304789'
HM_HMIP_SGTIN='3014F711A061A7D7098E0E95'
HM_HMIP_VERSION='2.8.6'
HM_HMRF_ADDRESS='0x583ABE'
HM_HMRF_ADDRESS_ACTIVE='0x583ABE'
HM_HMRF_DEV='HM-MOD-RPI-PCB'
HM_HMRF_DEVNODE='/dev/raw-uart'
HM_HMRF_DEVTYPE='GPIO'
HM_HMRF_SERIAL='OEQ0304789'
HM_HMRF_VERSION='2.8.6'
HM_HOST='rpi0w'
HM_LED_GREEN='/sys/class/leds/led0'
HM_LED_GREEN_MODE1='mmc0'
HM_LED_GREEN_MODE2='heartbeat'
HM_LED_RED='/sys/class/leds/led1'
HM_LED_RED_MODE1='timer'
HM_LED_RED_MODE2='mmc0'
HM_LED_YELLOW=''
HM_LED_YELLOW_MODE1='none'
HM_LED_YELLOW_MODE2='none'
HM_MODE='NORMAL'
HM_RTC='mcp7941x'
root@homematic-raspi:~#

als GW:

root@homematic-raspi:~# cat /var/hm_mode
HM_HMIP_ADDRESS='0xB0BD30'
HM_HMIP_ADDRESS_ACTIVE='0xB0BD30'
HM_HMIP_DEV='HM-MOD-RPI-PCB'
HM_HMIP_DEVNODE='/dev/raw-uart'
HM_HMIP_DEVTYPE='GPIO'
HM_HMIP_SERIAL='OEQ0304789'
HM_HMIP_SGTIN='3014F711A061A7D7098E0E95'
HM_HMIP_VERSION='1.4.1'
HM_HMRF_ADDRESS='0x583ABE'
HM_HMRF_ADDRESS_ACTIVE='0x583ABE'
HM_HMRF_DEV='HM-MOD-RPI-PCB'
HM_HMRF_DEVNODE='/dev/raw-uart'
HM_HMRF_DEVTYPE='GPIO'
HM_HMRF_SERIAL='OEQ0304789'
HM_HMRF_VERSION='1.4.1'
HM_HOST='rpi0w'
HM_LED_GREEN='/sys/class/leds/led0'
HM_LED_GREEN_MODE1='mmc0'
HM_LED_GREEN_MODE2='heartbeat'
HM_LED_RED='/sys/class/leds/led1'
HM_LED_RED_MODE1='timer'
HM_LED_RED_MODE2='mmc0'
HM_LED_YELLOW=''
HM_LED_YELLOW_MODE1='none'
HM_LED_YELLOW_MODE2='none'
HM_MODE='HM-LGW'
HM_RTC='mcp7941x'
root@homematic-raspi:~#

als normale CCU

root@hmlgw:~# cat /var/log/messages
Nov 30 13:32:52 homematic-raspi user.info root: Updating RF Lan Gateway Coprocessor Firmware
Nov 30 13:32:52 homematic-raspi user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Nov 30 13:32:52 homematic-raspi user.info root: Updating RF Lan Gateway Firmware
Nov 30 13:32:52 homematic-raspi user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Nov 30 13:32:52 homematic-raspi user.info kernel: [ 43.664323] eq3loop: created slave mmd_hmip
Nov 30 13:32:52 homematic-raspi user.info kernel: [ 43.668569] eq3loop: created slave mmd_bidcos
Nov 30 13:32:55 homematic-raspi user.info kernel: [ 46.208466] eq3loop: eq3loop_open_slave() mmd_bidcos
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.756843] eq3loop: eq3loop_open_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.757718] eq3loop: eq3loop_close_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.772530] eq3loop: eq3loop_open_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.773267] eq3loop: eq3loop_close_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.778473] eq3loop: eq3loop_open_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.788086] eq3loop: eq3loop_close_slave() mmd_hmip
Nov 30 13:34:00 homematic-raspi user.info kernel: [ 110.837056] eq3loop: eq3loop_open_slave() mmd_hmip
Nov 30 12:34:36 homematic-raspi daemon.info : starting pid 939, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Nov 30 13:34:37 homematic-raspi user.info monit[939]: Starting Monit 5.27.2 daemon with http interface at /var/run/monit.sock
Nov 30 13:34:37 homematic-raspi user.info monit[939]: 'homematic-raspi' Monit 5.27.2 started
Nov 30 13:34:37 homematic-raspi user.err monit[939]: 'chronyd' process is not running
Nov 30 13:34:37 homematic-raspi user.info monit[939]: 'chronyd' trying to restart
Nov 30 13:34:37 homematic-raspi user.info monit[939]: 'chronyd' restart: '/etc/init.d/S46chrony restart'
Nov 30 13:34:38 homematic-raspi daemon.err ntpdate[954]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:34:38 homematic-raspi daemon.err ntpdate[955]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:35:08 homematic-raspi user.err monit[939]: 'chronyd' failed to restart (exit status 0) -- '/etc/init.d/S46chrony restart': Stopping chrony: FAIL Starting chrony: using default NTPs, could not sync to '0.de.pool.ntp.org 1.de.pool.ntp.org 2.de.pool.ntp.org 3.de.pool.ntp.org', FAIL

als GW:

root@hmlgw:~# cat /var/log/messages
Nov 30 13:43:43 homematic-raspi syslog.info syslogd started: BusyBox v1.33.1
Nov 30 13:43:43 homematic-raspi user.notice kernel: klogd started: BusyBox v1.33.1 (2021-11-13 09:58:25 UTC)
Nov 30 13:43:43 homematic-raspi daemon.notice wpa_supplicant[343]: wlan0: Trying to associate with SSID 'aksnet WLAN'
Nov 30 13:43:43 homematic-raspi user.info kernel: [ 28.346784] NET: Registered protocol family 10
Nov 30 13:43:43 homematic-raspi user.info kernel: [ 28.353949] Segment Routing with IPv6
Nov 30 13:43:44 homematic-raspi daemon.notice wpa_supplicant[343]: wlan0: Associated with 34:31:c4:c3:2f:49
Nov 30 13:43:44 homematic-raspi daemon.notice wpa_supplicant[343]: wlan0: CTRL-EVENT-CONNECTED - Connection to 34:31:c4:c3:2f:49 completed [id=0 id_str=]
Nov 30 13:43:44 homematic-raspi daemon.notice wpa_supplicant[343]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Nov 30 13:43:44 homematic-raspi user.info kernel: [ 28.779973] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Nov 30 13:43:44 homematic-raspi daemon.notice wpa_supplicant[343]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Nov 30 13:43:44 homematic-raspi user.info firewall: configuration set
Nov 30 13:43:44 homematic-raspi daemon.info ifplugd(wlan0)[425]: ifplugd 0.28 initializing.
Nov 30 13:43:44 homematic-raspi daemon.info ifplugd(wlan0)[425]: Using interface wlan0/B8:27:EB:F2:14:D9 with driver <brcmfmac> (version: 7.45.98.94)
Nov 30 13:43:44 homematic-raspi daemon.info ifplugd(wlan0)[425]: Using detection mode: wireless extension
Nov 30 13:43:44 homematic-raspi daemon.info ifplugd(wlan0)[425]: Initialization complete, link beat detected.
Nov 30 13:43:45 homematic-raspi daemon.info ifplugd(wlan0)[425]: Executing '/etc/ifplugd/ifplugd.action wlan0 up'.
Nov 30 13:43:45 homematic-raspi daemon.warn ifplugd(wlan0)[425]: client: ifup: interface wlan0 already configured
Nov 30 13:43:45 homematic-raspi daemon.info ifplugd(wlan0)[425]: Program executed successfully.
Nov 30 13:43:45 homematic-raspi daemon.err ntpdate[431]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:43:45 homematic-raspi daemon.err ntpdate[432]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:43:45 homematic-raspi user.info kernel: [ 30.104045] raw-uart raw-uart: Reset radio module
Nov 30 13:43:56 homematic-raspi user.info update-coprocessor: Version: 2.8.6
Nov 30 13:43:56 homematic-raspi user.info update-coprocessor: Update necessary, installed: 2.8.6, avaiable 1.4.1
Nov 30 13:44:19 homematic-raspi user.info update-coprocessor: Firmwareupdate successfull
Nov 30 13:44:23 homematic-raspi daemon.err xinetd[522]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Nov 30 13:44:23 homematic-raspi daemon.crit xinetd[522]: 522 {init_services} no services. Exiting...
Nov 30 13:44:24 homematic-raspi auth.info sshd[552]: Server listening on 0.0.0.0 port 22.
Nov 30 13:44:24 homematic-raspi auth.info sshd[552]: Server listening on :: port 22.
Nov 30 12:44:25 homematic-raspi daemon.info : starting pid 653, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Nov 30 13:44:25 homematic-raspi user.info monit[653]: Starting Monit 5.27.2 daemon with http interface at /var/run/monit.sock
Nov 30 13:44:25 homematic-raspi user.info monit[653]: 'homematic-raspi' Monit 5.27.2 started
Nov 30 13:44:26 homematic-raspi user.err monit[653]: 'chronyd' process is not running
Nov 30 13:44:26 homematic-raspi user.info monit[653]: 'chronyd' trying to restart
Nov 30 13:44:26 homematic-raspi user.info monit[653]: 'chronyd' restart: '/etc/init.d/S46chrony restart'
Nov 30 13:44:27 homematic-raspi daemon.err ntpdate[670]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:44:27 homematic-raspi daemon.err ntpdate[671]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:44:45 homematic-raspi auth.info sshd[672]: Accepted password for root from 192.168.0.209 port 61135 ssh2
Nov 30 13:44:46 homematic-raspi auth.info sshd[674]: Accepted password for root from 192.168.0.209 port 61136 ssh2
Nov 30 13:44:56 homematic-raspi user.err monit[653]: 'chronyd' failed to restart (exit status 0) -- '/etc/init.d/S46chrony restart': Stopping chrony: FAIL Starting chrony: using default NTPs, could not sync to '0.de.pool.ntp.org 1.de.pool.ntp.org 2.de.pool.ntp.org 3.de.pool.ntp.org', FAIL
Nov 30 13:45:09 homematic-raspi user.err monit[653]: 'irqbalanceEnabled' status failed (1) -- no output
Nov 30 13:45:09 homematic-raspi user.err monit[653]: 'eth0Exists' status failed (1) -- no output
Nov 30 13:45:09 homematic-raspi user.err monit[653]: 'chronyd' service restarted 1 times within 1 cycles(s) - exec
Nov 30 13:45:09 homematic-raspi user.info monit[653]: 'chronyd' exec: '/bin/triggerAlarm.tcl Chrony-NTP restarted WatchDog: chronyd-restart true'
Nov 30 13:45:09 homematic-raspi user.err monit[653]: 'chronyd' process is not running
Nov 30 13:45:09 homematic-raspi user.info monit[653]: 'chronyd' trying to restart
Nov 30 13:45:09 homematic-raspi user.info monit[653]: 'chronyd' restart: '/etc/init.d/S46chrony restart'
Nov 30 13:45:11 homematic-raspi daemon.err ntpdate[728]: name server cannot be used: Temporary failure in name resolution (-3)
Nov 30 13:45:11 homematic-raspi daemon.err ntpdate[729]: name server cannot be used: Temporary failure in name resolution (-3)

Danke
Andreas

[jmaus]

seit dem update auf RaspberryMatic-3.61.5.20211113-rpi0.zip verhält sich mein Pi Zero als LAN-Gateway sehr seltsam.
Ich habe die SD-Karte nochmal neugeflasht und keine Veränderungen vorgenommen.

Es wurde hier schon mehrmals thematisiert, das ein Update eines als reiner LAN-Gateway betriebenes RaspberryMatic keinerlei Sinn ergibt. An der LAN Gateway Funktion hat sich seit vielen Jahren nichts verändert und wird sich vmtl. auch nie wieder was ändern. Deshalb einfach bei der alten Version bleiben denn du hast keinerlei Gewinn dadurch das du auf eine neuere Version updatest.

[jp112sdl]

So teils zumindest:

root@hmlgw:~# echo "load tclrpc.so; puts [xmlrpc http://127.0.0.1:32001/ listBidcosInterfaces]" | tclsh
Transport error on xmlrpc call listBidcosInterfaces to http://127.0.0.1:32001/: Error in XmlRpcClient::writeRequest: write error (error 111).

Diese Abfrage kann auf einem Gateway nicht funktionieren.
Die Ausgabe ist somit korrekt.

Achtet mal auf die Firmware!

Hab ich. Und was ist die Frage dazu?

V 1.4.1 ist nun mal die letzt non-DualCoPro Version, mit der ein HM-MOD-UART als Gateway betrieben werden kann.


Ansonsten kann ich mich Jens nur anschließen, dass

ein Update eines als reiner LAN-Gateway betriebenes RaspberryMatic keinerlei Sinn ergibt

[aksnet]

Hallo Jens, hallo Jérôme,
danke für die Antworten.

seit dem update auf RaspberryMatic-3.61.5.20211113-rpi0.zip verhält sich mein Pi Zero als LAN-Gateway sehr seltsam.
Ich habe die SD-Karte nochmal neugeflasht und keine Veränderungen vorgenommen.

Es wurde hier schon mehrmals thematisiert, das ein Update eines als reiner LAN-Gateway betriebenes RaspberryMatic keinerlei Sinn ergibt. An der LAN Gateway Funktion hat sich seit vielen Jahren nichts verändert und wird sich vmtl. auch nie wieder was ändern. Deshalb einfach bei der alten Version bleiben denn du hast keinerlei Gewinn dadurch das du auf eine neuere Version updatest.

Der Gedanke für ein Update kam, weil ich 3.61 auf dem Zero 2 installieren wollte und zuerst meinen normalen Zero 1 geupdated habe. Als der dann nicht mehr ging habe ich mit dem Zero 2 bisher gar nicht rumgespielt.

So teils zumindest:

root@hmlgw:~# echo "load tclrpc.so; puts [xmlrpc http://127.0.0.1:32001/ listBidcosInterfaces]" | tclsh
Transport error on xmlrpc call listBidcosInterfaces to http://127.0.0.1:32001/: Error in XmlRpcClient::writeRequest: write error (error 111).

Diese Abfrage kann auf einem Gateway nicht funktionieren.
Die Ausgabe ist somit korrekt.

Mir war bis dato nicht bewusst, dass der LAN Modus diese Infoanzeige deaktiviert/ändert und auf der Suche nach dem Problem bin ich da drüber gestolpert.

Achtet mal auf die Firmware!

Hab ich. Und was ist die Frage dazu?

V 1.4.1 ist nun mal die letzt non-DualCoPro Version, mit der ein HM-MOD-UART als Gateway betrieben werden kann.

Auch hier kannte ich bisher immer nur die Firmware 1.4.1. Eine Firmware 2.8.6 ist mir das erste mal aufgefallen. Und das sich die Firmware mit dem Modus ändert, war mir bis heute auch neu.

Wie auch immer, auf dem Pi Zero 1 W mit RaspberryMatic-3.61.5.20211113-rpi0.zip bekomme ich keine Verbindung, auch nicht mit allen Tricks aus meinem anderen Thread:
viewtopic.php?f=65&t=70159&start=20

Ich bin nun auf Version RaspberryMatic-3.57.5.20210424-rpi0.zip und hier kann ich zumindest mit einem 2. "fake" GW das eigentliche GW wieder zu einer Verbindung überreden.

Danke und Gruß
Andreas