Zum Ende der Metadaten springen
Zum Anfang der Metadaten

Seit Loxone Config v9 berichten Anwender von deaktivierten Pico C Progammen. Seit ich vor einigen Tagen meinen MS direkt von v8 auf v10 aktualisiert habe, tauchen bei mir ebenfalls diese Probleme auf.

Symptom: Das Pico-C Programm läuft nicht mehr. Der Status (TeQ des Programmbausteins) meldet "Program disabled". In der Loxone Config sind folgende Zeilen am Anfang des Pico-C Programmes ergänzt worden:

Pico-C Programm Helios easyControls
// *** PROGRAM DISABLED ***
// This PicoC program caused an error.
// Please find and correct the error and remove these three comment lines to enable this PicoC program again.
// KWL mit easyControls über Modbus TCP steuern v5.4
...

Im Loxone Log (/log/def.log), welches z.B. mit FileZilla per FTP aus dem MS ausgelesen werden kann, waren folgende Einträge:

Loxone Log
2018-11-12 00:46:43.251;PRG got restart command
2018-11-11 23:46:48.678;Read network config: IP: 192.168.128.25, Mask: 255.255.255.0, Gateway: 192.168.128.1, NTP: 0.pool.ntp.org
2018-11-12 00:46:51.098;PRG Reboot 10.0.9.24
2018-11-12 00:46:53.004;Loading sps_new.zip - Remove old custom changes
2018-11-12 00:47:00.347;Disabled PicoC program Helios KWL because it caused an error.
2018-11-12 00:47:03.149;PRG start program
2018-11-12 00:47:04.595;RestoreRemanenceState /sys/rem/rem18.xml and /sys/rem/rem118.xml
2018-11-12 00:47:01.473;Rename program /prog/sps_0156_20181112004705.zip
2018-11-12 00:47:04.878;PRG start 1-Wire Extension (Schaltschrank R03 P03 (Loxone 1-Wire Extension)) serial 056c0043 version 9.0.8.22
2018-11-12 00:48:00.008;Warning 503, '@Home' - 'Neustart des Systems', admins, Miniserver (Uuid 039659af-6e35-11df-9d15efc088fafadd)
2018-11-12 00:59:00.018;Warning 506, '@Home' - 'Meldung', admins, Miniserver (Uuid 039659af-6e35-11df-9d15efc088fafadd)
2018-11-12 22:34:20.396;Program send: /prog/sps_0156_20181112004705.zip


Um 00:48:00 (entspricht der drittletzten Meldung im Log) bekam ich per E-Mail die folgende Meldung:

Mindestens ein PicoC-Programm verursacht Fehler und wurde daher deaktiviert.
Bitte prüfen und korrigieren Sie Ihre PicoC Programme.

Seit dem Upgrade auf v10 hat das Pico-C Programm folgende Meldungen/Fehler gesendet:

