FHEM Forum

FHEM - Hausautomations-Systeme => 1Wire => Thema gestartet von: fhem-challenge am 24 März 2014, 11:35:31

Titel: 1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 24 März 2014, 11:35:31
Guten Tag!

mit meiner FHEM Umgebung habe ich nun (nahezu) alles erfolgreich integrieren können, was integrierbar scheint.
Allein die Messung weiter 10 Temperaturen in und um meine Heizungsanlage mit OWX ist eher schwerer lösbar.

Hier persistiert ein Problem mit OWX/OWTHERM (vielleicht ja auch nur in meiner Umgebung).
Insbesondere das Timing in FHEM selbst (genauer OWX & OWTHERM) scheint ein Problem zu sein, was
die produktive Nutzung mehrerer 1-Wire devices in FHEM erschwert.
Meine Frustration hinsichtlich 1-Wire & OWX ist mittlerweile so groß, dass ich nun als Interimslösung mithilfe
meines arduino die AD-Eingänge mit dem LM35 nutze (nutzen muss).
Das ist sicherlich allein schon aufgrund der geringen Auflösung (10Bit) und des kleinen nutzbaren Fensters keine wirkliche Alternative zu 1-Wire (18B20 etc.).
(Beim LM35 ist ja sinnvoll nur der Bereich von 0-100°C == 0-1V nutzbar, und das bei 10 Bit AD resosultion habe ich grob 0,5°C Steps ...)

Vorweg:
ich finde leider keine vollständige Darstellung aller zu umschiffenden Klippen (und das sind viele), wenn man 1-Wire mit Arduino und FHEM nutzen möchte.
Ich finde hier (und ich habe intensiv und viel gesucht) zwar eine große Ansammlung von Infos/Codesnips, die aber erst mit viel Müher zusammengefasst, ein grobes Bild ergeben.

Derzeitiger Stand:

Nun, ich nutze zwar (derzeit 5) 1-Wire DS18B20 (arduino->Conf.Firmata->OWX), wobei das Timing und das Gesamtgewerke um OWX mir sehr fragil erscheint und einen produktiven Einsatz in meiner Heizung eher vereiteln.

Problem 1:

Die 1-Wire Devices werden nur sporadisch, änfänglich mehr, dann teilweise überhaupt nicht mehr gelesen (am arduino schon, der 1-Wire bus ist Okay, ich nutze überdies "reale" buspower)

Änderungen am "sampling-interval" am FIRMATA device ändern die Situation gravierend, aber Werte über 300(ms) führen schlicht dazu,
dass ein verlässliches "Lesen" der 18B20 in FHEM nicht mehr möglich ist.

Einzig durch viel "Experimentieren" am "sampling-interval" am FIRMATA device ist noch etwas heraus zu holen.

Werte für das sampling-interval von 100(ms) bringen eine stabile Situation und ich kann verlässlich alle 1-Wire Temp.Sensoren lesen (alle 5 Minuten).

Der in ...
http://www.fhemwiki.de/wiki/Arduino_Firmata
... vorgegebene Wert "attr FIRMATA sampling-interval 1000" ist hier eher verwirrend und aus meiner Sicht nicht praktikabel.

Problem 2:

Natürlich ist aufgrund des dann nur nutzbaren, geringen sampling-interval von 100(ms) die CPU load auf meinem FHEM Rechner unnötig groß.
Da ich zu 1-Wire parallel auch LM35 TempSensoren via FIRMATA (analoginput) am Arduino "lese", führt das zwangsläufig zu Performanceproblemen, da auch die LM35 als "FRM_AD" Device viel zu häufig
"gelesen" werden. Abhilfe schafft ansich nur ein "event-min-interval.*:180" am "FRM_AD" Device, um das auf z.B. 3 Minuten zu erhöhen.

Problem 3: OWX/OWTHERM ist sehr CPU-hungrig und steht immer an der Spitze der verzögernden Funktionen.

fhem> apptime

                                name             function    max  count    total  average maxDly
               tmr-OWTHERM_GetValues      HASH(0x94ab81c)   2868      2     3859  1929.50      1 HASH(0x94ab81c)
               tmr-OWTHERM_GetValues      HASH(0x95d21fc)   2654      2     4488  2244.00      1 HASH(0x95d21fc)
               tmr-OWTHERM_GetValues      HASH(0x95d1bbc)   1838      2     3594  1797.00      1 HASH(0x95d1bbc)
               tmr-OWTHERM_GetValues      HASH(0x95d189c)   1758      2     3422  1711.00      1 HASH(0x95d189c)
               tmr-OWTHERM_GetValues      HASH(0x95d1edc)   1749      2     3416  1708.00      2 HASH(0x95d1edc)
                        tmr-OWX_Kick      HASH(0x93ad534)    503      2     1006   503.00      1 HASH(0x93ad534)
          FHEMWEB:x.x.x.x:60011              FW_Read    473     95     1940    20.42      0 HASH(0x9c93a84)
                          sysstatlog          FileLog_Get    441      3     1217   405.67      0 HASH(0x8b7d67c); sysstatlog; CURRENT; INT; 2014-03-24_00:00:00; 2014-03-25_00:00:01; 4:sysstat.load\x3a:0:; 4:sysstat./\x3a:0:; 4:sysstat.uptime\x3a:0:
          FHEMWEB:x.x.x.x:60008              FW_Read    419     85     1366    16.07      0 HASH(0x986a2a4)
                               CUNO2             CUL_Read    204    120     2153    17.94      0 HASH(0x849ad38)
          FHEMWEB:x.x.x.x:60014              FW_Read    187     81      902    11.14      0 HASH(0x9f2add0)
          FHEMWEB:x.x.x.x:60020              FW_Read    187     89      817     9.18      0 HASH(0x9f2cf1c)
          FHEMWEB:x.x.x.x:60017              FW_Read    185     84      849    10.11      0 HASH(0x9e68fb4)
                         tmr-at_Exec      HASH(0x8ceedc8)    148      2      296   148.00      1 HASH(0x8ceedc8)
                               logdb            DbLog_Log    135    237      749     3.16      0 HASH(0x88ebfe4); HASH(0x97bbe14)
                     check_all_state          notify_Exec    132      2      264   132.00      0 HASH(0x8cee1e8); HASH(0x8cee1e8)
        FileLog_Heizung_Schlafzimmer          FileLog_Get    131      4      280    70.00      0 HASH(0x9309478); FileLog_Heizung_Schlafzimmer; CURRENT; INT; 2014-03-24_00:00:00; 2014-03-25_00:00:01; 4:measured-temp\x3a:0:; 4:desired-temp\x3a:0:; 4:actuator\x3a:0:
           FileLog_Heizung_Hobbyraum          FileLog_Get    130      3      386   128.67      0 HASH(0x92d8fc0); FileLog_Heizung_Hobbyraum; CURRENT; INT; 2014-03-24_00:00:00; 2014-03-25_00:00:01; 4:measured-temp\x3a:0:; 4:desired-temp\x3a:0:; 4:actuator\x3a:0:
          FHEMWEB:x.x.x.x:60004              FW_Read    115     87      734     8.44      0 HASH(0x9e7aac0)
                FileLog_Heizung_Flur          FileLog_Get     99      3      181    60.33      0 HASH(0x8f743b8); FileLog_Heizung_Flur; CURRENT; INT; 2014-03-24_00:00:00; 2014-03-25_00:00:01; 4:temperature:0:; 4:desired:0:; 6:H\x3a:0:; 4:actuator:0:int
            FRM:192.168.100.221:1039             FRM_Read     78  22083     1881     0.09      0 HASH(0x877b06c)

         
         
         
Frage 1:
Hat jemand wirklich produktiv (also für einen längeren Zeitraum stabile 1-Wire Temperaturmessungen) in FHEM realisiert, ohne dabei hinsichtlich der Gesamtperformance auf eine Parallelrechnercluster für FHEM ausweichen zu müssen ? (sorry für den leichten Sarkassmus im letzten Satzteil)

Frage 2: Sieht jemand eine Lösung, stabil 1-Wire Devices (genauer 18B20), auch mit einem sampling-interval >> 1000 zu lesen, damit die CPU des FHEM Server nicht allzu belastet wird ?


Anbei meine Umgebung:

- arduino mega 2560 mit EthernetShield: Es läuft die ConfigurableFirmata (V_2_05)
- FHEM (kurzum aktuellste Version)
   # $Id: fhem.pl 5238 2014-03-16 16:23:31Z rudolfkoenig $
   ...
   # $Id: 21_OWTHERM.pm 5035 2014-02-24 03:47:01Z pahenning $
   # $Id: 00_OWX.pm 5267 2014-03-20 20:44:43Z ntruchsess $
- Derzeit 5 x 1-wire Temp.Sensoren DS18B20 (später sollen es 10 werden)
- Nutzung von 5 AD-ports am Arduino (LM35 als Temp.sensoren)


1-Wire Umgebung in FHEM:

fhem> list FIRMATA
Internals:
   CFGFN      /usr/share/fhem/FHEM/fhem.onewire.cfg
   CONNECTS   1
   DEF        3030 global
   DeviceName 3030
   FD         4
   NAME       FIRMATA
   NOTIFYDEV  global
   NR         1472
   NTFY_ORDER 50-FIRMATA
   PORT       3030
   STATE      Initialized
   TYPE       FRM
...snip ...
     BUF       
     DeviceName 3030
     FD         16
     NAME       FRM:192.168.100.221:1039
     NR         2207
     SNAME      FIRMATA
     STATE      Connected
     TEMPORARY  1
     TYPE       FRM
