OWX asynchron überarbeitet

Begonnen von ntruchsess, 30 Juni 2013, 00:55:59

Vorheriges Thema - Nächstes Thema

ntruchsess

Mit der OWX_ASYNC-implementierung geht's übrigens bald weiter. Ich habe da in der letzten Woche noch einiges im code bereinigt. Die eher schwierig zu beherschenden asynchronen callback-interfaces sind durch das Protothreads-basierte Refactoring obsolet geworden und entfallen mit der nächsten Version ersatzlos. Jetzt ist ausnahmslos alles, was nicht per 'get'-befehl aus dem Web-frontend abgerufen wird, asynchron implementiert. Sieht im Test schon recht gut aus, bei mir gibt's nach der Initialisierung keine weiteren Freezes mehr. Werde ich in Kürze ins FHEM-SVN commiten.

Gruß,

Norbert
while (!asleep()) {sheep++};

ntruchsess

#226
Zitat von: ntruchsess am 12 Juli 2014, 11:49:34
Mit der OWX_ASYNC-implementierung geht's übrigens bald weiter.

das angekündigte Update von OWX_ASYNC habe ich gerade ins SVN committed. Ist damit in Kürze per fhem-update erhältlich.

Wichtig: Das asynchrone Interface zwischen den Client-Devices und dem OWX_ASYNC-device habe ich geändert, es gibt keine Kompatibilität älterer OWX-Client-device-versionen mit dem jetzt aktuellen OWX_ASYNC und umgekehrt! Also Update geht nur ganz oder gar nicht.

(Das betrifft nicht die Kombination der OWX-devices mit dem normalen, synchronen OWX. Da kann man OWX-clients und OWX durchaus in unterschiedlichen Versionen kombinieren).

Funktionell hat sich nicht viel nach außen sichtbares geändert. Das update dient ist im wesentlichen der Stabilität und Bereinigung von Altlasten. Ich denke, dass das asynchrone Framework jetzt gut ausentwickelt ist, damit ist der Weg frei weitere Busmaster-klassen zu entwickeln (z.B. für DS2480 über Ethernet, DS9097, DS2482 an I2C, CUNO...). Stay tuned ;-)

Bugs die jetzt behoben sind (sein sollten ;-)):
- freezes in Verbindung mit FRM
- freezes in Verbindung mit OWID
- (unnötig) hohe CPU-last
- Abstürze durch nicht gefangene Exceptions
- inkonsistente Werte von 'present'
- einige timingbedingte CRC-fehler in Verbindung mit FRM
- Fehler bei verzögertem connect von FRM over Ethernet

Über Feedback würde ich mich wie immer freuen.

Gruß,

Norbert
while (!asleep()) {sheep++};

hexenmeister

Hallo!

Habe die neue Version ausprobiert. Leider stürzt damit FHEM ab.
Was ist zuletzt im Log sehe:
Zitat2014.07.15 21:56:22.458 5: OWX_DS2480 read: After loop no. 1 received: cdec80089f6eaa88a28a200000000000008a
2014.07.15 21:56:22.466 5: OWX_SER::Search: new alarm device found 28.7BAFBD040000.B0
2014.07.15 21:56:22.477 5: OWX_DS2480.query sending out: e3c5
2014.07.15 21:56:22.553 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.15 21:56:22.559 5: OWX_DS2480.query sending out: e180088aaa000000000000000000000000e3a5
2014.07.15 21:56:22.562 4: OWX_ASYNC_RunTasks: OWio1 task timed out

Ich vermute, es hat etwas mit dem Problem zu tun, was ich gerade habe: Aufgrund eines Leitungsproblems habe ich mit der Synchronen Version oft "Reset failure". Die Asynchrone stürzt in dieser Situation scheinbar ganz ab.

So gesehen habe ich gerade die Wahl: Mit Frieses leben oder mit den Abstürzen. ;)

Grüße,

Alexander

cwagner

Hallo Norbert,

gebessert hat sich die Stabilität, geblieben sind die Vorteile (geringere CPU-Belastung).

ABER: Leider funktioniert mein Switch (DS2408) nicht - er wird initialisiert und ist present, aber dabei bleibt es dann. Kein set <devicename> output A...H on|off hat Wirkung.

Bei einem get <devicename>version erhalte ich als Fehlermeldung: Undefined subroutine &main::OWX_ASYNC_PT_Verify called

Versionsinfo: 5.17

Herzliche Grüße

Christian
PI 2B+/5 Raspbian 12, Perl 5.36.0, FHEM 6.3: 295 Module in ConfigDB: Steuerung Heizkessel, FBH, Solarthermie, kontr. Lüftung mit WRG. Smarthome u.a. HMCUL, 1-Wire (FT232RL ; DS2480B), EnOcean (TCM EPS3), MQTT2. DOIF, PID20, Threshold, OWX; Micropelt IRTV, Volkszähler, SolarForecast; MariaDB

ntruchsess

#229
Hallo Alexander,
Zitat von: hexenmeister am 15 Juli 2014, 22:01:56
Was ist zuletzt im Log sehe:

2014.07.15 21:56:22.562 4: OWX_ASYNC_RunTasks: OWio1 task timed out

... Aufgrund eines Leitungsproblems habe ich mit der Synchronen Version oft "Reset failure" ...
Ja, Leitungsprobleme führen mit OWX_ASYNC entweder zu CRC- (oder anderen 'fehlerhafte Daten'-fehlern) oder zu Timeouts, wenn weniger Bytes als erforderlich zurückkommen. Abstürzen soll es dabei natürlich nicht - was steht dabei denn auf der Konsole?


Hallo Christian,
Zitat von: cwagner am 15 Juli 2014, 23:00:35
Bei einem get <devicename>version erhalte ich als Fehlermeldung: Undefined subroutine &main::OWX_ASYNC_PT_Verify called
Merkwürdig, da scheint das OWX_ASYNC-Modul noch nicht geladen zu sein? Passt denn die Reihenfolge in der fhem.cfg? Irgendwelche Fehlermeldungen beim Laden des OWX_ASYNC? Eventuell sollte ich noch ein passendes require in alle Client-files aufnehmen, aber wenn OWX_ASYNC nicht geladen ist, dann gehen die Client-devices eh nicht. Die set-commandos gehen dann natürlich auch nicht.
Kann es außerdem sein, dass Du nicht get <devicename> version abgeschickt, sondern 'get <devicename> present' abgeschickt hast? get Version kann den eigentlich überhauptnicht produzieren?

Gruß,

Norbert
while (!asleep()) {sheep++};

hexenmeister

Hallo Norbert,

auf err wird folgendes geloggt:
EG_HA_OWTS11.Warmwasser_Speicher unexpected thread state after termination: 0 at ./FHEM/00_OWX_ASYNC.pm line 1117.
oder welche Konsole meinst Du?
Ich habe zwei ds2480 1w-Controller. Das Leitungsproblem hat aber eigentlich nicht der, wo EG_HA_OWTS11.Warmwasser_Speicher dran hängt, sondern der andere.  ???

Grüße,

Alexander

ntruchsess

Hallo Christian,
Zitat von: cwagner am 15 Juli 2014, 23:00:35
Bei einem get <devicename>version erhalte ich als Fehlermeldung: Undefined subroutine &main::OWX_ASYNC_PT_Verify called
kann es sein, dass Dein Update nicht vollständig war und OWX_ASYNC noch auf einer älteren (nicht mit den neuen OWX-devices kompatiblen) Version geblieben ist?

Hallo Alexander,
Zitat von: hexenmeister am 16 Juli 2014, 02:17:02
auf err wird folgendes geloggt:
EG_HA_OWTS11.Warmwasser_Speicher unexpected thread state after termination: 0 at ./FHEM/00_OWX_ASYNC.pm line 1117.
oder welche Konsole meinst Du?
danke, die meinte ich. Hast Du für das Device 'doKick' -> 'tempConv: onkick' aktiviert?

Gruß,

Norbert
while (!asleep()) {sheep++};

cwagner

Hi Norbert, meine Antwort und Deine Frage überschnitten sich - ich werde sicherheitshalber nun noch ein update force machen.

Grüße

Christian
PI 2B+/5 Raspbian 12, Perl 5.36.0, FHEM 6.3: 295 Module in ConfigDB: Steuerung Heizkessel, FBH, Solarthermie, kontr. Lüftung mit WRG. Smarthome u.a. HMCUL, 1-Wire (FT232RL ; DS2480B), EnOcean (TCM EPS3), MQTT2. DOIF, PID20, Threshold, OWX; Micropelt IRTV, Volkszähler, SolarForecast; MariaDB

hexenmeister

Zitat von: ntruchsess am 16 Juli 2014, 07:34:06
Hast Du für das Device 'doKick' -> 'tempConv: onkick' aktiviert?
Ja, ist aktiv.

Grüße,

Alexander

cwagner

#234
Hallo Norbert,