Meldungen seit Upgrade
2018-11-08 22:30:19.122;PRG Reboot 10.0.9.24
2018-11-08 22:30:33.092;PRG start program
2018-11-08 22:30:30.512;RestoreRemanenceState /sys/rem/rem40.xml and /sys/rem/rem140.xml
2018-11-08 22:30:31.018;Save program 
2018-11-08 22:30:35.254;INFO: Helios KWL Modbus TCP start
2018-11-08 22:49:17.735;PRG got restart command
2018-11-08 22:49:30.924;PRG start program
2018-11-08 22:49:37.211;INFO: Helios KWL Modbus TCP start
2018-11-08 22:58:02.869;PRG got restart command
2018-11-08 22:58:16.877;PRG start program
2018-11-08 22:58:23.104;INFO: Helios KWL Modbus TCP start
2018-11-08 22:58:24.400;ERROR: Helios KWL modbusTCP select or write variable v01036=16 failed (0 bytes read) !
2018-11-08 23:00:36.498;Update Miniserver /update/10000924_Miniserver.upd (force)
2018-11-08 23:01:19.555;Update Miniserver /update/10000924_Miniserver.upd erfolgreich
2018-11-08 23:01:19.873;Reboot Loxone Miniserver
2018-11-08 23:01:40.068;PRG Reboot 10.0.9.24
2018-11-08 23:01:53.666;PRG start program
2018-11-08 23:01:56.176;INFO: Helios KWL Modbus TCP start
2018-11-08 23:01:56.882;ERROR: Helios KWL modbusTCP select or write variable v01035=22 failed (0 bytes read) !
2018-11-08 23:02:21.651;LNK start update for Extension (MinVersion 9000822, HW Version 0)
2018-11-08 23:04:05.867;PRG start Extension (Schaltschrank R01 P02 (Loxone Extension)) serial 01d8xxxx version 9.0.8.22
2018-11-08 23:04:05.873;PRG start Extension (Schaltschrank R01 P03 (Loxone Extension)) serial 01d8xxxx version 9.0.8.22
2018-11-08 23:04:19.054;LNK update for Extension succeeded (Retried pages: 2)
2018-11-08 23:04:19.294;LNK start update for Dimmer Extension (MinVersion 9000904, HW Version 0)
2018-11-08 23:06:27.239;Warning 502, '@Home' - 'SD-Karte Schreiblast hoch', admins, Miniserver (Uuid 039659af-6e35-11df-9d15efc088fafadd)
2018-11-08 23:06:38.602;PRG start Dimmer Extension (Schaltschrank R04 P03 (Loxone Dimmer Extension)) serial 02d8xxxx version 9.0.9.4
2018-11-08 23:06:38.620;PRG start Dimmer Extension (Schaltschrank R05 P03 (Loxone Dimmer Extension)) serial 02d8xxxx version 9.0.9.4
2018-11-08 23:06:51.711;LNK update for Dimmer Extension succeeded (Retried pages: 2)
2018-11-08 23:06:51.922;LNK start update for Relay Extension (MinVersion 9000822, HW Version 0)
2018-11-08 23:08:16.139;PRG start Relay Extension (Schaltschrank R02 P02 (Loxone Relay Extension)) serial 0bd8xxxx version 9.0.8.22
2018-11-08 23:08:16.145;PRG start Relay Extension (Schaltschrank R02 P01 (Loxone Relay Extension)) serial 0bd8xxxx version 9.0.8.22
2018-11-08 23:08:29.249;LNK update for Relay Extension succeeded (Retried pages: 2)
2018-11-08 23:08:29.483;LNK start update for DMX Extension (MinVersion 9000915, HW Version 0)
2018-11-08 23:10:16.663;PRG start DMX Extension (Schaltschrank R01 P03 (Loxone DMX Extension)) serial 0470xxxx version 9.0.9.15
2018-11-08 23:10:29.769;LNK update for DMX Extension succeeded (Retried pages: 1)
2018-11-08 23:10:30.003;LNK start update for 1Wire Extension (MinVersion 9000822, HW Version 0)
2018-11-08 23:12:23.113;PRG start 1-Wire Extension (Schaltschrank R03 P03 (Loxone 1-Wire Extension)) serial 056cxxxx version 9.0.8.22
2018-11-08 23:12:36.211;LNK update for 1Wire Extension succeeded (Retried pages: 1)
2018-11-08 23:14:00.008;Warning 506, '@Home' - 'Meldung', admins, Miniserver (Uuid 039659af-6e35-11df-9d15efc088fafadd)
2018-11-09 00:15:38.095;ERROR: Helios KWL modbusTCP read variable v01300 - answer too short, only 0 Bytes, <>
2018-11-09 00:15:39.719;ERROR: Helios KWL modbusTCP select or write variable v01301 failed (0 bytes read) !
2018-11-09 00:15:41.931;ERROR: Helios KWL modbusTCP read variable v01302 - answer too short, only 12 Bytes, <>
2018-11-09 14:25:43.358;ERROR: Helios KWL modbusTCP read variable v00000 - answer too short, only 0 Bytes, <>
2018-11-11 20:49:22.821;PRG got restart command
2018-11-11 20:49:37.664;PRG start program
2018-11-11 20:49:43.167;INFO: Helios KWL Modbus TCP start
2018-11-11 20:49:43.885;ERROR: Helios KWL modbusTCP select or write variable v01035=22 failed (0 bytes read) !
2018-11-11 21:01:00.006;Warning 506, '@Home' - 'Meldung', admins, Miniserver (Uuid 039659af-6e35-11df-9d15efc088fafadd)
2018-11-12 00:46:43.251;PRG got restart command
2018-11-12 00:46:51.098;PRG Reboot 10.0.9.24
2018-11-12 00:47:00.347;Disabled PicoC program Helios KWL because it caused an error.
2018-11-12 00:47:03.149;PRG start program