Attributes:
   event-min-interval .*:180
   room       OWX
   sampling-interval 250
   verbose    0

   
   fhem> list OW
Internals:
   ALARMED    no
   CFGFN      /usr/share/fhem/FHEM/fhem.onewire.cfg
   DEF        45
   FRM_OWX_CORRELATIONID 90
   INTERFACE  firmata
   IODev      FIRMATA
   NAME       OW
   NOTIFYDEV  global
   NR         1474
   NTFY_ORDER 50-OW
   PIN        45
   PRESENT    1
   ROM_ID     FF
   STATE      Initialized
   TYPE       OWX
   followAlarms off
   interval   300
   ALARMDEVS:
   DEVS:
     28.12E29D040000.90
     28.2A6757040000.E7
     28.1ACC57040000.49
     28.D6F59C040000.71
     28.EF1E58040000.97
   Frm_owx_replies:
     28.12E29D040000.90 1þþ >
     28.1ACC57040000.49 3þþ
    28.2A6757040000.E7 3þþ
    28.D6F59C040000.71 0KF¨
     28.EF1E58040000.97 /KF.
   Frm_owx_requests:
   Readings:
     2014-03-24 08:50:42   state           defined
Attributes:
   DbLogExclude .*
   IODev      FIRMATA
   buspower   real
   loglevel   0
   room       OWX



CONFIG Auszug für den Bereich 1-Wire:


# FIRMATA
define FIRMATA FRM 3030 global
attr FIRMATA event-min-interval .*:180
attr FIRMATA room OWX
attr FIRMATA sampling-interval 250
attr FIRMATA verbose 0

# OW
define OW OWX 45
attr OW DbLogExclude .*
attr OW IODev FIRMATA
attr OW buspower real
attr OW loglevel 0
attr OW room OWX

# Analog IN
define Firmata_ANALOG FRM_AD 54
attr Firmata_ANALOG DbLogExclude .*
attr Firmata_ANALOG IODev FIRMATA
attr Firmata_ANALOG event-min-interval .*:180
attr Firmata_ANALOG room OWX
attr Firmata_ANALOG stateFormat reading

# Analogeingang Port 55
define Firmata_ANALOG2 FRM_AD 55
attr Firmata_ANALOG2 DbLogExclude .*
attr Firmata_ANALOG2 IODev FIRMATA
attr Firmata_ANALOG2 event-min-interval .*:180
attr Firmata_ANALOG2 room OWX
attr Firmata_ANALOG2 stateFormat reading


###############
### DS18B20 ###
###############
define temp_12E29D040000 OWTHERM DS18B20 12E29D040000
attr temp_12E29D040000 DbLogExclude .*
attr temp_12E29D040000 IODev OW
attr temp_12E29D040000 model DS18B20
attr temp_12E29D040000 room OWX
attr temp_12E29D040000 tempConv onread
attr temp_12E29D040000 tempHigh 75
attr temp_12E29D040000 tempLow 70
define FileLog_12E29D040000 FileLog /var/log/fhem/ow_12E29D040000.%Y.log temp_12E29D040000:T:.*
attr FileLog_12E29D040000 logtype temp4:Temp,text
attr FileLog_12E29D040000 room OWX
... snip (es folgen derzeit noch 4 weitere DS18B20)



Viele Grüße!

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: Prof. Dr. Peter Henning am 24 März 2014, 12:21:19
Zitat
Frage 1:
Hat jemand wirklich produktiv (also für einen längeren Zeitraum stabile 1-Wire Temperaturmessungen) in FHEM realisiert, ohne dabei hinsichtlich der Gesamtperformance auf eine Parallelrechnercluster für FHEM ausweichen zu müssen ? (sorry für den leichten Sarkassmus im letzten Satzteil)
Aber sicher. Derzeit werden bei mir ca. 25 verschiedene 1-Wire Sensoren bedient  - allerdings nicht an einem Arduino, sondern über 3 verschiedene USB- und ein Ethernet-Interface, und alles zusammen hängt an einem einzigen Raspberry Pi. Wer Probleme mit dem Firmata-Interface hat, möge sich bitte an Norbert Truchsess wenden und solange alle ungefragte Polemik gegen OWX und OWTHERM unterdrücken.

Zitat
Frage 2: Sieht jemand eine Lösung, stabil 1-Wire Devices (genauer 18B20), auch mit einem sampling-interval >> 1000 zu lesen, damit die CPU des FHEM Server nicht allzu belastet wird ?

Nicht nur sehen. Sondern das wird von vielen hier betrieben.

pah
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 24 März 2014, 12:51:32
Zitat von: Prof. Dr. Peter Henning am 24 März 2014, 12:21:19
Aber sicher. Derzeit werden bei mir ca. 25 verschiedene 1-Wire Sensoren bedient  - allerdings nicht an einem Arduino, sondern über 3 verschiedene USB- und ein Ethernet-Interface, und alles zusammen hängt an einem einzigen Raspberry Pi. Wer Probleme mit dem Firmata-Interface hat, möge sich bitte an Norbert Truchsess wenden und solange alle ungefragte Polemik gegen OWX und OWTHERM unterdrücken.

Nicht nur sehen. Sondern das wird von vielen hier betrieben.

pah

Die "leichte" Polemik kann ich unterdrücken, dass Problem selbst hingegen nicht. Ich hatte meine Frage vermutlich zu unpräzise gestellt: Ich meinte eher einen produktiven Betrieb mit arduino-->Conf.Firmata-->fhem, nicht mit 1-wire USB Interfaces.

Das Problem mit massiven Aussetzern bei einem "sampling-interval > 300" besteht, reproduzierbar. Mich würde interessieren, ob jemand in der oberen Konstellation (arduino ...) mit einem sampling-interval >> 1000 stabile Werte über einen längeren Zeitraum t>2 Stunden erhält ?

Weiterhin sehe ich auch unverändert größere Verzögerung für OWTHERM ...

               tmr-OWTHERM_GetValues      HASH(0x94ab81c)   2868      2     3859  1929.50      1 HASH(0x94ab81c)
               tmr-OWTHERM_GetValues      HASH(0x95d21fc)   2654      2     4488  2244.00      1 HASH(0x95d21fc)
               tmr-OWTHERM_GetValues      HASH(0x95d1bbc)   1838      2     3594  1797.00      1 HASH(0x95d1bbc)

... wenngleich dieses auch ein Ergebnis aus dem sehr klein zu wählenden "sampling-interval=100"  sein kann ?


