vccu, Rauchmelder seit Update unreachable

dadoc · 06 August 2015, 23:45:18

Das Update war erfolgreich, Restart gemacht.
list hmusb:





Internals: 
   DEF        127.0.0.1:1234 
   DeviceName 127.0.0.1:1234 
   FD         65 
   NAME       hmusb 
   NR         269 
   NTFY_ORDER 50-hmusb 
   PARTIAL 
   RAWMSG     R04EF8933,0001,00010CC6,FF,FFC6,02A010299A2F4242420601000035 
   RSSI       -58 
   STATE      opened 
   TYPE       HMLAN 
   XmitOpen   1 
   assignedIDsCnt 3 
   hmusb_MSGCNT 2 
   hmusb_TIME 2015-08-06 23:34:12 
   msgKeepAlive dlyMax:1.014 bufferMin:3 
   msgLoadCurrent 0 
   msgLoadHistory 5min steps: 0/0/-/-/-/-/-/-/-/-/-/- 
   owner      424242 
   uptime     000 00:01:59.622 
   Readings: 
     2015-08-06 23:34:10   D-HMIdAssigned  424242 
     2015-08-06 23:34:10   D-HMIdOriginal  1EBD70 
     2015-08-06 23:34:10   D-firmware      0.967 
     2015-08-06 23:34:10   D-serialNr      JEQ0700605 
     2015-08-06 23:34:10   Xmit-Events     ok:1 disconnected:1 init:1 
     2015-08-06 23:34:10   cond            ok 
     2015-08-06 23:35:02   loadLvl         low 
     2015-08-06 23:03:36   prot_ERROR-Overload last 
     2015-08-06 23:05:10   prot_Warning-HighLoad last 
     2015-08-06 23:33:46   prot_disconnected last 
     2015-08-06 23:33:46   prot_init       last 
     2015-08-06 23:34:10   prot_ok         last 
     2015-08-06 23:33:46   state           opened 
   Helper: 
     assIdCnt   3 
     assIdRep   3 
     info       03C7,JEQ0700605,1EBD70,424242 
     setTime    43911 
     Cnd: 
       0          1 
       253        1 
       255        1 
     Ids: 
       107841: 
         name       RC19 
       299a2f: 
         chn        01 
         flg        0 
         msg 
         name       CUL_HM_HM_SEC_SD_299A2F 
         to         1438896852.91719 
       353dc1: 
         chn        00 
         flg        0 
         msg 
         name       HM_353DC1 
         to         1438896869.543 
     K: 
       BufMin     3 
       DlyMax     1.014 
       Next       1438896927.28857 
       Start      1438896902.28857 
     Loadlvl: 
       bl         40 
       a: 
         99 
         90 
         40 
         0 
       H: 
         0          low 
         40         batchLevel 
         90         high 
         99         suspended 
     Log: 
       all        0 
       sys        0 
       ids: 
         ARRAY(0x1bade40) 
     Q: 
       HMcndN     0 
       answerPend 0 
       hmLanQlen  1 
       keepAliveRec 1 
       keepAliveRpt 0 
       loadLast   0 
       loadNo     7 
       scnt       6 
       apIDs: 
     Ref: 
       hmtL       119622 
       kTs        0 
Attributes: 
   hmId       424242 
   hmLanQlen  1_min 
   loadLevel  0:low,40:batchLevel,90:high,99:suspended

Das gesniffte Log erschreckt vom Ausmaß her, daher als txt:

frank · 07 August 2015, 09:16:40

dass die loadlvl events fehlen ist kein wunder, wenn der hmusb keinen status liefert. ich habe keine ahnung, was diese hmlan_delay meldungen bedeuten. jedenfalls kann der hmusb nicht durch die wenigen gesendeten messages des logs in overload gehen. es macht den eindruck, als hätte er einen "nebenjob".

Code Auswählen

2015.08.06 23:39:12.414 0: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:0005A324 IDcnt:0003 L:0 %
2015.08.06 23:39:17.561 0: HMLAN_Parse: hmusb R:R04F42196 stat:0208 t:00000000 d:FF r:7FFF     m:01 B001 424242 353DC1 00050000000000
2015.08.06 23:39:17.562 1: HMLAN_Parse: hmusb new condition Warning-HighLoad

