Redmatic läuft beim Start auf Fehler - nach Werksreset

[Roland73]

Hallo und Hilfe,

ich bin ehrlich gesagt am verzweifeln...
Ich habe am WE ein komplettes Werksreset gemacht und alle meine Aktoren wieder angelernt. Bis gestern hat auch alles funktioniert. Dummerweise habe ich heute versucht meine HausBus-Taster wieder anzulernen. Dabei hing sich die CCU leider komplett auf und ich musste den Stecker ziehen. Seitdem geht gar nichts mehr. Erneut Werksreset gemacht und Backup eingespielt (Das von gestern, welches ja funktioniert hatte).
Der Redmatic-Button ist zwar da, aber es öffnet sich ein Tab mit "Fehler".
Also Redmatic neu installiert. Jetzt öffnet sich zwar der Reiter, Redmatic geht sofort wieder auf stopped. Wie gesagt es ist noch kein Flow drauf.

Anbei das Log... Kann mir jemand helfen?

Tausend Dank.
VG
Roland

{
"ccu": {
"VERSION": "3.61.6",
"PRODUCT": "ccu3",
"PLATFORM": "rpi3-armv7l",
"deviceTypes": [
"HM-LC-Sw2-FM",
"HM-RCV-50",
"HM-WDS30-T-O",
"HmIP-BWTH",
"HmIP-FROLL",
"HmIP-PCBS",
"HMIP-PS",
"HMIP-PSM",
"HmIP-RCV-50",
"HmIP-SPDR",
"HmIP-SWO-PR",
"HmIP-WRC6",
"HMW-IO-12-Sw14-DR",
"HMW-IO-12-Sw7-DR",
"HMW-LC-Bl1-DR",
"HMW-LC-Dim1L-DR",
"HMW-LC-Sw2-DR",
"HMW-RCV-50",
"HMW-Sen-SC-12-DR"
]
},
"redmatic": "7.2.1",
"nodejs": "14.16.0",
"@node-red-contrib-themes/midnight-red": "1.4.7",
"ain2": "3.0.0",
"node-red": "1.2.9",
"npm": "7.6.3",
"node-red-contrib-alexa-home-skill": "0.1.19",
"node-red-contrib-alexa-remote2-v2": "3.10.5",
"node-red-contrib-ccu": "3.4.2",
"node-red-contrib-combine": "1.8.1",
"node-red-contrib-cron-plus": "1.5.4",
"node-red-contrib-fritz": "1.4.5",
"node-red-contrib-luxtronik2": "0.4.0",
"node-red-contrib-magichome-controller": "0.1.3",
"node-red-contrib-milight-2": "1.0.8",
"node-red-contrib-pushover": "0.2.0",
"node-red-contrib-sun-position": "1.2.4",
"node-red-dashboard": "2.28.1",
"node-red-node-email": "1.8.3",
"node-red-node-openweathermap": "0.5.0",
"node-red-node-rbe": "0.4.0",
"redmatic-led": "1.1.0",
"redmatic-webapp": "0.3.1"
}

Filesystem Size Used Available Use% Mounted on
/dev/root 975.9M 454.6M 454.1M 50% /
devtmpfs 480.9M 0 480.9M 0% /dev
tmpfs 485.4M 0 485.4M 0% /dev/shm
tmpfs 485.4M 156.0K 485.3M 0% /tmp
tmpfs 485.4M 84.0K 485.3M 0% /run
tmpfs 485.4M 576.0K 484.9M 0% /var
tmpfs 485.4M 0 485.4M 0% /media
/dev/mmcblk0p3 6.0G 356.2M 5.3G 6% /usr/local
/dev/mmcblk0p1 255.7M 38.6M 217.2M 15% /mnt/boot_par
/dev/sda1 14.9G 84.9M 14.8G 1% /media/usb1

117.6M /usr/local/addons/redmatic/var
75.7M /usr/local/addons/redmatic/lib
4.0K /usr/local/addons/redmatic/tmp