Viele Grüße!

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 24 März 2014, 15:06:53
also ich weiß leider nicht, was Du da beobachtest, aber das FRM Attribute 'sampling-interval' hat keinerlei Bezug zu OWX. Das Attribut steuert, wie oft dir report (https://github.com/firmata/arduino/blob/configurable/examples/ConfigurableFirmata/ConfigurableFirmata.ino#L284)-methode am AnalogInput bzw. I2C-feature aufgerufen wird.
Das kann höchstens eine indirekte Wirkung haben: Wenn man mit zu kleinem sampling-interval (Default sind 19ms) arbeitet und Analog-input-pins konfiguriert sind, dann kommen die OneWire-responses nicht mehr so zügig über die Schnittstelle (56700 Baud seriell sind nicht die Welt). Bei großem Sampling-interval (1000ms) kommen halt weniger Daten über die Analog-pins. Aber das ist in der Regel eher günstig, weil FHEM die ganzen Analog-messages ja auch verarbeiten muss.

Wenn Du die DS18B20 in der normalen (synchronen) Version (das ist die, die aktuell noch regulär verteilt wird) mit zu kurzem Interval abfragst, dann bremst das FHEM als ganzes total aus, weil jede Messung FHEM für knapp eine Sekunde anhält (das ist unabhängig vom Arduino). Das default-abfrageinterval für OWTHERM ist nicht ohne Grund auf 300 Sekunden eingestellt.

Gruß,

Norbert

Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: Prof. Dr. Peter Henning am 24 März 2014, 16:45:24
Dann muss ich mich anschließen: Keine Ahnung, was er dort treibt.

LG

pah
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 24 März 2014, 17:28:24
hab grade gesehen, Du hast den Arduino ja über Ethernet angebunden. Da spielen die 56700 Baud der seriellen Schnittstelle natürlich keine Rolle. Wobei die Bandbreite des Ethernet-shields auch nicht so doll ist - das schickt mit Firmata nämlich für fast jedes Byte ein eigenes Netzwerkpacket über die Leitung (liegt daran, dass die Ethernet-lib nix puffert). Die sich ergebende Bandbreite ist dann extrem von der Netzwerklatenzzeit abhängig.
Mit einem Enc28J60 basierten Shield wäre der Durchsatz etwas besser (normalerweise mindestens 1 Firmata-message pro Netzwerk-packet), dafür braucht das mehr Speicher (wobei das beim verwendeten Mega eher keine Relevanz hat, da passt die komplette ConfigurableFirmata auch mit Enc28J8 UIPEthernet-lib rein).

Grundsätzlich ist das zum sampling-interval gesagte aber bei einem über Ethernet angebundenen Arduino aber genauso wie bei Serieller Anbindung.

Hast Du Logs mit Fehlermeldungen, an denen man sieht, was eigentlich schief geht?

Gruß,

Norbert
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 24 März 2014, 21:01:50
Zitat von: ntruchsess am 24 März 2014, 17:28:24
hab grade gesehen, Du hast den Arduino ja über Ethernet angebunden. Da spielen die 56700 Baud der seriellen Schnittstelle natürlich keine Rolle. Wobei die Bandbreite des Ethernet-shields auch nicht so doll ist - das schickt mit Firmata nämlich für fast jedes Byte ein eigenes Netzwerkpacket über die Leitung (liegt daran, dass die Ethernet-lib nix puffert). Die sich ergebende Bandbreite ist dann extrem von der Netzwerklatenzzeit abhängig.
Mit einem Enc28J60 basierten Shield wäre der Durchsatz etwas besser (normalerweise mindestens 1 Firmata-message pro Netzwerk-packet), dafür braucht das mehr Speicher (wobei das beim verwendeten Mega eher keine Relevanz hat, da passt die komplette ConfigurableFirmata auch mit Enc28J8 UIPEthernet-lib rein).

Grundsätzlich ist das zum sampling-interval gesagte aber bei einem über Ethernet angebundenen Arduino aber genauso wie bei Serieller Anbindung.

Hast Du Logs mit Fehlermeldungen, an denen man sieht, was eigentlich schief geht?

Gruß,

Norbert

Hallo,

ja, richtig, das war auch meine Vermutung. Reproduzierbar ist aber die (vielleicht ja sogar indirekte) Abhängigkeit des sampling-interval am "FRM" zu den Ergebnissen.


Mit dem "sampling-interval <= 300" funktioniert schlicht alles:

define FIRMATA FRM 3030 global
attr FIRMATA sampling-interval [color=red]250[/color]
attr FIRMATA verbose 5


... und ich bekomme regelmässige (5 minütige) Ergebnisse (den default von 300(s) hatte ich gelassen)

Logfile:

...
2014-03-21_14:11:35 temp_2A6757040000 T: 21.88 &deg;C ▾
2014-03-21_14:16:15 temp_2A6757040000 T: 21.81 &deg;C ▾
2014-03-21_14:21:15 temp_2A6757040000 T: 21.88 &deg;C ▾
2014-03-21_14:26:15 temp_2A6757040000 T: 21.94 &deg;C ▾
2014-03-21_14:31:15 temp_2A6757040000 T: 22.00 &deg;C ▾
2014-03-21_14:36:15 temp_2A6757040000 T: 21.94 &deg;C ▾
2014-03-21_14:41:15 temp_2A6757040000 T: 22.06 &deg;C ▾
2014-03-21_14:46:15 temp_2A6757040000 T: 21.94 &deg;C ▾
2014-03-21_14:51:22 temp_2A6757040000 T: 21.94 &deg;C ▾
2014-03-21_14:56:20 temp_2A6757040000 T: 22.00 &deg;C ▾
...



Mit dem "sampling-interval > 300" bekomme ich keine Ergebnisse mehr (oder nur sehr sporadisch)

define FIRMATA FRM 3030 global
attr FIRMATA sampling-interval [color=red]1000[/color]
attr FIRMATA verbose 5


Logfile:

...
2014-03-19_10:08:31 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_10:18:30 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_10:28:35 temp_2A6757040000 T: 19.31 &deg;C ▾
2014-03-19_10:43:34 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_10:58:36 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_13:45:13 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_16:31:53 temp_2A6757040000 T: 85.00 &deg;C ▴
2014-03-19_18:26:40 temp_2A6757040000 T: 20.31 &deg;C ▾
2014-03-21_14:11:35 temp_2A6757040000 T: 21.88 &deg;C ▾
...


Ich würde natürlich gerne das sampling-intervall deutlich höher stellen, aber dann bekomme ich schlicht keine Messwerte mehr von den 18B20 am 1-Wire bus.


Viele Grüße!

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 24 März 2014, 21:55:07
stell doch bitte am FRM-modul verbose auf 5, dann schreibt es die Firmata-kommunikation im Log mit. sampling-interval auf 1000 oder so. Dann kann man genau sehen, ob die Requests ausbleiben oder nicht beantwortet werden oder die Anwort fehlerhaft ist...

Gruß,

Norbert
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 25 März 2014, 11:39:21
Zitat von: ntruchsess am 24 März 2014, 21:55:07
stell doch bitte am FRM-modul verbose auf 5, dann schreibt es die Firmata-kommunikation im Log mit. sampling-interval auf 1000 oder so. Dann kann man genau sehen, ob die Requests ausbleiben oder nicht beantwortet werden oder die Anwort fehlerhaft ist...

Gruß,

Norbert

Habe verbose=5. Im log ist allerdings nicht viel zu sehen. Schon gar keine Fehler.

2014.03.25 09:16:09 3: FIRMATA: port 3030 opened
2014.03.25 09:16:09 1: OWX: 1-Wire bus OW: interface Firmata detected in FIRMATA
2014.03.25 09:16:09 0: Server started with 81 defined entities (version $Id: fhem.pl 5238 2014-03-16 16:23:31Z rudolfkoenig $, os linux, user root, pid 24533)
2014.03.25 09:16:14 4: Connection accepted from FRM:192.168.100.221:1159
2014.03.25 09:16:17 3: querying Firmata Firmware Version
2014.03.25 09:16:17 3: Firmata Firmware Version: fhem_firmata.ino V_2_05
2014.03.25 09:16:29 1: OWX: 1-Wire devices found on bus OW ()
2014.03.25 09:16:29 1: OWX: 1-Wire devices found on bus OW ()
2014.03.25 09:21:07 1: OWX: 1-Wire devices found on bus OW (temp_12E29D040000,temp_2A6757040000,temp_1ACC57040000,temp_D6F59C040000,temp_EF1E58040000
)


Es muss eine Abhängigkeit existieren, die schwer aufzulösen ist.

1.) Ich habe fhem mit einer Minimalconfig gestartet, bei dieser NUR! das FRM Modul und OWX eingebunden ist, damit ich etwaige Abhängigkeiten zu anderen Modulen ausschliessen kann. Der Effekt bleibt dennoch bestehen. Bei "sampling-interval < 300" funktioniert alles bei "sampling-interval > 300" nichts. Im "tcpdump" hatte ich mir die Kommunikation angesehen, diese offenbart auch keine Fehler.

2.) Ich habe parallel (neben dem Haupt-FHEM Server, der bei mir ein Laptop ist) noch ein Rpi laufen, auf diesem täglich die Config vom "Haupt-FHEM" Server gespiegelt wird. Wenn ich nun mein Arduino mit dem Rpi kommunizieren lasse, funktioniert es dort auch mit einem "sampling-interval > 300" tadellos.

... hmmm ... da bin auch ich sprachlos ...