nch update force bin ich nun komplett synchron mit dem SVN. Danach hat sich die Situation nicht wirklich verändert. Übrigens: Um die Reihenfolge des Initialisierens ganz sicher zu bestimmen, habe ich alle 1-wire-Devices in eine eigene CFG ausgelagert.

Positiv ist, dass die Initialisierung der Devices bei Start jetzt drastisch zügiger geht - lediglich der Switch braucht noch 1 Minute.

1. Mit get device erhalte ich eine unvollständige Deviceliste, obwohl alle devices ordnungsgemäß initialisiert sind. Nach einem weiteren Neustart mit vorherigem physischen Neustart des 1-Wire-Busses (also 5 Minuten keine Stromversorgung, damit auch alle Kondensatoren leer sind), habe ich jetzt eine vollständiger Deviceliste. Mein Problem Nr.
2. Der DS 2408 (Switch 29.68980C000000.DA) reagiert immer noch nicht. Ich habe um ~9:44:45 den Befehl set Switch_Heizkeller output D off  und um etwa 9: 45:15 "on" abgesetzt, finde aber im mit verbose 5 aufgezeichneten Log nicht wirklich etwas dazu. Habe Dir mal einen großzügigen Ausschnitt hier reingepackt:

2014.07.16 08:51:45 1: Including ./FHEM/1wire_devices.cfg
2014.07.16 08:52:33 3: OWTHERM: Device T_Vorlauf_FBH defined.
2014.07.16 08:52:33 3: OWTHERM: Device T_Heizung defined.
2014.07.16 08:52:34 3: OWTHERM: Device T_Warmwasser defined.
2014.07.16 08:52:34 3: OWTHERM: Device T_Ruecklauf_Anhebung defined.
2014.07.16 08:52:34 3: OWTHERM: Device T_Ruecklauf defined.
2014.07.16 08:52:34 3: OWTHERM: Device Dachfenster_Sued defined.
2014.07.16 08:53:52 3: OWAD:    Device Umweltsensor defined.
2014.07.16 08:53:53 3: OWTHERM: Device Zuluft defined.
2014.07.16 08:53:53 3: OWTHERM: Device Abluft defined.
2014.07.16 08:53:53 3: OWTHERM: Device Fortluft defined.
2014.07.16 08:53:53 3: OWTHERM: Device Aussenluft defined.
2014.07.16 08:55:09 3: OWSWITCH: Device Switch_Heizkeller defined.
2014.07.16 08:56:03 3: OWMULTI: Device TF_Galerie defined.
...
2014.07.16 09:14:25 5: OWX_DS2480 read: After loop no. 3 received: cd54280281a40400000482000000000000000000
2014.07.16 09:14:25 5: AfterExecute: context: ProtoThreads=HASH(0x1c937a0), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:25 3: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD Error running task: invalid data
2014.07.16 09:14:25 5: OWX_Executor: item ProtoThreads=HASH(0x197bba0) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:14:25 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:25 5: OWX_DS2480.query sending out: e155200c2c0c000000593c0f00ffff
2014.07.16 09:14:25 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494867.631213
2014.07.16 09:14:25 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 3 received: cd5400048204820000480400800000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x197bba0), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: 3c0f00ffff, numread: 0, readdata:
2014.07.16 09:14:26 5: OWX_Executor: item ProtoThreads=HASH(0x197bba0) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0000ffffffffffffffffffff
2014.07.16 09:14:26 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494868.056755
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 8 received: cd540004820482000048aa000000000000000000000000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x197bba0), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa0000, numread: 10, readdata: 00000000000000000000
2014.07.16 09:14:26 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:14:26 5: OWX_Executor: item ProtoThreads=HASH(0x197cab8) for 28.1307AC040000.68 eligible to run
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 09:14:26 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 schedule for timeout at 1405494868.206227
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 6 received: cd54281000a40400002882000000000000000000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x197cab8), success: 1, reset: 1, owx_dev: 28.1307AC040000.68, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:26 3: OWX_ASYNC_RunTasks: 28.1307AC040000.68 Error running task: invalid data
2014.07.16 09:14:26 5: OWX_Executor: item ProtoThreads=HASH(0x1c92c60) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa1000ffffffffffffffffffff
2014.07.16 09:14:26 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494868.349194
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 4 received: cd540004820482000048aa100000000000000000000000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x1c92c60), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa1000, numread: 10, readdata: 00000000000000000000
2014.07.16 09:14:26 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:14:26 5: OWX_Executor: item ProtoThreads=HASH(0x198e1a8) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0800ffffffffffffffffffff
2014.07.16 09:14:26 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494868.553716
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 7 received: cd540004820482000048aa088400000000000000000000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x198e1a8), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa0800, numread: 10, readdata: 00000000000000000000
2014.07.16 09:14:26 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:14:26 5: OWX_Executor: item ProtoThreads=HASH(0x1984660) for 28.A8A49B010000.11 eligible to run
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:26 5: OWX_DS2480.query sending out: e15528a8a49b01000011beffffffffffffffffff
2014.07.16 09:14:26 5: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 schedule for timeout at 1405494868.704503
2014.07.16 09:14:26 5: OWX_DS2480 read: After loop no. 6 received: cd5428a8a4880000001082000000000000000000
2014.07.16 09:14:26 5: AfterExecute: context: ProtoThreads=HASH(0x1984660), success: 1, reset: 1, owx_dev: 28.A8A49B010000.11, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:26 3: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 Error running task: invalid data
2014.07.16 09:14:27 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:14:28 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:14:30 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:14:39 5: OWX_ASYNC_RunTasks: 26.10F326010000.00 finished task
2014.07.16 09:14:43 5: OWX_ASYNC_RunTasks: 29.68980C000000.da finished task
2014.07.16 09:14:43 5: OWX_ASYNC_RunTasks: 29.68980C000000.da finished task
2014.07.16 09:14:49 5: OWX_ASYNC_PT_Kick: kicking DS14B20 temperature conversion
2014.07.16 09:14:49 5: OWX_Executor: item ProtoThreads=HASH(0x1caa978) for --- eligible to run
2014.07.16 09:14:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:49 5: OWX_DS2480.query sending out: e1cc44
2014.07.16 09:14:49 5: OWX_ASYNC_RunTasks: master schedule for timeout at 1405494891.295324
2014.07.16 09:14:49 5: OWX_DS2480 read: After loop no. 3 received: cd4444
2014.07.16 09:14:49 5: AfterExecute: context: ProtoThreads=HASH(0x1caa978), success: 1, reset: 1, owx_dev: undef, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:49 5: OWX_ASYNC_RunTasks: master task not finished, next executetime: 1405494890.39754
2014.07.16 09:14:49 5: OWX_ASYNC_RunTasks: master schedule next at 1405494890.397537
2014.07.16 09:14:50 5: OWX_Executor: command 1 eligible to run
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 09:14:50 5: OWX_ASYNC_RunTasks: master finished task
2014.07.16 09:14:50 5: OWX_DS2480 read: After loop no. 6 received: cd1099999999999999999999999999999999
2014.07.16 09:14:50 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.AA
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e188888888888888888888888888888828e3a5
2014.07.16 09:14:50 5: OWX_DS2480 read: After loop no. 4 received: cd1099999999999999999999999999999979
2014.07.16 09:14:50 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.6A
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e1888888888888888888888888888888a8e3a5
2014.07.16 09:14:50 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999999f9
2014.07.16 09:14:50 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.EA
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:50 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e188888888888888888888888888888802e3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 4 received: cd1099999999999999999999999999999967
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.5A
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1888888888888888888888888888888a2e3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999999e7
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.DA
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e18888888888888888888888888888880ae3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 5 received: cd109999999999999999999999999999999f
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.BA
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e18888888888888888888888888888882ae3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 4 received: cd109999999999999999999999999999997f
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.7A
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1888888888888888888888888888888aae3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999999ff
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.FA
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e188888888888888888888888888882800e3a5
2014.07.16 09:14:51 5: OWX_DS2480 read: After loop no. 4 received: cd1099999999999999999999999999997999
2014.07.16 09:14:51 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.A6
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:51 5: OWX_DS2480.query sending out: e188888888888888888888888888882828e3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd1099999999999999999999999999997979
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.66
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1888888888888888888888888888828a8e3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999979f9
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.E6
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e188888888888888888888888888882802e3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd1099999999999999999999999999997967
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.56
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1888888888888888888888888888828a2e3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999979e7
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.D6
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e18888888888888888888888888888280ae3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd109999999999999999999999999999799f
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.B6
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e18888888888888888888888888888282ae3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd109999999999999999999999999999797f
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.76
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:52 5: OWX_DS2480.query sending out: e1888888888888888888888888888828aae3a5
2014.07.16 09:14:52 5: OWX_DS2480 read: After loop no. 4 received: cd10999999999999999999999999999979ff
2014.07.16 09:14:52 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.F6
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e18888888888888888888888888888a800e3a5
2014.07.16 09:14:53 5: OWX_ASYNC_RunTasks: 10.787E83020800.65 schedule for timeout at 1405494895.123116
2014.07.16 09:14:53 5: OWX_DS2480 read: After loop no. 7 received: cd109999999999999999999999999999f999
2014.07.16 09:14:53 5: OWX_SER::Search: new device found AA.AAAAAAAAAAAA.AE
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:14:53 5: OWX_DS2480.query sending out: e18888888888888888888888888888a828e3a5
2014.07.16 09:14:53 5: OWX_DS2480 read: After loop no. 4 received: cd106c8888888888888888888888888888a828
2014.07.16 09:14:53 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 17
2014.07.16 09:14:53 5: OWX_Executor: command 2 eligible to run
2014.07.16 09:14:53 5: OWX_DS2480 read: After loop no. 5 received: 106c8888888888888888888888888888a828
2014.07.16 09:14:53 3: OWX_DS2480 reset failure on bus OWio1
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x197d428) for 10.787E83020800.65 eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 3 received: cd54100802000208842444
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x197d428), success: 1, reset: 1, owx_dev: 10.787E83020800.65, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x197d428) for 10.787E83020800.65 eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e15510787e8302080065beffffffffffffffffff
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 10.787E83020800.65 schedule for timeout at 1405494896.150849
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 5 received: cd54100802000208842482000000000000000000
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x197d428), success: 1, reset: 1, owx_dev: 10.787E83020800.65, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:54 3: OWX_ASYNC_RunTasks: 10.787E83020800.65 Error running task: invalid data
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x1c11c30) for 28.CA0FAC040000.04 eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 schedule for timeout at 1405494896.296437
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 3 received: cd54284a00800400000444
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x1c11c30), success: 1, reset: 1, owx_dev: 28.CA0FAC040000.04, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 task not finished, next executetime: 1405494895.19897
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x1c2ef60) for 28.905F9B010000.8B eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B schedule for timeout at 1405494896.424522
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 3 received: cd54289040880000008844
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x1c2ef60), success: 1, reset: 1, owx_dev: 28.905F9B010000.8B, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B task not finished, next executetime: 1405494895.32565
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x1bbf4a8) for 28.0E37AC040000.FD eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD schedule for timeout at 1405494896.552528
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 3 received: cd54280281a40400000444
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x1bbf4a8), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD task not finished, next executetime: 1405494895.45489
2014.07.16 09:14:54 5: OWX_Executor: item ProtoThreads=HASH(0x1c62808) for 10.0576A8020800.6D eligible to run
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:54 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule for timeout at 1405494896.684777
2014.07.16 09:14:54 5: OWX_DS2480 read: After loop no. 3 received: cd54100412a80208842444
2014.07.16 09:14:54 5: AfterExecute: context: ProtoThreads=HASH(0x1c62808), success: 1, reset: 1, owx_dev: 10.0576A8020800.6D, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D task not finished, next executetime: 1405494895.58684
2014.07.16 09:14:54 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule next at 1405494895.198972
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1989678) for 28.1307AC040000.68 eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 schedule for timeout at 1405494897.023500
2014.07.16 09:14:55 5: OWX_DS2480 read: After loop no. 6 received: cd54281000a40400002844
2014.07.16 09:14:55 5: AfterExecute: context: ProtoThreads=HASH(0x1989678), success: 1, reset: 1, owx_dev: 28.1307AC040000.68, writedata: 44, numread: 0, readdata:
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 task not finished, next executetime: 1405494895.93058
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1c11c30) for 28.CA0FAC040000.04 eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 schedule for timeout at 1405494897.205547
2014.07.16 09:14:55 5: OWX_DS2480 read: After loop no. 7 received: cd54284a00800400000482000000000000000000
2014.07.16 09:14:55 5: AfterExecute: context: ProtoThreads=HASH(0x1c11c30), success: 1, reset: 1, owx_dev: 28.CA0FAC040000.04, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:55 3: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 Error running task: invalid data
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1c2ef60) for 28.905F9B010000.8B eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B schedule for timeout at 1405494897.352852
2014.07.16 09:14:55 5: OWX_DS2480 read: After loop no. 5 received: cd54289040880000008882000000000000000000
2014.07.16 09:14:55 5: AfterExecute: context: ProtoThreads=HASH(0x1c2ef60), success: 1, reset: 1, owx_dev: 28.905F9B010000.8B, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:55 3: OWX_ASYNC_RunTasks: 28.905F9B010000.8B Error running task: invalid data
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1bbf4a8) for 28.0E37AC040000.FD eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD schedule for timeout at 1405494897.496604
2014.07.16 09:14:55 5: OWX_DS2480 read: After loop no. 5 received: cd54280281a40400000482000000000000000000
2014.07.16 09:14:55 5: AfterExecute: context: ProtoThreads=HASH(0x1bbf4a8), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:55 3: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD Error running task: invalid data
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1c62808) for 10.0576A8020800.6D eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule for timeout at 1405494897.640658
2014.07.16 09:14:55 5: OWX_DS2480 read: After loop no. 5 received: cd54100412a80208842482000000000000000000
2014.07.16 09:14:55 5: AfterExecute: context: ProtoThreads=HASH(0x1c62808), success: 1, reset: 1, owx_dev: 10.0576A8020800.6D, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:55 3: OWX_ASYNC_RunTasks: 10.0576A8020800.6D Error running task: invalid data
2014.07.16 09:14:55 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 schedule next at 1405494895.930580
2014.07.16 09:14:55 5: OWX_Executor: item ProtoThreads=HASH(0x1989678) for 28.1307AC040000.68 eligible to run
2014.07.16 09:14:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:14:56 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 09:14:56 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 schedule for timeout at 1405494897.935995
2014.07.16 09:14:56 5: OWX_DS2480 read: After loop no. 5 received: cd54281000a40400002882000000000000000000
2014.07.16 09:14:56 5: AfterExecute: context: ProtoThreads=HASH(0x1989678), success: 1, reset: 1, owx_dev: 28.1307AC040000.68, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:14:56 3: OWX_ASYNC_RunTasks: 28.1307AC040000.68 Error running task: invalid data
2014.07.16 09:15:05 5: OWX_ASYNC_RunTasks: 29.68980C000000.da finished task
2014.07.16 09:15:05 5: OWX_ASYNC_RunTasks: 29.68980C000000.da finished task
2014.07.16 09:15:19 5: OWX_ASYNC_PT_Kick: kicking DS14B20 temperature conversion
2014.07.16 09:15:19 5: OWX_Executor: item ProtoThreads=HASH(0x1c2f730) for --- eligible to run
2014.07.16 09:15:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:19 5: OWX_DS2480.query sending out: e1cc44
2014.07.16 09:15:19 5: OWX_ASYNC_RunTasks: master schedule for timeout at 1405494921.298703
2014.07.16 09:15:19 5: OWX_DS2480 read: After loop no. 5 received: cd4444
2014.07.16 09:15:19 5: AfterExecute: context: ProtoThreads=HASH(0x1c2f730), success: 1, reset: 1, owx_dev: undef, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:19 5: OWX_ASYNC_RunTasks: master task not finished, next executetime: 1405494920.40078
2014.07.16 09:15:19 5: OWX_ASYNC_RunTasks: master schedule next at 1405494920.400775
2014.07.16 09:15:20 5: OWX_Executor: command 1 eligible to run
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 09:15:20 5: OWX_ASYNC_RunTasks: master finished task
2014.07.16 09:15:20 5: OWX_DS2480 read: After loop no. 6 received: cd1099999999999999999999999999999999
2014.07.16 09:15:20 5: OWX_Executor: command 2 eligible to run
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 09:15:20 5: OWX_DS2480 read: After loop no. 5 received: cd2499999999999999999999999999999999
2014.07.16 09:15:20 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.AA
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e188888888888888888888888888888828e3a5
2014.07.16 09:15:20 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999999979
2014.07.16 09:15:20 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.6A
2014.07.16 09:15:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1888888888888888888888888888888a8e3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999999f9
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.EA
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e188888888888888888888888888888802e3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999999967
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.5A
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1888888888888888888888888888888a2e3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 5 received: cd24999999999999999999999999999999e7
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.DA
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e18888888888888888888888888888880ae3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999999f
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.BA
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e18888888888888888888888888888882ae3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999997f
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.7A
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e1888888888888888888888888888888aae3a5
2014.07.16 09:15:21 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999999ff
2014.07.16 09:15:21 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.FA
2014.07.16 09:15:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e188888888888888888888888888882800e3a5
2014.07.16 09:15:22 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999997999
2014.07.16 09:15:22 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.A6
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e188888888888888888888888888882828e3a5
2014.07.16 09:15:22 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999997979
2014.07.16 09:15:22 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.66
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1888888888888888888888888888828a8e3a5
2014.07.16 09:15:22 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999979f9
2014.07.16 09:15:22 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.E6
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e188888888888888888888888888882802e3a5
2014.07.16 09:15:22 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:15:22 5: OWX_DS2480 read: After loop no. 2 received: cd2499999999999999999999999999997967
2014.07.16 09:15:22 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.56
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e1888888888888888888888888888828a2e3a5
2014.07.16 09:15:22 5: OWX_DS2480 read: After loop no. 6 received: cd24999999999999999999999999999979e7
2014.07.16 09:15:22 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.D6
2014.07.16 09:15:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e18888888888888888888888888888280ae3a5
2014.07.16 09:15:23 5: OWX_ASYNC_RunTasks: 10.787E83020800.65 schedule for timeout at 1405494925.113133
2014.07.16 09:15:23 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999799f
2014.07.16 09:15:23 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.B6
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e18888888888888888888888888888282ae3a5
2014.07.16 09:15:23 5: OWX_DS2480 read: After loop no. 6 received: cd249999999999999999999999999999797f
2014.07.16 09:15:23 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.76
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1888888888888888888888888888828aae3a5
2014.07.16 09:15:23 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999979ff
2014.07.16 09:15:23 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.F6
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e18888888888888888888888888888a800e3a5
2014.07.16 09:15:23 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999f999
2014.07.16 09:15:23 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.AE
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:23 5: OWX_DS2480.query sending out: e18888888888888888888888888888a828e3a5
2014.07.16 09:15:25 3: OWX_ASYNC_RunTasks: 10.787E83020800.65 task timed out
2014.07.16 09:15:25 5: OWX_ASYNC_RunTasks: 10.787E83020800.65 TimeoutTime: 1405494925.113133, now: 1405494925.939639
2014.07.16 09:15:25 5: OWX_DS2480 read: After loop no. 2 received: cd249999999999999999999999999999f979
2014.07.16 09:15:25 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.6E
2014.07.16 09:15:25 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e18888888888888888888888888888a8a8e3a5
2014.07.16 09:15:26 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 schedule for timeout at 1405494927.943928
2014.07.16 09:15:26 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:15:26 5: OWX_DS2480 read: After loop no. 11 received: cd249999999999999999999999999999f9f9
2014.07.16 09:15:26 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.EE
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e18888888888888888888888888888a802e3a5
2014.07.16 09:15:26 5: OWX_DS2480 read: After loop no. 6 received: cd249999999999999999999999999999f967
2014.07.16 09:15:26 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.5E
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e18888888888888888888888888888a8a2e3a5
2014.07.16 09:15:26 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999f9e7
2014.07.16 09:15:26 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.DE
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e18888888888888888888888888888a80ae3a5
2014.07.16 09:15:26 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999f99f
2014.07.16 09:15:26 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.BE
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:26 5: OWX_DS2480.query sending out: e18888888888888888888888888888a82ae3a5
2014.07.16 09:15:26 5: OWX_DS2480 read: After loop no. 4 received: cd249999999999999999999999999999f97f
2014.07.16 09:15:26 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.7E
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e18888888888888888888888888888a8aae3a5
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 2 received: cd249999999999999999999999999999f9ff
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.FE
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e188888888888888888888888888880200e3a5
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999996799
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.A5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e188888888888888888888888888882228e3a5
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999996779
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.65
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1888888888888888888888888888822a8e3a5
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999967f9
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.E5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e188888888888888888888888888882202e3a5
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 4 received: cd2499999999999999999999999999996767
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.55
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:27 5: OWX_DS2480.query sending out: e1888888888888888888888888888822a2e3a5
2014.07.16 09:15:27 3: OWX_ASYNC_RunTasks: 28.1307AC040000.68 task timed out
2014.07.16 09:15:27 5: OWX_ASYNC_RunTasks: 28.1307AC040000.68 TimeoutTime: 1405494927.943928, now: 1405494927.947705
2014.07.16 09:15:27 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 schedule for timeout at 1405494929.951815
2014.07.16 09:15:27 5: OWX_DS2480 read: After loop no. 4 received: cd24999999999999999999999999999967e7
2014.07.16 09:15:27 5: OWX_SER::Search: new alarm device found AA.AAAAAAAAAAAA.D5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e18888888888888888888888888888220ae3a5
2014.07.16 09:15:28 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 4 received: cd246b8888888888888888888888888888220a85
2014.07.16 09:15:28 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 18
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1c50800) for 10.787E83020800.65 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 5 received: cd54100802000208842444
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1c50800), success: 1, reset: 1, owx_dev: 10.787E83020800.65, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1c50fa0) for 28.1307AC040000.68 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 5 received: cd54281000a40400002844
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1c50fa0), success: 1, reset: 1, owx_dev: 28.1307AC040000.68, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1b9fa58) for 28.CA0FAC040000.04 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 5 received: cd54284a00800400000444
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1b9fa58), success: 1, reset: 1, owx_dev: 28.CA0FAC040000.04, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 task not finished, next executetime: 1405494928.75427
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1c125b0) for 10.541E0B000800.35 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e15510541e0b0008003544
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 10.541E0B000800.35 schedule for timeout at 1405494930.548059
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 3 received: cd54105402088408841444
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1c125b0), success: 1, reset: 1, owx_dev: 10.541E0B000800.35, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 10.541E0B000800.35 task not finished, next executetime: 1405494929.44966
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1992828) for 28.810671010000.D3 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e15528810671010000d344
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 28.810671010000.D3 schedule for timeout at 1405494930.681957
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 5 received: cd54280002100000005044
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1992828), success: 1, reset: 1, owx_dev: 28.810671010000.D3, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 28.810671010000.D3 task not finished, next executetime: 1405494929.58378
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x1b9fa58) for 28.CA0FAC040000.04 eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 09:15:28 5: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 schedule for timeout at 1405494930.817771
2014.07.16 09:15:28 5: OWX_DS2480 read: After loop no. 5 received: cd54284a00800400000482000000000000000000
2014.07.16 09:15:28 5: AfterExecute: context: ProtoThreads=HASH(0x1b9fa58), success: 1, reset: 1, owx_dev: 28.CA0FAC040000.04, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:28 3: OWX_ASYNC_RunTasks: 28.CA0FAC040000.04 Error running task: invalid data
2014.07.16 09:15:28 5: OWX_Executor: item ProtoThreads=HASH(0x198adb0) for 28.905F9B010000.8B eligible to run
2014.07.16 09:15:28 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B schedule for timeout at 1405494930.962112
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 3 received: cd54289040880000008844
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x198adb0), success: 1, reset: 1, owx_dev: 28.905F9B010000.8B, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B task not finished, next executetime: 1405494929.86472
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1988be8) for 28.0E37AC040000.FD eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD schedule for timeout at 1405494931.089840
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 3 received: cd54280281a40400000444
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1988be8), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD task not finished, next executetime: 1405494929.99159
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1c93960) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e155200c2c0c000000593c0f00ffff
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494931.218082
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 3 received: cd5400048204820000480400800000
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1c93960), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: 3c0f00ffff, numread: 0, readdata:
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1c93960) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0000ffffffffffffffffffff
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494931.364264
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 5 received: cd540004820482000048aa000000000000000000000000
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1c93960), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa0000, numread: 10, readdata: 00000000000000000000
2014.07.16 09:15:29 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1c125b0) for 10.541E0B000800.35 eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e15510541e0b00080035beffffffffffffffffff
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 10.541E0B000800.35 schedule for timeout at 1405494931.507202
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 5 received: cd54105402088408841482000000000000000000
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1c125b0), success: 1, reset: 1, owx_dev: 10.541E0B000800.35, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:29 3: OWX_ASYNC_RunTasks: 10.541E0B000800.35 Error running task: invalid data
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1992828) for 28.810671010000.D3 eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e15528810671010000d3beffffffffffffffffff
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 28.810671010000.D3 schedule for timeout at 1405494931.650353
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 5 received: cd54280002100000005082000000000000000000
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1992828), success: 1, reset: 1, owx_dev: 28.810671010000.D3, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:29 3: OWX_ASYNC_RunTasks: 28.810671010000.D3 Error running task: invalid data
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x1bbf038) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa1000ffffffffffffffffffff
2014.07.16 09:15:29 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494931.795116
2014.07.16 09:15:29 5: OWX_DS2480 read: After loop no. 5 received: cd540004820482000048aa100000000000000000000000
2014.07.16 09:15:29 5: AfterExecute: context: ProtoThreads=HASH(0x1bbf038), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa1000, numread: 10, readdata: 00000000000000000000
2014.07.16 09:15:29 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:15:29 5: OWX_Executor: item ProtoThreads=HASH(0x198adb0) for 28.905F9B010000.8B eligible to run
2014.07.16 09:15:29 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 28.905F9B010000.8B schedule for timeout at 1405494931.938957
2014.07.16 09:15:30 5: OWX_DS2480 read: After loop no. 6 received: cd54289040880000008882000000000000000000
2014.07.16 09:15:30 5: AfterExecute: context: ProtoThreads=HASH(0x198adb0), success: 1, reset: 1, owx_dev: 28.905F9B010000.8B, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:30 3: OWX_ASYNC_RunTasks: 28.905F9B010000.8B Error running task: invalid data
2014.07.16 09:15:30 5: OWX_Executor: item ProtoThreads=HASH(0x1988be8) for 28.0E37AC040000.FD eligible to run
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD schedule for timeout at 1405494932.082355
2014.07.16 09:15:30 5: OWX_DS2480 read: After loop no. 5 received: cd54280281a40400000482000000000000000000
2014.07.16 09:15:30 5: AfterExecute: context: ProtoThreads=HASH(0x1988be8), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:30 3: OWX_ASYNC_RunTasks: 28.0E37AC040000.FD Error running task: invalid data
2014.07.16 09:15:30 5: OWX_Executor: item ProtoThreads=HASH(0x1c33270) for 20.0C2C0C000000.59 eligible to run
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0800ffffffffffffffffffff
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 schedule for timeout at 1405494932.231879
2014.07.16 09:15:30 5: OWX_DS2480 read: After loop no. 5 received: cd540004820482000048aa088400000000000000000000
2014.07.16 09:15:30 5: AfterExecute: context: ProtoThreads=HASH(0x1c33270), success: 1, reset: 1, owx_dev: 20.0C2C0C000000.59, writedata: aa0800, numread: 10, readdata: 00000000000000000000
2014.07.16 09:15:30 3: OWX_ASYNC_RunTasks: 20.0C2C0C000000.59 Error running task: invalid CRC
2014.07.16 09:15:30 5: OWX_Executor: item ProtoThreads=HASH(0x1b77e30) for 28.A8A49B010000.11 eligible to run
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e15528a8a49b0100001144
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 schedule for timeout at 1405494932.370517
2014.07.16 09:15:30 5: OWX_DS2480 read: After loop no. 3 received: cd5428a8a4880000001044
2014.07.16 09:15:30 5: AfterExecute: context: ProtoThreads=HASH(0x1b77e30), success: 1, reset: 1, owx_dev: 28.A8A49B010000.11, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 task not finished, next executetime: 1405494931.27276
2014.07.16 09:15:30 5: OWX_Executor: item ProtoThreads=HASH(0x19927a8) for 10.0576A8020800.6D eligible to run
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:30 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule for timeout at 1405494932.497719
2014.07.16 09:15:30 5: OWX_DS2480 read: After loop no. 4 received: cd54100412a80208842444
2014.07.16 09:15:30 5: AfterExecute: context: ProtoThreads=HASH(0x19927a8), success: 1, reset: 1, owx_dev: 10.0576A8020800.6D, writedata: 44, numread: 0, readdata:
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D task not finished, next executetime: 1405494931.39975
2014.07.16 09:15:30 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule next at 1405494931.272764
2014.07.16 09:15:31 5: OWX_Executor: item ProtoThreads=HASH(0x1b77e30) for 28.A8A49B010000.11 eligible to run
2014.07.16 09:15:31 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:31 5: OWX_DS2480.query sending out: e15528a8a49b01000011beffffffffffffffffff
2014.07.16 09:15:31 5: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 schedule for timeout at 1405494933.279999
2014.07.16 09:15:31 5: OWX_DS2480 read: After loop no. 8 received: cd5428a8a4880000001082000000000000000000
2014.07.16 09:15:31 5: AfterExecute: context: ProtoThreads=HASH(0x1b77e30), success: 1, reset: 1, owx_dev: 28.A8A49B010000.11, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:31 3: OWX_ASYNC_RunTasks: 28.A8A49B010000.11 Error running task: invalid data
2014.07.16 09:15:31 5: OWX_Executor: item ProtoThreads=HASH(0x19927a8) for 10.0576A8020800.6D eligible to run
2014.07.16 09:15:31 5: OWX_DS2480.query sending out: e3c5
2014.07.16 09:15:31 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 09:15:31 5: OWX_ASYNC_RunTasks: 10.0576A8020800.6D schedule for timeout at 1405494933.426530
2014.07.16 09:15:31 5: OWX_DS2480 read: After loop no. 6 received: cd54100412a80208842482000000000000000000
2014.07.16 09:15:31 5: AfterExecute: context: ProtoThreads=HASH(0x19927a8), success: 1, reset: 1, owx_dev: 10.0576A8020800.6D, writedata: be, numread: 9, readdata: 000000000000000000
2014.07.16 09:15:31 3: OWX_ASYNC_RunTasks: 10.0576A8020800.6D Error running task: invalid data
2014.07.16 09:15:39 5: OWX_ASYNC_RunTasks: 26.10F326010000.00 finished task