also wirklich, msgload in 5 sek von 0% auf 90% (highload).

wo hängt der hmusb dran? bekommt er genügend strom? ist hmland aktuell? läuft der hmland eventuell mehrmals? greifen noch andere dienste/programme auf den hmusb zu? eq3-sw oder die tools von mgernoth. vielleicht würde ein debug-log vom hmland etwas mitteilen. wie startest du den hmland?

dadoc · 07 August 2015, 10:14:02

Zitatwo hängt der hmusb dran?

Direkt am Raspi-USB-Port.

Zitatbekommt er genügend strom?

Das weiß ich nicht, allerdings hat er mit der RC19 ohne Probleme funktioniert.
list RC19:

Code Auswählen

Readings: 
     2015-01-31 16:17:30   CommandAccepted yes 
     2015-01-26 23:13:37   D-firmware      1.0 
     2015-01-26 23:13:37   D-serialNr      EEQ0035939 
     2015-02-14 15:59:26   PairedTo        0x424242 
     2015-01-26 23:13:39   R-backAtCharge  off 
     2015-01-26 23:13:39   R-backAtKey     on 
     2015-01-26 23:13:39   R-backAtMotion  on 
     2015-01-26 23:13:39   R-backOnTime    5 s 
     2015-01-26 23:13:39   R-language      English 
     2015-01-26 23:13:39   R-pairCentral   0x424242 
     2015-02-14 15:59:26   RegL_00:        02:01 07:00 0A:42 0B:42 0C:42 0D:C0 0E:05 00:00 
     2015-02-14 15:59:21   alive           yes 
     2015-02-14 15:59:21   battery         ok 
     2015-02-14 15:59:21   powerOn         2015-02-14 15:59:21 
     2015-02-14 15:59:21   recentStateType info 
     2015-02-14 15:59:44   state           CMDs_done

Code Auswählen

ist hmland aktuell?
Wenn das das ist, was in /opt/hmcfgusb/version.h steht: #define VERSION "0.097-git"
HMLAN.pm ist: 9012 2015-08-02 08:41:25Z martinp876 $

Zitatgreifen noch andere dienste/programme auf den hmusb zu? eq3-sw oder die tools von mgernoth.

Nicht dass ich wüsste, kann mich nicht erinnern, da jemals manuell herumgebastelt zu haben. Bei mir läuft fast alles noch auf FS20, außer einem Rauchmelder und der RC19, die aber nicht wirklich aktiv im Einsatz ist.

Zitatvielleicht würde ein debug-log vom hmland etwas mitteilen.

Das werde ich heute Abend mal versuchen (über verbose...?)

Zitatwie startest du den hmland?

Ich nehme an, das ist es (aus etc/init.d/fhem

Code Auswählen

case "$1" in
'start')
        echo "Starting fhem..."
        /opt/hmcfgusb/hmland -d -p 1234
        perl fhem.pl fhem.cfg
        RETVAL=$?

ps ax ergibt:

Code Auswählen