Vielleicht bleibt mir nichts anderes übrig, als den FHEM auf dem laptop vollständig neu aufzusetzen :-(
Kein schöner Gedanke, da ich die eigentliche Ursache nicht finden kann.

Gruss

Andreas




Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 25 März 2014, 12:13:30
Ich verstehe nicht, dass verbose nix macht? Die Verwendung von Log3 ist seit dem 17.November 2013 (https://github.com/ntruchsess/fhem-mirror/commit/2877b8d24cd90ed999661bc5fbb5328a142c5793) eingebaut. Log3 funktioniert allerdings erst nach dem global INITIALIZED dran war. D.h. die Initialisierung sieht man mit verbose=5 im FRM erst seit dem 15.März 2014 (https://github.com/ntruchsess/fhem-mirror/commit/89464b2c1c317674ead9417307053eb5c2fecb0e). Wobei das verbose-logging nach der Initialisierung auch vor dem 15.03 funktioniert hat. Zur Not kannst Du auch global verbose auf 5 setzen.

testest Du am Pi und am Haup-FHEM mit dem gleichen Arduino? Welche Features hast Du im Sketch auskommentiert?
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 25 März 2014, 13:20:12
... jetzt beschleicht mich der Verdacht, das ich noch die "alten" Versionen in meinem FHEM (Hauptsystem) fahre:

Zitat-rw-r--r-- 1 root root  7846 Mar 25 11:53 Base.pm
-rw-r--r-- 1 root root 21217 Mar 25 11:53 Constants.pm
-rw-r--r-- 1 root root  2254 Mar 25 11:53 Error.pm
-rw-r--r-- 1 root root  1709 Mar 25 11:53 IO.pm
-rw-r--r-- 1 root root  2210 Mar 25 11:53 Language.pm
drwxr-xr-x 2 root root  4096 Mar 25 11:53 Platform
-rw-r--r-- 1 root root 29426 Mar 25 11:53 Platform.pm
-rw-r--r-- 1 root root 31010 Mar 25 11:53 Protocol.pm
You have new mail in /var/mail/root
root@ai-serv:/usr/share/fhem/FHEM/lib/Device/Firmata#

Meine Timestamps sind zwar von heute, aber das liegt am "copy" von heute.

und ja, ich verwende den gleichen arduino (ich ändere ja nur die " remote_ip IPAddress" im sketch. Daher läuft alles auf Seiten des arduino korrekt.

Anbei die wesentlichen Auszüge aus dem Sketch (ich habe ja ein Mega 2560 und ein EtherShield (kein ENC28J60).

define NETWORK_FIRMATA
//replace with ip of server you want to connect to, comment out if using 'remote_host'
#define remote_ip IPAddress(192,168,100,222)
//replace with hostname of server you want to connect to, comment out if using 'remote_ip'
//#define remote_host "server.local"
//replace with the port that your server is listening on
#define remote_port 3030
//replace with arduinos ip-address. Comment out if Ethernet-startup should use dhcp
#define local_ip IPAddress(192,168,100,221)
//replace with ethernet shield mac. It's mandatory every device is assigned a unique mac
const byte mac[] = {0x00,0x50,0x56,0x92,0x39,0x42};
#endif

... snip

#include <Firmata.h>

/*
* by default Firmata uses the Serial-port (over USB) of Arduino.
* ConfigurableFirmata may also comunicate over ethernet using tcp/ip.
* To configure this 'Network Firmata' to use the original WIZ5100-based
* ethernet-shield or Arduino Ethernet uncomment the includes of 'SPI.h' and 'Ethernet.h':
*/

#include <SPI.h>
#include <Ethernet.h>

... snip

  pinMode(PIN_TO_DIGITAL(53), OUTPUT); // configure hardware-SS as output on MEGA
  pinMode(PIN_TO_DIGITAL(4), OUTPUT); // switch off SD-card bypassing Firmata
  digitalWrite(PIN_TO_DIGITAL(4), HIGH); // SS is active low;

... snip



verbose=5 am FRM-Modul macht immer noch kein Logging. Ich habe übrigens emine komplette FHEM überprüft, es waren noch keine "alten" Module dabei und bin auf dem Stand vom 24.3.2014.


ich habe nun "global vebose=5" eingeschaltet. Dabei habe ich wieder eine minimalversion der config NUR! mit FRM gestartet (sonst wäre das logging nicht mehr lesbar).

Logging mit verbose=4 (global)

2014.03.25 14:14:04 1: Including /usr/share/fhem/FHEM/fhem.onewire.cfg
2014.03.25 14:14:05 3: OWTHERM: Device temp_12E29D040000 defined.
2014.03.25 14:14:05 3: OWTHERM: Device temp_1ACC57040000 defined.
2014.03.25 14:14:05 3: OWTHERM: Device temp_D6F59C040000 defined.
2014.03.25 14:14:05 3: OWTHERM: Device temp_EF1E58040000 defined.
2014.03.25 14:14:05 3: OWTHERM: Device temp_2A6757040000 defined.
2014.03.25 14:14:05 1: Including /var/log/fhem/fhem.save
2014.03.25 14:14:05 3: FIRMATA: port 3030 opened
2014.03.25 14:14:05 1: OWX: 1-Wire bus OW: interface Firmata detected in FIRMATA
2014.03.25 14:14:05 0: Server started with 46 defined entities (version $Id: fhem.pl 5238 2014-03-16 16:23:31Z rudolfkoenig $, os linux, user root, pid 4785)
2014.03.25 14:14:07 4: Connection accepted from FRM:192.168.100.221:1045
2014.03.25 14:14:10 3: querying Firmata Firmware Version
2014.03.25 14:14:10 3: Firmata Firmware Version: fhem_firmata.ino V_2_05
2014.03.25 14:14:27 1: OWX: 1-Wire devices found on bus OW ()
2014.03.25 14:14:27 1: OWX: 1-Wire devices found on bus OW ()



Logging mit verbose=5 (global)

2014.03.25 14:16:57 5: Cmd: >attr FIRMATA sampling-interval 5000<
2014.03.25 14:16:57 5: 15>f0,7a,08,27,f7
2014.03.25 14:16:57 5: SW: ð'÷
2014.03.25 14:16:57 5: Cmd: >{ReadingsVal("FIRMATA","reinit","")}<
2014.03.25 14:16:57 5: Cmd: >{AttrVal("FIRMATA","room","")}<
2014.03.25 14:17:01 5: 15<e0,27,02
2014.03.25 14:17:01 5: 15<e1,2c,02,e2,2f,02
2014.03.25 14:17:01 5: 15<e3,27,02,e4,22,02,e5
2014.03.25 14:17:01 5: 15<1f,02,e6,23,02,e7
2014.03.25 14:17:01 5: 15<1f,02,e8,22,02
2014.03.25 14:17:04 5: Triggering sysstat (7 changes)
2014.03.25 14:17:04 5: Notify loop for sysstat 0.05 0.05 0.05
2014.03.25 14:17:06 5: 15<e0,26,02
2014.03.25 14:17:06 5: 15<e1,2b,02,e2,2e,02,e3,25,02,e4,20,02,e5,1d,02,e6,22,02,e7,1e,02,e8,22,02
2014.03.25 14:17:11 5: 15<e0,26
2014.03.25 14:17:11 5: 15<02,e1,2b,02,e2,2e,02,e3
2014.03.25 14:17:11 5: 15<25,02,e4,20,02
2014.03.25 14:17:11 5: 15<e5,1d,02,e6
2014.03.25 14:17:11 5: 15<22,02,e7
2014.03.25 14:17:11 5: 15<1e,02,e8,23
2014.03.25 14:17:11 5: 15<02
2014.03.25 14:17:11 5: Triggering Firmata_ANALOG9 (1 changes)
2014.03.25 14:17:11 5: Notify loop for Firmata_ANALOG9 reading: 291
2014.03.25 14:17:16 5: 15<e0,25
2014.03.25 14:17:16 5: 15<02,e1,2a,02
2014.03.25 14:17:16 5: Triggering Firmata_ANALOG (1 changes)
2014.03.25 14:17:16 5: Notify loop for Firmata_ANALOG reading: 293
2014.03.25 14:17:16 5: Triggering Firmata_ANALOG2 (1 changes)
2014.03.25 14:17:16 5: Notify loop for Firmata_ANALOG2 reading: 298
2014.03.25 14:17:16 5: 15<e2,2d,02,e3,24,02,e4,1f,02,e5,1d,02,e6,22,02,e7,1e,02,e8,24,02
2014.03.25 14:17:16 5: Triggering Firmata_ANALOG3 (1 changes)
2014.03.25 14:17:16 5: Notify loop for Firmata_ANALOG3 reading: 301
2014.03.25 14:17:16 5: Triggering Firmata_ANALOG4 (1 changes)
2014.03.25 14:17:16 5: Notify loop for Firmata_ANALOG4 reading: 292
2014.03.25 14:17:16 5: Triggering Firmata_ANALOG5 (1 changes)
2014.03.25 14:17:16 5: Notify loop for Firmata_ANALOG5 reading: 287
2014.03.25 14:17:21 5: 15<e0,24
2014.03.25 14:17:21 5: 15<02,e1,29,02,e2,2d,02,e3,24,02
2014.03.25 14:17:21 5: 15<e4,1f,02,e5,1d,02,e6,23
2014.03.25 14:17:21 5: 15<02,e7,1f,02
2014.03.25 14:17:21 5: Triggering Firmata_ANALOG7 (1 changes)
2014.03.25 14:17:21 5: Notify loop for Firmata_ANALOG7 reading: 291
2014.03.25 14:17:21 5: Triggering Firmata_ANALOG8 (1 changes)
2014.03.25 14:17:21 5: Notify loop for Firmata_ANALOG8 reading: 287
2014.03.25 14:17:21 5: 15<e8,25,02
2014.03.25 14:17:26 5: 15<e0,24,02
2014.03.25 14:17:26 5: 15<e1,29,02
2014.03.25 14:17:26 5: 15<e2,2d,02
2014.03.25 14:17:26 5: 15<e3,24,02
2014.03.25 14:17:26 5: 15<e4,20
2014.03.25 14:17:26 5: 15<02,e5,1e,02
2014.03.25 14:17:26 5: Triggering Firmata_ANALOG6 (1 changes)
2014.03.25 14:17:26 5: Notify loop for Firmata_ANALOG6 reading: 286
2014.03.25 14:17:26 5: 15<e6,24,02,e7,20,02,e8,26,02
2014.03.25 14:17:31 5: 15<e0,24
2014.03.25 14:17:31 5: 15<02,e1,29,02,e2
2014.03.25 14:17:31 5: 15<2d,02,e3,24,02
2014.03.25 14:17:31 5: 15<e4,21,02,e5,1f,02,e6,25,02,e7,21,02,e8,27,02
2014.03.25 14:17:36 5: 15<e0,24
2014.03.25 14:17:36 5: 15<02,e1,29,02
2014.03.25 14:17:36 5: 15<e2,2d,02,e3
2014.03.25 14:17:36 5: 15<25,02,e4,21,02,e5,20,02,e6,26,02,e7,22,02,e8,28,02
2014.03.25 14:17:41 5: 15<e0,24,02
2014.03.25 14:17:41 5: 15<e1,29,02,e2,2d,02,e3,25,02,e4,21,02,e5,20,02,e6
2014.03.25 14:17:41 5: 15<26,02,e7,21,02
2014.03.25 14:17:41 5: 15<e8,28,02
2014.03.25 14:17:46 5: 15<e0,24
2014.03.25 14:17:46 5: 15<02,e1,2a,02
2014.03.25 14:17:46 5: 15<e2,2e,02,e3
2014.03.25 14:17:46 5: 15<25,02,e4,22,02
2014.03.25 14:17:46 5: 15<e5,22,02,e6
2014.03.25 14:17:46 5: 15<27,02,e7,22,02,e8,29,02
2014.03.25 14:17:51 5: 15<e0,25
2014.03.25 14:17:51 5: 15<02,e1,2a,02,e2
2014.03.25 14:17:51 5: 15<2f,02,e3,27,02,e4
2014.03.25 14:17:51 5: 15<24,02,e5,24,02,e6,28,02
2014.03.25 14:17:51 5: 15<e7,24,02,e8,2a,02
2014.03.25 14:17:56 5: 15<e0,25
2014.03.25 14:17:56 5: 15<02,e1,2b,02
2014.03.25 14:17:56 5: 15<e2,30,02,e3
2014.03.25 14:17:56 5: 15<28,02,e4,25,02,e5
2014.03.25 14:17:56 5: 15<25,02,e6,29,02,e7,24,02,e8,2a,02
2014.03.25 14:17:57 5: Cmd: >{ReadingsVal("FIRMATA","reinit","")}<
2014.03.25 14:17:57 5: Cmd: >{AttrVal("FIRMATA","room","")}<
2014.03.25 14:18:01 5: 15<e0,26
2014.03.25 14:18:01 5: 15<02,e1,2c,02,e2,31,02,e3,29,02,e4,26,02,e5,26,02,e6,29,02,e7
2014.03.25 14:18:01 5: 15<25,02,e8,2a,02
2014.03.25 14:18:04 5: Triggering sysstat (7 changes)
2014.03.25 14:18:04 5: Notify loop for sysstat 0.02 0.04 0.05
2014.03.25 14:18:06 5: 15<e0,26,02
2014.03.25 14:18:06 5: 15<e1,2c,02,e2,31,02,e3,29,02,e4,26,02,e5,26,02,e6,2a,02,e7,25,02,e8,2a,02
2014.03.25 14:18:11 5: 15<e0,27
2014.03.25 14:18:11 5: 15<02,e1,2d,02,e2,32,02,e3,2a,02,e4,27,02,e5,26,02
2014.03.25 14:18:11 5: 15<e6,2a,02,e7,24,02,e8,29,02
2014.03.25 14:18:16 5: 15<e0,28,02
2014.03.25 14:18:16 5: 15<e1,2e,02,e2,33
2014.03.25 14:18:16 5: 15<02,e3,2b,02
2014.03.25 14:18:16 5: 15<e4,27,02,e5,25,02,e6,29
2014.03.25 14:18:16 5: 15<02,e7,23,02,e8,28,02


Gruss

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: Prof. Dr. Peter Henning am 25 März 2014, 14:14:52
Nachdem es noch einen zweiten Thread mit ähnlich seltsamen Problemen gibt, habe ich den Verdacht, dass einige Hauptkomponenten von FHEM beim Update inkonsistent umgebaut wurden und nunmehr doppelt geladen werden (z.B. die 99_getstate.pm) oder widersprüchliche Versionen haben.

LG

pah
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 25 März 2014, 14:32:19
Zitat von: Prof. Dr. Peter Henning am 25 März 2014, 14:14:52
Nachdem es noch einen zweiten Thread mit ähnlich seltsamen Problemen gibt, habe ich den Verdacht, dass einige Hauptkomponenten von FHEM beim Update inkonsistent umgebaut wurden und nunmehr doppelt geladen werden (z.B. die 99_getstate.pm) oder widersprüchliche Versionen haben.

LG

pah

Ja, auch dieser Effekt, wie im zweiten thread beschrieben, tauchte bei mir zeitgleich mit dem o.g. Problem auf.

Gruss

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 25 März 2014, 14:35:35
Zitat von: fhem-challenge am 25 März 2014, 13:20:12
Logging mit verbose=5 (global)

Gibts das auch mit OWX? Mit FRM 'alleine' gibt's ja (in den mitgeloggten 2 Minuten) keine Probleme. Schalte dazu ruhig mal ein größeres Sampling-interval an, dann wird das log nicht so geflutet.

Wenn Du am Sketch sonst nix geändert hast, sollte das in Ordnung sein.
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 25 März 2014, 15:07:50
ja, habe jetzt das sampling-interval auf 10000 gestellt.

Gleichzeitig lese ich noch einige AD Ports vom Arduino aus.

Das Logging sagt mir jetzt zugegeben nicht sehr viel. Es kommen mit dem o.g. sampling-interval regelmässige Logeinträge, dann ab "2014.03.25 14:58:42" für einen Zeitraum eine ganze reihe Events im Sekundentakt, danach wieder weniger.

2014.03.25 14:56:08 5: Cmd: >attr FIRMATA sampling-interval 10000<
2014.03.25 14:56:08 5: 14>f0,7a,10,4e,f7
2014.03.25 14:56:08 5: SW: ðzN÷
2014.03.25 14:56:09 5: Cmd: >{ReadingsVal("FIRMATA","reinit","")}<
2014.03.25 14:56:09 5: Cmd: >{AttrVal("FIRMATA","room","")}<
2014.03.25 14:56:11 5: Cmd: >save<
2014.03.25 14:56:11 5: Triggering global (1 changes)
2014.03.25 14:56:11 5: Notify loop for global SAVE
2014.03.25 14:56:18 5: 14<e0,26
2014.03.25 14:56:18 5: 14<02,e1,2c,02
2014.03.25 14:56:18 5: 14<e2,30,02,e3,26,02,e4
2014.03.25 14:56:18 5: 14<21,02,e5,1e,02,e6
2014.03.25 14:56:18 5: 14<23,02,e7,1e,02,e8,22,02
2014.03.25 14:56:28 5: 14<e0,23
2014.03.25 14:56:28 5: 14<02,e1,28,02
2014.03.25 14:56:28 5: 14<e2,2d,02,e3,24,02
2014.03.25 14:56:28 5: 14<e4,21,02,e5,21,02
2014.03.25 14:56:28 5: 14<e6,26,02,e7,21,02,e8
2014.03.25 14:56:28 5: 14<28,02
2014.03.25 14:56:31 5: Triggering sysstat (7 changes)
2014.03.25 14:56:31 5: Notify loop for sysstat 0.04 0.03 0.05
2014.03.25 14:56:38 5: 14<e0,26,02
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG reading: 294
2014.03.25 14:56:38 5: 14<e1,2c,02,e2,31,02,e3,27,02,e4,22,02
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG2 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG2 reading: 300
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG3 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG3 reading: 305
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG4 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG4 reading: 295
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG5 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG5 reading: 290
2014.03.25 14:56:38 5: 14<e5,20,02,e6,24,02,e7,1f,02,e8,23,02
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG6 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG6 reading: 288
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG7 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG7 reading: 292
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG8 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG8 reading: 287
2014.03.25 14:56:38 5: Triggering Firmata_ANALOG9 (1 changes)
2014.03.25 14:56:38 5: Notify loop for Firmata_ANALOG9 reading: 291
2014.03.25 14:56:48 5: 14<e0,23
2014.03.25 14:56:48 5: 14<02,e1,28,02
2014.03.25 14:56:48 5: 14<e2,2c,02,e3,23,02
2014.03.25 14:56:48 5: 14<e4,20,02,e5,1f,02
2014.03.25 14:56:48 5: 14<e6,24,02,e7,20,02,e8,27,02
2014.03.25 14:56:58 5: 14<e0,26,02
2014.03.25 14:56:58 5: 14<e1,2c,02,e2,31,02
2014.03.25 14:56:58 5: 14<e3,29,02,e4,26,02,e5,25
2014.03.25 14:56:58 5: 14<02,e6,28,02,e7,22,02
2014.03.25 14:56:58 5: 14<e8,27,02
2014.03.25 14:57:08 5: 14<e0,26
... snip


2014.03.25 14:58:12 5: OWX_Set request OW FF ?
2014.03.25 14:58:12 5: Cmd: >{ReadingsVal("OW","followAlarms","")}<
2014.03.25 14:58:12 5: Cmd: >{AttrVal("OW","room","")}<
2014.03.25 14:58:18 5: 14<e0,27
2014.03.25 14:58:18 5: 14<02,e1,2d,02
2014.03.25 14:58:18 5: 14<e2,31,02,e3,28,02
2014.03.25 14:58:18 5: 14<e4,23,02,e5,21,02
2014.03.25 14:58:18 5: 14<e6,25,02,e7,1f,02,e8,23,02
2014.03.25 14:58:28 5: 14<e0,23
2014.03.25 14:58:28 5: 14<02,e1,28,02
2014.03.25 14:58:28 5: 14<e2,2d,02,e3,24,02
2014.03.25 14:58:28 5: 14<e4,20,02,e5,20,02,e6,26,02
2014.03.25 14:58:28 5: 14<e7,21,02,e8,28,02
2014.03.25 14:58:31 5: Triggering sysstat (7 changes)
2014.03.25 14:58:31 5: Notify loop for sysstat 0.01 0.02 0.05
2014.03.25 14:58:31 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:31 5: SW: ðs-÷
2014.03.25 14:58:31 5: 14>f0,73,20,2d,4c,09,01,f7
2014.03.25 14:58:31 5: SW: ðs -L        ÷
2014.03.25 14:58:38 5: 14<e0,27,02
2014.03.25 14:58:38 5: 14<e1,2d,02,e2,31,02
2014.03.25 14:58:38 5: 14<e3,28,02,e4,23,02
2014.03.25 14:58:38 5: 14<e5,21,02,e6,25,02,e7,1f,02
2014.03.25 14:58:38 5: 14<e8,23,02
2014.03.25 14:58:41 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:41 5: SW: ðs-÷
2014.03.25 14:58:41 5: 14>f0,73,24,2d,28,2c,57,67,49,00,00,00,71,08,01,f7
2014.03.25 14:58:41 5: SW: ðs$-(,WgI÷
2014.03.25 14:58:41 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:41 5: SW: ðs-÷
2014.03.25 14:58:41 5: 14>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,10,01,40,2f,f7
2014.03.25 14:58:41 5: SW: ðs,-(,WgIq@/÷
2014.03.25 14:58:42 5: 14<f0
2014.03.25 14:58:42 5: 14<73,43,2d
2014.03.25 14:58:42 5: 14<12,00
2014.03.25 14:58:42 5: 14<50
2014.03.25 14:58:42 5: 14<09
2014.03.25 14:58:42 5: 14<30
2014.03.25 14:58:42 5: 14<49
2014.03.25 14:58:42 5: 14<51
2014.03.25 14:58:42 5: 14<3f
2014.03.25 14:58:42 5: 14<7f
2014.03.25 14:58:42 5: 14<19
2014.03.25 14:58:42 5: 14<40
2014.03.25 14:58:42 5: 14<60
2014.03.25 14:58:42 5: 14<01,f7
2014.03.25 14:58:42 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:42 5: SW: ðs-÷
2014.03.25 14:58:42 5: 14>f0,73,24,2d,28,2c,57,67,49,00,00,00,71,08,01,f7
2014.03.25 14:58:42 5: SW: ðs$-(,WgI÷
2014.03.25 14:58:42 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:42 5: SW: ðs-÷
2014.03.25 14:58:42 5: 14>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,18,01,40,2f,f7
2014.03.25 14:58:42 5: SW: ðs,-(,WgIq@/÷
2014.03.25 14:58:43 5: 14<f0
2014.03.25 14:58:43 5: 14<73,43,2d
2014.03.25 14:58:43 5: 14<13,00
2014.03.25 14:58:43 5: 14<50
2014.03.25 14:58:43 5: 14<09
2014.03.25 14:58:43 5: 14<30
2014.03.25 14:58:43 5: 14<49
2014.03.25 14:58:43 5: 14<51
2014.03.25 14:58:43 5: 14<3f
2014.03.25 14:58:43 5: 14<7f
2014.03.25 14:58:43 5: 14<19
2014.03.25 14:58:43 5: 14<40
2014.03.25 14:58:43 5: 14<60
2014.03.25 14:58:43 5: 14<01,f7
2014.03.25 14:58:43 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:43 5: SW: ðs-÷
2014.03.25 14:58:43 5: 14>f0,73,24,2d,28,2c,57,67,49,00,00,00,71,08,01,f7
2014.03.25 14:58:43 5: SW: ðs$-(,WgI÷
2014.03.25 14:58:43 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:43 5: SW: ðs-÷
2014.03.25 14:58:43 5: 14>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,20,01,40,2f,f7
2014.03.25 14:58:43 5: SW: ðs,-(,WgIq @/÷
2014.03.25 14:58:44 5: 14<f0
2014.03.25 14:58:44 5: 14<73,43,2d
2014.03.25 14:58:44 5: 14<14,00
2014.03.25 14:58:44 5: 14<50
2014.03.25 14:58:44 5: 14<09
2014.03.25 14:58:44 5: 14<30
2014.03.25 14:58:44 5: 14<49
2014.03.25 14:58:44 5: 14<51
2014.03.25 14:58:44 5: 14<3f
2014.03.25 14:58:44 5: 14<7f
2014.03.25 14:58:44 5: 14<19
2014.03.25 14:58:44 5: 14<40
2014.03.25 14:58:44 5: 14<60
2014.03.25 14:58:44 5: 14<01,f7
2014.03.25 14:58:44 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:44 5: SW: ðs-÷
2014.03.25 14:58:44 5: 14>f0,73,24,2d,28,5e,7b,40,45,00,00,00,17,09,01,f7
2014.03.25 14:58:44 5: SW: ðs$-(^{@E    ÷
2014.03.25 14:58:44 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:44 5: SW: ðs-÷
2014.03.25 14:58:44 5: 14>f0,73,2c,2d,28,5e,7b,40,45,00,00,00,17,13,00,28,01,40,2f,f7
2014.03.25 14:58:44 5: SW: ðs,-(^{@E(@/÷
2014.03.25 14:58:45 5: 14<f0
2014.03.25 14:58:45 5: 14<73,43,2d
2014.03.25 14:58:45 5: 14<15,00
2014.03.25 14:58:45 5: 14<4c
2014.03.25 14:58:45 5: 14<09
2014.03.25 14:58:45 5: 14<30
2014.03.25 14:58:45 5: 14<49
2014.03.25 14:58:45 5: 14<51
2014.03.25 14:58:45 5: 14<3f
2014.03.25 14:58:45 5: 14<7f
2014.03.25 14:58:45 5: 14<1b
2014.03.25 14:58:45 5: 14<40
2014.03.25 14:58:45 5: 14<40
2014.03.25 14:58:45 5: 14<00,f7
2014.03.25 14:58:45 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:45 5: SW: ðs-÷
2014.03.25 14:58:45 5: 14>f0,73,24,2d,28,5e,7b,40,45,00,00,00,17,09,01,f7
2014.03.25 14:58:45 5: SW: ðs$-(^{@E    ÷
2014.03.25 14:58:45 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:45 5: SW: ðs-÷
2014.03.25 14:58:45 5: 14>f0,73,2c,2d,28,5e,7b,40,45,00,00,00,17,13,00,30,01,40,2f,f7
2014.03.25 14:58:45 5: SW: ðs,-(^{@E0@/÷
2014.03.25 14:58:46 5: 14<f0
2014.03.25 14:58:46 5: 14<73,43,2d,16
2014.03.25 14:58:46 5: 14<00
2014.03.25 14:58:46 5: 14<4c
2014.03.25 14:58:46 5: 14<09
2014.03.25 14:58:46 5: 14<30
2014.03.25 14:58:46 5: 14<49
2014.03.25 14:58:46 5: 14<51
2014.03.25 14:58:46 5: 14<3f
2014.03.25 14:58:46 5: 14<7f
2014.03.25 14:58:46 5: 14<1b
2014.03.25 14:58:46 5: 14<40
2014.03.25 14:58:46 5: 14<40
2014.03.25 14:58:46 5: 14<00,f7
2014.03.25 14:58:46 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:46 5: SW: ðs-÷
2014.03.25 14:58:46 5: 14>f0,73,24,2d,28,5e,7b,40,45,00,00,00,17,09,01,f7
2014.03.25 14:58:46 5: SW: ðs$-(^{@E    ÷
2014.03.25 14:58:46 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:46 5: SW: ðs-÷
2014.03.25 14:58:46 5: 14>f0,73,2c,2d,28,5e,7b,40,45,00,00,00,17,13,00,38,01,40,2f,f7
2014.03.25 14:58:46 5: SW: ðs,-(^{@E8@/÷
2014.03.25 14:58:47 5: 14<f0
2014.03.25 14:58:47 5: 14<73,43
2014.03.25 14:58:47 5: 14<2d,17,00
2014.03.25 14:58:47 5: 14<4c
2014.03.25 14:58:47 5: 14<09
2014.03.25 14:58:47 5: 14<30
2014.03.25 14:58:47 5: 14<49
2014.03.25 14:58:47 5: 14<51
2014.03.25 14:58:47 5: 14<3f
2014.03.25 14:58:47 5: 14<7f
2014.03.25 14:58:47 5: 14<1b
2014.03.25 14:58:47 5: 14<40
2014.03.25 14:58:47 5: 14<40
2014.03.25 14:58:47 5: 14<00,f7
2014.03.25 14:58:47 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:47 5: SW: ðs-÷
2014.03.25 14:58:47 5: 14>f0,73,24,2d,28,54,1c,3b,45,00,00,00,67,09,01,f7
2014.03.25 14:58:47 5: SW: ðs$-(T;Eg    ÷
2014.03.25 14:58:48 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:48 5: SW: ðs-÷
2014.03.25 14:58:48 5: 14>f0,73,2c,2d,28,54,1c,3b,45,00,00,00,67,13,00,40,01,40,2f,f7
2014.03.25 14:58:48 5: SW: ðs,-(T;Eg@@/÷
2014.03.25 14:58:48 5: 14<f0
2014.03.25 14:58:48 5: 14<73,43,2d
2014.03.25 14:58:48 5: 14<18,00
2014.03.25 14:58:48 5: 14<58
2014.03.25 14:58:48 5: 14<09
2014.03.25 14:58:48 5: 14<30
2014.03.25 14:58:48 5: 14<49
2014.03.25 14:58:48 5: 14<51
2014.03.25 14:58:48 5: 14<3f
2014.03.25 14:58:48 5: 14<7f
2014.03.25 14:58:48 5: 14<15
2014.03.25 14:58:48 5: 14<40
2014.03.25 14:58:48 5: 14<00
2014.03.25 14:58:48 5: 14<03,f7
2014.03.25 14:58:48 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:48 5: SW: ðs-÷
2014.03.25 14:58:48 5: 14>f0,73,24,2d,28,54,1c,3b,45,00,00,00,67,09,01,f7
2014.03.25 14:58:48 5: SW: ðs$-(T;Eg    ÷
2014.03.25 14:58:49 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:49 5: SW: ðs-÷
2014.03.25 14:58:49 5: 14>f0,73,2c,2d,28,54,1c,3b,45,00,00,00,67,13,00,48,01,40,2f,f7
2014.03.25 14:58:49 5: SW: ðs,-(T;EgH@/÷
2014.03.25 14:58:49 5: 14<e0,23,02,e1,29,02,e2,2f,02,e3,26,02,e4,23,02,e5,23,02,e6,28,02,e7,23,02,e8,29,02
2014.03.25 14:58:49 5: 14<f0
2014.03.25 14:58:49 5: 14<73,43,2d
2014.03.25 14:58:49 5: 14<19,00
2014.03.25 14:58:49 5: 14<58
2014.03.25 14:58:49 5: 14<09
2014.03.25 14:58:49 5: 14<30
2014.03.25 14:58:49 5: 14<49
2014.03.25 14:58:49 5: 14<51
2014.03.25 14:58:49 5: 14<3f
2014.03.25 14:58:49 5: 14<7f
2014.03.25 14:58:49 5: 14<15
2014.03.25 14:58:49 5: 14<40
2014.03.25 14:58:49 5: 14<00
2014.03.25 14:58:49 5: 14<03,f7
2014.03.25 14:58:49 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:49 5: SW: ðs-÷
2014.03.25 14:58:49 5: 14>f0,73,24,2d,28,54,1c,3b,45,00,00,00,67,09,01,f7
2014.03.25 14:58:49 5: SW: ðs$-(T;Eg    ÷
2014.03.25 14:58:50 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:50 5: SW: ðs-÷
2014.03.25 14:58:50 5: 14>f0,73,2c,2d,28,54,1c,3b,45,00,00,00,67,13,00,50,01,40,2f,f7
2014.03.25 14:58:50 5: SW: ðs,-(T;EgP@/÷
2014.03.25 14:58:50 5: 14<f0
2014.03.25 14:58:50 5: 14<73,43,2d
2014.03.25 14:58:50 5: 14<1a,00
2014.03.25 14:58:50 5: 14<5c
2014.03.25 14:58:50 5: 14<09
2014.03.25 14:58:50 5: 14<30
2014.03.25 14:58:50 5: 14<49
2014.03.25 14:58:50 5: 14<51
2014.03.25 14:58:50 5: 14<3f
2014.03.25 14:58:50 5: 14<7f
2014.03.25 14:58:50 5: 14<13
2014.03.25 14:58:50 5: 14<40
2014.03.25 14:58:50 5: 14<30
2014.03.25 14:58:50 5: 14<02,f7
2014.03.25 14:58:51 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:51 5: SW: ðs-÷
2014.03.25 14:58:51 5: 14>f0,73,24,2d,28,24,08,6f,49,00,00,00,10,09,01,f7
2014.03.25 14:58:51 5: SW: ðs$-(oI      ÷
2014.03.25 14:58:51 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:51 5: SW: ðs-÷
2014.03.25 14:58:51 5: 14>f0,73,2c,2d,28,24,08,6f,49,00,00,00,10,13,00,58,01,40,2f,f7
2014.03.25 14:58:51 5: SW: ðs,-(oIX@/÷
2014.03.25 14:58:52 5: 14<f0
2014.03.25 14:58:52 5: 14<73,43,2d
2014.03.25 14:58:52 5: 14<1b,00
2014.03.25 14:58:52 5: 14<54
2014.03.25 14:58:52 5: 14<09
2014.03.25 14:58:52 5: 14<30
2014.03.25 14:58:52 5: 14<49
2014.03.25 14:58:52 5: 14<51
2014.03.25 14:58:52 5: 14<3f
2014.03.25 14:58:52 5: 14<7f
2014.03.25 14:58:52 5: 14<17
2014.03.25 14:58:52 5: 14<40
2014.03.25 14:58:52 5: 14<08
2014.03.25 14:58:52 5: 14<03,f7
2014.03.25 14:58:52 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:52 5: SW: ðs-÷
2014.03.25 14:58:52 5: 14>f0,73,24,2d,28,24,08,6f,49,00,00,00,10,09,01,f7
2014.03.25 14:58:52 5: SW: ðs$-(oI      ÷
2014.03.25 14:58:52 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:52 5: SW: ðs-÷
2014.03.25 14:58:52 5: 14>f0,73,2c,2d,28,24,08,6f,49,00,00,00,10,13,00,60,01,40,2f,f7
2014.03.25 14:58:52 5: SW: ðs,-(oI`@/÷
2014.03.25 14:58:53 5: 14<f0
2014.03.25 14:58:53 5: 14<73,43,2d
2014.03.25 14:58:53 5: 14<1c,00
2014.03.25 14:58:53 5: 14<54
2014.03.25 14:58:53 5: 14<09
2014.03.25 14:58:53 5: 14<30
2014.03.25 14:58:53 5: 14<49
2014.03.25 14:58:53 5: 14<51
2014.03.25 14:58:53 5: 14<3f
2014.03.25 14:58:53 5: 14<7f
2014.03.25 14:58:53 5: 14<17
2014.03.25 14:58:53 5: 14<40
2014.03.25 14:58:53 5: 14<08
2014.03.25 14:58:53 5: 14<03,f7
2014.03.25 14:58:53 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:53 5: SW: ðs-÷
2014.03.25 14:58:53 5: 14>f0,73,24,2d,28,24,08,6f,49,00,00,00,10,09,01,f7
2014.03.25 14:58:53 5: SW: ðs$-(oI      ÷
2014.03.25 14:58:53 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:53 5: SW: ðs-÷
2014.03.25 14:58:53 5: 14>f0,73,2c,2d,28,24,08,6f,49,00,00,00,10,13,00,68,01,40,2f,f7
2014.03.25 14:58:53 5: SW: ðs,-(oIh@/÷
2014.03.25 14:58:54 5: 14<f0
2014.03.25 14:58:54 5: 14<73,43,2d,1d
2014.03.25 14:58:54 5: 14<00
2014.03.25 14:58:54 5: 14<54
2014.03.25 14:58:54 5: 14<09
2014.03.25 14:58:54 5: 14<30
2014.03.25 14:58:54 5: 14<49
2014.03.25 14:58:54 5: 14<51
2014.03.25 14:58:54 5: 14<3f
2014.03.25 14:58:54 5: 14<7f
2014.03.25 14:58:54 5: 14<17
2014.03.25 14:58:54 5: 14<40
2014.03.25 14:58:54 5: 14<08
2014.03.25 14:58:54 5: 14<03,f7
2014.03.25 14:58:54 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:54 5: SW: ðs-÷
2014.03.25 14:58:54 5: 14>f0,73,24,2d,28,34,30,3e,45,00,00,00,49,08,01,f7
2014.03.25 14:58:54 5: SW: ðs$-(40>E÷
2014.03.25 14:58:54 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:54 5: SW: ðs-÷
2014.03.25 14:58:54 5: 14>f0,73,2c,2d,28,34,30,3e,45,00,00,00,49,12,00,70,01,40,2f,f7
2014.03.25 14:58:54 5: SW: ðs,-(40>EIp@/÷
2014.03.25 14:58:55 5: 14<f0
2014.03.25 14:58:55 5: 14<73,43,2d,1e
2014.03.25 14:58:55 5: 14<00
2014.03.25 14:58:55 5: 14<5c
2014.03.25 14:58:55 5: 14<09
2014.03.25 14:58:55 5: 14<30
2014.03.25 14:58:55 5: 14<49
2014.03.25 14:58:55 5: 14<51
2014.03.25 14:58:55 5: 14<3f
2014.03.25 14:58:55 5: 14<7f
2014.03.25 14:58:55 5: 14<13
2014.03.25 14:58:55 5: 14<40
2014.03.25 14:58:55 5: 14<30
2014.03.25 14:58:55 5: 14<02,f7
2014.03.25 14:58:55 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:55 5: SW: ðs-÷
2014.03.25 14:58:55 5: 14>f0,73,24,2d,28,34,30,3e,45,00,00,00,49,08,01,f7
2014.03.25 14:58:55 5: SW: ðs$-(40>E÷
2014.03.25 14:58:55 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:55 5: SW: ðs-÷
2014.03.25 14:58:55 5: 14>f0,73,2c,2d,28,34,30,3e,45,00,00,00,49,12,00,78,01,40,2f,f7
2014.03.25 14:58:55 5: SW: ðs,-(40>EIx@/÷
2014.03.25 14:58:56 5: 14<f0
2014.03.25 14:58:56 5: 14<73,43,2d,1f
2014.03.25 14:58:56 5: 14<00
2014.03.25 14:58:56 5: 14<5c
2014.03.25 14:58:56 5: 14<09
2014.03.25 14:58:56 5: 14<30
2014.03.25 14:58:56 5: 14<49
2014.03.25 14:58:56 5: 14<51
2014.03.25 14:58:56 5: 14<3f
2014.03.25 14:58:56 5: 14<7f
2014.03.25 14:58:56 5: 14<13
2014.03.25 14:58:56 5: 14<40
2014.03.25 14:58:56 5: 14<30
2014.03.25 14:58:56 5: 14<02,f7
2014.03.25 14:58:56 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:56 5: SW: ðs-÷
2014.03.25 14:58:56 5: 14>f0,73,24,2d,28,34,30,3e,45,00,00,00,49,08,01,f7
2014.03.25 14:58:56 5: SW: ðs$-(40>E÷
2014.03.25 14:58:56 5: 14>f0,73,01,2d,f7
2014.03.25 14:58:56 5: SW: ðs-÷
2014.03.25 14:58:56 5: 14>f0,73,2c,2d,28,34,30,3e,45,00,00,00,49,12,00,00,02,40,2f,f7
2014.03.25 14:58:56 5: SW: ðs,-(40>EI@/÷
2014.03.25 14:58:57 5: 14<f0
2014.03.25 14:58:57 5: 14<73,43
2014.03.25 14:58:57 5: 14<2d,20
2014.03.25 14:58:57 5: 14<00
2014.03.25 14:58:57 5: 14<5c
2014.03.25 14:58:57 5: 14<09
2014.03.25 14:58:57 5: 14<30
2014.03.25 14:58:57 5: 14<49
2014.03.25 14:58:57 5: 14<51
2014.03.25 14:58:57 5: 14<3f
2014.03.25 14:58:57 5: 14<7f
2014.03.25 14:58:57 5: 14<13
2014.03.25 14:58:57 5: 14<40
2014.03.25 14:58:57 5: 14<30
2014.03.25 14:58:57 5: 14<02,f7
2014.03.25 14:58:58 5: 14<e0,27
2014.03.25 14:58:58 5: 14<02,e1,2d,02,e2
2014.03.25 14:58:58 5: 14<31,02,e3,28,02,e4,23,02
2014.03.25 14:58:58 5: 14<e5,21,02,e6,25,02,e7,1f
2014.03.25 14:58:58 5: 14<02,e8,23,02


Gruss

Andreas
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 25 März 2014, 15:19:20
die Einträge mit f0,f3...f7 sind die 1-Wire-commandos (mit > das was FRM an den Arduino schickt, mit < das was zurückkommt). Das Format ist hier beschrieben (http://www.firmata.org/wiki/Proposals#OneWire_Proposal).

Aber lass das doch mal länger laufen. Du hast doch bemängelt, dass bei größerem sampling-interval die OWTHERM-werte nicht mehr regelmäßig geupdatet werden. Das kann man (bei einem OWTHERM-Interval von 300 Sekunden) natürlich nicht sehen, wenn das Log grade mal kappe 3 Minuten insgesammt lang ist.

Gruß,

Norbert
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 25 März 2014, 15:50:15
... anbei ein längeres Logfile incl. Start von FHEM und Initialisierung.

Ab 2014.03.25 15:27:51 ... gehts dann mit "Firmata" los.

Warum aber das Logging direkt via verbose am FRM nicht geht ... ist mir ein Rätsel.


Gruss

Andreas

Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 25 März 2014, 17:20:43

2014.03.25 15:27:58 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,06,00,00,00,40,53,25,46,7e,01,f7
2014.03.25 15:27:59 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,08,00,40,2f,f7
2014.03.25 15:28:00 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,10,00,40,2f,f7
2014.03.25 15:28:01 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,18,00,40,2f,f7
2014.03.25 15:32:58 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,20,01,40,2f,f7
2014.03.25 15:32:59 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,28,01,40,2f,f7
2014.03.25 15:33:00 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,30,01,40,2f,f7
2014.03.25 15:37:58 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,18,02,40,2f,f7
2014.03.25 15:37:59 5: 13>f0,73,2c,2d,28,2c,57,67,49,00,00,00,71,12,00,20,02,40,2f,f7


hier sind die Kommandos von 3 Temperaturmessungen im 5-Minuten-abstand drin, die dazugehörigen Antworten der Firmata sind auch alle im Log. Nur 2 der Messungen führen auch zu einem Event:

2014.03.25 15:27:58 5: Triggering temp_D6F59C040000 (1 changes)
2014.03.25 15:38:00 5: Triggering temp_D6F59C040000 (2 changes)

Der Event dazwischen ist ausgelassen (daher auch '2 changes' beim zweiten, da steckt der ausgelassene mit drin).

hast Du event-min-interval gesetzt?

Aus der Ecke kommt das Verhalten jedenfalls her.
Das erklärt vieleicht auch einen indirekten Zusammenhang mit dem sampling-interval (auch wenn es nicht logisch ist) - kleines Sampling-interval -> viele Events, großes sampling-interval -> wenige Events.
Bin grad am schauen, ob da in der letzten Zeit irgendeine Änderung in die fhem.pl reingekommen ist
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: Prof. Dr. Peter Henning am 25 März 2014, 19:29:08
Das ist auch mein Verdacht - irgendetwas wurde da gemacht. Das sollte aber immer noch nicht für die Abbrüche verantwortlich sein.

LG

pah
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: ntruchsess am 25 März 2014, 22:18:10
also die fhem.pl-änderungen der letzen 4 Wochen bin ich mal im Detail durchgegangen. Da kanns eigentlich nicht dran liegen, das ist bis auf die IODev-geschichte alles eher harmlos (und wenn das IODev nicht stimmt, dann gibt's definitiv andere Fehlermeldungen). Was läuft denn alles an eigenenm perl-code (99_Utils.pm, notifies etc...)?

Gruß,

Norbert
Titel: Antw:1-Wire, OWX/OWTHERM persistierende Timingprobleme
Beitrag von: fhem-challenge am 26 März 2014, 09:29:36
Danke schon einmal!

event-min-interval habe ich am FRM nicht gesetzt. In der "99_Utils.pm" habe ich nur eine eigene Funktion (spezielles eMail-versenden, SMS send) drin. Aber auch in einer reinen Testumgebung (ohne eigene Erweiterungen in 99_Utils.pm), und nur bei Nutzung von FRM,OWX,OWTHERM tritt diese Effekt auf. Ich habe die o.g. Log's auch mit einer Minimalconfig erzeugt. Sämtliche anderen Module waren nicht geladen. Natürlich war auch das IODev gesetzt.

Anbei die momentane Test-Config.


define FIRMATA FRM 3030 global
attr FIRMATA room OWX
attr FIRMATA sampling-interval 2000
attr FIRMATA verbose 5
#
define OW OWX 45
attr OW DbLogExclude .*
attr OW IODev FIRMATA
attr OW buspower real
attr OW loglevel 6
attr OW room OWX
attr OW verbose 5

# test IO Port
define Firmata_OUT FRM_OUT 40
attr Firmata_OUT DbLogExclude .*
attr Firmata_OUT IODev FIRMATA
attr Firmata_OUT room OWX
attr Firmata_OUT stateFormat value

# test IO Port
# Port 41 als Eingang schalten
define Firmata_IN FRM_IN 41
attr Firmata_IN DbLogExclude .*
attr Firmata_IN IODev FIRMATA
attr Firmata_IN room OWX
attr Firmata_IN stateFormat reading

# Analogeingang
define Firmata_ANALOG FRM_AD 54
attr Firmata_ANALOG DbLogExclude .*
attr Firmata_ANALOG IODev FIRMATA
attr Firmata_ANALOG event-min-interval .*:180
attr Firmata_ANALOG room OWX
attr Firmata_ANALOG stateFormat reading

###########################
### Temperatur-Sensoren DS18B20 ###
###########################
define temp_12E29D040000 OWTHERM DS18B20 12E29D040000
attr temp_12E29D040000 DbLogExclude .*
attr temp_12E29D040000 IODev OW
attr temp_12E29D040000 model DS18B20
attr temp_12E29D040000 room OWX
attr temp_12E29D040000 tempHigh 75
attr temp_12E29D040000 tempLow 70
attr temp_12E29D040000 verbose 5
#
define temp_1ACC57040000 OWTHERM DS18B20 1ACC57040000
attr temp_1ACC57040000 DbLogExclude .*
attr temp_1ACC57040000 IODev OW
attr temp_1ACC57040000 model DS18B20
attr temp_1ACC57040000 room OWX
attr temp_1ACC57040000 tempHigh 75
attr temp_1ACC57040000 tempLow 70
#
define temp_D6F59C040000 OWTHERM DS18B20 D6F59C040000
attr temp_D6F59C040000 DbLogExclude .*
attr temp_D6F59C040000 IODev OW
attr temp_D6F59C040000 model DS18B20
attr temp_D6F59C040000 room OWX
attr temp_D6F59C040000 tempConv onread
attr temp_D6F59C040000 tempHigh 75
attr temp_D6F59C040000 tempLow 70
#
define temp_EF1E58040000 OWTHERM DS18B20 EF1E58040000
attr temp_EF1E58040000 DbLogExclude .*
attr temp_EF1E58040000 IODev OW
attr temp_EF1E58040000 model DS18B20
attr temp_EF1E58040000 room OWX
attr temp_EF1E58040000 tempHigh 75
attr temp_EF1E58040000 tempLow 70
#
define temp_2A6757040000 OWTHERM DS18B20 2A6757040000
attr temp_2A6757040000 DbLogExclude .*
attr temp_2A6757040000 IODev OW
attr temp_2A6757040000 model DS18B20
attr temp_2A6757040000 room OWX
attr temp_2A6757040000 tempHigh 75
attr temp_2A6757040000 tempLow 70

###
define FileLog_EF1E58040000 FileLog /var/log/fhem/ow_EF1E58040000.%Y.log temp_EF1E58040000:T:.*
attr FileLog_EF1E58040000 logtype temp4:Temp,text
attr FileLog_EF1E58040000 room OWX
###
define FileLog_2A6757040000 FileLog /var/log/fhem/ow_2A6757040000.%Y.log temp_2A6757040000:T:.*
attr FileLog_2A6757040000 logtype temp4:Temp,text
attr FileLog_2A6757040000 room OWX
###
define FileLog_1ACC57040000 FileLog /var/log/fhem/ow_1ACC57040000.%Y.log temp_1ACC57040000:T:.*
attr FileLog_1ACC57040000 logtype temp4:Temp,text
attr FileLog_1ACC57040000 room OWX
###
define FileLog_12E29D040000 FileLog /var/log/fhem/ow_12E29D040000.%Y.log temp_12E29D040000:T:.*
attr FileLog_12E29D040000 logtype temp4:Temp,text
attr FileLog_12E29D040000 room OWX
###
define FileLog_D6F59C040000 FileLog /var/log/fhem/ow_D6F59C040000.%Y.log temp_D6F59C040000:T:.*
attr FileLog_D6F59C040000 logtype temp4:Temp,text
attr FileLog_D6F59C040000 room OWX
###

### Plots
define SVG_0 SVG FileLog_D6F59C040000:temp4:CURRENT
attr SVG_0 room OWX
define SVG_1 SVG FileLog_12E29D040000:temp4:CURRENT
attr SVG_1 room OWX
define SVG_2 SVG FileLog_2A6757040000:temp4:CURRENT
attr SVG_2 room OWX
define SVG_3 SVG FileLog_D6F59C040000:temp4:CURRENT
attr SVG_3 room OWX
define SVG_4 SVG FileLog_EF1E58040000:temp4:CURRENT
attr SVG_4 room OWX


Ansonsten kam heute (nur einmal) ein Fehler:

Can't call method "packet_onewire_request" on an undefined value at /usr/share/fhem/FHEM/lib/Device/Firmata/Platform.pm line 781.

... aber das auf meinen Rpi und nicht auf meinem laptop. Auf dem Rpi läuft ja die 1-wire Umgebung korrekt.


Gruss

Andreas