Hallo
habe diverse server restarts bei fhem.
restart Counter geht hoch.
im Log finde ich keine Fehlermeldungen die ich richtig interpretieren kann.
aktuell versuche ich gerade Readingsgroup und structure zu implementieren.
Dies hängt da wohl irgendwie mit zusammen.
logeintrag:
2019.02.12 20:55:58.373 1: checkFritzMACpresent (FB3390_32): mac_FC_53_9E_A6_BB_B1 nicht gefunden, abwesend.
2019.02.12 20:55:59.819 4: Connection closed for WEB8086_192.168.0.10_57958: EOF
2019.02.12 20:56:04.950 4: WEB8086_192.168.0.10_57946 POST /fhem?cmd.attrWZ_Licht_state%3Dattr%20WZ_Licht_state%20comment%20problem%3A%20%E2%90%A4state%20zur%C3%BCcksetzen%20nach%20on-for-timer%20%E2%90%A4%E2%90%A4-%3E%20neue%20notifys%3F%E2%90%A4%E2%90%A4-%3E%20check%20structure%3F&XHR=1&fw_id=12451; BUFLEN:0
2019.02.12 20:56:04.952 4: authorize WEB8086/cmd/attr: allowed returned dont care
2019.02.12 20:56:04.952 4: authorize WEB8086/devicename/WZ_Licht_state: allowed returned dont care
2019.02.12 20:56:04.990 4: WEB8086: /fhem?cmd.attrWZ_Licht_state%3Dattr%20WZ_Licht_state%20comment%20problem%3A%20%E2%90%A4state%20zur%C3%BCcksetzen%20nach%20on-for-timer%20%E2%90%A4%E2%90%A4-%3E%20neue%20notifys%3F%E2%90%A4%E2%90%A4-%3E%20check%20structure%3F&XHR=1&fw_id=12451 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:05.011 4: WEB8086_192.168.0.10_57946 GET /fhem?detail=WZ_Licht_state; BUFLEN:0
2019.02.12 20:56:05.104 4: WEB8086: /fhem?detail=WZ_Licht_state / RL:6754 / text/html; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:05.125 4: Connection closed for WEB8086_192.168.0.10_57954: EOF
2019.02.12 20:56:05.221 4: WEB8086_192.168.0.10_57946 GET /fhem?cmd=%7BAttrVal(%22WZ_Licht_state%22%2C%22room%22%2C%22%22)%7D&XHR=1; BUFLEN:0
2019.02.12 20:56:05.222 4: authorize WEB8086/cmd/perl: allowed returned dont care
2019.02.12 20:56:05.223 4: WEB8086: /fhem?cmd=%7BAttrVal(%22WZ_Licht_state%22%2C%22room%22%2C%22%22)%7D&XHR=1 / RL:64 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:05.276 4: Connection accepted from WEB8086_192.168.0.10_57990
2019.02.12 20:56:06.049 4: Connection accepted from WEB8086_192.168.0.10_57992
2019.02.12 20:56:08.728 1: checkFritzMACpresent (FB3390_32): mac_FC_53_9E_A6_BB_B1 nicht gefunden, abwesend.
2019.02.12 20:56:09.775 4: Connection closed for WEB8086_192.168.0.10_57964: EOF
2019.02.12 20:56:15.762 4: Connection accepted from WEB8086_192.168.0.10_57996
2019.02.12 20:56:19.046 1: checkFritzMACpresent (FB3390_32): mac_FC_53_9E_A6_BB_B1 nicht gefunden, abwesend.
2019.02.12 20:56:20.764 4: Connection closed for WEB8086_192.168.0.10_57966: EOF
2019.02.12 20:56:26.653 4: WEB8086_192.168.0.10_57946 POST /fhem?cmd.modifyWKD_Licht_SW_DIM%3Dmodify%20WKD_Licht_SW_DIM%20KWD_Lichter_S_Type1%20%20model%3DHM-LC-.*%3AFILTER%3Droom%3D1_%5BWKD%5D.*Licht&XHR=1&fw_id=12280; BUFLEN:0
2019.02.12 20:56:26.654 4: authorize WEB8086/cmd/modify: allowed returned dont care
2019.02.12 20:56:27.495 5: SET: Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename down fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg old:noArg on-for-timer on-till on:noArg pair:noArg pct:slider,0,1,100 peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg stop:noArg templateDel toggle:noArg unpair:noArg up
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all down getConfig:noArg getRegRaw inhibit:on,off off:noArg old:noArg on-for-timer on-till on:noArg pct:slider,0,1,100 peerBulk peerIODev press regBulk regSet sign:on,off statusRequest:noArg stop:noArg templateDel toggle:noArg up
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename eventL eventS fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press pressS:self01 pressL:self01 raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg tplSet_self01:SwCondAbove_long,SwCondAbove_short,SwCondBelow_long,SwCondBelow_short,SwOff_long,SwOff_short,SwOnCond_long,SwOnCond_short,SwOn_long,SwOn_short,SwToggle_long,SwToggle_short,autoOff_long,autoOff_short,motionOnSw_long,motionOnSw_short
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
2019.02.12 20:56:27.502 4: WEB8086: /fhem?cmd.modifyWKD_Licht_SW_DIM%3Dmodify%20WKD_Licht_SW_DIM%20KWD_Lichter_S_Type1%20%20model%3DHM-LC-.*%3AFILTER%3Droom%3D1_%5BWKD%5D.*Licht&XHR=1&fw_id=12280 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:30.715 4: Connection accepted from WEB8086_192.168.0.10_57998
2019.02.12 20:56:31.510 4: WEB8086_192.168.0.10_57946 GET /fhem?room=02_structure; BUFLEN:0
2019.02.12 20:56:31.867 5: SET: Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename down fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg old:noArg on-for-timer on-till on:noArg pair:noArg pct:slider,0,1,100 peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg stop:noArg templateDel toggle:noArg unpair:noArg up
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all down getConfig:noArg getRegRaw inhibit:on,off off:noArg old:noArg on-for-timer on-till on:noArg pct:slider,0,1,100 peerBulk peerIODev press regBulk regSet sign:on,off statusRequest:noArg stop:noArg templateDel toggle:noArg up
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename eventL eventS fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press pressS:self01 pressL:self01 raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg tplSet_self01:SwCondAbove_long,SwCondAbove_short,SwCondBelow_long,SwCondBelow_short,SwOff_long,SwOff_short,SwOnCond_long,SwOnCond_short,SwOn_long,SwOn_short,SwToggle_long,SwToggle_short,autoOff_long,autoOff_short,motionOnSw_long,motionOnSw_short
Unknown argument ?, choose one of assignHmKey:noArg clear:readings,trigger,register,oldRegs,rssi,msgEvents,msgErrors,attack,all deviceRename fwUpdate getConfig:noArg getDevInfo:noArg getRegRaw getSerial:noArg getVersion:noArg inhibit:on,off off:noArg on-for-timer on-till on:noArg pair:noArg peerBulk peerIODev press raw regBulk regSet reset:noArg sign:on,off statusRequest:noArg templateDel toggle:noArg unpair:noArg
2019.02.12 20:56:31.988 4: WEB8086: /fhem?room=02_structure / RL:6633 / text/html; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:32.040 1: checkFritzMACpresent (FB3390_32): mac_FC_53_9E_A6_BB_B1 nicht gefunden, abwesend.
2019.02.12 20:56:35.562 4: Connection closed for WEB8086_192.168.0.10_57972: EOF
2019.02.12 20:56:38.963 4: Connection accepted from WEB8086_192.168.0.10_58004
2019.02.12 20:56:39.671 4: Connection accepted from WEB8086_192.168.0.10_58006
2019.02.12 20:56:40.359 4: WEB8086_192.168.0.10_57946 GET /fhem?detail=WKD_Licht_Alle; BUFLEN:0
2019.02.12 20:56:40.458 4: WEB8086: /fhem?detail=WKD_Licht_Alle / RL:5935 / text/html; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:40.860 4: WEB8086_192.168.0.10_57946 GET /fhem?cmd=%7BReadingsVal(%22WKD_Licht_Alle%22%2C%22pct%22%2C%22%22)%7D&XHR=1; BUFLEN:0
2019.02.12 20:56:40.862 4: authorize WEB8086/cmd/perl: allowed returned dont care
2019.02.12 20:56:40.865 4: WEB8086: /fhem?cmd=%7BReadingsVal(%22WKD_Licht_Alle%22%2C%22pct%22%2C%22%22)%7D&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:40.881 4: Connection closed for WEB8086_192.168.0.10_58004: EOF
2019.02.12 20:56:40.882 4: WEB8086_192.168.0.10_57944 GET /fhem?cmd=%7BAttrVal(%22WKD_Licht_Alle%22%2C%22room%22%2C%22%22)%7D&XHR=1; BUFLEN:0
2019.02.12 20:56:40.883 4: authorize WEB8086/cmd/perl: allowed returned dont care
2019.02.12 20:56:40.887 4: WEB8086: /fhem?cmd=%7BAttrVal(%22WKD_Licht_Alle%22%2C%22room%22%2C%22%22)%7D&XHR=1 / RL:63 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/ Cache-Control: no-cache, no-store, must-revalidate
2019.02.12 20:56:40.951 4: Connection accepted from WEB8086_192.168.0.10_58008
2019.02.12 20:56:46.044 4: Closing inactive connection WEB8086_192.168.0.10_57844
2019.02.12 20:56:46.046 4: Closing inactive connection WEB8086_192.168.0.10_57948
2019.02.12 20:56:46.047 4: Closing inactive connection WEB8086_192.168.0.10_57922
2019.02.12 20:56:46.048 4: Closing inactive connection WEB8086_192.168.0.10_57920
Unmatched ( in regex; marked by <-- HERE in m/^{(ReadingsTimestamp( <-- HERE $DEVICE$/ at ./FHEM/33_readingsGroup.pm line 1383.
2019.02.12 20:56:46 1: reload: Error:Modul 99_myJson deactivated:
Illegal declaration of subroutine main::myUtils_Initialize at ./FHEM/99_myJson.pm line 15.
2019.02.12 20:56:46 1: PERL WARNING: Subroutine myUtils_Initialize redefined at ./FHEM/99_myUtils.pm line 15.
2019.02.12 20:56:47.076 1: Including fhem.cfg
2019.02.12 20:56:47.099 1: PERL WARNING: Subroutine myUtils_Initialize redefined at ./FHEM/99_myHeizung.pm line 15, <$fh> line 17.
2019.02.12 20:56:47.105 1: reload: Error:Modul 99_myJson deactivated:
Illegal declaration of subroutine main::myUtils_Initialize at ./FHEM/99_myJson.pm line 15, <$fh> line 17.
2019.02.12 20:56:47.108 1: Including ./fhemtr/includes/Telegram_bot.cfg
2019.02.12 20:56:54.402 1: cul_ub10_ser2net: Can't connect to 192.168.0.10:2022: Connection refused
2019.02.12 20:56:54.675 1: nanoCul868: Can't open /dev/ttyUSB1: No such file or directory
2019.02.12 20:56:54.680 1: cul_nas_ser2net: Can't connect to 192.168.0.22:2022: Connection refused
2019.02.12 20:57:00.934 1: cul_HM_ser2net: Can't connect to 192.168.0.97:2022: Connection timed out
2019.02.12 20:57:05.143 1: PERL WARNING: Use of uninitialized value $evcode in concatenation (.) or string at ./FHEM/10_IT.pm line 775, <$fh> line 1990.
2019.02.12 20:57:08.377 1: cul_rpi_remote_ser2net_lan: Can't connect to 192.168.0.99:2022: Connection timed out
2019.02.12 20:57:11.385 1: cul_wohn_ser2net_rpi_wlan: Can't connect to 192.168.0.55:2022: Connection timed out
2019.02.12 20:57:14.393 1: cul_rpi_91_ser2net_lan: Can't connect to 192.168.0.91:2022: Connection timed out
2019.02.12 20:57:17.402 1: cul_LAPTOP_ser2net: Can't connect to 192.168.0.25:2022: Connection timed out
2019.02.12 20:57:19.752 1: PERL WARNING: Unescaped left brace in regex is deprecated here (and will be fatal in Perl 5.30), passed through in regex; marked by <-- HERE in m/^Value("osmc_tagesschau").{ <-- HERE .*}$/ at ./FHEM/98_expandJSON.pm line 64, <$fh> line 7687.
2019.02.12 20:57:19.754 1: PERL WARNING: Unescaped left brace in regex is deprecated here (and will be fatal in Perl 5.30), passed through in regex; marked by <-- HERE in m/^ReadingsVal("osmc_tagesschau","state","").{ <-- HERE .fil*}$/ at ./FHEM/98_expandJSON.pm line 64, <$fh> line 7689.
2019.02.12 20:57:20.742 1: PERL WARNING: can't getattr: Input/output error at FHEM/DevIo.pm line 420.
2019.02.12 20:57:20.743 1: NAD_T773SER: Can't open /dev/ttyS20: Input/output error
2019.02.12 20:57:21.641 1: PERL WARNING: Unescaped left brace in regex is deprecated here (and will be fatal in Perl 5.30), passed through in regex; marked by <-- HERE in m/^OSMC95Wohn:jsonResponse:.{ <-- HERE .*}$/ at ./FHEM/98_expandJSON.pm line 64, <$fh> line 13033.
2019.02.12 20:57:21.660 1: Including ./fhem.save
2019.02.12 20:57:22.564 5: CUL_HM HM_29553A queue configRead, register incomplete
2019.02.12 20:57:22.843 5: Update structure 'WKD_Licht_Alle' to undefined because device HM_2E3200 has changed
2019.02.12 20:57:22.890 5: Update structure 'WKD_Licht_SW_DIM' to on because device HM_2E3200 has changed
2019.02.12 20:57:22.936 5: Update structure 'WKD_Licht_Stuct' to undefined because device HM_2E3200 has changed
2019.02.12 20:57:24.485 1: PERL WARNING: ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE $/ at ./FHEM/33_readingsGroup.pm line 154.
2019.02.12 20:57:24.486 1: PERL WARNING: ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE $/ at ./FHEM/33_readingsGroup.pm line 160.
2019.02.12 20:57:24.970 5: HM_Components: not on any display, ignoring notify
2019.02.12 20:57:25.170 5: HM_Components: not on any display, ignoring notify
2019.02.12 20:57:25.452 1: usb create starting
Danke fürs Schauen.
VG T
Mein Bauch sagt mir, dass Du als erstes einen Image Backup von der SD Karte machen solltest --> das Log schreit nach Bad Blocks oder (nach dem Backup) das Netzteil mal tauschen ...
scheint ein wenig undeterministisch zu sein
Hallo
das ist kein Rapsberry
Ein normaler Rechner ubuntu 18 Server mit HDD.
Etwas betagtes Gerät zwar aber läuft auch nicht so viel drauf.
Im jounalctl finde ich :
fhem.service: Main process exited, code=exited, status=255/n/a
12 20:19:51 ub9 sshd[18790]: pam_unix(sshd:session): session opened for user fhem by (uid=0)
Feb 12 20:19:51 ub9 systemd[1]: Started Session 364 of user fhem.
Feb 12 20:19:51 ub9 systemd-logind[1075]: New session 364 of user fhem.
Feb 12 20:20:58 ub9 sshd[18648]: pam_unix(sshd:session): session closed for user fhem
Feb 12 20:21:00 ub9 sshd[18790]: pam_unix(sshd:session): session closed for user fhem
Feb 12 20:27:18 ub9 sudo[19621]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:29:27 ub9 sudo[19810]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:33:02 ub9 sudo[20173]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:49:31 ub9 sudo[21711]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:51:04 ub9 sudo[21862]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:51:08 ub9 sudo[21866]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:56:46 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 12 20:56:46 ub9 systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 12 20:56:46 ub9 systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 12 20:56:46 ub9 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 3.
Feb 12 20:57:38 ub9 sudo[22482]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 20:58:00 ub9 sshd[22486]: Accepted publickey for fhem from 192.168.0.10 port 46925 ssh2: RSA SHA256:xxx
Feb 12 20:58:00 ub9 sshd[22486]: pam_unix(sshd:session): session opened for user fhem by (uid=0)
Feb 12 20:58:00 ub9 systemd-logind[1075]: New session 365 of user fhem.
Feb 12 20:58:00 ub9 systemd[1]: Started Session 365 of user fhem.
Feb 12 20:59:07 ub9 sshd[22486]: pam_unix(sshd:session): session closed for user fhem
Feb 12 21:12:36 ub9 sudo[23872]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 21:12:39 ub9 sudo[23891]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 21:12:41 ub9 sudo[23902]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 12 21:13:38 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 12 21:13:38 ub9 systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 12 21:13:38 ub9 systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 12 21:13:38 ub9 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 4.
Feb 12 21:13:48 ub9 sudo[24022]: zwh100 : TTY=pts/2 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
top fhem liefert:
op - 21:18:57 up 7 days, 3:20, 1 user, load average: 1.30, 0.90, 0.84
Tasks: 141 total, 2 running, 98 sleeping, 0 stopped, 0 zombie
%Cpu(s): 20.2 us, 4.6 sy, 0.0 ni, 68.2 id, 6.6 wa, 0.0 hi, 0.3 si, 0.0 st
KiB Mem : 2040980 total, 628588 free, 861872 used, 550520 buff/cache
KiB Swap: 2097148 total, 1503484 free, 593664 used. 1003136 avail Mem
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
24287 fhem 20 0 225192 137052 8856 S 19.4 6.7 0:57.57 perl
24511 fhem 20 0 225192 131412 3216 S 1.0 6.4 0:00.03 perl
24512 fhem 20 0 15100 1068 960 S 0.3 0.1 0:00.01 ping
10054 fhem 20 0 76768 6952 5916 S 0.0 0.3 0:00.08 systemd
10055 fhem 20 0 272248 2680 0 S 0.0 0.1 0:00.00 (sd-pam)
10230 fhem 20 0 120576 3420 2344 S 0.0 0.2 0:00.01 sshd
10231 fhem 20 0 13060 1956 1796 S 0.0 0.1 0:00.00 sftp-server
hast Du vorher Updates gemacht?
Sind noch Updates ausstehend?
Wann hast Du das letzte FHEM Update gemacht?
Irgendwelche Module zurück gehalten?
Hallo
update glaube ich letzte Woche.
checke gleich mal
zurückgehalten habe ich nur ein Modul das ich angepasst habe für Denon.
Wo sehe ich wann das letzte Update war?
Bin mir eigentlich ziemlich sicher das es mit der Structure zusammenhängt.
Habe hier eine mit Homematic Switches und Dimmern kombiniert.
Fast bei jedem update dieser Structure erfolgt ein restart
defmod WKD_Licht_SW_DIM structure KWD_Lichter_S_Type1 model=HM-LC-.*:FILTER=room=1_[WKD].*Licht
attr WKD_Licht_SW_DIM room 02_structure,0_test,1_Wohnzimmer,Z_Control
attr WKD_Licht_SW_DIM verbose 5
hier ist der Status oft undefined, glaube wegen dem Dimmer.
...
Bin hier am recherchieren und neu in readingsgroup und structure unterwegs..
Hilft das?
Danke
Habe auch eine readingsgruppe zur Kontrolle der HM Geräte.
die structure mit den Dimmern ist undefined
defmod WKD_Licht_SW_DIM structure KWD_Lichter_S_Type1 model=HM-LC-.*:FILTER=room=1_[WKD].*Licht
Dimmer haben state 50 , wegen den pct. Werten
HM_Wo_Ku_Lichter
Gerät
State
Model
S/N
HM_DIM2_WZ_Sofa_Leselampe_HM_1DCC51_JEQ0193659
55
HM-LC-Dim1T-Pl-2
JEQ0193659
HM_SW_HM_29553A_LTK0038886_Küche_Schrank
on
HM-LC-SW1-PL2
LTK0038886
HM_2C5AA7_Dim_Lampe_TV_Channel0_DIMMER
chn:on phys:80
HM-LC-Dim1T-Pl-3
HM_SW_Lichterkette_klein_HM_2E3200_LTK0071739
on
HM-LC-SW1-PL2
LTK0071739
HM_SW_Lichterkette_lang_HM_4A29F9_NEQ0181196
on
HM-LC-SW1-PL2
NEQ0181196
HM_1_Küche-fkt_NEQ0179861_HM_4A2F49
on
HM-LC-SW1-PL2
NEQ0179861
HM_SW_SL-KLAVIER-_NEQ0179540_HM_4A3089
on
HM-LC-SW1-PL2
NEQ0179540
Macht das irgendwie Sinn in dem Kontext?
starte mal FHEM mache ein SAVE danach SHUTDOWN und noch einmal Starten
Hallo
das habe ich schon mehrfach gemacht denke ich .
shutdown , save mache ich laufend -
Fhem started nach shutdown automatisch.
Habe aber auch den fhem process mit stop start re-gestarted
mach am besten erst einmal ein FHEM Update ... neSciherung sollte ja vorhanden sein
Ja, update läuft gerade. Danke
Hallo
so nun alles frisch.
werde das mal weiter beobachten.
Melde mich dann
Hatte gerade wieder einen neustart
glaube es liegt hieran:
defmod MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
Im log dazu
2019.02.13 10:30:29.466 1: usb create end
2019.02.13 10:30:29.581 0: Featurelevel: 5.9
2019.02.13 10:30:29.581 0: Server started with 1253 defined entities (fhem.pl:18497/2019-02-05 perl:5.026001 os:linux user:fhem pid:2409)
MAX_Mode_Problem
2019.02.13 10:30:35.108 5: MAX_Mode_Problem: not on any display, ignoring notify
Was sicher auch nicht optimal ist, sind die Fehlermeldungen bei der Initialisierung der diversen myUtils-Dateien.
Siehe dazu im Wiki (https://wiki.fhem.de/wiki/99_myUtils_anlegen#Eine_neue_Programmdatei_erzeugen):
ZitatDer Name der Programmdatei muss mit dem Namen der Initialize-Routine übereinstimmen. Wenn Sie Ihr Programm also 99_Werkzeugkasten.pm nennen, muss die im code dargestellte initialize-Routine sub Werkzeugkasten_Initialize heißen.
Ansonsten wird ein Haufen Zeug nicht gefunden, darf nicht gelesen werden usw.. Da solltest du dich erst mal kümmern. Wenn es keine funktionierenden IO's gibt, brauchst du dich nicht zu wundern, dass es auch keine auszuwertenden Daten der Devices gibt, die darüber reinkommen.
Und schalte das usb-Autocreate ab...
Zitat von: Beta-User am 13 Februar 2019, 10:53:59
Was sicher auch nicht optimal ist, sind die Fehlermeldungen bei der Initialisierung der diversen myUtils-Dateien.
Siehe dazu im Wiki (https://wiki.fhem.de/wiki/99_myUtils_anlegen#Eine_neue_Programmdatei_erzeugen):
Ansonsten wird ein Haufen Zeug nicht gefunden, darf nicht gelesen werden usw.. Da solltest du dich erst mal kümmern. Wenn es keine funktionierenden IO's gibt, brauchst du dich nicht zu wundern, dass es auch keine auszuwertenden Daten der Devices gibt, die darüber reinkommen.
Und schalte das usb-Autocreate ab...
Hallo beta-User,
danke,
die Utils themen sind schon erledigt
waren aber ewig da aus alten Gehversuchen ,
usb autocreate werde ich abschalten.
Danke T
Zitat von: riker1 am 13 Februar 2019, 10:35:14
Hatte gerade wieder einen neustart
glaube es liegt hieran:
defmod MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
Im log dazu
2019.02.13 10:30:29.466 1: usb create end
2019.02.13 10:30:29.581 0: Featurelevel: 5.9
2019.02.13 10:30:29.581 0: Server started with 1253 defined entities (fhem.pl:18497/2019-02-05 perl:5.026001 os:linux user:fhem pid:2409)
MAX_Mode_Problem
2019.02.13 10:30:35.108 5: MAX_Mode_Problem: not on any display, ignoring notify
wo kommt denn das defmod her?
Zitat von: Wuppi68 am 13 Februar 2019, 16:20:20
wo kommt denn das defmod her?
Das ist doch eine normale Readingsgroup oder nicht?
aber sobald ich die editiere, aktualisiere startet der Server neu.
Zitat von: riker1 am 13 Februar 2019, 16:33:53
Das ist doch eine normale Readingsgroup oder nicht?
aber sobald ich die editiere, aktualisiere startet der Server neu.
das ist nicht die Antwort auf meine Frage?
Hi
dann habe ich die Frage nicht ganz verstanden.
in fhem cfg steht:
define MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
setuuid MAX_Mode_Problem 5c633189-f33f-74bb-4588-ac5c1d5847c2b172
attr MAX_Mode_Problem disable 1
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
wenn ich edit raw mache:
defmod MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
attr MAX_Mode_Problem disable 1
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
meintest du das?
Zitat von: Beta-User am 13 Februar 2019, 10:53:59
Ansonsten wird ein Haufen Zeug nicht gefunden, darf nicht gelesen werden usw.. Da solltest du dich erst mal kümmern. Wenn es keine funktionierenden IO's gibt, brauchst du dich nicht zu wundern, dass es auch keine auszuwertenden Daten der Devices gibt, die darüber reinkommen.
Hi,
habe einige Culs zum testen, leider kann man da das Attribut disabled nicht setzen, will sie ungerne löschen und dann immer wieder anlegen
Ok, das ist an sich nicht verkehrt, IO's "vorne" zu halten.
Trotzdem wäre es evtl. eine Idee, die jeweils auf eine _unterschiedliche fiktive_ Schnittstelle zu definieren, sonst kloppen die sich alle um dieselbe (...USB0 usw.). Nicht gut...
Zitat von: Beta-User am 13 Februar 2019, 17:43:34
Ok, das ist an sich nicht verkehrt, IO's "vorne" zu halten.
Trotzdem wäre es evtl. eine Idee, die jeweils auf eine _unterschiedliche fiktive_ Schnittstelle zu definieren, sonst kloppen die sich alle um dieselbe (...USB0 usw.). Nicht gut...
Ich habe die meistens Culs als Ser2Net definiert, also keine doppelten Schnittstellen.
define cul_wohn_ser2net_rpi CUL 192.168.0.88:2022 3841Wäre doch ok.
Warum gibt es denn das
attibut disabled hier nicht?
Danke T
setz mal im Device Global stacktrace und verbose 5
produziere dann einen restart und hänge mal das komplette LOG hier an
Hallo
danke fürs Helfen.
restart erzeugt mit der readingsgroup.
Hatte das Attribut disable 1 und gelöscht.
Das löste den Restart aus.
<Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
log attached.
Zitat von: riker1 am 13 Februar 2019, 18:31:43
Hallo
danke fürs Helfen.
restart erzeugt mit der readingsgroup.
Hatte das Attribut disable 1 und gelöscht.
Das löste den Restart aus.
<Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
log attached.
Danke für das Log ...
in welcher Zeile hat der Server denn neu gestartet?
das 99_myJson Problem ist in Zeile 1 und Zeile 50000+ zu finden, dazwischen wurde irgendwo FHEM neu gestartet
Kodi macht Dir auch ein paar Probleme im Log (JSON Rückgabewerte)
Ungut sieht mir auch das "set BADDUMMY ok" aus. Das taucht eine Zeitlang ziemlich wiederholt auf (unbeabsichtigte loop?), dürfte aber auch keinen Absturz oder restart verursachen.
Zitat von: Beta-User am 14 Februar 2019, 16:56:17
Ungut sieht mir auch das "set BADDUMMY ok" aus. Das taucht eine Zeitlang ziemlich wiederholt auf (unbeabsichtigte loop?), dürfte aber auch keinen Absturz oder restart verursachen.
das werde ich mal abklemmen und neuen log erzeugen
Zitat von: Wuppi68 am 14 Februar 2019, 16:32:43
Danke für das Log ...
in welcher Zeile hat der Server denn neu gestartet?
das 99_myJson Problem ist in Zeile 1 und Zeile 50000+ zu finden, dazwischen wurde irgendwo FHEM neu gestartet
Kodi macht Dir auch ein paar Probleme im Log (JSON Rückgabewerte)
mache nochmal ein log
die JSON Kodi probleme sind alt, da bin ich dran, klemme das mal ab
noch Kleinigkeiten bereinigt.
der Server stürzt immer ab wenn ich dieses dvice:
MAX_Mode_Problemdefine MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
setuuid MAX_Mode_Problem 5c633189-f33f-74bb-4588-ac5c1d5847c2b172
attr MAX_Mode_Problem disable 1
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5anpacke : Habe ein Moddef gemacht und schon wieder ein Absturz.
Neues Log ist dran.
Vielen Dank fürs Checken.
VG T
Kann die structure denn mit dem Readingstimestamp?
define MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
setuuid MAX_Mode_Problem 5c633189-f33f-74bb-4588-ac5c1d5847c2b172
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
überhauptgehen?
Zitat von: riker1 am 14 Februar 2019, 18:08:35
Kann die structure denn mit dem Readingstimestamp?
define MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{(ReadingsTimestamp($DEVICE,'temperature',''))}
setuuid MAX_Mode_Problem 5c633189-f33f-74bb-4588-ac5c1d5847c2b172
attr MAX_Mode_Problem mapping %ALIAS
attr MAX_Mode_Problem room 02_structure
attr MAX_Mode_Problem verbose 5
überhauptgehen?
keine Ahnung ....
bevor ich mir noch 3,6 MEGABYTE an Log reinziehe
set mal im Device GLOBAL verbose auf 3 runter und in der REadingsgroup die evtl. das Problem ist Verbose auf 5 .... dann sollte das Log auch etwas kleiner sein ;-)
OK mache ich morgen
Danke
Guten Morgen,
hatte verbose 5 ja eingestellt da du es so angefragt hattest.
hier ein kleines log
Loaded: loaded (/etc/systemd/system/fhem.service; enabled; vendor preset: enabled)
Active: deactivating (stop-sigterm) (Result: exit-code) since Fri 2019-02-15 07:41:16 CET; 3s ago
Process: 15190 ExecStart=/usr/bin/perl fhem.pl fhem.cfg (code=exited, status=0/SUCCESS)
Main PID: 15205 (code=exited, status=255)
Tasks: 2 (limit: 2319)
CGroup: /system.slice/fhem.service
├─15680 /usr/bin/perl fhem.pl fhem.cfg
└─15709 ping -c 6 192.168.0.31
Feb 15 07:36:28 ub9 systemd[1]: Starting FHEM Home Automation...
Feb 15 07:36:29 ub9 systemd[1]: Started FHEM Home Automation.
Feb 15 07:41:16 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
structure MAX_Mode_Problem mit verbose 5
Global Verbose 3
habe das attribute disable gelöscht und schon war der server wieder weg.
Vielen Dank für den super Support
Hallo
hatte vergessen stacktrace bei Global zu aktivieren.
Hier das log damit
Absturz beim Refresh der structure
Danke nochmal
Nochmal ein paar grundsätzliche Dinge:
- Kann es sein, dass was anderes den FHEM-Dienst neu startet? Z.B., weil FHEM zu lange nicht reagiert? Könnte dann in den Linux-logs zu finden sein.- Was m.E. zu oft auftaucht, sind irgendwelche Netzwerkaktivitäten, v.a. Presence-Pings, die - jedenfalls nach meinem Gefühl - unglaublich oft aktiv aufgerufen werden. Muß das sein? (Mir reicht bisher ein notify, das auf Meldungen der Fritzbox reagiert; wenn du mehrere zu checkende AP's hast hast: ggf. mit structure arbeiten oder intern die entsprechenden Readings der weiteren Geräte checken).
- Du hast unglaublich viele Dinge da im Log, die auf irgendwelchen Code hindeuten, der nicht funktioniert (vergessenes Leerzeichen bei setreading usw.). Räum' doch erst mal da auf, bevor du was neues anfängst...
Wenn du damit fertig bist zu der ReadingsGroup: Was willst du eigentlich erreichen und wo hast du den Code her?
(Ich werde dazu aber nichts sagen, solange das log so Kraut und Rüben ist :P !)
Hallo Beta User,
ok danke, werde mal säubern und aufräumen.
Simple Fehler beim setreading checke ich gleich mal.
Wollte ja mit structure und readingsgroup alles besser strukturieren, aber da kam der Absturz....
Wie checke ich denn die Unix logs am Besten um Fhem Probleme zu finden?
dmesg -T | grep fhem
dmesg -T | grep perl
liefert gar nichts.
journalctl | grep fhem zum Zeitpunkt des Absturzes:
eb 15 07:41:14 ub9 sudo[15689]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:41:15 ub9 sudo[15697]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:41:16 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 07:41:20 ub9 sudo[15711]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:41:21 ub9 systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 07:41:21 ub9 systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 07:41:21 ub9 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.
Feb 15 07:43:21 ub9 sshd[15951]: Accepted publickey for fhem from 192.168.0.10 port 59211 ssh2: RSA SHA256:OrNysD7t4ufM7qzFwg2jTo8ncknbja6u9DV3U1P0yl0
Feb 15 07:43:21 ub9 sshd[15951]: pam_unix(sshd:session): session opened for user fhem by (uid=0)
Feb 15 07:43:21 ub9 systemd-logind[1075]: New session 512 of user fhem.
Feb 15 07:43:21 ub9 systemd[1]: Started Session 512 of user fhem.
Feb 15 07:43:43 ub9 sudo[16077]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl start fhem
Feb 15 07:44:29 ub9 sshd[15951]: pam_unix(sshd:session): session closed for user fhem
Feb 15 07:45:48 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 07:45:48 ub9 systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 07:45:48 ub9 systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 07:45:48 ub9 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 2.
Feb 15 07:47:44 ub9 sudo[16479]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:06 ub9 sudo[16503]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:15 ub9 sudo[16527]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:31 ub9 sudo[16547]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:33 ub9 sudo[16553]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:33 ub9 sudo[16557]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:34 ub9 sudo[16561]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:35 ub9 sudo[16565]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:36 ub9 sudo[16571]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:36 ub9 sudo[16575]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:37 ub9 sudo[16581]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:38 ub9 sudo[16588]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:39 ub9 sudo[16592]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:40 ub9 sudo[16596]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:42 ub9 sudo[16603]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:44 ub9 sudo[16614]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:45 ub9 sudo[16619]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:48:46 ub9 sudo[16623]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:49:07 ub9 sudo[16663]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:49:08 ub9 sudo[16668]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:49:09 ub9 sudo[16672]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:49:17 ub9 systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 07:49:17 ub9 systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 07:49:17 ub9 systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 07:49:17 ub9 systemd[1]: fhem.service: Scheduled restart job, restart counter is at 3.
Feb 15 07:49:21 ub9 sudo[16707]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:49:34 ub9 sudo[16713]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl stop fhem
Feb 15 07:51:18 ub9 sshd[16787]: Accepted publickey for fhem from 192.168.0.10 port 34325 ssh2: RSA SHA256:OrNysD7t4ufM7qzFwg2jTo8ncknbja6u9DV3U1P0yl0
Feb 15 07:51:18 ub9 sshd[16787]: pam_unix(sshd:session): session opened for user fhem by (uid=0)
Feb 15 07:51:18 ub9 systemd-logind[1075]: New session 513 of user fhem.
Feb 15 07:51:18 ub9 systemd[1]: Started Session 513 of user fhem.
Feb 15 07:52:01 ub9 sudo[16870]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 07:52:24 ub9 sshd[16787]: pam_unix(sshd:session): session closed for user fhem
Feb 15 08:03:47 ub9 sudo[16890]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl status -l fhem
Feb 15 08:04:01 ub9 sshd[16893]: Accepted publickey for fhem from 192.168.0.10 port 57468 ssh2: RSA SHA256:OrNysD7t4ufM7qzFwg2jTo8ncknbja6u9DV3U1P0yl0
Feb 15 08:04:01 ub9 sshd[16893]: pam_unix(sshd:session): session opened for user fhem by (uid=0)
Feb 15 08:04:01 ub9 systemd[1]: Started Session 514 of user fhem.
Feb 15 08:04:01 ub9 systemd-logind[1075]: New session 514 of user fhem.
Feb 15 08:04:23 ub9 sudo[16982]: zwh100 : TTY=pts/0 ; PWD=/home/zwh100 ; USER=root ; COMMAND=/bin/systemctl start fhem
Feb 15 08:05:17 ub9 sshd[16893]: pam_unix(sshd:session): session closed for user fhem
Danke
Ne, sorry, ist eindeutig, kommt von der MAX-Readings-Group. Da mag er die einleitende Klammer wohl nicht, weil sie auf der anderen Seite nicht geschlossen ist (vor ReadingsTimestamp). Die ist vermutlich eh' nicht erforderlich, aber wie gesagt: kümmere dich erst mal um den Rest...
Unix (Linux) Logs sind i m L0og-verzeichnis:
ls -lha /var/log/
Am besten mal das syslog nach einem Absturz/restart prüfenprüfen:
tail -n50 /var/log/syslog
Die 50 bedeuten: Die letzten 50 Zeilen. Eventuell anpassen ....
Zitat von: Beta-User am 15 Februar 2019, 08:50:22
Ne, sorry, ist eindeutig, kommt von der MAX-Readings-Group. Da mag er die einleitende Klammer wohl nicht, weil sie auf der anderen Seite nicht geschlossen ist (vor ReadingsTimestamp). Die ist vermutlich eh' nicht erforderlich, aber wie gesagt: kümmere dich erst mal um den Rest...
Hallo
ja bin am aufräumen.
Eventuell bin ich blind. Wo ist die Klammer nicht geschlossen?
defmod MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,
{(
ReadingsTimestamp($DEVICE,'temperature','')
)
}Danke T
PS. Absturz auch mit (Klammern weggenommen):
defmod MAX_Mode_Problem readingsGroup <Name>,<Temp>,<DesiredTemp>,<mode>,<t-time> MAX.*:temperature,state,mode,{ ReadingsTimestamp($DEVICE,'temperature','')}
Zitat von: Wernieman am 15 Februar 2019, 08:52:58
Unix (Linux) Logs sind i m L0og-verzeichnis:
ls -lha /var/log/
Am besten mal das syslog nach einem Absturz/restart prüfenprüfen:
tail -n50 /var/log/syslog
Die 50 bedeuten: Die letzten 50 Zeilen. Eventuell anpassen ....
habe da mal gecheckt, sieht für mich unauffällig auf.
absturz war 9:32.
Feb 15 08:04:01 localhost systemd[1]: Started Session 514 of user fhem.
Feb 15 08:04:23 localhost systemd[1]: Starting FHEM Home Automation...
Feb 15 08:04:23 localhost systemd[1]: Started FHEM Home Automation.
Feb 15 08:10:15 localhost systemd-networkd[19950]: enp1s0: DHCPv6 lease lost
Feb 15 08:10:15 localhost systemd-networkd[19950]: enp1s0: DHCPv6 address 2a02:810b:c740:131e:219:66ff:fe33:5095/128 timeout preferred 3600 valid 7200
Feb 15 08:17:01 localhost CRON[18204]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Feb 15 08:20:02 localhost kernel: [829314.326259] CIFS VFS: Server 192.168.0.22 has not responded in 120 seconds. Reconnecting...
Feb 15 08:20:02 localhost kernel: [829314.326425] CIFS VFS: Server 192.168.0.22 has not responded in 120 seconds. Reconnecting...
Feb 15 08:20:02 localhost kernel: [829314.326548] CIFS VFS: Server 192.168.0.22 has not responded in 120 seconds. Reconnecting...
Feb 15 08:20:02 localhost kernel: [829314.326652] CIFS VFS: Server 192.168.0.22 has not responded in 120 seconds. Reconnecting...
Feb 15 08:23:10 localhost systemd[1]: Starting Daily apt download activities...
Feb 15 08:23:13 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:23:19 localhost systemd-resolved[20017]: message repeated 18 times: [ Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.]
Feb 15 08:24:30 localhost systemd[1]: Started Daily apt download activities.
Feb 15 08:52:13 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:52:13 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:52:13 localhost systemd-resolved[20017]: Using degraded feature set (UDP) for DNS server fd00::ca0e:14ff:fe4f:808b.
Feb 15 08:52:13 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:52:13 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:52:25 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 08:52:25 localhost systemd-resolved[20017]: message repeated 3 times: [ Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.]
Feb 15 08:56:49 localhost systemd[1]: Starting Message of the Day...
Feb 15 08:56:50 localhost 50-motd-news[22559]: * 'snap info' now shows the freshness of each channel.
Feb 15 08:56:50 localhost 50-motd-news[22559]: Try 'snap info microk8s' for all the latest goodness.
Feb 15 08:56:50 localhost systemd[1]: Started Message of the Day.
Feb 15 09:01:00 localhost systemd[1]: Started Session 516 of user fhem.
Feb 15 09:01:21 localhost systemd-resolved[20017]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
Feb 15 09:01:21 localhost systemd-resolved[20017]: message repeated 3 times: [ Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.]
Feb 15 09:17:01 localhost CRON[24685]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.
Feb 15 09:28:52 localhost systemd[1]: Stopped FHEM Home Automation.
Feb 15 09:28:52 localhost systemd[1]: Starting FHEM Home Automation...
Feb 15 09:28:53 localhost systemd[1]: Started FHEM Home Automation.
Feb 15 09:32:00 localhost systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 09:32:00 localhost systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 09:32:00 localhost systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 09:32:00 localhost systemd[1]: fhem.service: Scheduled restart job, restart counter is at 2.
Feb 15 09:32:00 localhost systemd[1]: Stopped FHEM Home Automation.
Feb 15 09:32:00 localhost systemd[1]: Starting FHEM Home Automation...
Feb 15 09:32:00 localhost systemd[1]: Started FHEM Home Automation.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.
.......
Nicht gaaaaans, restart war schon 09:28:52.
Wurde von systemd restartet
Wie hast Du den Service configuriert?
Zitat von: riker1 am 15 Februar 2019, 09:26:54
Eventuell bin ich blind. Wo ist die Klammer nicht geschlossen?
;D Der Punkt ist nicht: Bist du blind, sondern: FHEM sieht die schließende Klammer nicht. Das stand jedenfalls bisher im log. Daher auch meine Frage, wo du den code für die ReadingsGroup her hast (und die Überzeugung nimmst, dass ReadingsGroup an der Stelle überhaupt Perl-Code akzeptiert; vorab: ich habe nicht in die cref dazu geschaut).
Zitat von: riker1 am 15 Februar 2019, 09:26:54
ja bin am aufräumen.
Zitat von: riker1 am 15 Februar 2019, 08:35:19
Wollte ja mit structure und readingsgroup alles besser strukturieren, aber da kam der Absturz....
Nochmal: schau erst mal ins log und versuche darüber (und das Nachdenken über die Meldungen) erst mal eine Bereinigung durchzuführen. Zusätzlicher code und Ignoranz gegenüber den Aussagen des Programms (via log) bringen dich nicht weiter...
Just my2ct.
Zitat von: Wernieman am 15 Februar 2019, 10:08:21
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Main process exited, code=exited, status=255/n/a
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Failed with result 'exit-code'.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Service hold-off time over, scheduling restart.
Feb 15 09:28:52 localhost systemd[1]: fhem.service: Scheduled restart job, restart counter is at 1.
.......
Wie hast Du den Service configuriert?
Hallo
systemd
# $Id: fhem.service 16001 2018-01-26 11:54:41Z betateilchen $
[Unit]
Description=FHEM Home Automation
Wants=network.target
After=network.target
[Service]
Type=forking
User=fhem
Group=dialout
WorkingDirectory=/opt/fhem
ExecStart=/usr/bin/perl fhem.pl fhem.cfg
#ExecStart=/usr/bin/perl fhem.pl configDB
Restart=always
[Install]
WantedBy=multi-user.targethabe ich aber nicht manuel angepackt.
Ist das nicht die Standardconfiguartion?
d.h. fhem schmiert Dir "gans einfach ab" und Systemd startet Ihn dann wieder ...
Wie schon Beta-User sagte:
Mach Dein FHEM sauber .... irgendwo hast Du ein GROßES Problem ...
deaktivier mal die Readingsgroup dann sollte erst einmal ein wenig Ruhe sein ...
ansonsten kannst Du noch in eine funktionieren 99_.*.pm folgendes einfügen (MEGA quick and realy very dirty)
$SIG{__DIE__} = sub {
my ($msg) = @_;
Log 1, "PERL ERROR: $msg";
stacktrace();
}
ob FHEM danach noch funktioniert steht dann durch die Glaskugel den Kaffeesatz in den Sternen gelesen
Hallo
habe versucht die Fehlermeldung einzubauen.
Eigene 99_myHeizung.pm datei.
es gibt aber den Fehler:
2019.02.15 14:15:23 1: PERL WARNING: Number found where operator expected at ./FHEM/99_myHeizung.pm line 30, near "1"
2019.02.15 14:15:23 1: PERL WARNING: (Missing semicolon on previous line?)
2019.02.15 14:15:23 1: reload: Error:Modul 99_myHeizung deactivated:
syntax error at ./FHEM/99_myHeizung.pm line 30, near "1"
# $Id: myUtilsTemplate.pm 7570 2015-01-14 18:31:44Z rudolfkoenig $
#
# Save this file as 99_myUtils.pm, and create your own functions in the new
# file. They are then available in every Perl expression.
package main;
use strict;
use warnings;
use POSIX;
sub
myHeizung_Initialize($$)
{
my ($hash) = @_;
}
$SIG{__DIE__} = sub {
my ($msg) = @_;
Log 1, "PERL ERROR: $msg";
stacktrace();
}
# Enter you functions below _this_ line.
1;
Vielleicht nicht der Grund, aber warum beachtest du das
# Enter you functions below _this_ line.
nicht? => Code erst mal verschieben...
Habe ich verschoben, hat aber keine Wirkung leider
Na ja, und wie sieht es mit dem Tip aus der Fehlermeldung aus: ein Semicolon vor dem "1;"? (Mal direkt nach der letzten schließenden geschweiften Klammer )
Hi,
die Uils sieht so aus.
Bin nicht so sicher in Perl, aber sonst sehe ich da nirgends ;
Weiss hier dann nicht weiter....
##############################################
# $Id: myUtilsTemplate.pm 7570 2015-01-14 18:31:44Z rudolfkoenig $
#
# Save this file as 99_myUtils.pm, and create your own functions in the new
# file. They are then available in every Perl expression.
package main;
use strict;
use warnings;
use POSIX;
sub
myHeizung_Initialize($$)
{
my ($hash) = @_;
};
# Enter you functions below _this_ line.
$SIG{__DIE__} = sub {
my ($msg) = @_;
Log 1, "PERL ERROR: $msg";
stacktrace();
}
;
1;
Danke für die Info, dass die utils jetzt fehlerfrei geladen wird...
(Wird sie doch, oder ::) ?)
Hallo
ich denke schon, die Meldung sehe ich nicht mehr...
aber nun: 2019.02.15 15:45:38.447 1: PERL ERROR: Can't use an undefined value as an ARRAY reference at fhem.pl line 564.
im log:
2019.02.15 15:45:38.447 1: PERL ERROR: Can't use an undefined value as an ARRAY reference at fhem.pl line 564.
2019.02.15 15:45:38.450 1: stacktrace:
2019.02.15 15:45:38.451 1: main::__ANON__ called by fhem.pl (564)
2019.02.15 15:45:38.451 1: (eval) called by fhem.pl (564)
2019.02.15 15:45:38.469 1: Including fhem.cfg
2019.02.15 15:45:38.513 1: Including ./fhemtr/includes/Telegram_bot.cfg
2019.02.15 15:45:38.626 3: TelegramBot_Define TR_BOT: called
2019.02.15 15:45:38.780 1: PERL ERROR: Can't locate object method "tid" via package "threads" at /usr/share/perl/5.26/XSLoader.pm line 114.
2019.02.15 15:45:38.780 1: stacktrace:
2019.02.15 15:45:38.780 1: main::__ANON__ called by /usr/share/perl/5.26/XSLoader.pm (114)
2019.02.15 15:45:38.780 1: (eval) called by /usr/share/perl/5.26/XSLoader.pm (114)
2019.02.15 15:45:38.781 1: XSLoader::load called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (439)
2019.02.15 15:45:38.781 1: (eval) called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (441)
2019.02.15 15:45:38.781 1: (eval) called by /usr/share/perl5/IO/Socket/SSL.pm (19)
2019.02.15 15:45:38.781 1: IO::Socket::SSL::BEGIN called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (0)
2019.02.15 15:45:38.781 1: (eval) called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (0)
2019.02.15 15:45:38.781 1: (eval) called by (eval 23) (1)
2019.02.15 15:45:38.781 1: main::BEGIN called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (0)
2019.02.15 15:45:38.781 1: (eval) called by /usr/lib/x86_64-linux-gnu/perl5/5.26/Net/SSLeay.pm (0)
2019.02.15 15:45:38.781 1: (eval) called by FHEM/HttpUtils.pm (465)
2019.02.15 15:45:38.781 1: main::HttpUtils_Connect2 called by FHEM/HttpUtils.pm (454)
2019.02.15 15:45:38.781 1: main::HttpUtils_Connect called by FHEM/HttpUtils.pm (906)
2019.02.15 15:45:38.781 1: main::HttpUtils_BlockingGet called by ./FHEM/50_TelegramBot.pm (1611)
2019.02.15 15:45:38.781 1: main::TelegramBot_DoUrlCommand called by ./FHEM/50_TelegramBot.pm (2835)
2019.02.15 15:45:38.781 1: main::TelegramBot_Setup called by ./FHEM/50_TelegramBot.pm (390)
2019.02.15 15:45:38.782 1: main::TelegramBot_Define called by fhem.pl (3689)
2019.02.15 15:45:38.782 1: main::CallFn called by fhem.pl (2066)
2019.02.15 15:45:38.782 1: main::CommandDefine called by fhem.pl (1233)
2019.02.15 15:45:38.782 1: main::AnalyzeCommand called by fhem.pl (1079)
2019.02.15 15:45:38.782 1: main::AnalyzeCommandChain called by fhem.pl (1374)
2019.02.15 15:45:38.782 1: main::CommandInclude called by fhem.pl (1233)
2019.02.15 15:45:38.782 1: main::AnalyzeCommand called by fhem.pl (1079)
2019.02.15 15:45:38.782 1: main::AnalyzeCommandChain called by fhem.pl (1374)
2019.02.15 15:45:38.782 1: main::CommandInclude called by fhem.pl (596)
2019.02.15 15:45:38.996 3: telnetPort7072: port 7072 opened
So habe nun den cfg bzw log soweit bereinigt
die Fehlerroutine ist drinnen, aber die Fehler sagen mir nicht viel..
Serverabsturz beim editieren der Readingsgroup - nicht structure wie oben falsch geschrieben
nur das attribut disable gelöscht und absturz.
In der cref habe ich nicht genaues gefunden ob man ReadingTimestamp nicht verwenden kann, darf...es steht man kann die timestamps uinterdrücken mit notime, aber angezeigt habe ich keine gesehen.
https://wiki.fhem.de/wiki/ReadingsGroup#Alle_Readings_eines_Ger.C3.A4tes.2C_mit_Ausnahme_von... (https://wiki.fhem.de/wiki/ReadingsGroup#Alle_Readings_eines_Ger.C3.A4tes.2C_mit_Ausnahme_von...)
Hier mal das log attached.
Danke immer noch.
Habe den Thread Titel angepaßt, da die Ursache ja gefunden, aber nicht verstanden ist.. so ok?
Hatte auch schon mal hier angefragt wegen der Readingsgruppe.
https://forum.fhem.de/index.php/topic,97285.0.html (https://forum.fhem.de/index.php/topic,97285.0.html)
kommt nun zusammen das Thema
Na ja, offensichtlich mag ReadingsGroup den Perl-Code an der Stelle nicht ;) .
Das mit notime ist doch eigentlich klar: Es wird immer der timestamp der einzelnen Readings in der RG mit angezeigt, es sei denn, es wird unterdrückt. Warum das bei dir in der RG scheinbar nicht so ist? Keine Ahnung, ich habe mir den code auch nicht angesehen. Kann alles mögliche sein.
Es bleibt jedenfalls dabei: FHEM sieht die schließende Klammer nicht. Also solltest du dir eine andere Lösung überlegen, als darauf zu beharren, dass Perlcode (und zwar genau der) genau da rein muß...
Bis auf die Max-Mode und Presence-Dinger sieht es doch schon mal nicht schlecht aus (auf die Schnelle).
Hallo
danke fürs Schauen,
Ja glaube der perl Code ist das Problem da.
Weiss aber nicht wie ich die Readings-Details an die Readings-group bekommen soll.
Mal weiter recherchieren.
Danke
mach Dir doch ein userReading im Device auf das ReadingsTimestamp und frag dann dieses Reading ab.
Zitat von: mi.ke am 15 Februar 2019, 18:22:24
mach Dir doch ein userReading im Device auf das ReadingsTimestamp und frag dann dieses Reading ab.
..genau, oder so:-)
viele Wege es gibt:-)