root@raspberrypi:~# ps ax
  PID TTY      STAT   TIME COMMAND
    1 ?        Ss     0:03 init [2]
    2 ?        S      0:00 [kthreadd]
    3 ?        S      0:00 [ksoftirqd/0]
    4 ?        S      0:00 [kworker/0:0]
    5 ?        S<     0:00 [kworker/0:0H]
    7 ?        S      0:00 [rcu_preempt]
    8 ?        S      0:00 [rcu_bh]
    9 ?        S      0:00 [rcu_sched]
   10 ?        S<     0:00 [khelper]
   11 ?        S      0:00 [kdevtmpfs]
   12 ?        S<     0:00 [netns]
   13 ?        S<     0:00 [writeback]
   14 ?        S<     0:00 [bioset]
   15 ?        S<     0:00 [crypto]
   16 ?        S<     0:00 [kblockd]
   17 ?        S      0:00 [khubd]
   18 ?        S      0:10 [kworker/0:1]
   19 ?        S<     0:00 [rpciod]
   20 ?        S      0:00 [khungtaskd]
   21 ?        S      0:00 [kswapd0]
   22 ?        S      0:00 [fsnotify_mark]
   23 ?        S<     0:00 [nfsiod]
   29 ?        S<     0:00 [kthrotld]
   30 ?        S<     0:00 [VCHIQ-0]
   31 ?        S<     0:00 [VCHIQr-0]
   32 ?        S<     0:00 [VCHIQs-0]
   33 ?        S<     0:00 [iscsi_eh]
   34 ?        S<     0:00 [dwc_otg]
   35 ?        S<     0:00 [DWC Notificatio]
   37 ?        S<     0:00 [deferwq]
   39 ?        S      0:02 [mmcqd/0]
   40 ?        S      0:01 [jbd2/mmcblk0p6-]
   41 ?        S<     0:00 [ext4-rsv-conver]
  156 ?        Ss     0:00 udevd --daemon
  316 ?        S      0:00 udevd --daemon
  669 ?        S      0:00 udevd --daemon
 1638 ?        S      0:04 /usr/sbin/ifplugd -i lo -q -f -u0 -d10 -w -I
 1646 ?        S      0:23 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
 1803 ?        S      0:15 /opt/hmcfgusb/hmland -d -p 1234
 1922 ?        Ss     0:00 dhclient -v -pf /run/dhclient.eth0.pid -lf /var/lib/d
 1976 ?        Sl     0:00 /usr/sbin/rsyslogd -c5
 2023 ?        Ss     0:00 /usr/sbin/cron
 2053 ?        Ss     0:00 /usr/bin/dbus-daemon --system
 2088 ?        S      5:58 perl fhem.pl fhem.cfg
 2095 ?        Ss     0:00 startpar -f -- fhem
 2109 ?        Ss     0:06 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /var/lib/nt
 2173 ?        Ss     0:00 /usr/sbin/lircd --driver=uirt2_raw --device=/dev/ttyU
 2217 ?        Ss     0:00 /usr/sbin/sshd
 2246 ?        Ss     0:00 /usr/sbin/thd --daemon --triggers /etc/triggerhappy/t
 2257 tty1     Ss+    0:00 /sbin/getty --noclear 38400 tty1
 2258 tty2     Ss+    0:00 /sbin/getty 38400 tty2
 2259 tty3     Ss+    0:00 /sbin/getty 38400 tty3
 2260 tty4     Ss+    0:00 /sbin/getty 38400 tty4
 2261 tty5     Ss+    0:00 /sbin/getty 38400 tty5
 2262 tty6     Ss+    0:00 /sbin/getty 38400 tty6
 2263 ttyAMA0  Ss+    0:00 /sbin/getty -L ttyAMA0 115200 vt100
 2448 ?        S      0:00 [kworker/u2:0]
 2464 ?        S      0:00 [kworker/u2:2]
 2472 ?        Ss     0:00 sshd: root@notty
 2476 ?        Ss     0:00 /usr/lib/openssh/sftp-server
 2477 ?        Ss     0:00 sshd: root@pts/0
 2481 pts/0    Ss     0:00 -bash
 2491 pts/0    R+     0:00 ps ax

In der fhem.cfg:

Code Auswählen

define hmusb HMLAN 127.0.0.1:1234
attr hmusb hmId 424242
attr hmusb hmLanQlen 1_min
attr hmusb loadLevel 0:low,40:batchLevel,90:high,99:suspended
attr hmusb logIDs sys,all

Ist es denn denkbar, dass ich mich bei der Hardware "verlötet" habe? Das Modul verhält sich beim Start etwas merkwürdig: die erste LED blinkt lang-kurz-kurz, was lt. Manual "Gerät defekt (z. B. TRX868 lässt sich nicht initialisieren)" bedeutet, Nach Unterbrechung der Stromversorgung startet es dann immer normal. Und senden tut es ja, ebenso wie über die Taster schalten.

EDIT: Ergänzung: Stick-Firmware = 0.967

frank · 07 August 2015, 11:06:51