Mem: 387656K used, 606476K free, 816K shrd, 55248K buff, 147596K cached
CPU: 2% usr 0% sys 0% nic 97% idle 0% io 0% irq 0% sirq
Load average: 0.67 0.39 0.16 1/207 1703
PID PPID USER STAT VSZ %VSZ %CPU COMMAND
1702 1636 root R 3116 0% 2% top -b -n1
825 1 root S 248m 25% 0% java -Xmx128m -Dos.arch=arm -Dlog4j.configuration=file:///etc/config/log4j.xml -Dfile.encoding=ISO-8859-1 -Dgnu.io.rxtx.SerialPorts=/dev/mmd_hmip -cp /opt/HMServer/coupling/ESHBridge.jar:/opt/HMServer/HMIPServer.jar de.eq3.ccu.server.ip.HMIPServer /etc/crRFD.conf /etc/HMServer.conf
951 1 root S 40848 4% 0% /bin/ReGaHss.community -f /etc/rega.conf -l 2
799 1 root S 19188 2% 0% /bin/rfd -f /etc/config/rfd.conf -l 5
754 753 root S 16856 2% 0% /bin/hs485d -l 5 -g -i 0 -f /var/etc/hs485d.conf
762 1 root S< 11972 1% 0% /bin/multimacd -f /etc/multimacd.conf -l 5
720 689 root S 8468 1% 0% /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
721 689 root S 8460 1% 0% /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
719 689 root S 8300 1% 0% /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
718 689 root S 8300 1% 0% /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
654 1 root S 7192 1% 0% /usr/sbin/ntpd -g 0.de.pool.ntp.org 1.de.pool.ntp.org 2.de.pool.ntp.org 3.de.pool.ntp.org
689 687 root S 6676 1% 0% /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
700 1 root S 4652 0% 0% /usr/sbin/sshd
1636 720 root S 4556 0% 0% /bin/tclsh /www/addons/redmatic/log.cgi
188 1 root S 4392 0% 0% /bin/hss_led -l 6
753 1 root S 4160 0% 0% /bin/hs485dLoader -l 5 -dw /var/etc/hs485d.conf
215 1 root S 4156 0% 0% /sbin/udevd -d
1701 215 root S 4156 0% 0% /sbin/udevd -d
680 1 root S 4088 0% 0% /bin/eq3configd
945 1 root S 4068 0% 0% /bin/eq3-uds-services
694 1 root S 3908 0% 0% /bin/ssdpd
405 1 root S 3808 0% 0% /usr/sbin/irqbalance
1140 1 root S 3244 0% 0% /usr/sbin/crond -f -l 9
1 0 root S 3116 0% 0% init
459 1 root S 3116 0% 0% /sbin/syslogd -n -m 0
462 1 root S 3116 0% 0% /sbin/klogd -n
489 1 root S 3116 0% 0% /sbin/udhcpc -b -t 100 -T 3 -S -x hostname:ccu3-webui -i eth0 -F ccu3-webui -V eQ3-CCU3 -s /bin/dhcp.script -p /var/run/udhcpc_eth0.pid
1703 1636 root S 3116 0% 0% grep -v ]$
95 1 root S 2984 0% 0% watchdog -t 5 /dev/watchdog
86 1 root S 2968 0% 0% /usr/bin/psplash -n
421 1 dbus S 2892 0% 0% dbus-daemon --system
637 1 root S 1824 0% 0% /usr/sbin/ifplugd -i eth0 -fI -u0 -d10
641 1 root S 1824 0% 0% /usr/sbin/ifplugd -i wlan0 -MwI -u5 -d5
412 1 root S 1720 0% 0% /usr/sbin/rngd
687 1 root S 1580 0% 0% /usr/sbin/lighttpd-angel -f /etc/lighttpd/lighttpd.conf -D


child process exited abnormally

Chain INPUT (policy DROP 249 packets, 38090 bytes)
pkts bytes target prot opt in out source destination
17800 7011K ACCEPT all -- lo * 0.0.0.0/0 0.0.0.0/0
1597 388K ACCEPT all -- * * 0.0.0.0/0 0.0.0.0/0 state RELATED,ESTABLISHED
0 0 ACCEPT tcp -- * * 0.0.0.0/0 0.0.0.0/0 tcp dpt:9293 state NEW
0 0 ACCEPT tcp -- * * 0.0.0.0/0 0.0.0.0/0 tcp dpt:22 state NEW
18 1080 ACCEPT tcp -- * * 0.0.0.0/0 0.0.0.0/0 tcp dpt:80 state NEW
11 572 ACCEPT tcp -- * * 0.0.0.0/0 0.0.0.0/0 tcp dpt:443 state NEW
2 158 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp spt:43439
2 84 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp dpt:43439
0 0 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp spt:23272
0 0 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp dpt:23272
17 1287 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp dpt:43438
98 40624 ACCEPT udp -- * * 0.0.0.0/0 0.0.0.0/0 udp dpt:1900
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:1901
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:1901
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:1902
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:1902
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:5987
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:5987
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:8088 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:8088 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:9099 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:9099 state NEW
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:10000
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:10000
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:48899
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:48899
0 0 ACCEPT udp -- * * 192.168.0.1 0.0.0.0/0 udp dpt:49880
0 0 ACCEPT udp -- * * 192.168.0.0/16 0.0.0.0/0 udp dpt:49880
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:2000 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:2000 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:2001 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:2001 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:2002 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:2002 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:2010 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:2010 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:9292 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:9292 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:42000 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:42000 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:42001 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:42001 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:42010 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:42010 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:49292 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:49292 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:1999 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:1999 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:8181 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:8181 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:41999 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:41999 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.1 0.0.0.0/0 tcp dpt:48181 state NEW
0 0 ACCEPT tcp -- * * 192.168.0.0/16 0.0.0.0/0 tcp dpt:48181 state NEW
0 0 ACCEPT icmp -- * * 0.0.0.0/0 0.0.0.0/0 icmptype 8 state NEW
0 0 ACCEPT icmp -- * * 0.0.0.0/0 0.0.0.0/0 icmptype 8 state NEW