Herzliche Grüße

Christian
PI 2B+/5 Raspbian 12, Perl 5.36.0, FHEM 6.3: 295 Module in ConfigDB: Steuerung Heizkessel, FBH, Solarthermie, kontr. Lüftung mit WRG. Smarthome u.a. HMCUL, 1-Wire (FT232RL ; DS2480B), EnOcean (TCM EPS3), MQTT2. DOIF, PID20, Threshold, OWX; Micropelt IRTV, Volkszähler, SolarForecast; MariaDB

ntruchsess

#235
Hallo Christian,

Zitat von: cwagner am 16 Juli 2014, 09:49:26
nch update force bin ich nun komplett synchron mit dem SVN.
[...]

2014.07.16 09:14:25 5: AfterExecute: context: ProtoThreads=HASH(0x1c937a0), success: 1, reset: 1, owx_dev: 28.0E37AC040000.FD, writedata: be, numread: 9, readdata: 000000000000000000


da scheint das update (noch) nicht zu funktionieren. Diese Logzeile ist in der aktuellen SVN-version von 00_OWX_ASYNC.pm (Revision 6261 vom 15.07.2014) definitiv ersatzlos entfallen. In der Revision 6108 vom 13.06.2014 war sie noch drin.

Ich teste nachher selber mal, was man per fhem-update bekommt (das muss aus dem svn ja erst mal auf den update-server), ich arbeite ja lokal auf einem svn-workspace und kriege diese Verzögerung ja nicht direkt mit. (Einen SVN-workspace kannst Du Dir leicht mit 'svn checkout http://svn.code.sf.net/p/fhem/code/trunk/fhem' selber auschecken und dann immer mit 'svn update' auf dem letzen Stand halten...)