Es ist ja sehr fürsorglich von Loxone, dass jetzt der Miniserver fehlerhafte Pico-C Programme erkennt und diese deaktiviert. Sehr unglücklich finde ich aber, dass dies ohne einen erkennbaren Grund passiert und weder im Log noch in der E-Mail ein brauchbarer Hinweis auf den Grund zu finden ist. 


(temporäre) Lösung: Löschen der 3 ersten Zeilen im Pico C Programm und senden des geänderten Codes an den MS. Wer mithelfen möchte, die Problemursache zu finden bzw. einzugrenzen, bitte im vorhandenen Beitrag im Loxforum "Helios KWL (easycontrols) über modbus tcp einbinden" posten. Möglicherweise treten die Probleme immer am Anfang des Pico-C Programmes auf. Daher habe ich testweise am Anfang mehrere Sicherheitsabfragen ergänzt.


UPDATE: Es liegt jetzt die Version 5.5.1 vor, in der etliche Meldungen beim Start des Programms ausgegeben werden. Über diese Meldungen, die im Log protokolliert werden, ist es hoffentlich möglich, den Code, der in bestimmten Fällen für eine Deaktivierung des Programms verantwortlich ist, zu identifizieren. Jeder Anwender, der ebenfalls Probleme hat, ist herzlich eingeladen, die Version zu installieren und die Ausgabe der Meldungspunkte aus dem Log im Loxforum zu posten, wenn bei ihm das Programm deaktiviert wurde.

UPDATE 2: Es liegt jetzt die Version 5.5.2 vor, in der die Überprüfung beim Schreiben von Variablen erweitert wurde. Nach mehrmaligen Neustarts des MS kurz hintereinander wurde bei mir das Programm mit 5.5.1 deaktiviert. Daraufhin habe ich die Log-Meldungen auf mehrere Schleifendurchläufe ergänzt und festgestellt, dass Fehler beim Schreiben ignoriert wurden und damit Variablen erst im nächsten Intervall wieder geschrieben wurden. Hier habe ich die Rückgabewerte der Funktionen überarbeitet, obwohl es mir unklar ist, wie dadurch das Programm deaktiviert werden konnte.

Über die Meldungen, die im Log protokolliert werden, ist es hoffentlich möglich, den Code, der in bestimmten Fällen für eine Deaktivierung des Programms verantwortlich ist, zu identifizieren. Jeder Anwender, der ebenfalls Probleme hat, ist herzlich eingeladen, die Version zu installieren und die Ausgabe der Meldungspunkte aus dem Log im Loxforum zu posten, wenn bei ihm das Programm deaktiviert wurde.

So sieht das Log aus, wenn das Programm NICHT deaktiviert wurde:

Debug Meldungen des Programms
2018-11-18 14:24:13.419;PRG got restart command
2018-11-18 14:24:14.425;Loading sps_new.zip - Remove old custom changes
2018-11-18 14:24:32.579;PRG start program
2018-11-18 14:24:33.777;RestoreRemanenceState /sys/rem/rem97.xml and /sys/rem/rem197.xml
2018-11-18 14:24:34.259;Program started: /prog/sps_0156_20181118142241.zip
2018-11-18 14:24:34.639;Rename program /prog/sps_0156_20181118142434.zip
2018-11-18 14:24:39.088;DEBUG: Helios KWL Modbus TCP - Watchpoint 1 - Start of program
2018-11-18 14:24:39.106;INFO: Helios KWL Modbus TCP start
2018-11-18 14:24:39.106;DEBUG: Helios KWL Modbus TCP - Watchpoint 2 - variables defined
2018-11-18 14:24:39.110;DEBUG: Helios KWL Modbus TCP - Watchpoint 3 - modbus variable initialized
2018-11-18 14:24:39.170;DEBUG: Helios KWL Modbus TCP - Watchpoint 4 - begin of main
2018-11-18 14:24:49.179;DEBUG: Helios KWL Modbus TCP - Watchpoint 5 - INPUT betriebsart = 0 
2018-11-18 14:24:49.179;DEBUG: Helios KWL Modbus TCP - Watchpoint 6 - INPUT errorMax = 50 
2018-11-18 14:24:49.183;DEBUG: Helios KWL Modbus TCP - Watchpoint 7 - INPUT errorInterval = 3600 
2018-11-18 14:24:49.189;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:24:49.189;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 10
2018-11-18 14:24:49.193;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:24:49.193;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:24:49.193;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:24:49.201;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:24:49.205;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:24:49.205;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:24:49.209;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:24:49.209;DEBUG: Helios KWL Modbus TCP - Watchpoint 16 - before creating TCP stream
2018-11-18 14:24:49.211;DEBUG: Helios KWL Modbus TCP - Watchpoint 17 - TCP stream created
2018-11-18 14:24:49.215;DEBUG: Helios KWL Modbus TCP - Watchpoint 18 - WRITE luefterStufe = 2 
2018-11-18 14:24:49.781;ERROR: Helios KWL modbusTCP select or write variable v00102=2 failed (0 bytes read) !
2018-11-18 14:24:50.791;DEBUG: Helios KWL Modbus TCP - Watchpoint 20 - before setoutput
2018-11-18 14:24:50.841;DEBUG: Helios KWL Modbus TCP - Watchpoint 21 - setoutput and sleep finished
2018-11-18 14:24:50.845;DEBUG: Helios KWL Modbus TCP - Watchpoint 22 - WRITE bypassTemp = 22 
2018-11-18 14:24:51.411;DEBUG: Helios KWL Modbus TCP - Watchpoint 23 - bypassTemp = 22 written
2018-11-18 14:24:51.465;DEBUG: Helios KWL Modbus TCP - Watchpoint 24 - WRITE minAussenBypassTemp = 16 
2018-11-18 14:24:52.041;DEBUG: Helios KWL Modbus TCP - Watchpoint 25 - minAussenBypassTemp = 16 written
2018-11-18 14:24:52.095;DEBUG: Helios KWL Modbus TCP - Watchpoint 26 - READ tempAussenluft
2018-11-18 14:24:53.275;DEBUG: Helios KWL Modbus TCP - Watchpoint 27 - READ tempAussenluft
2018-11-18 14:24:54.499;DEBUG: Helios KWL Modbus TCP - Watchpoint 28 - READ tempFortluft
2018-11-18 14:24:55.745;DEBUG: Helios KWL Modbus TCP - Watchpoint 29 - READ tempAbluft
2018-11-18 14:24:57.000;DEBUG: Helios KWL Modbus TCP - Watchpoint 30 - READ tempAussenluft
2018-11-18 14:24:58.246;DEBUG: Helios KWL Modbus TCP - Watchpoint 31 - READ rpmAbluft
2018-11-18 14:24:59.496;DEBUG: Helios KWL Modbus TCP - Watchpoint 32 - READ bypass
2018-11-18 14:25:00.746;DEBUG: Helios KWL Modbus TCP - Watchpoint 33 - READ anzahlFehler
2018-11-18 14:25:01.996;DEBUG: Helios KWL Modbus TCP - Watchpoint 34 - READ anzahlWarnungen
2018-11-18 14:25:03.250;DEBUG: Helios KWL Modbus TCP - Watchpoint 35 - READ anzahlInfo
2018-11-18 14:25:04.496;DEBUG: Helios KWL Modbus TCP - Watchpoint 36 - READ real luefterstufe
2018-11-18 14:25:05.750;DEBUG: Helios KWL Modbus TCP - Watchpoint 37 - READ artikelBez
2018-11-18 14:25:06.907;DEBUG: Helios KWL Modbus TCP - Watchpoint 38 - before close stream
2018-11-18 14:25:06.911;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:07.415;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:07.415;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 9
2018-11-18 14:25:07.421;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:07.421;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:07.421;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:07.429;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:07.433;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:07.433;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:07.437;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:07.437;DEBUG: Helios KWL Modbus TCP - Watchpoint 16 - before creating TCP stream
2018-11-18 14:25:07.443;DEBUG: Helios KWL Modbus TCP - Watchpoint 17 - TCP stream created
2018-11-18 14:25:07.443;DEBUG: Helios KWL Modbus TCP - Watchpoint 18 - WRITE luefterStufe = 2 
2018-11-18 14:25:07.981;DEBUG: Helios KWL Modbus TCP - Watchpoint 19 - luefterStufe = 2 written
2018-11-18 14:25:07.981;DEBUG: Helios KWL Modbus TCP - Watchpoint 20 - before setoutput
2018-11-18 14:25:08.035;DEBUG: Helios KWL Modbus TCP - Watchpoint 21 - setoutput and sleep finished
2018-11-18 14:25:08.149;DEBUG: Helios KWL Modbus TCP - Watchpoint 26 - READ tempAussenluft
2018-11-18 14:25:09.399;DEBUG: Helios KWL Modbus TCP - Watchpoint 27 - READ tempAussenluft
2018-11-18 14:25:10.649;DEBUG: Helios KWL Modbus TCP - Watchpoint 28 - READ tempFortluft
2018-11-18 14:25:11.903;DEBUG: Helios KWL Modbus TCP - Watchpoint 29 - READ tempAbluft
2018-11-18 14:25:13.149;DEBUG: Helios KWL Modbus TCP - Watchpoint 30 - READ tempAussenluft
2018-11-18 14:25:14.403;DEBUG: Helios KWL Modbus TCP - Watchpoint 31 - READ rpmAbluft
2018-11-18 14:25:15.649;DEBUG: Helios KWL Modbus TCP - Watchpoint 32 - READ bypass
2018-11-18 14:25:16.904;DEBUG: Helios KWL Modbus TCP - Watchpoint 33 - READ anzahlFehler
2018-11-18 14:25:18.150;DEBUG: Helios KWL Modbus TCP - Watchpoint 34 - READ anzahlWarnungen
2018-11-18 14:25:19.404;DEBUG: Helios KWL Modbus TCP - Watchpoint 35 - READ anzahlInfo
2018-11-18 14:25:20.650;DEBUG: Helios KWL Modbus TCP - Watchpoint 36 - READ real luefterstufe
2018-11-18 14:25:21.900;DEBUG: Helios KWL Modbus TCP - Watchpoint 37 - READ artikelBez
2018-11-18 14:25:23.150;DEBUG: Helios KWL Modbus TCP - Watchpoint 38 - before close stream
2018-11-18 14:25:23.154;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:23.658;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:23.658;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 8
2018-11-18 14:25:23.662;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:23.662;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:23.666;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:23.670;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:23.674;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:23.674;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:23.678;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:23.738;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:24.244;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:24.244;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 7
2018-11-18 14:25:24.244;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:24.248;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:24.248;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:24.256;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:24.258;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:24.262;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:24.266;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:24.322;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:24.826;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:24.826;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 6
2018-11-18 14:25:24.830;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:24.830;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:24.830;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:24.838;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:24.842;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:24.842;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:24.846;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:24.906;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:25.411;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:25.411;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 5
2018-11-18 14:25:25.411;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:25.417;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:25.419;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:25.427;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:25.427;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:25.431;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:25.435;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:25.491;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:25.995;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:25.995;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 4
2018-11-18 14:25:26.000;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:26.001;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:26.001;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:26.009;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:26.013;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:26.013;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:26.017;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:26.083;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:26.583;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:26.587;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 3
2018-11-18 14:25:26.587;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:26.591;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:26.591;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:26.599;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:26.599;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:26.603;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:26.607;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:26.663;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:27.167;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:27.167;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 2
2018-11-18 14:25:27.171;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:27.171;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:27.171;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:27.179;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:27.183;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:27.183;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:27.187;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:27.243;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:27.747;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - start of main loop ===============================
2018-11-18 14:25:27.747;DEBUG: Helios KWL Modbus TCP - Watchpoint 8 - loop counter = 1
2018-11-18 14:25:27.751;DEBUG: Helios KWL Modbus TCP - Watchpoint 9 - INPUT luefterStufeNormal = 2 
2018-11-18 14:25:27.751;DEBUG: Helios KWL Modbus TCP - Watchpoint 10 - INPUT stosslueften = 0 
2018-11-18 14:25:27.755;DEBUG: Helios KWL Modbus TCP - Watchpoint 11 - INPUT intensivlueften = 0 
2018-11-18 14:25:27.763;DEBUG: Helios KWL Modbus TCP - Watchpoint 12 - INPUT bypassTemp = 22 
2018-11-18 14:25:27.763;DEBUG: Helios KWL Modbus TCP - Watchpoint 13 - INPUT minAussenBypassTemp = 16 
2018-11-18 14:25:27.767;DEBUG: Helios KWL Modbus TCP - Watchpoint 14 - INPUT pullFreq = 30 
2018-11-18 14:25:27.767;DEBUG: Helios KWL Modbus TCP - Watchpoint 15 - before test of write
2018-11-18 14:25:27.827;DEBUG: Helios KWL Modbus TCP - Watchpoint 39 - end of main loop
2018-11-18 14:25:27.827;DEBUG: Helios KWL Modbus TCP - Watchpoint 40 - end of startup debugging