Dec 04 21:12:49

Dec 07 18:43:46


child process exited abnormally
Dec 7 18:44:19 ccu3-webui daemon.info redmatic: RedMatic v7.2.1 (c) Sebastian Raff https://github.com/rdmtc/RedMatic
Dec 7 18:44:19 ccu3-webui daemon.info redmatic: Starting Node-RED
Dec 7 18:44:24 ccu3-webui daemon.info node-red[1134]: Welcome to Node-RED
Dec 7 18:44:24 ccu3-webui daemon.info node-red[1134]: ===================
Dec 7 18:44:24 ccu3-webui daemon.info node-red[1134]: Node-RED version: v1.2.9
Dec 7 18:44:24 ccu3-webui daemon.info node-red[1134]: Node.js version: v14.16.0
Dec 7 18:44:24 ccu3-webui daemon.info node-red[1134]: Linux 4.14.34 arm LE
Dec 7 18:44:25 ccu3-webui daemon.err node-red: (node:1134) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Dec 7 18:44:25 ccu3-webui daemon.err node-red: (Use `node --trace-deprecation ...` to show where the warning was created)
Dec 7 18:44:27 ccu3-webui daemon.info node-red[1134]: Loading palette nodes
Dec 7 18:44:34 ccu3-webui daemon.info node-red[1134]: node-red-contrib-milight-2@1.0.8 started
Dec 7 18:44:36 ccu3-webui daemon.info node-red[1134]: node-red-contrib-ccu version: 3.4.2
Dec 7 18:44:37 ccu3-webui daemon.info node-red[1134]: Dashboard version 2.28.1 started at /addons/red/ui
Dec 7 18:44:39 ccu3-webui daemon.warn node-red[1134]: Missing node modules:
Dec 7 18:44:39 ccu3-webui daemon.warn node-red[1134]: - node-red-contrib-harmony-websocket (2.2.6): harmonyws-server, harmonyws-activity, harmonyws-command, harmonyws-observer, harmonyws-automation, harmonyws-sequence
Dec 7 18:44:39 ccu3-webui daemon.info node-red[1134]: Removing modules from config
Dec 7 18:44:39 ccu3-webui daemon.info node-red[1134]: Settings file : /usr/local/addons/redmatic/lib/settings.js
Dec 7 18:44:39 ccu3-webui daemon.info node-red[1134]: Context store : 'default' [module=localfilesystem]
Dec 7 18:44:39 ccu3-webui daemon.info node-red[1134]: Context store : 'memory' [module=memory]
Dec 7 18:44:39 ccu3-webui daemon.err node-red[1134]: Failed to start server:
Dec 7 18:44:39 ccu3-webui daemon.err node-red[1134]: Error: Error loading context store: Error: EIO: i/o error, read
Dec 7 18:44:39 ccu3-webui daemon.err node-red[1134]: at /usr/local/addons/redmatic/lib/node_modules/node-red/node_modules/@node-red/runtime/lib/nodes/context/index.js:175:15
Dec 7 18:45:52 ccu3-webui daemon.info redmatic: RedMatic v7.2.1 (c) Sebastian Raff https://github.com/rdmtc/RedMatic
Dec 7 18:45:52 ccu3-webui daemon.info redmatic: Starting Node-RED
Dec 7 18:45:55 ccu3-webui daemon.info node-red[1548]: Welcome to Node-RED
Dec 7 18:45:55 ccu3-webui daemon.info node-red[1548]: ===================
Dec 7 18:45:55 ccu3-webui daemon.info node-red[1548]: Node-RED version: v1.2.9
Dec 7 18:45:55 ccu3-webui daemon.info node-red[1548]: Node.js version: v14.16.0
Dec 7 18:45:55 ccu3-webui daemon.info node-red[1548]: Linux 4.14.34 arm LE
Dec 7 18:45:55 ccu3-webui daemon.err node-red: (node:1548) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Dec 7 18:45:55 ccu3-webui daemon.err node-red: (Use `node --trace-deprecation ...` to show where the warning was created)
Dec 7 18:45:57 ccu3-webui daemon.info node-red[1548]: Loading palette nodes
Dec 7 18:46:01 ccu3-webui daemon.info node-red[1548]: node-red-contrib-milight-2@1.0.8 started
Dec 7 18:46:02 ccu3-webui daemon.info node-red[1548]: node-red-contrib-ccu version: 3.4.2
Dec 7 18:46:03 ccu3-webui daemon.info node-red[1548]: Dashboard version 2.28.1 started at /addons/red/ui
Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Settings file : /usr/local/addons/redmatic/lib/settings.js
Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Context store : 'default' [module=localfilesystem]
Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Context store : 'memory' [module=memory]
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: Failed to start server:
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: Error: Error loading context store: Error: EIO: i/o error, read
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: at /usr/local/addons/redmatic/lib/node_modules/node-red/node_modules/@node-red/runtime/lib/nodes/context/index.js:175:15