Code Auswählen

deepthought [~/hmcfgusb]> ./hmland -h
Syntax: ./hmland options

Possible options:
        -D              debug mode
        -d              daemon mode
        -h              this help
        -I              pretend to be HM-LAN-IF for compatibility with client-software (previous default)
        -i              interactive mode (connect HM-CFG-USB to terminal)
        -l ip           listen on given IP address only (for example 127.0.0.1)
        -L logfile      log network-communication to logfile
        -P              create PID file /var/run/hmland.pid in daemon mode
        -p n            listen on port n (default: 1000)
        -r n            reboot HM-CFG-USB after n seconds (0: no reboot, default: 86400 if FW < 0.967, 0 otherwise)
           hh:mm        reboot HM-CFG-USB daily at hh:mm
        -v              verbose mode
        -V              show version (0.101)

deepthought [~/hmcfgusb]> ./hmland -D -p 1234

letzte zeile: starten im debug mode.

ZitatIst es denn denkbar, dass ich mich bei der Hardware "verlötet" habe? Das Modul verhält sich beim Start etwas merkwürdig: die erste LED blinkt lang-kurz-kurz, was lt. Manual "Gerät defekt (z. B. TRX868 lässt sich nicht initialisieren)" bedeutet, Nach Unterbrechung der Stromversorgung startet es dann immer normal. Und senden tut es ja, ebenso wie über die Taster schalten.

hört sich nicht gut an. in deinem letzten log waren jedenfalls keine messages von dem device, bis auf eine anlernmessage.

vielleicht liefert verbose=5 beim hmusb noch was neues.

dadoc · 07 August 2015, 11:30:07

Zitatin deinem letzten log waren jedenfalls keine messages von dem device

Doch, wenn man auf einen Taster drückt, erscheint brav die Message unter Events.
Müsste sich das nicht gem. http://fhemwiki.de/wiki/HomeMatic_Devices_pairen manuell per

Code Auswählen

set hmusb hmPairSerial LEQ1521719
pairen lassen (muss dazu der Empfänger im Anlernmode sein?)? Oder habe ich da etwas falsch verstanden?
Ich habe jetzt auch mal die aktuelle hmland installiert.
Mit Verbose=5 und dem manuellen Pairing über WebUI steht im Log:

Code Auswählen

2015.08.07 11:27:08 0: Server started with 205 defined entities (version $Id: fhem.pl 9002 2015-07-29 05:46:10Z rudolfkoenig $, os linux, user fhem, pid 2291)
2015.08.07 11:27:08 5: HMLAN_Send:  hmusb I:+299A2F,00,00,00
2015.08.07 11:27:08 5: HMLAN_Send:  hmusb I:+353DC1,00,00,00
2015.08.07 11:27:08 5: HMLAN_Send:  hmusb I:+107841,00,00,00
2015.08.07 11:27:08 3: CUL_HM set CUL_HM_HM_SEC_SD_299A2F statusRequest
2015.08.07 11:27:08 5: HMLAN_Send:  hmusb I:K
2015.08.07 11:27:08 3: Opening Denon device 192.168.50.31:23
2015.08.07 11:27:09 5: HMLAN/RAW: /HHM-USB-IF,03C7,JEQ0700605,1EBD70,424242,0025A7F4,0003,49
I00,00,00,00
I00,00,00,00
I00,00,00,00
R077BCE12,0002,00000000,FF,7FFF,998112999999000000
HHM-USB-IF,03C7,JEQ0700605,1EBD70,424242,00261FF4,0003,49