In dem o.a. Beispiel kann man sehen, dass bei Schreibfehlern gezielt der Schreibvorgang dieser Variable (v00102) wiederholt wird.

Liste von Erkenntnissen, Annahmen und Vermutungen zu dem Problem

  • Auffälligkeit: In meinem Log sind Meldungen mit dem Text "PRG Reboot 10.0.9.24" (oder andere Version seit v9) GENAU DANN zu finden, wenn danach das Programm disabled wird. Bin mir aber noch nicht sicher, was die Meldung genau bedeutet. Vielleicht wird die durch einen weiteren Befehl "in MS speichern" ausgelöst wird, bevor der MS richtig arbeitet oder durch die unbekannte  Erkennungsfunktion von Loxone auf "schlechte" Pico-C Programme, die dann das Pico-C Programm deaktiviert und den MS neu startet. 
  • Vermutung: Das Problem könnte mit der Unterbrechung der TCP Kommunikation zwischen MS und der Lüftungsanlage durch den Loxone Config Befehl "in MS speichern" zusammenhängen. Ein anderes Pico-C Programm von mir, welches keine TCP Stream Funktionen verwendet, hat keine Probleme.


  • Keine Stichwörter

2 Kommentare

  1. Ich bin sehr gerne bereit mit an einer Lösung zu arbeiten, habe das Problem ca. seit 3 Jahren - seit der Idee mit dem Helios 4 Eaysycontrols. Ich arbeite mit parallelen Programmen zur Abfrage von 3 Helios Anlagen. Soweit nur eine Anlage involviert war läuft es manchmal wochenlang stabil, mehr als eine Anlage parallel habe ich aber noch nicht durchgehend am Laufen gehabt.

    Wie wollen wir weiter vorgehen?

    1. Wie oben geschrieben, habe ich hierzu was im Loxforum gepostet. Ich denke, dass die Platform besser für einen Austausch geeignet ist und mehr Anwender dort einen Account haben.