Gruß,

Norbert

Gruß,

Norbert
while (!asleep()) {sheep++};

cwagner

#236
Hallo Norbert,

vielen Dank für den wertvollen Tipp mit SVN - nun habe ich die von Dir angesagten Versionen (geprüft mit version) auf dem System und tatsächlich kommen wir beim Switch auch einen Schritt weiter: Sein Status wird korrekt gezeigt, ABER: Beim ersten Versuch, zu schalten, gibt es wiederholbar ein hartes Beenden von FHEM. Wech isses! Das konnte ich dreimal wiederholen - solange ich nicht switche, läuft alles bingo.
Hier die letzten Zeilen des log bei Verbose 5 vor dem Verenden:
2014.07.16 12:40:51 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 12:40:52 5: OWX_ASYNC_Set request OWio1 FF ?
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405507256.035180
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 2 received: cd5528905f9b0100008b44
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405507256.169702
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 3 received: cd55281307ac0400006844
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405507256.298042
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 3 received: cd55100576a80208006d44
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405507256.425884
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 2 received: cd5510787e830208006544
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405507256.554741
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 3 received: cd55280e37ac040000fd44
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405507256.682069
2014.07.16 12:40:54 5: OWX_DS2480 read: After loop no. 3 received: cd5528ca0fac0400000444
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:54 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 12:40:54 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405507256.842963
2014.07.16 12:40:55 5: OWX_DS2480 read: After loop no. 2 received: cd5528905f9b0100008bbe0b014b467fff0510a8
2014.07.16 12:40:55 5: OWX_ASYNC_RunTasks: Aussenluft finished task
2014.07.16 12:40:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:55 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 12:40:55 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405507257.615115
2014.07.16 12:40:55 5: OWX_DS2480 read: After loop no. 3 received: cd55281307ac04000068be69014b467fff07105e
2014.07.16 12:40:55 5: OWX_ASYNC_RunTasks: Fortluft finished task
2014.07.16 12:40:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:55 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 12:40:55 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405507257.760619
2014.07.16 12:40:55 5: OWX_DS2480 read: After loop no. 3 received: cd55100576a80208006dbe2d004b46ffff0310dd
2014.07.16 12:40:55 5: OWX_ASYNC_RunTasks: T_Heizung finished task
2014.07.16 12:40:55 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:56 5: OWX_DS2480.query sending out: e15510787e8302080065beffffffffffffffffff
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405507257.907127
2014.07.16 12:40:56 5: OWX_DS2480 read: After loop no. 2 received: cd5510787e8302080065be2f004b46ffff02109f
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH finished task
2014.07.16 12:40:56 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:56 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405507258.147732
2014.07.16 12:40:56 5: OWX_DS2480 read: After loop no. 3 received: cd55280e37ac040000fdbe77014b467fff09106f
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: Abluft finished task
2014.07.16 12:40:56 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:40:56 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405507258.287078
2014.07.16 12:40:56 5: OWX_DS2480 read: After loop no. 3 received: cd5528ca0fac04000004be21014b467fff0f104b
2014.07.16 12:40:56 5: OWX_ASYNC_RunTasks: Zuluft finished task
2014.07.16 12:41:12 5: OWX_ASYNC_PT_Kick: kicking DS14B20 temperature conversion
2014.07.16 12:41:12 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:12 5: OWX_DS2480.query sending out: e1cc44
2014.07.16 12:41:12 5: OWX_ASYNC_RunTasks: OWio1 schedule for timeout at 1405507274.826286
2014.07.16 12:41:12 5: OWX_DS2480 read: After loop no. 2 received: cdcc44
2014.07.16 12:41:13 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 12:41:14 5: OWX_ASYNC_RunTasks: OWio1 schedule for timeout at 1405507275.963892
2014.07.16 12:41:14 5: OWX_DS2480 read: After loop no. 3 received: cdf04509a000a008a0000000000000008222
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 12:41:14 5: OWX_ASYNC_RunTasks: OWio1 schedule for timeout at 1405507276.166066
2014.07.16 12:41:14 5: OWX_DS2480 read: After loop no. 3 received: cdec4102912aa82a0a800800800000002228
2014.07.16 12:41:14 5: OWX_SER::Search: new alarm device found 10.787E83020800.65
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e100022000000000000000000000000000e3a5
2014.07.16 12:41:14 5: OWX_DS2480 read: After loop no. 3 received: cdec41023122a8028a00000080000000220a
2014.07.16 12:41:14 5: OWX_SER::Search: new alarm device found 10.541E0B000800.35
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e100020200000000000000000000000000e3a5
2014.07.16 12:41:14 5: OWX_DS2480 read: After loop no. 2 received: cdec41022300282a8088080080000000a228
2014.07.16 12:41:14 5: OWX_SER::Search: new alarm device found 10.0576A8020800.6D
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 12:41:14 5: OWX_DS2480.query sending out: e180000000000000000000000000000000e3a5
2014.07.16 12:41:14 5: OWX_DS2480 read: After loop no. 3 received: cdec6880000000000000000000000000000000
2014.07.16 12:41:14 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 17
2014.07.16 12:41:14 5: OWX_ASYNC_RunTasks: OWio1 finished task
2014.07.16 12:41:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:41:24 5: OWX_DS2480.query sending out: e1552968980c000000daf08800ffffffffffffffffffff


