[gelöst] Pairen nicht mehr möglich / waiting for data / set desired temp

[jeti]

Hallo zusammen,
kurze Zusammenfassung bisher:
ich habe einen geflashten cube als CUL_MAX eingebunden und damit erfolgreich 3 Thermostate gesteuert.
Dann habe ich auf 6 Thermostate aufgestockt, diese konnte ich alle pairen, aber eines nicht auslesen bzw. steuern. Da ich auf nur einem Stockwerk wohne und es maximal eine trockenbauwand zwischen Cube und Thermostat. Nach ein paar weiteren reset und erneuten pairen der Thermostat ging dann gar nichts mehr (software keine Änderung des CUL oder CUL_MAX) also habe ich den cube auf V 1.20.04 a-culfw Build: 180 geflasht, was aber keine Änderung gebracht hat. Also alles in FHEM gelöscht und genau nach wiki Eintrag neu angelegt (siehe auch Anhang). -> Ich kann keines der Thermostat mit dem Cube verbinden...
In welcher Richtung könnte das Problem liegen?

[Rince]

Ich tippe auf fehlende Credits.

Die Lösung hätte daher darin bestanden, einfach 1-2 Stunden nix zu tun   

[jeti]

Auf die Gefahr hin mich in die Nesseln zu setzten es geht um Erfahrungscredits oder wirklich etwas im System.

Ich habe sich das System über Nacht "erholen" lassen und erst nach den erfolglosen Versuchen hier gepostet 

jetzt konnte ich doch wieder vorerst 2 der Thermostate pairen  ich sehe noch keine Temperature readings und kann diese nicht einstellen, aber schonmal etwas, dann lasse ich das ganze nochmal über nacht ruhen, obwohl, wenn ich mich nicht irre, das vorher ohne Verzögerung geklappt hat...

[jeti]

Letzer Status:

Ich habe nun drei Thermostate Verbunden, zwei haben den state "waiting for data" und sind nicht ansprechbar
und das dritte zeigt zwar die Temperature an ist aber auch nicht ansteuerbar...
es liegt also nicht am pairen (das funktioniert mit langer Zeit Verzögerung, sondern an der Kommunikation, oder?

so habe mal den log auf verbose 5 gestellt und das kommt heraus wenn ich die Temperatur einstellen will:

2016-02-12 09:59:23 MAX MAX_074731 desiredTemperature 10.0
2016.02.12 09:59:23 4 : WEB_192.168.1.127_34531 GET /fhem?detail=MAX_074731&fw_id=; BUFLEN:0
2016.02.12 09:59:23 4 : name: /fhem?detail=MAX_074731&fw_id= / RL:4091 / text/html; charset=UTF-8 / Content-Encoding: gzip /
2016.02.12 09:59:23 4 : Connection closed for WEB_192.168.1.127_34533: EOF
2016.02.12 09:59:23 4 : WEB_192.168.1.127_34531 GET /fhem?cmd={ReadingsVal(%22MAX_074731%22,%22associate%22,%22%22)}&XHR=1; BUFLEN:0
2016.02.12 09:59:23 5 : Cmd: >{ReadingsVal("MAX_074731","associate","")}<
2016.02.12 09:59:23 4 : name: /fhem?cmd={ReadingsVal(%22MAX_074731%22,%22associate%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip /
2016.02.12 09:59:23 4 : Connection accepted from WEB_192.168.1.127_34534
2016.02.12 09:59:23 4 : WEB_192.168.1.127_34534 GET /fhem?cmd={AttrVal(%22MAX_074731%22,%22room%22,%22%22)}&XHR=1; BUFLEN:0
2016.02.12 09:59:23 5 : Cmd: >{AttrVal("MAX_074731","room","")}<
2016.02.12 09:59:23 4 : name: /fhem?cmd={AttrVal(%22MAX_074731%22,%22room%22,%22%22)}&XHR=1 / RL:28 / text/plain; charset=UTF-8 / Content-Encoding: gzip /
2016.02.12 09:59:23 4 : WEB_192.168.1.127_34534 GET /fhem?XHR=1&inform=type=status;filter=MAX_074731;since=1455267562;fmt=JSON&fw_id=345&timestamp=1455267563800; BUFLEN:0
2016.02.12 09:59:32 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:32 5 : SW: X
2016.02.12 09:59:32 5 : CUL/RAW (ReadAnswer): 00 110
2016.02.12 09:59:32 5 : needPreamble: 1, necessaryCredit: 110, credit10ms: 110
2016.02.12 09:59:32 5 : CUL_MAX sending Zs0b0a00401234560670360051
2016.02.12 09:59:32 5 : SW: Zs0b0a00401234560670360051
2016.02.12 09:59:33 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:33 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:34 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:35 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:35 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:36 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:36 5 : CUL_MAX_SendQueueHandler: Retry 067036 for 0b0a00401234560670360051 count: 1
2016.02.12 09:59:40 5 : PIONEERAVR pioneer: PIONEERAVR_checkConnection() --- state: disconnected
2016.02.12 09:59:40 2 : Attempt to write to disconnected device.
2016.02.12 09:59:40 5 : PIONEERAVR pioneer: PIONEERAVR_checkConnection() --- state after DevIo_Expect(): disconnected, previous state: disconnected, reply from DevIo_Expect: "<nothing>"
2016.02.12 09:59:40 5 : PIONEERAVR pioneer: PIONEERAVR_checkConnection() --- no reply after DevIo_Expect()-> reopen()
2016.02.12 09:59:40 5 : PIONEERAVR pioneer: PIONEERAVR_Reopen()
2016.02.12 09:59:43 5 : PIONEERAVR pioneer: PIONEERAVR_checkConnection() --- state after PIONEERAVR_Reopen(): disconnected, previous state: disconnected
2016.02.12 09:59:43 5 : PIONEERAVR pioneer: PIONEERAVR_checkConnection(): set internaltimer(120s)
2016.02.12 09:59:43 5 : CUL_MAX_SendQueueHandler: 14 items in queue
2016.02.12 09:59:43 5 : SW: X
2016.02.12 09:59:43 5 : CUL/RAW (ReadAnswer): 00 11
2016.02.12 09:59:43 5 : needPreamble: 1, necessaryCredit: 110, credit10ms: 11
2016.02.12 09:59:43 2 : CUL_MAX_SendQueueHandler: Not enough credit! credit10ms is 11, but we need 110. Waiting 99 seco
nds.

bin über jeden Tip Dankbar

Gruß

[Rince]

Steht öfter in deinem Log:

2016.02.12 09:59:43 5 : needPreamble: 1, necessaryCredit: 110, credit10ms: 11
2016.02.12 09:59:43 2 : CUL_MAX_SendQueueHandler: Not enough credit! credit10ms is 11, but we need 110. Waiting 99 seco

Ok. Bevor du jetzt tagelang löscht und neu anlegst, mach doch mal von den Teilen die nicht gehen ein list.

Einfach oben in fhem eingeben:
list <Gerätename>

Das Resultat dann hiet posten. Dann wissen wir, ob das Gerät richtig gepaired ist

[jeti]

ok erstes Thermostat:

Internals:
   DEF        HeatingThermostat 074731
   IODev      cm
   NAME       MAX_074731
   NR         244
   STATE      waiting for data
   TYPE       MAX
   addr       074731
   type       HeatingThermostat
   Readings:
     2016-02-11 20:39:19   RSSI            -54
     2016-02-11 21:22:45   TimeInformationHour 1
     2016-02-11 20:39:19   boostDuration   25
     2016-02-11 20:39:19   boostValveposition 80
     2016-02-11 20:39:19   comfortTemperature 21.0
     2016-02-11 20:39:19   decalcification Sat 12:00
     2016-02-11 20:39:19   ecoTemperature  17.0
     2016-02-11 20:39:18   firmware        1.8
     2016-02-11 20:39:18   groupid         0
     2016-02-11 20:39:19   maxValveSetting 100
     2016-02-11 20:39:19   maximumTemperature on
     2016-02-11 20:39:19   measurementOffset 0.0
     2016-02-11 20:39:19   minimumTemperature off
     2016-02-12 14:23:24   msgcnt          14
     2016-02-11 20:39:19   state           waiting for data
     2016-02-11 20:39:18   testresult      255
     2016-02-11 20:39:19   valveOffset     0
     2016-02-11 20:39:19   weekprofile-0-Sat-temp 17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-0-Sat-time 00:00-06:00  /  06:00-22:00  /  22:00-00:00
     2016-02-11 20:39:19   weekprofile-1-Sun-temp 17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-1-Sun-time 00:00-06:00  /  06:00-22:00  /  22:00-00:00
     2016-02-11 20:39:19   weekprofile-2-Mon-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-2-Mon-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:39:19   weekprofile-3-Tue-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-3-Tue-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:39:19   weekprofile-4-Wed-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-4-Wed-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:39:19   weekprofile-5-Thu-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-5-Thu-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:39:19   weekprofile-6-Fri-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:39:19   weekprofile-6-Fri-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:39:19   windowOpenDuration 15
     2016-02-11 20:39:19   windowOpenTemperature 12.0
   Internals:
     interfaces thermostat;battery;temperature
Attributes:
   IODev      cm
   alias      Büro NW
   room       Heizung

zweites:

Internals:
   DEF        HeatingThermostat 0ca687
   IODev      cm
   NAME       MAX_0ca687
   NR         242
   STATE      waiting for data
   TYPE       MAX
   addr       0ca687
   type       HeatingThermostat
   Readings:
     2016-02-11 20:33:20   RSSI            -43.5
     2016-02-11 21:22:45   TimeInformationHour 0
     2016-02-11 20:33:20   boostDuration   25
     2016-02-11 20:33:20   boostValveposition 80
     2016-02-11 20:33:20   comfortTemperature 21.0
     2016-02-11 20:33:20   decalcification Sat 12:00
     2016-02-11 20:33:20   ecoTemperature  17.0
     2016-02-11 20:33:20   firmware        1.0
     2016-02-11 20:33:20   groupid         0
     2016-02-11 20:33:20   maxValveSetting 100
     2016-02-11 20:33:20   maximumTemperature on
     2016-02-11 20:33:20   measurementOffset 0.0
     2016-02-11 20:33:20   minimumTemperature off
     2016-02-12 19:23:24   msgcnt          14
     2016-02-11 20:33:20   state           waiting for data
     2016-02-11 20:33:20   testresult      160
     2016-02-11 20:33:20   valveOffset     0
     2016-02-11 20:33:20   weekprofile-0-Sat-temp 17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-0-Sat-time 00:00-06:00  /  06:00-22:00  /  22:00-00:00
     2016-02-11 20:33:20   weekprofile-1-Sun-temp 17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-1-Sun-time 00:00-06:00  /  06:00-22:00  /  22:00-00:00
     2016-02-11 20:33:20   weekprofile-2-Mon-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-2-Mon-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:33:20   weekprofile-3-Tue-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-3-Tue-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:33:20   weekprofile-4-Wed-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-4-Wed-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:33:20   weekprofile-5-Thu-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-5-Thu-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:33:20   weekprofile-6-Fri-temp 17.0 °C  /  21.0 °C  /  17.0 °C  /  21.0 °C  /  17.0 °C
     2016-02-11 20:33:20   weekprofile-6-Fri-time 00:00-06:00  /  06:00-09:00  /  09:00-17:00  /  17:00-23:00  /  23:00-00:00
     2016-02-11 20:33:20   windowOpenDuration 15
     2016-02-11 20:33:20   windowOpenTemperature 12.0
   Internals:
     interfaces thermostat;battery;temperature
Attributes:
   IODev      cm
   alias      Büro West
   room       Heizung

drittes:

Internals:
   DEF        HeatingThermostat 067036
   IODev      cm
   NAME       MAX_067036
   NR         247
   STATE      16.0 °C
   TYPE       MAX
   addr       067036
   type       HeatingThermostat
   Readings:
     2016-02-12 08:57:34   RSSI            -69.5
     2016-02-12 01:22:45   TimeInformationHour 2
     2016-02-12 08:57:34   battery         ok
     2016-02-12 08:57:34   desiredTemperature 16.0
     2016-02-12 09:00:45   groupid         0
     2016-02-12 08:57:34   mode            manual
     2016-02-12 15:23:24   msgcnt          18
     2016-02-12 08:57:34   state           16.0 °C
     2016-02-12 08:57:34   temperature     17.4
     2016-02-12 08:57:34   valveposition   0
   Internals:
     interfaces thermostat;battery;temperature
Attributes:
   IODev      cm
   alias      WoZi N
   room       Heizung

woran sehe ich nun ob diese korrekt gepaired sind? oder nicht? 

[Rince]

Gute Frage 
Wenn niemand anders vorher was schreibt, vergleiche ich die Readings Sonntag mit meinen, da bin ich wieder zu Hause 

[jeti]

Danke schon Mal! kannst du dann auch dein CUL bzw deinen CUL_MAX mal vergleichen? 

ich kann auch keine weiteren Thermostate mehr mit dem geflashten cube pairen... kann es an der firmware liegen?

hier auch noch das list des CUL (cubes):

nternals:
   CMDS       BbCFiAZNEkGMKLUYRTVWXefltxz
   CUL_MAX_MSGCNT 104
   CUL_MAX_TIME 2016-02-13 09:02:41
   Clients    :CUL_MAX:HMS:CUL_IR:STACKABLE_CC:
   DEF        /dev/ttyACM0@9600 0000
   DeviceName /dev/ttyACM0@9600
   FD         15
   FHTID      0000
   NAME       CUL_MAX
   NR         240
   NR_CMD_LAST_H 4
   PARTIAL
   RAWMSG     Z0A000A0306703604AB8A00
   RSSI       -74
   STATE      Initialized
   TYPE       CUL
   VERSION    V 1.20.04 a-culfw Build: 180 (2016-01-28_21-57-20) CUBe (F-Band: 868MHz)
   initString X21
Zr
Za123456
Zw111111
   Matchlist:
     1:CUL_MAX  ^Z........................
     8:HMS      ^810e04....(1|5|9).a001
     D:CUL_IR   ^I............
     H:STACKABLE_CC ^\*
   Readings:
     2016-02-12 09:32:47   cmds             B b C F i A Z N E k G M K L U Y R T V W X e f l t x z
     2016-02-13 08:23:47   credit10ms      586
     2016-02-11 11:14:09   raw             No answer
     2016-02-13 09:02:41   state           Initialized
     2016-02-12 09:48:42   uptime          0 13:16:50
     2016-02-11 11:14:13   version         V 1.20.04 a-culfw Build: 180 (2016-01-28_21-57-20) CUBe (F-Band: 868MHz)
   XMIT_TIME:
     1455348204.82738
     1455348210.99427
     1455348221.25196
     1455348227.66053
Attributes:
   rfmode     MAX
   room       System

und des CUL_MAX:

Internals:
   CUL_MAX_MSGCNT 11
   CUL_MAX_RAWMSG Z170004000DC353000000001001A04B4841303031333038
   CUL_MAX_RSSI -46.5
   CUL_MAX_TIME 2016-02-12 20:10:38
   DEF        123456
   IODev      CUL_MAX
   LASTInputDev CUL_MAX
   MSGCNT     11
   NAME       cm
   NR         241
   STATE      Defined
   TYPE       CUL_MAX
   addr       123456
   cnt        0
   pairmode   0
   retryCount 0
   Readings:
     2016-02-13 08:23:50   packetsLost     33
   sendQueue:
Attributes:
   IODev      CUL_MAX
   room       System

[Rince]

Hier ist ein funktionsfähiges (ohne Weekprofile)

Code Auswählen Erweitern


Internals:
   DEF        HeatingThermostat 05615b
   IODev      cm
   LASTInputDev cm
   MSGCNT     48
   NAME       sz_HZ_Heizung
   NR         57
   RSSI       -65.5
   STATE      17.0 °C
   TYPE       MAX
   addr       05615b
   backend    cm
   cm_MSGCNT  48
   cm_TIME    2016-02-14 08:00:32
   dstsetting 1
   mode       0
   rferror    0
   type       HeatingThermostat
   Readings:
     2016-02-14 08:00:32   RSSI            -65.5
     2014-11-18 23:07:22   TimeInformationHour 5
     2016-02-14 08:00:32   battery         ok
     2015-01-05 13:48:03   boostDuration   25
     2015-01-05 13:48:03   boostValveposition 80
     2015-01-05 13:48:03   comfortTemperature 21.0
     2015-01-05 13:48:03   decalcification Sat 12:00
     2016-02-14 08:00:32   desiredTemperature 17.0
     2015-01-10 16:36:54   ecoTemperature  12.0
     2015-06-26 03:36:27   firmware        1.6
     2015-06-26 03:36:27   groupid         0
     2015-06-08 22:36:45   humidity        0
     2015-01-05 13:48:03   maxValveSetting 100
     2015-01-05 13:48:03   maximumTemperature on
     2015-01-05 13:48:03   measurementOffset 0.0
     2015-01-05 13:48:03   minimumTemperature off
     2016-02-14 08:00:32   mode            auto
     2016-02-14 06:46:38   msgcnt          118
     2016-02-14 08:00:32   state           17.0 °C
     2016-02-14 08:00:32   temperature     14.3
     2015-06-26 03:36:27   testresult      255
     2015-01-05 13:48:03   valveOffset     0
     2016-02-14 08:00:32   valveposition   100


Da steht doch einiges mehr drin.

Ergo würde ich sagen, das Pairing ging in die Hose.

Credits:

Code Auswählen Erweitern

get CUL_MAX credit10ms
Sollte >= 900 sein

Wenn das nicht so ist, verheizt etwas deine Credits.
In deinem Fall die nicht funktionierenden Max Geräte. Kritisch wird es, wenn du so viele unbekannte Geräte hast, dass keine Credits fürs Pairing mehr frei werden
Gib diesen per attr <Gerätename> ignore 1 mal eine Auszeit

In der Wiki steht auch, wie du die Geräte wieder sichtbar bekommst
http://www.fhemwiki.de/wiki/Fremdgeräte_ignorieren_Rückgängig

Wenn du das bei allen nicht-gehenden gemacht hast, sollten sich deine Credits quasi erholen. Mit dem obigen get credit10ms Befehl kannst du das schön verfolgen)

Dann mach von dem Thetmostat einen Werksreset: Batterien raus, 1 Minute warten, Pairing Taste gedrückt halten.... (bitte in der Gebrauchsanleitung nachlesen, hab es nicht exakt im Kopf)

Bringe dein Max Modul in den Pairingmodus, dann das Thermostatventil...

Immer nur 1 auf einmal!

[jeti]

Hi Rince,

klasse, hilft, ich war da wohl zu schnell beim pairen!
Jetzt mit mehr Zeit und prüfen der credits gehts! vielen Dank!

-> langsam und credits prüfen 

Gruß

[Rince]

Sehr schön.
Dann mach doch bitte ein (gelöst) vor den Threadtitel
(1. Posting editieren, da geht das)

[jaritz]

Auch ich hatte Pairing Probleme:

Die Lösung war:

attr CUL_MAX IOdev CUL (in meinem Fall attr CM IOdev nanoCUL

Vorher war der Status des CUL_MAX auch immer . Jetzt ist er CUL:ok

So einfach, muss man erst mal dran kommen.

[Wzut]

und dafür gräbst du eine vier Jahre alte Leiche aus ?