[McPan]

Dec 7 18:44:39 ccu3-webui daemon.warn node-red[1134]: Missing node modules:
Dec 7 18:44:39 ccu3-webui daemon.warn node-red[1134]: - node-red-contrib-harmony-websocket (2.2.6): harmonyws-server, harmonyws-activity, harmonyws-command, harmonyws-observer, harmonyws-automation, harmonyws-sequence
Dec 7 18:44:39 ccu3-webui daemon.info node-red[1134]: Removing modules from config

Könnte es sein, dass hier noch nachträglich installierte Nodes fehlen? Hab gerade nicht auf'm Schirm, ob diese auch Bestandteil des Backups der CCU sind. Laut Log gab es ja zwei Startversuche von Nodered, beim ersten Versuch (Quote) wurde die Konfiguration der Nodes entfernt, beim zweiten Versuch werden diese zwar nicht mehr bemängelt, aber ich denke mal, dass diese Nodes vermutlich nicht automatisch auch aus deinen Flows entfernt werden und dass sich Nodered möglicherweise daren stört.

Frag mich jetzt aber nicht, wie du diese Nodes wieder in eine noch nicht laufende Nodered-Installation reinbekommst.

Gruß,
Marco

[McPan]

Ggf. RedMatic im Safe-Mode starten (https://github.com/rdmtc/RedMatic/wiki/Safe-Mode) und dann versuchen, die fehlenden Nodes (vermutlich diese) wieder zu ergänzen.

[ptweety]

Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Settings file : /usr/local/addons/redmatic/lib/settings.js
Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Context store : 'default' [module=localfilesystem]
Dec 7 18:46:04 ccu3-webui daemon.info node-red[1548]: Context store : 'memory' [module=memory]
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: Failed to start server:
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: Error: Error loading context store: Error: EIO: i/o error, read
Dec 7 18:46:04 ccu3-webui daemon.err node-red[1548]: at /usr/local/addons/redmatic/lib/node_modules/node-red/node_modules/@node-red/runtime/lib/nodes/context/index.js:175:15

Ich lese hier was von I/O Error. Ich weiß allerdings nicht, in welcher Datei der Kontext Store liegt ... um mal nachzuschauen. Aber bei so einer Meldung empfiehlt sich m.E. sowieso eine neue SD Card.

[McPan]

Ich lese hier was von I/O Error. Ich weiß allerdings nicht, in welcher Datei der Kontext Store liegt ...

Oops, hatte ich glatt übersehen!

... um mal nachzuschauen. Aber bei so einer Meldung empfiehlt sich m.E. sowieso eine neue SD Card.

Nein! Die SD-Karte ist vermutlich noch iO.

Der Context Store ist sowas wie der Variablen- und Flow-Speicher von Redmatic. Die Empfehlung ist einfach, selbigen mittels SSH-Konsole zu löschen. Siehe auch https://github.com/rdmtc/RedMatic/issue ... -745343264. Ein vorhandenes Backup aller Flows ist an dieser Stelle wohl hilfreich.

Gruß,
Marco