heute ist folgender Fehler in meinem Raspberrymatic-System (3.47.22.20191026, Raspi 3B+) aufgetreten:
ca. 16:32 > HM-IP-Geräte nicht erreichbar
ca. 16:40 > CPU-Last >25%
ca. 17:05 > Erstes Auftreten von "eq3loop_write_master() mmd_hmip: not enough space in buffers."
ca. 17:10 > Ein Starten von Programmen löst keine Aktion/Änderung mehr aus (weder bei Geräten noch bei SysVars)
ca. 17:16 > Monit versucht den HMIPServer-Prozess neuzustarten. Dies blieb jedoch erfolglos.
ca. 17:30 > Einzig nach einem Neustart des Raspi hat alles wieder ordnungsgemäß funktioniert.
Dazu folgende Einträge aus dem Syslog:
Code: Alles auswählen
Nov 24 16:32:47 homematic-ccu2 user.warn rfd: XmlRpc transport failed (first try), retrying...
Nov 24 16:32:57 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","CONTROL_MODE",1}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","FAULT_REPORTING",0}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","BATTERY_STATE",2.400000}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","VALVE_STATE",0}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","BOOST_STATE",0}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","ACTUAL_TEMPERATURE",17.800000}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","SET_TEMPERATURE",15.000000}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","PARTY_TEMPERATURE",5.000000}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","PARTY_START_TIME",0}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","PARTY_START_DAY",1}],[methodName:"event",params:{"BidCos-RF_java","LEQ1199401:4","PARTY_START_MONTH",1}],[methodName:"event",params:{"BidCos-R
Nov 24 16:32:57 homematic-ccu2 user.err rfd: XmlRpc transport error
Nov 24 16:33:07 homematic-ccu2 user.warn rfd: XmlRpc transport failed (first try), retrying...
Nov 24 16:33:17 homematic-ccu2 user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","OEQ1667451:2","ACTUAL_TEMPERATURE",20.200000}],[methodName:"event",params:{"BidCos-RF_java","OEQ1667451:2","ACTUAL_HUMIDITY",50.000000}],[methodName:"event",params:{"BidCos-RF_java","OEQ1667451:2","SET_TEMPERATURE",17.500000}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","CONTROL_MODE",1}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","FAULT_REPORTING",0}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","BATTERY_STATE",2.600000}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","VALVE_STATE",0}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","BOOST_STATE",0}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","ACTUAL_TEMPERATURE",19.600000}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","SET_TEMPERATURE",17.000000}],[methodName:"event",params:{"BidCos-RF_java","MEQ1550667:4","PARTY_TEMPERATURE",5.000000}],[methodName:"
Nov 24 16:33:17 homematic-ccu2 user.err rfd: XmlRpc transport error
Nov 24 16:33:27 homematic-ccu2 user.warn hs485d: XmlRpc transport failed (first try), retrying...
Nov 24 16:33:27 homematic-ccu2 user.warn rfd: XmlRpc transport failed (first try), retrying...
Nov 24 16:33:37 homematic-ccu2 user.err hs485d: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-Wired_java","CENTRAL","PONG","nr"}]}) on http://127.0.0.1:39292/bidcos:
Nov 24 16:33:37 homematic-ccu2 user.err hs485d: XmlRpc transport error
Code: Alles auswählen
Nov 24 16:37:57 homematic-ccu2 user.warn hs485d: XmlRpc transport failed (first try), retrying...
Nov 24 16:38:07 homematic-ccu2 user.err hs485d: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-Wired_java","CENTRAL","PONG","nr"}]}) on http://127.0.0.1:39292/bidcos:
Nov 24 16:38:07 homematic-ccu2 user.err hs485d: XmlRpc transport error
Nov 24 16:41:39 homematic-ccu2 daemon.info node-red[2978]: [ccu-connection:localhost] Interface HmIP-RF disconnected
Nov 24 16:41:39 homematic-ccu2 daemon.warn node-red[2978]: [ccu-connection:localhost] ping timeout HmIP-RF 632
Nov 24 16:41:39 homematic-ccu2 daemon.info node-red[2978]: [ccu-connection:localhost] init HmIP-RF http://127.0.0.1:2048 nr_D24DjW_HmIP-RF
Nov 24 16:42:52 homematic-ccu2 daemon.err node-red[2978]: [ccu-connection:localhost] < HmIP-RF ping Error: socket hang up
Nov 24 16:55:15 homematic-ccu2 user.warn multimac: Bad cast: std::bad_cast
Nov 24 16:55:44 homematic-ccu2 user.warn multimac: Bad cast: std::bad_cast
Nov 24 16:59:42 homematic-ccu2 user.warn multimac: Bad cast: std::bad_cast
Nov 24 17:00:38 homematic-ccu2 user.warn multimac: Bad cast: std::bad_cast
Nov 24 17:05:35 homematic-ccu2 user.err kernel: [2444324.016104] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 32
Nov 24 17:05:35 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:05:37 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:05:37 homematic-ccu2 user.info kernel: [2444324.016115] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:05:37 homematic-ccu2 user.err kernel: [2444326.381038] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 43
Nov 24 17:05:46 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:05:46 homematic-ccu2 user.info kernel: [2444326.381050] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:05:46 homematic-ccu2 user.err kernel: [2444335.176149] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 33
Nov 24 17:05:58 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:05:58 homematic-ccu2 user.info kernel: [2444335.176158] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:05:58 homematic-ccu2 user.err kernel: [2444346.919945] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 43
Nov 24 17:06:30 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:06:30 homematic-ccu2 user.info kernel: [2444346.919953] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:06:30 homematic-ccu2 user.err kernel: [2444378.981351] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 48
Nov 24 17:06:57 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:06:57 homematic-ccu2 user.info kernel: [2444378.981365] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:06:57 homematic-ccu2 user.err kernel: [2444406.017705] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 27
Nov 24 17:06:59 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:06:59 homematic-ccu2 user.info kernel: [2444406.017714] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:06:59 homematic-ccu2 user.err kernel: [2444408.129438] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 28
Nov 24 17:07:07 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:07:07 homematic-ccu2 user.info kernel: [2444408.129448] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:07:07 homematic-ccu2 user.err kernel: [2444415.668080] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 32
Nov 24 17:07:24 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:07:24 homematic-ccu2 user.info kernel: [2444415.668090] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:07:24 homematic-ccu2 user.err kernel: [2444432.655719] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 43
Nov 24 17:07:54 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:07:54 homematic-ccu2 user.info kernel: [2444432.655727] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:07:54 homematic-ccu2 user.err kernel: [2444463.097931] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 43
Nov 24 17:07:59 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:07:59 homematic-ccu2 user.info kernel: [2444463.097941] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:07:59 homematic-ccu2 user.err kernel: [2444468.040577] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 32
Nov 24 17:08:10 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:08:10 homematic-ccu2 user.info kernel: [2444468.040586] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:08:10 homematic-ccu2 user.err kernel: [2444479.167932] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 33
Nov 24 17:08:35 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:08:35 homematic-ccu2 user.info kernel: [2444479.167941] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:08:35 homematic-ccu2 user.err kernel: [2444504.270630] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 27
Nov 24 17:08:52 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:08:52 homematic-ccu2 user.info kernel: [2444504.270640] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:08:52 homematic-ccu2 user.err kernel: [2444521.488732] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 28
Nov 24 17:08:59 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:08:59 homematic-ccu2 user.info kernel: [2444521.488741] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:08:59 homematic-ccu2 user.err kernel: [2444528.482687] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 49
Nov 24 17:09:16 homematic-ccu2 user.warn multimac: UpstreamCharConnection slave device mmd_hmip write error: Bad address
Nov 24 17:09:16 homematic-ccu2 user.info kernel: [2444528.482697] eq3loop: eq3loop_write_master() return error: -14
Nov 24 17:09:16 homematic-ccu2 user.err kernel: [2444544.686241] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 21, required space = 29
Nov 24 17:14:36 homematic-ccu2 user.warn monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:14:59 homematic-ccu2 user.warn monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:15:22 homematic-ccu2 user.warn monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:15:45 homematic-ccu2 user.warn monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:16:08 homematic-ccu2 user.err monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:16:08 homematic-ccu2 user.info monit[1203]: 'HMIPServer' trying to restart
Nov 24 17:16:08 homematic-ccu2 user.info monit[1203]: 'HMIPServer' restart: '/etc/init.d/S62HMServer restart'
Nov 24 17:16:38 homematic-ccu2 user.err monit[1203]: 'HMIPServer' failed to restart (exit status -1) -- '/etc/init.d/S62HMServer restart': Program timed out -- Stopping HMIPServer: OK Starting HMIPServer: ..............
Nov 24 17:16:56 homematic-ccu2 user.err monit[1203]: 'HMIPServer' service restarted 1 times within 1 cycles(s) - exec
Nov 24 17:16:56 homematic-ccu2 user.info monit[1203]: 'HMIPServer' exec: '/bin/triggerAlarm.tcl HMIPServer restarted WatchDog-Alarm'
Nov 24 17:16:56 homematic-ccu2 user.info monit[1203]: 'HMIPServer' process is running after previous exec error (slow starting or manually recovered?)
Nov 24 17:16:56 homematic-ccu2 user.info monit[1203]: 'HMIPServer' process is running after previous restart timeout (manually recovered?)
Nov 24 17:17:01 homematic-ccu2 user.err monit[1203]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection timed out
Nov 24 17:17:01 homematic-ccu2 user.info monit[1203]: 'HMIPServer' trying to restart
Nach etwas googlen bin ich auf https://github.com/jens-maus/RaspberryMatic/issues/703 gestoßen. In diesem Eintrag wird ziemlich genau das aufgetretene Fehlerbild beschrieben, der Fehler scheint also nicht mit 3.47.18.20190918 behoben zu sein.
Dieser Fehler scheint jedoch durch etwas anders ausgelöst zu werden, denn die Probleme haben ja schon etwas vorher begonnen, leider reichen meine Kenntnisse für eine weitere Analyse nicht aus.
Wenn es hilft kann ich gerne diese Infos auch noch bei GitHub hinzufügen bzw. den kompletten SysLog bzw. hmserver.log per PN zusenden.
Viele Grüße und Danke für alles
Patrick