Ältere Versionen des Pico C Programm vor Version 5.5.2 werden beim Update auf Loxone Config v9 und v10 in bestimmten Fällen deaktiviert. Leider gibt es von Loxone keinen Hinweis auf die Ursache, warum das Pico C Programm deaktiviert wurde. Eine Erläuterung von Loxone, unter welchen Bedingungen Pico C Programme in der Config v9 und v10 deaktiviert werden, konnte ich bisher auf den Webseiten von Loxone nicht finden. Das Problem tritt nur direkt nach dem Hochladen einer neuen Konfiguration auf den MS auf und nicht während des Betriebs.
Da ich mittlerweile auch auf v10 aktualisiert habe und bei mir das Problem ebenfalls aufgetreten ist (bisher 1x), habe ich mehrere Sicherheitsabfragen und Meldepunkte eingebaut. Nach Auffälligkeiten beim Schreiben habe ich auch hier noch zusätzliche Überprüfungen eingefügt. Jeder Anwender, der ebenfalls Probleme mit der Deaktivierung hat, ist herzlich eingeladen die Version zu testen. Es werden nur am Anfang bei der Initialisierung Meldungen in das Log geschrieben. Nach einem Schleifendurchlauf wird das Logging beendet, um hohe Schreiblast auf der SD-Karte zu vermeiden.
Wer ebenfalls das Problem hat, kann gerne im Loxforum Hinweise zur Verfügung stellen (Fragenliste vorhanden), die bei der Suche nach der Ursache helfen. Wer Zeit hat, kann sich auch an den Loxone Support wenden, um die Ursache der Deaktivierung herauszufinden. Der Loxone Support kann wahrscheinlich nicht jedes selbstgeschriebene Pico C Programm analysieren, aber ich hatte die Hoffnung, dass der Support zumindest einen Grund für die Deaktivierung zu nennen kann - bisher habe ich leider keine Hinweise von anderen Anwendern erhalten.
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:
// *** 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:
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:
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:
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.
- 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.