2015.08.07 11:27:09 5: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:0025A7F4 IDcnt:0003 L:73 %
2015.08.07 11:27:09 5: HMLAN_Parse: hmusb R:R077BCE12 stat:0002 t:00000000 d:FF r:7FFF     m:99 8112 999999 000000 
2015.08.07 11:27:09 1: HMLAN_Parse: hmusb new condition ok
2015.08.07 11:27:09 5: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:00261FF4 IDcnt:0003 L:73 %
2015.08.07 11:27:09 5: HMLAN_Send:  hmusb S:+299A2F,00,00,00
2015.08.07 11:27:09 5: HMLAN_Send:  hmusb S:S077C498B stat:  00 t:00000000 d:01 r:077C498B m:02 B001 424242 299A2F 010E
2015.08.07 11:27:09 3: CUL_HM set HM_353DC1_Sw_01 statusRequest
2015.08.07 11:27:10 3: CUL_HM set HM_353DC1_Sw_02 statusRequest
2015.08.07 11:27:10 5: HMLAN/RAW: /E299A2F,0000,00262597,FF,FFC6,02A010299A2F4242420601000035
R077C498B,0001,0026259C,FF,FFC6,02A010299A2F4242420601000035

2015.08.07 11:27:10 5: HMLAN_Parse: hmusb R:E299A2F   stat:0000 t:00262597 d:FF r:FFC6     m:02 A010 299A2F 424242 0601000035
2015.08.07 11:27:10 5: hmusb dispatch A0E02A010299A2F4242420601000035::-58:hmusb
2015.08.07 11:27:10 5: HMLAN: Skip ACK
2015.08.07 11:27:10 5: HMLAN_Parse: hmusb R:R077C498B stat:0001 t:0026259C d:FF r:FFC6     m:02 A010 299A2F 424242 0601000035
2015.08.07 11:27:10 5: hmusb dispatch A0E02A010299A2F4242420601000035::-58:hmusb
2015.08.07 11:27:10 5: HMLAN_Send:  hmusb S:+353DC1,00,00,00
2015.08.07 11:27:10 5: HMLAN_Send:  hmusb S:S077C4EDE stat:  00 t:00000000 d:01 r:077C4EDE m:02 B001 424242 353DC1 010E
2015.08.07 11:27:11 3: CUL_HM set HM_353DC1_Sw_03 statusRequest
2015.08.07 11:27:12 5: HMLAN_Delay: hmusb 353DC1
2015.08.07 11:27:12 3: CUL_HM set HM_353DC1_Sw_04 statusRequest
2015.08.07 11:27:13 5: HMLAN_Send:  hmusb S:+000000,00,00,00
2015.08.07 11:27:13 5: HMLAN_Send:  hmusb S:S077C58B0 stat:  00 t:00000000 d:01 r:077C58B0 m:01 8401 424242 000000 010A4c455131353231373139
2015.08.07 11:27:13 5: HMLAN/RAW: /R077C58B0,0002,00000000,FF,7FFF,018401424242000000010A4C455131353231373139
R077C4EDE,0008,00000000,FF,7FFF,02B001424242353DC1010E

2015.08.07 11:27:13 5: HMLAN_Parse: hmusb R:R077C58B0 stat:0002 t:00000000 d:FF r:7FFF     m:01 8401 424242 000000 010A4C455131353231373139
2015.08.07 11:27:13 5: HMLAN_Parse: hmusb R:R077C4EDE stat:0008 t:00000000 d:FF r:7FFF     m:02 B001 424242 353DC1 010E
2015.08.07 11:27:13 5: HMLAN_Parse: hmusb no ACK from 353DC1
2015.08.07 11:27:13 3: CUL_HM set HM_353DC1_Sw_05 statusRequest
2015.08.07 11:27:14 3: CUL_HM set HM_353DC1_Sw_06 statusRequest
2015.08.07 11:27:15 3: CUL_HM set HM_353DC1_Sw_07 statusRequest
2015.08.07 11:27:16 3: CUL_HM set HM_353DC1_Sw_08 statusRequest
2015.08.07 11:27:20 4: HMLAN_ack: timeout - clear queue
2015.08.07 11:27:33 5: HMLAN_Send:  hmusb I:K
2015.08.07 11:27:33 5: HMLAN/RAW: /HHM-USB-IF,03C7,JEQ0700605,1EBD70,424242,00268126,0003,4D

2015.08.07 11:27:33 5: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:00268126 IDcnt:0003 L:77 %
2015.08.07 11:27:58 5: HMLAN_Send:  hmusb I:K
2015.08.07 11:27:58 5: HMLAN/RAW: /HHM-USB-IF,03C7,JEQ0700605,1EBD70,424242,0026E2D3,0003,4D

