OWX Next Generation

[UweH]

Doch, ist gesetzt, in beiden Interfaces.

Gruß
Uwe

[Prof. Dr. Peter Henning]

Kann irgendwie nicht sein. Sicher, dass das Debug-Attribut den Wert "AsyncQueue,HighLevelIO,LowLevelIO" hat ? Verbose braucht es dafür nicht.

LG

pah

[UweH]

Ja, die drei Attribute waren gesetzt. Wie ich jetzt festgestellt habe, ist OWX kurz danach stehengeblieben...FHEM an sich läuft noch. Queue steht mittlerweile bei 885. Icih starte jetzt erst mal neu...

[UweH]

Wow, damit habe ich mein Testsystem erst mal nachhaltig abgeschossen. Bekomme ich nicht mehr gestartet.
Wie bekomme ich die Attribute gelöscht, wenn ich configDB verwende und FHEM nicht läuft...?

[UweH]

So, erst mal mit fhem.cfg gestartet. Auch hier schmiert FHEM mit den gesetzten Attributen kurze Zeit später ab. Ohne läuft's.
Die letzten Logeinträge sehen so aus.

Code Auswählen Erweitern

2017.03.09 19:14:01 0: Server started with 46 defined entities (fhem.pl:13593/2017-03-04 perl:5.022001 os:linux user:fhem pid:1919)
2017.03.09 19:14:03 1: OWX: 1-Wire devices found on bus 1wire_Test (OWX_28_F09339050000)
2017.03.09 19:14:11 1: QOMPLEX called with data =�
2017.03.09 19:14:11 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=3ACF840C000000e2 context=ds2413.getstate.final
2017.03.09 19:14:11 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.09 19:14:11 1:      => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.09 19:14:11 5: SW: e3c5
2017.03.09 19:14:11 1: OWX_TCP::Query Receiving 0xcd
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 1 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: QOMPLEX called with data =�
2017.03.09 19:14:11 1: QOMPLEX called with data =���
2017.03.09 19:14:11 1: QOMPLEX called with data =�
2017.03.09 19:14:11 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=2CF29A070000007A numread=2
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 4 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 3A1D9D0C000000c7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: QOMPLEX called with data =�
2017.03.09 19:14:11 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=2CF29A070000007A numread=2
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 5 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 3A1D9D0C000000c7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: QOMPLEX called with data =NKF
2017.03.09 19:14:11 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=3
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 6 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 3A1D9D0C000000c7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_OWX.pm line 1150.
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 3 bytes, waiting w. delay 0.05
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: QOMPLEX called with data =D
2017.03.09 19:14:11 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=0
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 7 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 3A1D9D0C000000c7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 3 bytes, waiting w. delay 0.05
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.09 19:14:11 1: ----------------------------------------------
2017.03.09 19:14:11 1: QOMPLEX called with data =�
2017.03.09 19:14:11 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=9
2017.03.09 19:14:11 1:    1wire_Test_2 queue contains 8 entries after insertion
2017.03.09 19:14:11 1:     => 3ACF840C000000e2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:14:11 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 3A1D9D0C000000c7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 2CF29A070000007A context getstate expecting 2 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 3 bytes, waiting w. delay 0.05
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.09 19:14:11 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.09 19:14:11 1: ----------------------------------------------
Can't locate object method "Log3" via package "1wire_Test_2" (perhaps you forgot to load "1wire_Test_2"?) at ./FHEM/11_OWX_TCP.pm line 322.
2017.03.09 19:18:57 1: PERL WARNING: Subroutine myUtils_Initialize redefined at ./FHEM/99_myUtils.pm line 26.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 383.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 384.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 387.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 388.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 391.
2017.03.09 19:18:57 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 392.
2017.03.09 19:18:57 1: Including fhem.cfg
2017.03.09 19:18:57 1: PERL WARNING: shift on reference is experimental at ./FHEM/00_OWX.pm line 1130, <$fh> line 207.
2017.03.09 19:18:57 1: PERL WARNING: Useless use of string in void context at ./FHEM/11_OWX_TCP.pm line 322, <$fh> line 207.
2017.03.09 19:18:57 1: PERL WARNING: Useless use of string in void context at ./FHEM/11_OWX_TCP.pm line 420, <$fh> line 207.
2017.03.09 19:18:57 1: OWX: Performing define and low level init of bus 1wire_Test
2017.03.09 19:18:57 1: OWX_TCP::Init called on device 192.168.178.37:23 for bus 1wire_Test, state is ???
2017.03.09 19:18:57 1: OWX_Init called for bus 1wire_Test with interface state opened, now going for detect
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test: interface master DS2480 re-detected
2017.03.09 19:18:57 1: OWX: Performing define and low level init of bus 1wire_Test_2
2017.03.09 19:18:57 1: OWX_TCP::Init called on device 192.168.178.37:26 for bus 1wire_Test_2, state is ???
2017.03.09 19:18:57 1: OWX_Init called for bus 1wire_Test_2 with interface state opened, now going for detect
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x07 0x05 0x0b 0x0f 0x01
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x07 0x05 0x0b 0x0f 0x01
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x07 0x05 0x0b 0x0f 0x01
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x07 0x05 0x0b 0x0f 0x01
2017.03.09 19:18:57 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not detected, answer was 0x07 0x05 0x0b 0x0f 0x01
2017.03.09 19:18:57 1: OWX_Detect failed
2017.03.09 19:18:57 1: OWX: Error initializing 1wire_Test_2: OWX_Detect failed
2017.03.09 19:19:06 1: Including ./log/fhem.save
2017.03.09 19:19:06 1: usb create starting
2017.03.09 19:19:07 1: usb create end
2017.03.09 19:19:07 0: Featurelevel: 5.8
2017.03.09 19:19:07 0: Server started with 46 defined entities (fhem.pl:13593/2017-03-04 perl:5.022001 os:linux user:fhem pid:1995)
2017.03.09 19:19:08 1: OWX: 1-Wire devices found on bus 1wire_Test (OWX_28_F09339050000)
2017.03.09 19:21:58 1: OWX: Performing define and low level init of bus 1_wire1
2017.03.09 19:21:58 1: OWX_TCP::Init called on device 192.168.178.37:23 for bus 1_wire1, state is ???
2017.03.09 19:21:58 1: OWX: Error initializing 1_wire1: OWX_Init finds a disconnected interface
2017.03.09 19:22:34 1: OWX: Performing define and low level init of bus 1_wire2
2017.03.09 19:22:34 1: OWX_TCP::Init called on device 192.168.178.37:26 for bus 1_wire2, state is ???
2017.03.09 19:22:34 1: OWX: Error initializing 1_wire2: OWX_Init finds a disconnected interface
2017.03.09 19:23:34 1: 192.168.178.37:26 reappeared (1_wire2)
2017.03.09 19:23:46 1: OWX: 1-Wire devices found on bus 1wire_Test (OWX_28_F09339050000)
2017.03.09 19:24:39 1: PERL WARNING: Use of uninitialized value $v in substitution (s///) at fhem.pl line 1019.
2017.03.09 19:24:39 1: PERL WARNING: Use of uninitialized value $v in concatenation (.) or string at fhem.pl line 1020.
2017.03.09 19:24:39 1: /dev/ttyUSB0 reappeared (NXT_USB)
2017.03.09 19:28:03 1: OWX: 1-Wire devices found on bus 1wire_Test_2 (Temp.AZ,Poti,DS2406,Aktor_2,Aktor_1)
2017.03.09 19:29:15 1: QOMPLEX called with data =�
2017.03.09 19:29:15 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=3ACF840C000000E2 context=ds2413.getstate.final
2017.03.09 19:29:15 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.09 19:29:15 1:      => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.09 19:29:15 1: ----------------------------------------------
2017.03.09 19:29:15 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.09 19:29:15 1: OWX_TCP::Query Receiving 0xcd
2017.03.09 19:29:15 1:    1wire_Test_2 queue contains 1 entries after insertion
2017.03.09 19:29:15 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.09 19:29:15 1: ----------------------------------------------
Can't locate object method "Log3" via package "1wire_Test_2" (perhaps you forgot to load "1wire_Test_2"?) at ./FHEM/11_OWX_TCP.pm line 322.
2017.03.09 19:30:42 1: PERL WARNING: Subroutine myUtils_Initialize redefined at ./FHEM/99_myUtils.pm line 26.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 383.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 384.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 387.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 388.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $filename masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 391.
2017.03.09 19:30:42 1: PERL WARNING: "my" variable $fh masks earlier declaration in same scope at ./FHEM/99_myUtils.pm line 392.
2017.03.09 19:30:42 1: Including fhem.cfg
2017.03.09 19:30:42 1: PERL WARNING: shift on reference is experimental at ./FHEM/00_OWX.pm line 1130, <$fh> line 207.
2017.03.09 19:30:42 1: PERL WARNING: Useless use of string in void context at ./FHEM/11_OWX_TCP.pm line 322, <$fh> line 207.
2017.03.09 19:30:42 1: PERL WARNING: Useless use of string in void context at ./FHEM/11_OWX_TCP.pm line 420, <$fh> line 207.
2017.03.09 19:30:42 1: OWX: Performing define and low level init of bus 1wire_Test
2017.03.09 19:30:42 1: OWX_TCP::Init called on device 192.168.178.37:23 for bus 1wire_Test, state is ???
2017.03.09 19:30:42 1: OWX_Init called for bus 1wire_Test with interface state opened, now going for detect
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test: interface master DS2480 detected for the first time
2017.03.09 19:30:42 1: OWX: Performing define and low level init of bus 1wire_Test_2
2017.03.09 19:30:42 1: OWX_TCP::Init called on device 192.168.178.37:26 for bus 1wire_Test_2, state is ???
2017.03.09 19:30:42 1: OWX_Init called for bus 1wire_Test_2 with interface state opened, now going for detect
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x14 0x04 0x18 0x0c 0x10
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x14 0x04 0x18 0x0c 0x10
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x14 0x04 0x18 0x0c 0x10
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not found, answer was 0x14 0x04 0x18 0x0c 0x10
2017.03.09 19:30:42 1: OWX_TCP::Detect 1-Wire bus 1wire_Test_2: interface not detected, answer was 0x14 0x04 0x18 0x0c 0x10
2017.03.09 19:30:42 1: OWX_Detect failed
2017.03.09 19:30:42 1: OWX: Error initializing 1wire_Test_2: OWX_Detect failed
2017.03.09 19:30:51 1: Including ./log/fhem.save
2017.03.09 19:30:51 1: usb create starting
2017.03.09 19:30:51 1: usb create end
2017.03.09 19:30:51 0: Featurelevel: 5.8
2017.03.09 19:30:51 0: Server started with 46 defined entities (fhem.pl:13593/2017-03-04 perl:5.022001 os:linux user:fhem pid:2348)
2017.03.09 19:30:51 1: PERL WARNING: Use of uninitialized value $v in substitution (s///) at fhem.pl line 1019.
2017.03.09 19:30:51 1: PERL WARNING: Use of uninitialized value $v in concatenation (.) or string at fhem.pl line 1020.
2017.03.09 19:30:53 1: OWX: 1-Wire devices found on bus 1wire_Test (OWX_28_F09339050000)

[Prof. Dr. Peter Henning]

OK, ein weiteres Timingproblem behoben.

Anbei die leicht modifizierten Versionen OWX, OWX_SER und OWX_TCP (6.1alpha2).

Dieses leichte Timingproblem kann aber keinesfalls zu einem Absturz von FHEM führen - sondern das lag in einem nicht abgefangenen Log3 in den geposteten Dateien 

Außerdem anbei die aktuelle Version von OWCOUNT, ebenfalls für den asynchronen Betrieb gefixt.

LG

pah

[UweH]

Läuft wieder, auch mit den gesetzten Attributen
Ich teste.

Gruß
Uwe

[Prof. Dr. Peter Henning]

Fein. Bei mir läuft das jetzt mit einer ganzen Latte von Sensoren und Aktoren sehr stabil, im Schnitt warten ca. 10 Kommandos in der Queue.

Manchmal (nicht immer) habe ich noch Probleme nach einem Reconnect - wahrscheinlich muss ich dann eine komplette Initialisierung des Bus einbauen.

LG

pah

[UweH]

Moin,

weiter geht's 
Ich habe die Verbindung zum Busmaster unterbrochen, Log sieht so aus:

Code Auswählen Erweitern

2017.03.10 09:37:08 2: 1wire_Test_2: first attempt to read timed out, trying to close and open the device.
2017.03.10 09:37:08 3: Opening 1wire_Test_2 device 192.168.178.37:26
2017.03.10 09:37:11 3: Can't connect to 192.168.178.37:26: No route to host
2017.03.10 09:37:11 5: SW: e3c5
2017.03.10 09:37:11 2: 1wire_Test_2: second attempt to read timed out, this is an unrecoverable error.
2017.03.10 09:37:11 1: OWX_TCP::Query Receiving
2017.03.10 09:37:11 1: OWX_TCP: Trying second reset on bus 1wire_Test_2, state is disconnected
2017.03.10 09:37:11 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:37:11 2: Attempt to write to disconnected device.
2017.03.10 09:37:11 1: OWX_TCP::Query Receiving
2017.03.10 09:37:11 1: OWX_TCP: Reset_TCP failure on bus 1wire_Test_2
2017.03.10 09:37:11 1: OWX_TCP::Search reset failed on bus 1wire_Test_2
2017.03.10 09:37:11 1: OWX: 1-Wire devices found on bus 1wire_Test_2 ()
2017.03.10 09:37:15 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:37:15 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:37:19 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:37:21 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:37:25 1: QOMPLEX called with data =�
2017.03.10 09:37:25 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=3A1D9D0C000000C7 context=ds2413.getstate.final
2017.03.10 09:37:25 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 09:37:25 1:      => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:25 1: ----------------------------------------------
2017.03.10 09:37:25 1:    1wire_Test_2 queue contains 1 entries after insertion
2017.03.10 09:37:25 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:25 1: ----------------------------------------------
2017.03.10 09:37:25 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:25 1:      => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:25 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:25 1:      => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:25 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:25 1:      => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:38 1: QOMPLEX called with data =D
2017.03.10 09:37:38 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=0
2017.03.10 09:37:38 1:    1wire_Test_2 queue contains 2 entries after insertion
2017.03.10 09:37:38 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:38 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:38 1: ----------------------------------------------
2017.03.10 09:37:38 1: QOMPLEX called with data =�
2017.03.10 09:37:38 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=9
2017.03.10 09:37:38 1:    1wire_Test_2 queue contains 3 entries after insertion
2017.03.10 09:37:38 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:38 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:38 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:37:38 1: ----------------------------------------------
2017.03.10 09:37:38 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_OWX.pm line 1195.
2017.03.10 09:37:38 1: [OWX_PrQueue] Removed top entry 10BF6654020800BA w. context  from OWX queue due to timeout
2017.03.10 09:37:38 1: [OWX_PrQueue] starting send-receive cycle, queue length 2. Setting entry to active
2017.03.10 09:37:38 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:38 1: ----------------------------------------------
2017.03.10 09:37:38 1: QOMPLEX called with data =�
2017.03.10 09:37:38 1: QOMPLEX called with data =���
2017.03.10 09:37:38 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:38 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_OWX.pm line 1201.
2017.03.10 09:37:38 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:38 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:38 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:38 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:38 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:39 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:39 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:39 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:39 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:39 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:39 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:40 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:40 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:40 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:40 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:40 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 09:37:40 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:37:41 1: [OWX_PrQueue] Removed top entry 1236FC7900000062 w. context ds2406.getstate.final from OWX queue due to timeout
2017.03.10 09:37:41 1: [OWX_PrQueue] starting send-receive cycle, queue length 3. Setting entry to active
2017.03.10 09:37:41 1:      => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 09:37:41 1: ----------------------------------------------
2017.03.10 09:37:41 1: [OWX_PrQueue] Removed top entry 3ACF840C000000E2 w. context ds2413.getstate.final from OWX queue due to timeout
2017.03.10 09:37:41 1: [OWX_PrQueue] starting send-receive cycle, queue length 2. Setting entry to active
2017.03.10 09:37:41 1:      => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:37:41 1: ----------------------------------------------
2017.03.10 09:37:41 1: [OWX_PrQueue] Removed top entry 10BF6654020800BA w. context  from OWX queue due to timeout
2017.03.10 09:37:41 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 09:37:41 1:      => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:37:41 1: ----------------------------------------------
2017.03.10 09:37:41 1: [OWX_PrQueue] Removed top entry  w. context  from OWX queue due to timeout
2017.03.10 09:38:20 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:38:20 2: Attempt to write to disconnected device.
2017.03.10 09:38:20 1: OWX_TCP::Query Receiving
2017.03.10 09:38:20 1: OWX_TCP: Trying second reset on bus 1wire_Test_2, state is disconnected
2017.03.10 09:38:20 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:38:20 2: Attempt to write to disconnected device.
2017.03.10 09:38:20 1: OWX_TCP::Query Receiving
2017.03.10 09:38:20 1: OWX_TCP: Reset_TCP failure on bus 1wire_Test_2
2017.03.10 09:38:20 1: OWX_Complex called with data �D
2017.03.10 09:38:20 1: OWX_TCP::Complex: Sending out �D
2017.03.10 09:38:20 1: OWX_TCP::Query: Sending out 0xe1 0xcc 0x44
2017.03.10 09:38:20 2: Attempt to write to disconnected device.
2017.03.10 09:38:20 1: OWX_TCP::Query Receiving
2017.03.10 09:38:20 1: PERL WARNING: Use of uninitialized value $res in concatenation (.) or string at ./FHEM/11_OWX_TCP.pm line 232.
2017.03.10 09:38:20 1: OWX_TCP::Complex: Receiving   
2017.03.10 09:38:20 3: OWX: Failure in temperature conversion

2017.03.10 09:41:14 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:42:25 1: QOMPLEX called with data =�
2017.03.10 09:42:27 1: QOMPLEX called with data =Z�
2017.03.10 09:42:40 1: QOMPLEX called with data =���
2017.03.10 09:42:40 1: QOMPLEX called with data =�
2017.03.10 09:42:40 1: QOMPLEX called with data =D
2017.03.10 09:42:40 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=0
2017.03.10 09:42:40 1:    1wire_Test_2 queue contains 6 entries after insertion
2017.03.10 09:42:40 1:     =>  context  expecting  bytes,  w. delay
2017.03.10 09:42:40 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 3A1D9D0C000000C7 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:42:40 1: ----------------------------------------------
2017.03.10 09:42:40 1: QOMPLEX called with data =�
2017.03.10 09:42:40 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=9
2017.03.10 09:42:40 1:    1wire_Test_2 queue contains 7 entries after insertion
2017.03.10 09:42:40 1:     =>  context  expecting  bytes,  w. delay
2017.03.10 09:42:40 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 3A1D9D0C000000C7 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:42:40 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:42:40 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:42:40 1: ----------------------------------------------
2017.03.10 09:43:20 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:43:20 2: Attempt to write to disconnected device.
2017.03.10 09:43:20 1: OWX_TCP::Query Receiving
2017.03.10 09:43:20 1: OWX_TCP: Trying second reset on bus 1wire_Test_2, state is disconnected
2017.03.10 09:43:20 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:43:20 2: Attempt to write to disconnected device.
2017.03.10 09:43:20 1: OWX_TCP::Query Receiving
2017.03.10 09:43:20 1: OWX_TCP: Reset_TCP failure on bus 1wire_Test_2
2017.03.10 09:43:20 1: OWX_Complex called with data �D
2017.03.10 09:43:20 1: OWX_TCP::Complex: Sending out �D
2017.03.10 09:43:20 1: OWX_TCP::Query: Sending out 0xe1 0xcc 0x44
2017.03.10 09:43:20 2: Attempt to write to disconnected device.
2017.03.10 09:43:20 1: OWX_TCP::Query Receiving
2017.03.10 09:43:20 1: OWX_TCP::Complex: Receiving   
2017.03.10 09:43:20 3: OWX: Failure in temperature conversion

Dann Stecker wieder rein, Verbindung zu beiden LAN-Interfaces wird wieder hergestellt.
Schaltversuch mit DS2413 ohne Erfolg, ein "get ... devices" wird korrekt ausgeführt.

Code Auswählen Erweitern

2017.03.10 09:45:37 1: 192.168.178.37:23 reappeared (1wire_Test)
2017.03.10 09:46:02 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:46:08 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:46:11 1: 192.168.178.37:26 reappeared (1wire_Test_2)
2017.03.10 09:46:12 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:46:36 1: QOMPLEX called with data =Z�
2017.03.10 09:46:40 1: QOMPLEX called with data =Z�

2017.03.10 09:47:00 1: QOMPLEX called with data =�
2017.03.10 09:47:04 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:47:07 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:47:07 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:47:10 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:10 5: SW: e3c5
2017.03.10 09:47:10 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:10 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:10 5: SW: e1f0e3b5
2017.03.10 09:47:10 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:10 1: OWX_TCP::Query: Sending out 0xe1 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:10 5: SW: e100000000000000000000000000000000e3a5
2017.03.10 09:47:10 1: OWX_TCP::Query Receiving 0x14 0x02 0xaa 0x8a 0x28 0x28 0x20 0x22 0x08 0x00 0x80 0x00 0x00 0x00 0x88 0x8a
2017.03.10 09:47:10 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:10 5: SW: e3c5
2017.03.10 09:47:10 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:11 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:11 5: SW: e1f0e3b5
2017.03.10 09:47:11 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:11 1: OWX_TCP::Query: Sending out 0xe1 0x20 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:11 5: SW: e120000000000000000000000000000000e3a5
2017.03.10 09:47:11 1: OWX_TCP::Query Receiving 0xb4 0x08 0x08 0xaa 0x88 0x82 0x2a 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x88 0x2a
2017.03.10 09:47:11 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:11 5: SW: e3c5
2017.03.10 09:47:11 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:11 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:11 5: SW: e1f0e3b5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:12 1: OWX_TCP::Query: Sending out 0xe1 0x08 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:12 5: SW: e108000000000000000000000000000000e3a5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0x4c 0x02 0x28 0x0a 0xa0 0xaa 0x82 0x2a 0x00 0x00 0x00 0x00 0x00 0x00 0x08 0x28
2017.03.10 09:47:12 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:12 5: SW: e3c5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:12 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:12 5: SW: e1f0e3b5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:12 1: OWX_TCP::Query: Sending out 0xe1 0x88 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:12 5: SW: e188000000000000000000000000000000e3a5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0xcc 0x0a 0xa6 0x02 0xa2 0x82 0xa0 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x2a 0xa0
2017.03.10 09:47:12 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:12 5: SW: e3c5
2017.03.10 09:47:12 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:13 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:13 5: SW: e1f0e3b5
2017.03.10 09:47:13 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:13 1: OWX_TCP::Query: Sending out 0xe1 0x88 0x0a 0x0a 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:13 5: SW: e1880a0a00000000000000000000000000e3a5
2017.03.10 09:47:13 1: OWX_TCP::Query Receiving 0xcc 0x0a 0xae 0xa0 0x20 0x80 0xa0 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x08 0xa8
2017.03.10 09:47:13 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 09:47:13 5: SW: e3c5
2017.03.10 09:47:13 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 09:47:13 1: OWX_TCP::Query: Sending out 0xe1 0xf0 0xe3 0xb5
2017.03.10 09:47:13 5: SW: e1f0e3b5
2017.03.10 09:47:13 1: OWX_TCP::Query Receiving 0xf0
2017.03.10 09:47:13 1: OWX_TCP::Query: Sending out 0xe1 0x88 0x0a 0x0a 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0xe3 0xa5
2017.03.10 09:47:13 5: SW: e1880a0a00000000000000000000000000e3a5
2017.03.10 09:47:13 1: OWX_TCP::Query Receiving 0xcc 0x0a 0xae 0xa0 0x20 0x80 0xa0 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x08 0xa8
2017.03.10 09:47:13 1: OWX: 1-Wire devices found on bus 1wire_Test_2 (Temp.AZ,Poti,DS2406,Aktor_2,Aktor_1)
2017.03.10 09:47:22 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 09:47:25 1: QOMPLEX called with data =�
2017.03.10 09:47:29 1: QOMPLEX called with data =D
2017.03.10 09:47:29 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=0
2017.03.10 09:47:29 1:    1wire_Test_2 queue contains 12 entries after insertion
2017.03.10 09:47:29 1:     =>  context  expecting  bytes,  w. delay
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.modstate.0.0 expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:47:29 1: ----------------------------------------------
2017.03.10 09:47:29 1: QOMPLEX called with data =�
2017.03.10 09:47:29 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=9
2017.03.10 09:47:29 1:    1wire_Test_2 queue contains 13 entries after insertion
2017.03.10 09:47:29 1:     =>  context  expecting  bytes,  w. delay
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.modstate.0.0 expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.setstate expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 09:47:29 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 09:47:29 1: ----------------------------------------------


Gruß
Uwe

[UweH]

Hier nun eine Variante mit USB-Busmaster vor und nach einem Verbindungsabbruch:

Code Auswählen Erweitern

2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 0
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 1
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 2
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 3
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 4
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 5
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 6
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 7
2017.03.10 10:09:47 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 8
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 9
2017.03.10 10:09:48 1: OWX_Read: 1wire_USB received -10 instead of 0 bytes
2017.03.10 10:09:48 1: buffer is
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 0
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 1
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 2
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 3
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 4
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 5
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 6
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 7
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 8
2017.03.10 10:09:48 1: OWX_SER::Read 1wire_USB: -10 of 0 bytes in attempt 9
2017.03.10 10:09:48 1: OWX_Read: 1wire_USB received -10 instead of 0 bytes
2017.03.10 10:09:48 1: buffer is
2017.03.10 10:09:48 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:09:48 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:09:48 1: PERL WARNING: Use of uninitialized value $string_part in concatenation (.) or string at ./FHEM/11_OWX_SER.pm line 753.
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:09:48 1: PERL WARNING: Use of uninitialized value $m in addition (+) at ./FHEM/11_OWX_SER.pm line 751.
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:09:48 1: OWX_SER::Query Receiving
2017.03.10 10:09:48 1: Trying second reset
2017.03.10 10:09:48 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:09:48 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:09:48 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:09:48 1: OWX_SER::Query Receiving
2017.03.10 10:09:48 1: OWX_SER::Reset_2480 failure on bus 1wire_USB
2017.03.10 10:09:48 1: /dev/ttyUSB1 disconnected, waiting to reappear (1wire_USB)
2017.03.10 10:09:48 1: OWX_SER::Search reset failed
2017.03.10 10:09:48 1: OWX: 1-Wire devices found on bus 1wire_USB ()


Die Kontaktaufnahme mit dem USB-Interface dauert länger, liegt an meiner Konfiguration, weil der Zugriff verweigert wurde. Ich musste zwischendurch die Rechte neu setzen...ups

Code Auswählen Erweitern

2017.03.10 10:10:00 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:00 5: SW: e3c5
2017.03.10 10:10:00 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:00 1: OWX_Complex called with data �D
2017.03.10 10:10:00 1: OWX_TCP::Complex: Sending out �D
2017.03.10 10:10:00 1: OWX_TCP::Query: Sending out 0xe1 0xcc 0x44
2017.03.10 10:10:00 5: SW: e1cc44
2017.03.10 10:10:00 1: OWX_TCP::Query Receiving 0xcc 0x44
2017.03.10 10:10:00 1: OWX_TCP::Complex: Receiving   �D
2017.03.10 10:10:09 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:09 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/i386-linux-gnu/perl5/5.22/Device/SerialPort.pm line 1732.
2017.03.10 10:10:09 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:10:09 1: PERL WARNING: Use of uninitialized value in vec at /usr/lib/i386-linux-gnu/perl5/5.22/Device/SerialPort.pm line 1405.
2017.03.10 10:10:09 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/i386-linux-gnu/perl5/5.22/Device/SerialPort.pm line 1417.
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:10:09 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:10:10 1: OWX_SER::Query Receiving
2017.03.10 10:10:10 1: Trying second reset
2017.03.10 10:10:10 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:10 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:10:10 1: OWX_SER::Query Receiving
2017.03.10 10:10:10 1: OWX_SER::Reset_2480 failure on bus 1wire_USB
2017.03.10 10:10:10 1: OWX_Complex called with data �D
2017.03.10 10:10:10 1: OWX_SER::Complex: Sending out 0xcc 0x44
2017.03.10 10:10:10 1: OWX_SER::Query: Sending out 0xe1 0xcc 0x44
2017.03.10 10:10:10 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:10:10 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:10:10 1: OWX_SER::Query Receiving
2017.03.10 10:10:10 1: OWX_SER::Complex: Receiving   
2017.03.10 10:10:20 1: QOMPLEX called with data =�
2017.03.10 10:10:20 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=3A1D9D0C000000C7 context=ds2413.getstate.final
2017.03.10 10:10:20 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 10:10:20 1:      => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:20 1: ----------------------------------------------
2017.03.10 10:10:20 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:20 5: SW: e3c5
2017.03.10 10:10:20 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:20 1:    1wire_Test_2 queue contains 1 entries after insertion
2017.03.10 10:10:20 1:     => 3A1D9D0C000000C7 context ds2413.getstate.final expecting 2 bytes, active w. delay 0
2017.03.10 10:10:20 1: ----------------------------------------------
2017.03.10 10:10:20 1: OWX_TCP::Read: 1wire_Test_2: 2 bytes received
2017.03.10 10:10:20 1: OWX_Read: buffer 2 bytes, expected 2. Calling callback for
2017.03.10 10:10:20 1:           3A1D9D0C000000C7 w. context ds2413.getstate.final
2017.03.10 10:10:20 1:           buffer content is 0x55 0x3a 0x1d 0x9d 0x0c 0x00 0x00 0x00 0xc7 0xf5 0x0f 0x0f
2017.03.10 10:10:20 1: OWX_Read: 1wire_Test_2 queue contains 0 entries after removal of active entry
2017.03.10 10:10:20 1: ----------------------------------------------
2017.03.10 10:10:21 1: QOMPLEX called with data =���
2017.03.10 10:10:21 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=1236FC7900000062 context=ds2406.getstate.final
2017.03.10 10:10:21 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 10:10:21 1:      => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:21 5: SW: e3c5
2017.03.10 10:10:21 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:21 1:    1wire_Test_2 queue contains 1 entries after insertion
2017.03.10 10:10:21 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, active w. delay 0
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: QOMPLEX called with data =�
2017.03.10 10:10:21 1: QOMPLEX called with data =D
2017.03.10 10:10:21 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=0
2017.03.10 10:10:21 1:    1wire_Test_2 queue contains 3 entries after insertion
2017.03.10 10:10:21 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, active w. delay 0
2017.03.10 10:10:21 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:21 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: QOMPLEX called with data =�
2017.03.10 10:10:21 1: OWX_Qomplex: Added to 1wire_Test_2 send queue dev=10BF6654020800BA numread=9
2017.03.10 10:10:21 1:    1wire_Test_2 queue contains 4 entries after insertion
2017.03.10 10:10:21 1:     => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, active w. delay 0
2017.03.10 10:10:21 1:     => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:21 1:     => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 10:10:21 1:     => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: OWX_TCP::Read: 1wire_Test_2: 6 bytes received
2017.03.10 10:10:21 1: OWX_Read: buffer 6 bytes, expected 4. Calling callback for
2017.03.10 10:10:21 1:           1236FC7900000062 w. context ds2406.getstate.final
2017.03.10 10:10:21 1:           buffer content is 0x55 0x12 0x36 0xfc 0x79 0x00 0x00 0x00 0x62 0xf5 0xdd 0xff 0xcf 0xff 0x6c 0x06
2017.03.10 10:10:21 1: OWX_Read: 1wire_Test_2 queue contains 3 entries after removal of active entry
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: [OWX_PrQueue] starting send-receive cycle, queue length 3. Setting entry to active
2017.03.10 10:10:21 1:      => 3ACF840C000000E2 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:21 5: SW: e3c5
2017.03.10 10:10:21 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:21 1: OWX_TCP::Read: 1wire_Test_2: 2 bytes received
2017.03.10 10:10:21 1: OWX_Read: buffer 2 bytes, expected 2. Calling callback for
2017.03.10 10:10:21 1:           3ACF840C000000E2 w. context ds2413.getstate.final
2017.03.10 10:10:21 1:           buffer content is 0x55 0x3a 0xcf 0x84 0x0c 0x00 0x00 0x00 0xe2 0xf5 0x0f 0x0f
2017.03.10 10:10:21 1: OWX_Read: 1wire_Test_2 queue contains 2 entries after removal of active entry
2017.03.10 10:10:21 1: ----------------------------------------------
2017.03.10 10:10:21 1: [OWX_PrQueue] 1wire_Test_2 mindelay not over, rescheduling. now=1489137021.60259, target time =1489137022.10171
2017.03.10 10:10:21 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 10:10:21 1: [OWX_PrQueue] 1wire_Test_2 mindelay not over, rescheduling. now=1489137021.90328, target time =1489137022.10171
2017.03.10 10:10:21 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 10:10:22 1: [OWX_PrQueue] starting send-receive cycle, queue length 2. Setting entry to active
2017.03.10 10:10:22 1:      => 10BF6654020800BA context  expecting 0 bytes, waiting w. delay 0.8
2017.03.10 10:10:22 1: ----------------------------------------------
2017.03.10 10:10:22 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:22 5: SW: e3c5
2017.03.10 10:10:22 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:22 1: OWX_TCP::Read: 1wire_Test_2: 0 bytes received
2017.03.10 10:10:22 1: OWX_Read: 1wire_Test_2 queue contains 1 entries after removal of active entry
2017.03.10 10:10:22 1: ----------------------------------------------
2017.03.10 10:10:22 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 10:10:22 1:      => 10BF6654020800BA context  expecting 9 bytes, waiting w. delay 0.01
2017.03.10 10:10:22 1: ----------------------------------------------
2017.03.10 10:10:22 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:22 5: SW: e3c5
2017.03.10 10:10:22 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:22 1: OWX_TCP::Read: 1wire_Test_2: 9 bytes received
2017.03.10 10:10:22 1: OWX_Read: buffer 9 bytes, expected 9. Calling callback for
2017.03.10 10:10:22 1:           10BF6654020800BA w. context
2017.03.10 10:10:22 1:           buffer content is 0x55 0x10 0xbf 0x66 0x54 0x02 0x08 0x00 0xba 0xbe 0x2e 0x00 0x4b 0x46 0xff 0xff 0x0d 0x10 0xc4
2017.03.10 10:10:22 1: OWX_TCP::Query: Sending out 0xe3 0xc5
2017.03.10 10:10:22 5: SW: e3c5
2017.03.10 10:10:22 1: OWX_TCP::Query Receiving 0xcd
2017.03.10 10:10:22 1: OWX_Read: 1wire_Test_2 queue contains 0 entries after removal of active entry
2017.03.10 10:10:22 1: ----------------------------------------------
2017.03.10 10:10:30 1: QOMPLEX called with data =�
2017.03.10 10:10:30 1: [OWX_Qomplex] Added to 1wire_USB send queue dev=3A41C002000000F8 context=ds2413.getstate.final
2017.03.10 10:10:30 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 10:10:30 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:30 1: ----------------------------------------------
2017.03.10 10:10:30 1:    1wire_USB queue contains 1 entries after insertion
2017.03.10 10:10:30 1:     => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:30 1: ----------------------------------------------
2017.03.10 10:10:30 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:30 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:30 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:30 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:30 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:30 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:31 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:31 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:31 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:31 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:31 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:31 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:32 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:32 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:32 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:32 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:32 1: [OWX_PrQueue] still waiting for reply, delay sending from queue
2017.03.10 10:10:32 1:      => 3A41C002000000F8 context ds2413.getstate.final expecting 2 bytes, waiting w. delay 0
2017.03.10 10:10:33 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_OWX.pm line 1195.
2017.03.10 10:10:33 1: [OWX_PrQueue] Removed top entry  w. context  from OWX queue due to timeout
2017.03.10 10:11:22 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:11:25 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:11:27 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:11:28 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:11:35 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:11:38 5: OWX_Set request 1wire_Test_2 FF ?
2017.03.10 10:13:23 1: OWX: Performing define and low level init of bus 1wire_USB
2017.03.10 10:13:23 1: OWX_SER::Init called on device /dev/ttyUSB1 for bus 1wire_USB, state is disconnected
2017.03.10 10:13:23 1: OWX_SER: Can't open serial device /dev/ttyUSB1: Permission denied
2017.03.10 10:13:23 1: OWX: Error initializing 1wire_USB: OWX_Init finds a disconnected interface
2017.03.10 10:13:46 1: OWX: Performing define and low level init of bus 1wire_USB
2017.03.10 10:13:46 1: OWX_SER::Init called on device /dev/ttyUSB1 for bus 1wire_USB, state is disconnected
2017.03.10 10:13:46 1: OWX_SER: opened serial device /dev/ttyUSB1: Illegal seek
2017.03.10 10:13:46 1: OWX_Init called for bus 1wire_USB with interface state opened, now going for detect
2017.03.10 10:13:46 1: OWX_SER::Detect 1-Wire bus 1wire_USB: interface master DS2480 detected for the first time
2017.03.10 10:13:57 1: OWX: 1-Wire devices found on bus 1wire_USB (DS2413_USB)

Aber auch hier lässt sich der DS2413 am USB-Interface nicht mehr schalten.

Gruß
Uwe

[UweH]

Noch eine Sache. Ich habe nun auch einen DS2408 mit angehangen, auch der lässt sich nach dem Wiederverbinden nicht mehr schalten.
Seitdem macht der USB-Busmaster aber kuriose Sachen. Wenn man nun ein "get...devices" absetzt, wird noch die MsgBox geöffnet, es werden (im Gegensatz zum LAN-Interface) keine Devices angezeigt und die Verbindung zum Busmaster bricht zusammen. Nach dem reconnect das Gleiche wieder. Über diese Aktionen gibt es keinen Log-Eintrag, nur einmal diese Meldung:

Code Auswählen Erweitern

2017.03.10 10:49:10 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:49:10 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:49:10 1: OWX_SER::Query Receiving
2017.03.10 10:49:10 1: Trying second reset
2017.03.10 10:49:10 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.10 10:49:10 1: OWX_SER::Query_2480: No return value after writing
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 1
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 2
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 3
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 4
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 5
2017.03.10 10:49:10 1: OWX_SER::Query_2480: Loop no. 6
2017.03.10 10:49:10 1: OWX_SER::Query Receiving
2017.03.10 10:49:10 1: OWX_SER::Reset_2480 failure on bus 1wire_USB
2017.03.10 10:49:10 1: /dev/ttyUSB1 disconnected, waiting to reappear (1wire_USB)
2017.03.10 10:49:10 1: OWX_SER::Search reset failed
2017.03.10 10:49:10 1: OWX: 1-Wire devices found on bus 1wire_USB ()
2017.03.10 10:49:12 1: QOMPLEX called with data =���
2017.03.10 10:49:12 1: [OWX_Qomplex] Added to 1wire_Test_2 send queue dev=1236FC7900000062 context=ds2406.getstate.final
2017.03.10 10:49:12 1: [OWX_PrQueue] starting send-receive cycle, queue length 1. Setting entry to active
2017.03.10 10:49:12 1:      => 1236FC7900000062 context ds2406.getstate.final expecting 4 bytes, waiting w. delay 0

Hab es nun mehrfach getestet, über das "get ... devices" und den anschließenden dis- und reconnects gibt es keine Meldungen im Log.

Gruß
Uwe

[UweH]

Nachtrag zum reconnect beim LAN-Interface: Der Zustand der Eingänge des DS2406 wird danach auch nicht mehr erkannt. Ist ebenso wie die anderen Devices present 1 und wird bei einem "get ... devices" erkannt, aber mehr nicht.

[krueuw]

Ich hatte ja die neue Version mit 60 DS1820 bzw. DS2438 im Einsatz und versprochen, mich zu melden, wenn es Probleme mit der neuen Version gibt. Bin dann aber doch heimlich erst zu asynchron=0 und dann zur alten Version zurückgekehrt, weil es sporadische Aussetzer bei der Erkennung der Sensoren gab.

Habe jetzt die aktuelle Beta getestet:
- asynchron=1: es werden nicht alle Sensoren erkannt
- danach asynchron=0 gesetzt: läuft

[cwagner]

Leider muss ich mit der 6.1alpha2  ebenfalls ein durchwachsenes Ergebnis melden. Erfreulich: FHEM "schmiert" nicht mehr ab. Zwar werden im async-Modus mit get Devices fast alle 24 Devices gemeldet (außer DS 2408/FamilyID 29 - der Switch, der aber trotzdem schaltet), aber ich habe Probleme mit der Stabilität:
Viermal habe ich nach unterschiedlich langer Zeit (so 20 Minuten bis 120 Minuten) festgestellt, dass die Sensor-Readings nicht mehr aktualisiert werden. Die Queue scheint dann noch eine Weile zu laufen und läuft dann einfach voll. Heute Nacht war um 2.40 Uhr nach rund 2 Stunden Schluss und ich fand um 7 Uhr dann eine Queue mit 716 Einträgen.

In den Devices lautet das Internal übrigens weiterhin asynchronous = 0

Das Zurücksetzen des attributs asynchronous ging heute morgen mit der supervollen Queue nicht mit dieser Fehlermeldung:

Code Auswählen Erweitern

fhemweb.js line 947:
Uncaught TypeError: Cannot read property 'length' of null


Überhaupt: Auf asynchronous =1 setzen geht immer, zurücksetzen auf 0 wird bei mir nicht wirksam. Ich kam überhaupt erst wieder in den synchronen MOdus, nach dem ich das Attriibut gelöscht und restart gemacht habe. attr OWio1 asynchronous 1 erhalte ich Fehlermeldung, dass dieses Attribut nicht gesetzt werden kann. Es ist auch nicht in der Liste der Atrribute die bei ? gezeigt wird.

Im Synchronen Modus gibt es derzeit keine negativen Auffälligkeiten. Beim 2408 erhalte ich bei jedem Schaltvorgang eine informative Zeile wie diese:

Code Auswählen Erweitern

2017.03.11 09:45:39 1: DEBUGGING OWXNG : After reading old gpio with outval=1 and outfnd=0 we are setting a new gpio as 239, die sich aber mit Verbose =0 weder am Device noch bei OWX ausschalten lässt.

Diese Logausschnitte könnten hilfreich sein:

Code Auswählen Erweitern

2017.03.09 09:16:15 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1732.
2017.03.09 09:16:15 1: PERL WARNING: Use of uninitialized value in vec at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1405.
2017.03.09 09:16:15 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1417.
2017.03.09 09:16:15 1: PERL WARNING: Use of uninitialized value $string_part in concatenation (.) or string at ./FHEM/11_OWX_SER.pm line 767.
2017.03.09 09:16:15 1: PERL WARNING: Use of uninitialized value $m in addition (+) at ./FHEM/11_OWX_SER.pm line 765.

Code Auswählen Erweitern

2017.03.11 10:14:14 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.11 10:14:14 1: OWX_SER::Query_2480: Loop no. 1
2017.03.11 10:14:14 1: OWX_SER::Query Receiving 0xcd
2017.03.11 10:14:14 1: OWX_SER::Write Sending out 0xe1 0x55 0x28 0x43 0xb8 0x9f 0x05 0x00 0x00 0x0d 0xbe 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff
2017.03.11 10:14:14 1: OWX_SER::Read OWio1: 6 of 9 bytes in attempt 0
2017.03.11 10:14:14 1: OWX_SER::Read OWio1: 9 of 9 bytes in attempt 1
2017.03.11 10:14:14 1: OWX_Read: buffer 9 bytes, expected 9. Calling callback for
2017.03.11 10:14:14 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/00_OWX.pm line 1328.
2017.03.11 10:14:14 1:           2843B89F0500000D w. context
2017.03.11 10:14:14 1:           buffer content is 0x55 0x28 0x43 0xb8 0x9f 0x05 0x00 0x00 0x0d 0xbe 0x30 0x01 0x4b 0x46 0x3f 0xff 0x10 0x10 0x48
2017.03.11 10:14:14 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.11 10:14:14 1: OWX_SER::Query_2480: Loop no. 1
2017.03.11 10:14:14 1: OWX_SER::Query Receiving 0xcd
2017.03.11 10:14:14 1: OWX_Read: OWio1 queue contains 0 entries after removal of active entry
2017.03.11 10:14:14 1: ----------------------------------------------


Code Auswählen Erweitern

2017.03.11 10:27:12 1: ----------------------------------------------
2017.03.11 10:27:12 1: OWX_SER::Query: Sending out 0xe3 0xc5
2017.03.11 10:27:12 1: OWX_SER::Query_2480: Loop no. 1
2017.03.11 10:27:12 1: OWX_SER::Query Receiving 0xcd
2017.03.11 10:27:12 1: OWX_SER::Write Sending out 0xe1 0x55 0x29 0x68 0x98 0x0c 0x00 0x00 0x00 0xda 0x5a 0xff 0x00 0xff
2017.03.11 10:27:12 1: OWX_SER::Read OWio1: 1 of 1 bytes in attempt 0
2017.03.11 10:27:12 1: OWX_Read: buffer 1 bytes, expected 1. Calling callback for
2017.03.11 10:27:12 1:           2968980C000000da w. context ds2408.setstate.255
2017.03.11 10:27:12 1:           buffer content is 0x55 0x29 0x68 0x98 0x0c 0x00 0x00 0x00 0xda 0x5a 0xff
2017.03.11 10:27:12 1: PERL WARNING: substr outside of string at ./FHEM/21_OWSWITCH.pm line 1023.
2017.03.11 10:27:12 1: PERL WARNING: Use of uninitialized value $res in numeric ne (!=) at ./FHEM/21_OWSWITCH.pm line 1146.
2017.03.11 10:27:12 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/21_OWSWITCH.pm line 1147.
2017.03.11 10:27:12 1: QOMPLEX called with data =��
2017.03.11 10:27:12 1: OWX_Read: OWio1 queue contains 1 entries after removal of active entry
2017.03.11 10:27:12 1: ----------------------------------------------
20

Vielen Danke für die vielen Energie, die in diesem Modul steckt.

Christian

[cwagner]

Nachtrag: Und wieder wurden die Readings nicht mehr aktualisiert und zeitgleich schnappte ich dies im Log auf:

Code Auswählen Erweitern

2017.03.11 11:06:25 1: /dev/ttyUSB0 disconnected, waiting to reappear (OWio1)
2017.03.11 11:06:25 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1732.
2017.03.11 11:06:25 1: PERL WARNING: Use of uninitialized value in vec at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1405.
2017.03.11 11:06:25 1: PERL WARNING: Use of uninitialized value in subroutine entry at /usr/lib/arm-linux-gnueabihf/perl5/5.20/Device/SerialPort.pm line 1417.
2017.03.11 11:06:25 1: PERL WARNING: Use of uninitialized value $string_part in concatenation (.) or string at ./FHEM/11_OWX_SER.pm line 753.
2017.03.11 11:06:25 1: PERL WARNING: Use of uninitialized value $m in addition (+) at ./FHEM/11_OWX_SER.pm line 751.
2017.03.11 11:07:25 1: /dev/ttyUSB0 reappeared (OWio1)
2017.03.11 11:07:25 1: /dev/ttyUSB0 disconnected, waiting to reappear (OWio1)
2017.03.11 11:09:25 1: /dev/ttyUSB0 reappeared (OWio1)
2017.03.11 11:09:25 1: /dev/ttyUSB0 disconnected, waiting to reappear (OWio1)
2017.03.11 11:11:25 1: /dev/ttyUSB0 reappeared (OWio1)
2017.03.11 11:11:25 1: /dev/ttyUSB0 disconnected, waiting to reappear (OWio1)


Christian