Auf der Konsole bit es noch diese Meldung:# Switch_Heizkeller unexpected thread state PT_WAITING without TimeoutTime at ./
FHEM/00_OWX_ASYNC.pm line 1085.


Außerdem kriege ich wiederholt:
# Argument "" isn't numeric in sprintf at ./FHEM/21_OWMULTI.pm line 427.

Ich habe mal einen Start mit verbose=5 gemacht. Vielleicht sind da nützliche INfos für Dich dabei:

...
014.07.16 12:50:04 3: Opening OWio1 device /dev/ttyUSB0
2014.07.16 12:50:04 3: Setting OWio1 baudrate to 9600
2014.07.16 12:50:04 3: OWio1 device opened
2014.07.16 12:50:04 2: OWX_SER: Serial device /dev/ttyUSB0@9600 defined
2014.07.16 12:50:05 5: OWX_DS2480.query sending out: c1
2014.07.16 12:50:06 5: OWX_DS2480 read: After loop no. 1274 received:  -> TIMEOUT
2014.07.16 12:50:06 5: OWX_DS2480.query sending out: 17455b0f91
2014.07.16 12:50:06 5: OWX_DS2480 read: After loop no. 22 received: 16445a0090
2014.07.16 12:50:06 3: OWX_SER::Detect 1-Wire bus OWio1: interface master DS2480 detected for the first time
...
014.07.16 12:50:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:50:18 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 12:50:18 5: OWX_DS2480.query sending out: e100000000000000000000000000000000e3a5
2014.07.16 12:50:18 5: OWX_ASYNC_RunTasks: OWio1 schedule for timeout at 1405507820.340727
2014.07.16 12:50:18 5: OWX_DS2480 read: After loop no. 1 received: cdf04509a000a008a0000000000000008222
2014.07.16 12:50:18 5: OWX_SER::Search: new device found 20.0C2C0C000000.59
2014.07.16 12:50:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:50:18 5: OWX_DS2480.query sending out: e1f0e3b5
2014.07.16 12:50:18 5: OWX_DS2480.query sending out: e100020000000000000000000000000000e3a5
2014.07.16 12:50:19 5: OWX_DS2480 read: After loop no. 1 received: cdf04503912aa82a0a800800800000002228
....
014.07.16 12:50:32 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:50:32 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 12:50:32 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405507834.595882
2014.07.16 12:50:32 5: OWX_DS2480 read: After loop no. 2 received: cd5528ca0fac0400000444
2014.07.16 12:50:32 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:50:32 5: OWX_DS2480.query sending out: e1552968980c000000daf08800ffffffffffffffffffff
2014.07.16 12:50:32 5: OWX_ASYNC_RunTasks: Switch_Heizkeller schedule for timeout at 1405507834.732516
2014.07.16 12:50:32 5: OWX_DS2480 read: After loop no. 2 received: cd552968980c000000daf08800ffff00000088ffffbb6f
2014.07.16 12:50:33 5: OWX_ASYNC_RunTasks: Switch_Heizkeller finished task
2014.07.16 12:50:33 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:50:33 5: OWX_DS2480.query sending out: e15528aee870010000b644
....
014.07.16 12:51:08 5: OWX_DS2480 read: After loop no. 3 received: cdec41023122a8028a00000080000000220a
2014.07.16 12:51:08 5: OWX_SER::Search: new alarm device found 10.541E0B000800.35
2014.07.16 12:51:08 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:08 5: OWX_DS2480.query sending out: e1ece3b5
2014.07.16 12:51:08 5: OWX_DS2480.query sending out: e100020200000000000000000000000000e3a5
2014.07.16 12:51:08 5: OWX_DS2480 read: After loop no. 3 received: cdec6c00000200000000000000000000000000
2014.07.16 12:51:08 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 17
2014.07.16 12:51:08 5: OWX_ASYNC_RunTasks: OWio1 finished task
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e15510541e0b0008003544
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: T_Warmwasser schedule for timeout at 1405507880.092018
2014.07.16 12:51:18 5: OWX_DS2480 read: After loop no. 1 received: cd5510541e0b0008003544
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e15528a8a49b0100001144
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung schedule for timeout at 1405507880.240401
2014.07.16 12:51:18 5: OWX_DS2480 read: After loop no. 2 received: cd5528a8a49b0100001144
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e155200c2c0c000000593c0f00ffff
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405507880.366370
2014.07.16 12:51:18 5: OWX_DS2480 read: After loop no. 2 received: cd55200c2c0c000000593c0f003a03
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0000ffffffffffffffffffff
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405507880.495112
2014.07.16 12:51:18 5: OWX_DS2480 read: After loop no. 3 received: cd55200c2c0c00000059aa0000a2f939ba8fb13ee94924
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: Umweltsensor finished task
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:51:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa1000ffffffffffffffffffff
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405507880.641008
2014.07.16 12:51:18 5: OWX_DS2480 read: After loop no. 1 received: cd55200c2c0c00000059aa1000f5ff00b900b900ff11ba
2014.07.16 12:51:18 5: OWX_ASYNC_RunTasks: Umweltsensor finished task
....
2014.07.16 12:52:09 5:  Alarms = 10.787E83020800.65 10.541E0B000800.35 10.0576A8020800.6D 28.905F9B010000.8B 28.CA0FAC040000.04 28.0E37AC040000.FD 28.AEE870010000.B6 28.810671010000.D3 28.1307AC040000.68 29.68980C000000.DA                        #####Alarm auf einem Switch???
....
12:55:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508120.339559
2014.07.16 12:55:18 5: OWX_DS2480 read: After loop no. 2 received: cd5400048204820000480400800000
2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0000ffffffffffffffffffff
2014.07.16 12:55:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508120.467405
2014.07.16 12:55:18 5: OWX_DS2480 read: After loop no. 3 received: cd540004820482000048aa000000000000000000000000
2014.07.16 12:55:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa1000ffffffffffffffffffff
2014.07.16 12:55:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508120.614009
2014.07.16 12:55:18 5: OWX_DS2480 read: After loop no. 3 received: cd540004820482000048aa100000000000000000000000
2014.07.16 12:55:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0800ffffffffffffffffffff
2014.07.16 12:55:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508120.757707
2014.07.16 12:55:18 5: OWX_DS2480 read: After loop no. 1 received: cd540004820482000048aa088400000000000000000000
2014.07.16 12:55:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:55:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e15528a8a49b01000011beffffffffffffffffff
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung schedule for timeout at 1405508120.927913
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 3 received: cd5428a8a4880000001082000000000000000000
2014.07.16 12:55:19 4: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e15510541e0b00080035beffffffffffffffffff
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: T_Warmwasser schedule for timeout at 1405508121.061381
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 3 received: cd54105402088408841482000000000000000000
2014.07.16 12:55:19 4: OWX_ASYNC_RunTasks: T_Warmwasser Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508121.204417
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008844
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508121.334384
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 2 received: cd54281000a40400002844
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e1552610f326010000004e0008
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508121.458127
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 3 received: cd54221010220000000042000884
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e1552610f326010000004800
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508121.583875
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000004800
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e1552610f3260100000044
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508121.711942
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 2 received: cd54221010220000000044
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:19 5: OWX_DS2480.query sending out: e15528810671010000d344
2014.07.16 12:55:19 5: OWX_ASYNC_RunTasks: T_Ruecklauf schedule for timeout at 1405508121.842554
2014.07.16 12:55:19 5: OWX_DS2480 read: After loop no. 1 received: cd54280002100000005044
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e1552610f32601000000b4
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508121.990036
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 2 received: cd54221010220000000094
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508122.114325
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008882000000000000000000
2014.07.16 12:55:20 4: OWX_ASYNC_RunTasks: Aussenluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508122.260324
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 3 received: cd54281000a40400002882000000000000000000
2014.07.16 12:55:20 4: OWX_ASYNC_RunTasks: Fortluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e1552610f32601000000b800
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508122.404483
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 2 received: cd5422101022000000008800
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508122.529893
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 2 received: cd54100412a80208842444
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e1552610f32601000000be00ffffffffffffffffff
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508122.658139
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000008200000000000000000000
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e1552610f326010000004e0000
2014.07.16 12:55:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508122.800811
2014.07.16 12:55:20 5: OWX_DS2480 read: After loop no. 3 received: cd542210102200000000420000
2014.07.16 12:55:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e1552610f326010000004800
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508122.927794
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 2 received: cd5422101022000000004800
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e1552610f32601000000b4
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508123.055741
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 2 received: cd54221010220000000094
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e15528810671010000d3beffffffffffffffffff
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: T_Ruecklauf schedule for timeout at 1405508123.186734
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 3 received: cd54280002100000005082000000000000000000
2014.07.16 12:55:21 4: OWX_ASYNC_RunTasks: T_Ruecklauf Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e1552610f32601000000b800
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508123.332248
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 2 received: cd5422101022000000008800
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508123.459432
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 3 received: cd54100412a80208842482000000000000000000
2014.07.16 12:55:21 4: OWX_ASYNC_RunTasks: T_Heizung Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:21 5: OWX_DS2480.query sending out: e1552610f32601000000be00ffffffffffffffffff
2014.07.16 12:55:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508123.603699
2014.07.16 12:55:21 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000008200000000000000000000
2014.07.16 12:55:22 3: set PID_Mischer_FBH desired 74 : Please define PID_Mischer_FBH first
2014.07.16 12:55:22 3: CUL_HM set SW_04_FBH_Pumpe on
2014.07.16 12:55:22 2: output of TH_FBH_Pumpe IF (1) (set PID_Mischer_FBH desired {(34+(21-[TF_Galerie:temperature:d])*2)});set SW_04_FBH_Pumpe on;set PID_Mischer_FBH restart 100: Please define PID_Mischer_FBH first
Please define PID_Mischer_FBH first
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: TF_Galerie finished task
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508124.210964
2014.07.16 12:55:22 5: OWX_DS2480 read: After loop no. 1 received: cd54100802000208842444
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508124.361220
2014.07.16 12:55:22 5: OWX_DS2480 read: After loop no. 1 received: cd54280281a40400000444
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508124.481217
2014.07.16 12:55:22 5: OWX_DS2480 read: After loop no. 2 received: cd54284a00800400000444
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e1552968980c000000daf08800ffffffffffffffffffff
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: Switch_Heizkeller schedule for timeout at 1405508124.608374
2014.07.16 12:55:22 5: OWX_DS2480 read: After loop no. 3 received: cd54282888048200004a10880000000000000000000000
2014.07.16 12:55:22 4: OWX_ASYNC_RunTasks: Switch_Heizkeller exited task: invalid data
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:22 5: OWX_DS2480.query sending out: e15528aee870010000b644
2014.07.16 12:55:22 5: OWX_ASYNC_RunTasks: Dachfenster_Sued schedule for timeout at 1405508124.767994
2014.07.16 12:55:22 5: OWX_DS2480 read: After loop no. 2 received: cd5428a228100000009244
2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e15510787e8302080065beffffffffffffffffff
2014.07.16 12:55:23 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508125.017697
2014.07.16 12:55:23 5: OWX_DS2480 read: After loop no. 3 received: cd54100802000208842482000000000000000000
2014.07.16 12:55:23 4: OWX_ASYNC_RunTasks: T_Vorlauf_FBH Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 12:55:23 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508125.167102
2014.07.16 12:55:23 5: OWX_DS2480 read: After loop no. 3 received: cd54280281a40400000482000000000000000000
2014.07.16 12:55:23 4: OWX_ASYNC_RunTasks: Abluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 12:55:23 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508125.314575
2014.07.16 12:55:23 5: OWX_DS2480 read: After loop no. 3 received: cd54284a00800400000482000000000000000000
2014.07.16 12:55:23 4: OWX_ASYNC_RunTasks: Zuluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:23 5: OWX_DS2480.query sending out: e15528aee870010000b6beffffffffffffffffff
2014.07.16 12:55:23 5: OWX_ASYNC_RunTasks: Dachfenster_Sued schedule for timeout at 1405508125.574074
2014.07.16 12:55:23 5: OWX_DS2480 read: After loop no. 3 received: cd5428a228100000009282000000000000000000
2014.07.16 12:55:23 4: OWX_ASYNC_RunTasks: Dachfenster_Sued Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508150.036951
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008844
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508150.175181
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 2 received: cd54281000a40400002844
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508150.299948
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 3 received: cd54100412a80208842444
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508150.427909
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 3 received: cd54100802000208842444
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508150.555879
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 3 received: cd54280281a40400000444
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508150.684583
2014.07.16 12:55:48 5: OWX_DS2480 read: After loop no. 3 received: cd54284a00800400000444
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:48 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 12:55:48 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508150.843066
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008882000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: Aussenluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 12:55:49 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508151.014499
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 3 received: cd54281000a40400002882000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: Fortluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 12:55:49 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508151.150090
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 3 received: cd54100412a80208842482000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: T_Heizung Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e15510787e8302080065beffffffffffffffffff
2014.07.16 12:55:49 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508151.302629
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 3 received: cd54100802000208842482000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: T_Vorlauf_FBH Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 12:55:49 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508151.437898
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 4 received: cd54280281a40400000482000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: Abluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:55:49 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 12:55:49 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508151.581236
2014.07.16 12:55:49 5: OWX_DS2480 read: After loop no. 4 received: cd54284a00800400000482000000000000000000
2014.07.16 12:55:49 4: OWX_ASYNC_RunTasks: Zuluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:13 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:13 5: OWX_DS2480.query sending out: e1552968980c000000daf08800ffffffffffffffffffff
2014.07.16 12:56:13 5: OWX_ASYNC_RunTasks: Switch_Heizkeller schedule for timeout at 1405508175.153985
2014.07.16 12:56:13 5: OWX_DS2480 read: After loop no. 14 received: cd54282888048200004a10880000000000000000000000
2014.07.16 12:56:13 4: OWX_ASYNC_RunTasks: Switch_Heizkeller exited task: invalid data
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e15510541e0b0008003544
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: T_Warmwasser schedule for timeout at 1405508180.049802
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 1 received: cd54105402088408841444
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e15528a8a49b0100001144
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung schedule for timeout at 1405508180.193814
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 3 received: cd5428a8a4880000001044
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e155200c2c0c000000593c0f00ffff
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508180.327868
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 3 received: cd5400048204820000480400800000
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0000ffffffffffffffffffff
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508180.467168
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 4 received: cd540004820482000048aa000000000000000000000000
2014.07.16 12:56:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa1000ffffffffffffffffffff
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508180.619313
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 3 received: cd540004820482000048aa100000000000000000000000
2014.07.16 12:56:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e155200c2c0c00000059aa0800ffffffffffffffffffff
2014.07.16 12:56:18 5: OWX_ASYNC_RunTasks: Umweltsensor schedule for timeout at 1405508180.763910
2014.07.16 12:56:18 5: OWX_DS2480 read: After loop no. 3 received: cd540004820482000048aa088400000000000000000000
2014.07.16 12:56:18 4: OWX_ASYNC_RunTasks: Umweltsensor Error task: invalid CRC at ./FHEM/21_OWAD.pm line 1622.