2015.08.07 11:27:58 5: HMLAN_Parse: hmusb V:03C7 sNo:JEQ0700605 d:1EBD70 O:424242 t:0026E2D3 IDcnt:0003 L:77 %

frank · 07 August 2015, 12:20:33

Zitat(muss dazu der Empfänger im Anlernmode sein?)

nein. er muss aber aufwachen und antworten. aber das habe ich bei deinem device noch nicht gesehen. wenn ich das richtig in erinnerung habe, kann das ding keinen burst, obwohl es vorgesehen ist. müsstes du mal die threads genauer durchstöbern.

mach mal noch ein log während du an den tasten des device schaltest. mal sehen wie der hmusb reagiert.

edit:

ZitatBei mir läuft fast alles noch auf FS20

dafür hast du sicherlich einen cul. mit dem könntest du zum testen mal monitoren, was der hmusb so sendet, während er sich "verausgabt". (verbose=4 und slowrfmode=homematic am cul)

dadoc · 07 August 2015, 12:52:05

Zitatwenn ich das richtig in erinnerung habe, kann das ding keinen burst

Im Wiki steht:

ZitatDas Modul befindet sich gewöhnlich im Stromsparmodus und muss zur Konfiguration und zum Schalten vom Sender per Burst "aufgeweckt" werden.

Zitatmach mal noch ein log während du an den tasten des device schaltest. mal sehen wie der hmusb reagiert.

ok, wenn ich heute Abend wieder vor Ort bin.
Aber wenn in fhem das Device mit seinen 8 Kanälen usw. angelegt wird: Heißt das nicht, dass es sendet?

Zitat(verbose=4 und slowrfmode=homematic am cul)

Also statt

Code Auswählen

define CUL_0 CUL /dev/ttyACM0@9600 1034
dann

Code Auswählen

define CUL_0 CUL /dev/ttyACM0@9600 1034
attr CUL_0 verbose 4
attr CUL_0 rfmode homematic

?

frank · 07 August 2015, 13:06:23

ZitatAber wenn in fhem das Device mit seinen 8 Kanälen usw. angelegt wird: Heißt das nicht, dass es sendet?

doch, das war die bisher einzige message, die ich in deinen logs gesehen habe. und ein paar sekunden später war der hmusb im overload. meine vermutung ist, dass dein device ein problem hat.

Code Auswählen

define CUL_0 CUL /dev/ttyACM0@9600 1034
attr CUL_0 verbose 4
attr CUL_0 rfmode HomeMatic

mgernoth · 07 August 2015, 18:36:30

Hallo,

Zitat von: dadoc am 07 August 2015, 10:14:02
Code Auswählen Erweitern
ist hmland aktuell?
Wenn das das ist, was in /opt/hmcfgusb/version.h steht: #define VERSION "0.097-git"

Du brauchst eine neuere hmland-Version, damit der richtige Loadlevel an Fhem übertragen wird. 0.101 ist aktuell.

Zitat
EDIT: Ergänzung: Stick-Firmware = 0.967

Gut, die brauchst Du für korrekte Loadmeldungen auch.

Gruß
Michael

dadoc · 07 August 2015, 19:19:15

Hatte die neueste hmland-Version ja wie w.o. geschrieben heute aus der Ferne installiert, konnte aber natürlich nicht gleich testen. Jetzt (vor Ort) habe ich mir das Modul noch einmal mit der Uhrmacherbrille angeschaut und eine (vermutlich) kalte Lötstelle am Funkmodul nachgelötet (bin kein wirklicher Löt-Profi).
Danach klappte das Pairen innerhalb von 2 Sekunden - ich tippe auf die Lötstelle, da das Modul beim Erst-Connecten auch nicht mehr den Fehlercode blinkt, aber vielleicht war es ja auch beides.
Vielen Dank für Eure Hilfe - werde demnächst "auf vielfachen Wunsch" noch posten, wofür und vor allem wie ich das Ganze gemacht habe (Polwendungs-Lösung für Velux 24V-Rolläden).
Schönes WE
Martin