2014.07.16 12:56:18 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e15510541e0b00080035beffffffffffffffffff
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: T_Warmwasser schedule for timeout at 1405508180.907393
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 1 received: cd54105402088408841482000000000000000000
2014.07.16 12:56:19 4: OWX_ASYNC_RunTasks: T_Warmwasser Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e15528a8a49b01000011beffffffffffffffffff
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung schedule for timeout at 1405508181.074819
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 3 received: cd5428a8a4880000001082000000000000000000
2014.07.16 12:56:19 4: OWX_ASYNC_RunTasks: T_Ruecklauf_Anhebung Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e15528905f9b0100008b44
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508181.209975
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008844
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e155281307ac0400006844
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508181.335418
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 3 received: cd54281000a40400002844
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e1552610f326010000004e0008
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508181.470973
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 3 received: cd54221010220000000042000884
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e1552610f326010000004800
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508181.606338
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000004800
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e1552610f3260100000044
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508181.734010
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 3 received: cd54221010220000000044
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:19 5: OWX_DS2480.query sending out: e155100576a80208006d44
2014.07.16 12:56:19 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508181.863644
2014.07.16 12:56:19 5: OWX_DS2480 read: After loop no. 1 received: cd54100412a80208842444
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e1552610f32601000000b4
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508181.993528
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 1 received: cd54221010220000000094
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e15528905f9b0100008bbeffffffffffffffffff
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: Aussenluft schedule for timeout at 1405508182.126371
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 2 received: cd54289040880000008882000000000000000000
2014.07.16 12:56:20 4: OWX_ASYNC_RunTasks: Aussenluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e155281307ac04000068beffffffffffffffffff
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: Fortluft schedule for timeout at 1405508182.266594
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 3 received: cd54281000a40400002882000000000000000000
2014.07.16 12:56:20 4: OWX_ASYNC_RunTasks: Fortluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e1552610f32601000000b800
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508182.410176
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 1 received: cd5422101022000000008800
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e15528810671010000d344
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: T_Ruecklauf schedule for timeout at 1405508182.534551
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 2 received: cd54280002100000005044
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e1552610f32601000000be00ffffffffffffffffff
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508182.663243
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000008200000000000000000000
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e1552610f326010000004e0000
2014.07.16 12:56:20 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508182.807368
2014.07.16 12:56:20 5: OWX_DS2480 read: After loop no. 2 received: cd542210102200000000420000
2014.07.16 12:56:20 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e1552610f326010000004800
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508182.933639
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000004800
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e1552610f32601000000b4
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508183.061822
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 2 received: cd54221010220000000094
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e155100576a80208006dbeffffffffffffffffff
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: T_Heizung schedule for timeout at 1405508183.192039
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 3 received: cd54100412a80208842482000000000000000000
2014.07.16 12:56:21 4: OWX_ASYNC_RunTasks: T_Heizung Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e1552610f32601000000b800
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508183.338161
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 2 received: cd5422101022000000008800
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e15528810671010000d3beffffffffffffffffff
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: T_Ruecklauf schedule for timeout at 1405508183.462844
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 3 received: cd54280002100000005082000000000000000000
2014.07.16 12:56:21 4: OWX_ASYNC_RunTasks: T_Ruecklauf Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e1552610f32601000000be00ffffffffffffffffff
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: TF_Galerie schedule for timeout at 1405508183.609230
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 3 received: cd5422101022000000008200000000000000000000
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: TF_Galerie finished task
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e15510787e830208006544
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508183.770579
2014.07.16 12:56:21 5: OWX_DS2480 read: After loop no. 2 received: cd54100802000208842444
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:21 5: OWX_DS2480.query sending out: e155280e37ac040000fd44
2014.07.16 12:56:21 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508183.894290
2014.07.16 12:56:22 5: OWX_DS2480 read: After loop no. 2 received: cd54280281a40400000444
2014.07.16 12:56:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:22 5: OWX_DS2480.query sending out: e15528ca0fac0400000444
2014.07.16 12:56:22 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508184.021228
2014.07.16 12:56:22 5: OWX_DS2480 read: After loop no. 3 received: cd54284a00800400000444
2014.07.16 12:56:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:22 5: OWX_DS2480.query sending out: e15510787e8302080065beffffffffffffffffff
2014.07.16 12:56:22 5: OWX_ASYNC_RunTasks: T_Vorlauf_FBH schedule for timeout at 1405508184.643436
2014.07.16 12:56:22 5: OWX_DS2480 read: After loop no. 1 received: cd54100802000208842482000000000000000000
2014.07.16 12:56:22 4: OWX_ASYNC_RunTasks: T_Vorlauf_FBH Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:22 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:23 5: OWX_DS2480.query sending out: e155280e37ac040000fdbeffffffffffffffffff
2014.07.16 12:56:23 5: OWX_ASYNC_RunTasks: Abluft schedule for timeout at 1405508184.963921
2014.07.16 12:56:23 5: OWX_DS2480 read: After loop no. 2 received: cd54280281a40400000482000000000000000000
2014.07.16 12:56:23 4: OWX_ASYNC_RunTasks: Abluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:23 5: OWX_DS2480.query sending out: e15528ca0fac04000004beffffffffffffffffff
2014.07.16 12:56:23 5: OWX_ASYNC_RunTasks: Zuluft schedule for timeout at 1405508185.095248
2014.07.16 12:56:23 5: OWX_DS2480 read: After loop no. 3 received: cd54284a00800400000482000000000000000000
2014.07.16 12:56:23 4: OWX_ASYNC_RunTasks: Zuluft Error task: invalid data at ./FHEM/21_OWTHERM.pm line 931.

2014.07.16 12:56:23 5: OWX_DS2480.query sending out: e3c5
2014.07.16 12:56:23 5: OWX_DS2480.query sending out: e1552968980c000000daf08800ffffffffffffffffffff



Und noch eine Beobachtung: In der Liste der geladenen Module taucht auch OWX.pm neben OWX_ASYNC.pm auf ?!?

Herzliche Grüße

Christian
PI 2B+/5 Raspbian 12, Perl 5.36.0, FHEM 6.3: 295 Module in ConfigDB: Steuerung Heizkessel, FBH, Solarthermie, kontr. Lüftung mit WRG. Smarthome u.a. HMCUL, 1-Wire (FT232RL ; DS2480B), EnOcean (TCM EPS3), MQTT2. DOIF, PID20, Threshold, OWX; Micropelt IRTV, Volkszähler, SolarForecast; MariaDB

ntruchsess

Hallo Christian,


# Switch_Heizkeller unexpected thread state PT_WAITING without TimeoutTime at ./

ist gefixed.

OWX.pm wird tatsächlich geladen (da sind die CRC-funtionen drin).

Hallo Alexander,

im OWX_ASYNC_Kick war tatsächlich ein Fehler, habe ich auch behoben.

----

hab die verbliebenen 'die'-Aufrufe (für Dinge, die eigentlich nie vorkommen sollten) aus der OWX_ASYNC_RunTasks entfernt. Zum Testen daher bitte verbose am OWX_ASYNC mindestens auf 4 setzen, sonst sieht man solche Fehler nicht mehr.

Gruß,

Norbert

while (!asleep()) {sheep++};

cwagner

Hallo Norbert,

jetzt bist Du in der 113. Minute :-)
Erstmals startet meine Installation nun vollständig und alle Devices laufen...

Diese beiden Log-Eintragungen fand ich bisher:
014.07.16 16:37:33 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 17
2014.07.16 16:38:38 4: OWX_ASYNC_RunTasks: OWio1 task timed out


Dennoch scheint alles zu laufen.


Herzliche Grüße

Christian
PI 2B+/5 Raspbian 12, Perl 5.36.0, FHEM 6.3: 295 Module in ConfigDB: Steuerung Heizkessel, FBH, Solarthermie, kontr. Lüftung mit WRG. Smarthome u.a. HMCUL, 1-Wire (FT232RL ; DS2480B), EnOcean (TCM EPS3), MQTT2. DOIF, PID20, Threshold, OWX; Micropelt IRTV, Volkszähler, SolarForecast; MariaDB

ntruchsess

Hallo Christian,

Zitat von: cwagner am 16 Juli 2014, 16:45:50
Diese beiden Log-Eintragungen fand ich bisher:
014.07.16 16:37:33 3: OWX_DS2480: Search 2nd return has wrong parameter with length = 17
2014.07.16 16:38:38 4: OWX_ASYNC_RunTasks: OWio1 task timed out


da ist eine Bussuche schief gegangen. Das kann viele Gründe haben. Wenn das nur sporadisch vorkommt, würde ich das jetzt mal nicht überbewerten.

Gruß,

Norbert
while (!asleep()) {sheep++};