Präsenz via Bluetooth Problem

Kampfgnom · 29 September 2014, 20:12:14

Nabend Forum,

ich kämpfe gerade mit der Präsenz Erkennung via Bluetooth.

Ich habe auf meinen Raspberry Pi den Bluetooth Dongle eingerichtet.

hier der Auszug aus meiner fhem.cfg

define Bluetooth PRESENCE local-bluetooth 78:1F:DB:A7:15:11 10 10
attr Bluetooth event-on-change-reading state
attr Bluetooth icon it_smartphone
attr Bluetooth room Anwesenheit

Leider bekomme ich nur den Status Active zu stande

ADDRESS
78:1F:DB:A7:15:11

CHANGED[/t]
DEF
local-bluetooth 78:1F:DB:A7:15:11 10 10

[/t]

MODE[/t]
local-bluetooth
NAME
Bluetooth

NR[/t]
324
STATE
active

TIMEOUT_NORMAL[/t]
10
TIMEOUT_PRESENT
10

TYPE[/t]
PRESENCE

Hat jemand ein plan was ich falsch mache

Markus Bloch · 29 September 2014, 20:23:31

Bitte einmal das Attribut "verbose" auf 5 stellen, dannach ein "set Bluetooth statusRequest" durchführen und anschließend die relevanten Zeilen aus dem fhem.log hier posten.

Gruß
Markus

Kampfgnom · 29 September 2014, 20:38:02

Das kommt dabei raus:

2014.09.29 20:07:46 5: PRESENCE (Bluetooth) - starting local scan
2014.09.29 20:07:46 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.09.29 20:08:24 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 24054
2014.09.29 20:08:24 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24065
2014.09.29 20:08:24 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24067
2014.09.29 20:08:24 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24078
2014.09.29 20:08:24 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24080
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24082
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24084
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24086
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24091
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24093
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24098
2014.09.29 20:08:25 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24100
2014.09.29 20:08:26 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24111
2014.09.29 20:08:26 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24113
2014.09.29 20:08:34 5: PRESENCE (Bluetooth) - stopping timer
2014.09.29 20:08:34 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.09.29 20:08:34 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.09.29 20:08:34 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.09.29 20:08:35 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4

Demnach sollte doch Fhem doch feststellen das das Gerät Galaxy S4 "Anwesend" ist oder

?
zumindest habe ich demnach wohl Debian wohl richtig konfiguriert, oder?

Markus Bloch · 01 Oktober 2014, 21:07:21

Nach dem Log zu urteilen funktioniert an sich die Erkennung. Allerdings werden die Ergebnisse nicht an FHEM übermittelt. Warum, kann ich leider nicht sagen. Dazu wäre ein detaillierterer Log-Auszug notwendig wo in der global-Definition das Attribut verbose 5 gesetzt ist.

Warum so viele Prozesse beendet werden aufgrund eines Timeouts kann ich auch nicht erkennen.

Evtl. mal einen größeren Zeitraum an Logs posten, so aus diesem Auschnitt lässt sich wenig erkennen und schlussfolgern.

Gruß
Markus

Kampfgnom · 01 Oktober 2014, 21:16:17

Ich werde das Verbose Attribut einmal auf 5 setzten,

und dann morgen die Logs der letzten 5 Stunden hier posten.

Vielen Dank Für Ihre Hilfe !

Kampfgnom · 02 Oktober 2014, 08:59:48

Moin Moin Forum,

hier noch einmal das Log nach der Umstellung auf Verbose 5. Ich glaube das Log der Letzten 5 Stunden wäre arg übertrieben und würde auch den rahmen sprengen.

[code]2014.10.02 07:59:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:00:11 4: Connection accepted from FHEMWEB:192.168.1.111:48628
2014.10.02 08:00:11 4: HTTP FHEMWEB:192.168.1.111:48628 GET /fhem?XHR=1&cmd=xmllist
2014.10.02 08:00:11 5: Cmd: >xmllist<
2014.10.02 08:00:12 5: SYSMON Set sysmon ?
2014.10.02 08:00:12 5: SET: Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
2014.10.02 08:00:12 4: /fhem?XHR=1&cmd=xmllist / RL:19426 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.10.02 08:00:13 4: Connection closed for FHEMWEB:192.168.1.111:48628
2014.10.02 08:00:14 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:00:14 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183525F5,0007,01

2014.10.02 08:00:14 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183525F5 IDcnt:0007
2014.10.02 08:00:26 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 21049
2014.10.02 08:00:29 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 21093
2014.10.02 08:00:29 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:00:29 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:00:36 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:00:36 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:00:36 4: BlockingCall created child (22043), uses telnetForBlockingFn to connect back
2014.10.02 08:00:36 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:00:36 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:00:38 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:00:39 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:00:39 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183587AA,0007,01

2014.10.02 08:00:39 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183587AA IDcnt:0007
2014.10.02 08:00:39 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:00:39 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:00:39 4: BlockingCall created child (22086), uses telnetForBlockingFn to connect back
2014.10.02 08:00:39 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:00:42 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=3.73 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=2.67 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=5.98 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=4.18 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.670/4.140/5.981/1.198 ms
2014.10.02 08:00:54 5: SYSMON Update
2014.10.02 08:00:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:00:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo get fs_boot:/boot
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo exec df /boot -m 2>/dev/null
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved 2 lines
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved line0 Filesystem 1M-blocks Used Available Use% Mounted on

2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo analyse line /dev/mmcblk0p1 56 10 47 18% /boot
for /boot
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo pattern (/boot) found
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo use line /dev/mmcblk0p1 56 10 47 18% /boot

2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo get fs_root:/:Root
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo exec df / -m 2>/dev/null
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved 2 lines
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved line0 Filesystem 1M-blocks Used Available Use% Mounted on

2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo analyse line /dev/root 3599 2942 466 87% /
for /
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo pattern (/) found
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo use line /dev/root 3599 2942 466 87% /

2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo get fs_usb1:/media/usb1:USB-Stick
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo exec df /media/usb1 -m 2>/dev/null
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved 0 lines
2014.10.02 08:00:54 5: SYSMON SYSMON_getFileSystemInfo recieved empty line
2014.10.02 08:00:54 5: Triggering sysmon (9 changes)
2014.10.02 08:00:54 5: Notify loop for sysmon fs_root: Total: 3599 MB, Used: 2942 MB, 87 %, Available: 466 MB at /
2014.10.02 08:00:58 5: HMLAN/RAW: /E27B823,0000,1835D2C2,FF,FFC1,1C867027B82300000000835E

2014.10.02 08:00:58 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:1835D2C2 d:FF r:FFC1 m:1C 8670 27B823 000000 00835E
2014.10.02 08:00:58 5: HMLAN1 dispatch A0C1C867027B82300000000835E::-63:HMLAN1
2014.10.02 08:00:58 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:00:58 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.1
2014.10.02 08:01:04 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:01:04 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1835E95A,0007,01

2014.10.02 08:01:04 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1835E95A IDcnt:0007
2014.10.02 08:01:29 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:01:29 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18364B25,0007,01

2014.10.02 08:01:29 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18364B25 IDcnt:0007
2014.10.02 08:01:36 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 22043
2014.10.02 08:01:39 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 22086
2014.10.02 08:01:39 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:01:39 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:01:46 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:01:46 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:01:46 4: BlockingCall created child (23037), uses telnetForBlockingFn to connect back
2014.10.02 08:01:47 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:01:47 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:01:48 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:01:49 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:01:49 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:01:49 4: BlockingCall created child (23078), uses telnetForBlockingFn to connect back
2014.10.02 08:01:49 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:01:52 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=7.18 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=2.84 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=5.42 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=1.96 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 1.964/4.355/7.184/2.070 ms
2014.10.02 08:01:54 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:01:54 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1836ACDA,0007,01

2014.10.02 08:01:54 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1836ACDA IDcnt:0007
2014.10.02 08:01:54 5: SYSMON Update
2014.10.02 08:01:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:01:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:01:54 5: Triggering sysmon (6 changes)
2014.10.02 08:01:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:02:19 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:02:19 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18370E9A,0007,01

2014.10.02 08:02:19 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18370E9A IDcnt:0007
2014.10.02 08:02:44 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:02:44 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1837704A,0007,01

2014.10.02 08:02:44 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1837704A IDcnt:0007
2014.10.02 08:02:44 4: HttpUtils url=http://weather.yahooapis.com/forecastrss?w=12832458&u=c
2014.10.02 08:02:45 4: http://weather.yahooapis.com/forecastrss?w=12832458&u=c: HTTP response code 200
2014.10.02 08:02:45 4: HttpUtils http://weather.yahooapis.com/forecastrss?w=12832458&u=c: Got data, length: 2848
2014.10.02 08:02:45 4: Weather MeinWetter: T: 14 H: 100 W: 8
2014.10.02 08:02:45 5: Triggering MeinWetter (51 changes)
2014.10.02 08:02:45 5: Notify loop for MeinWetter wind_direction: 270
2014.10.02 08:02:46 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 23037
2014.10.02 08:02:49 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 23078
2014.10.02 08:02:49 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:02:49 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:02:54 5: SYSMON Update
2014.10.02 08:02:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:02:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:02:54 5: Triggering sysmon (6 changes)
2014.10.02 08:02:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:02:56 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:02:56 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:02:56 4: BlockingCall created child (24031), uses telnetForBlockingFn to connect back
2014.10.02 08:02:57 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:02:57 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:02:58 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:02:59 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:02:59 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:02:59 4: BlockingCall created child (24084), uses telnetForBlockingFn to connect back
2014.10.02 08:02:59 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:03:02 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=3.92 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=3.66 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=4.93 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=6.48 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 3.666/4.754/6.489/1.111 ms
2014.10.02 08:03:09 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:03:09 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1837D218,0007,01

2014.10.02 08:03:09 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1837D218 IDcnt:0007
2014.10.02 08:03:34 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:03:34 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183833C9,0007,01

2014.10.02 08:03:34 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183833C9 IDcnt:0007
2014.10.02 08:03:46 5: HMLAN/RAW: /E27B823,0000,1838641B,FF,FFC1,1D867027B82300000000825E

2014.10.02 08:03:46 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:1838641B d:FF r:FFC1 m:1D 8670 27B823 000000 00825E
2014.10.02 08:03:46 5: HMLAN1 dispatch A0C1D867027B82300000000825E::-63:HMLAN1
2014.10.02 08:03:46 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:03:46 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:03:54 5: SYSMON Update
2014.10.02 08:03:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:03:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:03:54 5: Triggering sysmon (6 changes)
2014.10.02 08:03:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:03:57 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 24031
2014.10.02 08:03:59 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:03:59 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1838957E,0007,01

2014.10.02 08:03:59 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1838957E IDcnt:0007
2014.10.02 08:03:59 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 24084
2014.10.02 08:03:59 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:03:59 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:04:07 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:04:07 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:04:07 4: BlockingCall created child (25016), uses telnetForBlockingFn to connect back
2014.10.02 08:04:07 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:04:07 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:04:07 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:04:09 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:04:09 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:04:10 4: BlockingCall created child (25068), uses telnetForBlockingFn to connect back
2014.10.02 08:04:10 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:04:13 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=8.07 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=3.64 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=4.48 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=32.0 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 3.645/12.062/32.042/11.655 ms
2014.10.02 08:04:24 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:04:24 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1838F72E,0007,01

2014.10.02 08:04:24 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1838F72E IDcnt:0007
2014.10.02 08:04:49 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:04:49 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183958E5,0007,01

2014.10.02 08:04:49 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183958E5 IDcnt:0007
2014.10.02 08:04:54 5: SYSMON Update
2014.10.02 08:04:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:04:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:04:54 5: Triggering sysmon (6 changes)
2014.10.02 08:04:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:05:07 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 25016
2014.10.02 08:05:10 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 25068
2014.10.02 08:05:10 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:05:10 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:05:13 4: Connection accepted from FHEMWEB:192.168.1.111:55843
2014.10.02 08:05:13 4: HTTP FHEMWEB:192.168.1.111:55843 GET /fhem?XHR=1&cmd=xmllist
2014.10.02 08:05:13 5: Cmd: >xmllist<
2014.10.02 08:05:13 5: SYSMON Set sysmon ?
2014.10.02 08:05:14 5: SET: Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
2014.10.02 08:05:14 4: /fhem?XHR=1&cmd=xmllist / RL:19422 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.10.02 08:05:14 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:05:14 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1839BA95,0007,01

2014.10.02 08:05:14 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1839BA95 IDcnt:0007
2014.10.02 08:05:15 4: Connection closed for FHEMWEB:192.168.1.111:55843
2014.10.02 08:05:17 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:05:17 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:05:17 4: BlockingCall created child (26001), uses telnetForBlockingFn to connect back
2014.10.02 08:05:17 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:05:17 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:05:18 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:05:20 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:05:20 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:05:20 4: BlockingCall created child (26054), uses telnetForBlockingFn to connect back
2014.10.02 08:05:20 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:05:23 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=3.68 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=1.91 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=3.54 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=2.75 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 1.917/2.976/3.687/0.709 ms
2014.10.02 08:05:39 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:05:39 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183A1C67,0007,01

2014.10.02 08:05:39 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183A1C67 IDcnt:0007
2014.10.02 08:05:54 5: SYSMON Update
2014.10.02 08:05:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:05:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:05:54 5: Triggering sysmon (6 changes)
2014.10.02 08:05:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:06:04 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:06:04 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183A7E15,0007,01

2014.10.02 08:06:04 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183A7E15 IDcnt:0007
2014.10.02 08:06:17 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 26001
2014.10.02 08:06:20 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 26054
2014.10.02 08:06:20 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:06:20 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:06:20 5: HMLAN/RAW: /E27B823,0000,183ABCCD,FF,FFC0,1E867027B82300000000825E

2014.10.02 08:06:20 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:183ABCCD d:FF r:FFC0 m:1E 8670 27B823 000000 00825E
2014.10.02 08:06:20 5: HMLAN1 dispatch A0C1E867027B82300000000825E::-64:HMLAN1
2014.10.02 08:06:20 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:06:20 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:06:27 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:06:27 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:06:27 4: BlockingCall created child (26998), uses telnetForBlockingFn to connect back
2014.10.02 08:06:27 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:06:27 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:06:28 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:06:29 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:06:29 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183ADFD4,0007,01

2014.10.02 08:06:29 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183ADFD4 IDcnt:0007
2014.10.02 08:06:30 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:06:30 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:06:30 4: BlockingCall created child (27050), uses telnetForBlockingFn to connect back
2014.10.02 08:06:30 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:06:33 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=6.93 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=7.08 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=5.54 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=2.86 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.862/5.607/7.088/1.697 ms
2014.10.02 08:06:54 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:06:54 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183B417F,0007,01

2014.10.02 08:06:54 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183B417F IDcnt:0007
2014.10.02 08:06:54 5: SYSMON Update
2014.10.02 08:06:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:06:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:06:54 5: Triggering sysmon (6 changes)
2014.10.02 08:06:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:07:19 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:07:19 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183BA33F,0007,01

2014.10.02 08:07:19 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183BA33F IDcnt:0007
2014.10.02 08:07:27 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 26998
2014.10.02 08:07:30 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 27050
2014.10.02 08:07:30 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:07:30 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:07:37 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:07:37 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:07:37 4: BlockingCall created child (28015), uses telnetForBlockingFn to connect back
2014.10.02 08:07:37 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:07:37 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:07:37 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:07:40 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:07:40 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:07:40 4: BlockingCall created child (28063), uses telnetForBlockingFn to connect back
2014.10.02 08:07:40 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:07:43 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=10.5 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=16.7 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=5.48 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=13.0 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 5.481/11.464/16.772/4.104 ms
2014.10.02 08:07:44 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:07:44 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183C04F0,0007,01

2014.10.02 08:07:44 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183C04F0 IDcnt:0007
2014.10.02 08:07:54 5: SYSMON Update
2014.10.02 08:07:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:07:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:07:54 5: Triggering sysmon (6 changes)
2014.10.02 08:07:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:08:09 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:08:09 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183C66AF,0007,01

2014.10.02 08:08:09 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183C66AF IDcnt:0007
2014.10.02 08:08:34 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:08:34 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183CC874,0007,01

2014.10.02 08:08:34 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183CC874 IDcnt:0007
2014.10.02 08:08:37 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 28015
2014.10.02 08:08:40 5: HMLAN/RAW: /E27B823,0000,183CDDD3,FF,FFC1,1F867027B82300000000825E

2014.10.02 08:08:40 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:183CDDD3 d:FF r:FFC1 m:1F 8670 27B823 000000 00825E
2014.10.02 08:08:40 5: HMLAN1 dispatch A0C1F867027B82300000000825E::-63:HMLAN1
2014.10.02 08:08:40 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:08:40 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:08:40 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 28063
2014.10.02 08:08:40 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:08:40 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:08:47 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:08:47 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:08:47 4: BlockingCall created child (29025), uses telnetForBlockingFn to connect back
2014.10.02 08:08:47 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:08:47 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:08:48 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:08:50 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:08:50 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:08:50 4: BlockingCall created child (29076), uses telnetForBlockingFn to connect back
2014.10.02 08:08:50 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:08:53 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=2.18 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=4.89 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=1.90 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=6.12 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3005ms
rtt min/avg/max/mdev = 1.909/3.779/6.127/1.789 ms
2014.10.02 08:08:54 5: SYSMON Update
2014.10.02 08:08:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:08:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:08:54 5: Triggering sysmon (6 changes)
2014.10.02 08:08:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:08:59 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:08:59 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183D2A29,0007,01

2014.10.02 08:08:59 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183D2A29 IDcnt:0007
2014.10.02 08:09:24 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:09:24 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183D8BDA,0007,01

2014.10.02 08:09:24 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183D8BDA IDcnt:0007
2014.10.02 08:09:47 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 29025
2014.10.02 08:09:49 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:09:49 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183DED8B,0007,01

2014.10.02 08:09:49 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183DED8B IDcnt:0007
2014.10.02 08:09:50 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 29076
2014.10.02 08:09:50 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:09:50 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:09:54 5: SYSMON Update
2014.10.02 08:09:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:09:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:09:54 5: Triggering sysmon (6 changes)
2014.10.02 08:09:54 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:09:57 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:09:57 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:09:57 4: BlockingCall created child (30055), uses telnetForBlockingFn to connect back
2014.10.02 08:09:57 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:09:57 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:09:58 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:10:00 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:10:00 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:10:00 4: BlockingCall created child (30108), uses telnetForBlockingFn to connect back
2014.10.02 08:10:00 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:10:03 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=7.05 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=5.55 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=3.91 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=2.77 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.776/4.824/7.057/1.625 ms
2014.10.02 08:10:12 5: HMLAN/RAW: /E28DA68,0000,183E46E5,FF,FFBA,ECA64128DA6826EC8901E7C8

2014.10.02 08:10:12 5: HMLAN_Parse: HMLAN1 R:E28DA68 stat:0000 t:183E46E5 d:FF r:FFBA m:EC A641 28DA68 26EC89 01E7C8
2014.10.02 08:10:12 5: HMLAN1 dispatch A0CECA64128DA6826EC8901E7C8::-70:HMLAN1
2014.10.02 08:10:12 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 prep ACK for 01
2014.10.02 08:10:12 5: HMLAN_Send: HMLAN1 S:SCF7AED3E stat: 00 t:00000000 d:01 r:CF7AED3E m:EC 8002 26EC89 28DA68 0101C800
2014.10.02 08:10:12 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 protEvent:CMDs_done
2014.10.02 08:10:12 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 sent ACK:2
2014.10.02 08:10:12 5: Triggering CUL_HM_HM_SEC_SC_2_28DA68 (4 changes)
2014.10.02 08:10:12 5: Notify loop for CUL_HM_HM_SEC_SC_2_28DA68 trigDst_26EC89: noConfig
2014.10.02 08:10:12 5: HMLAN/RAW: /RCF7AED3E,0002,00000000,FF,7FFF,EC800226EC8928DA680101C800

2014.10.02 08:10:12 5: HMLAN_Parse: HMLAN1 R:RCF7AED3E stat:0002 t:00000000 d:FF r:7FFF m:EC 8002 26EC89 28DA68 0101C800
2014.10.02 08:10:14 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:10:14 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183E4F3D,0007,01

2014.10.02 08:10:14 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183E4F3D IDcnt:0007
2014.10.02 08:10:21 4: Connection accepted from FHEMWEB:192.168.1.111:48012
2014.10.02 08:10:21 4: HTTP FHEMWEB:192.168.1.111:48012 GET /fhem?XHR=1&cmd=xmllist
2014.10.02 08:10:21 5: Cmd: >xmllist<
2014.10.02 08:10:21 5: SYSMON Set sysmon ?
2014.10.02 08:10:21 5: SET: Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
2014.10.02 08:10:22 4: /fhem?XHR=1&cmd=xmllist / RL:19428 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.10.02 08:10:22 4: Connection closed for FHEMWEB:192.168.1.111:48012
2014.10.02 08:10:39 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:10:39 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183EB0F8,0007,01

2014.10.02 08:10:39 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183EB0F8 IDcnt:0007
2014.10.02 08:10:45 5: HMLAN/RAW: /E27B823,0000,183EC632,FF,FFC0,20867027B82300000000825E

2014.10.02 08:10:45 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:183EC632 d:FF r:FFC0 m:20 8670 27B823 000000 00825E
2014.10.02 08:10:45 5: HMLAN1 dispatch A0C20867027B82300000000825E::-64:HMLAN1
2014.10.02 08:10:45 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:10:45 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:10:54 5: SYSMON Update
2014.10.02 08:10:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:10:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo get fs_boot:/boot
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo exec df /boot -m 2>/dev/null
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo recieved 2 lines
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo recieved line0 Filesystem 1M-blocks Used Available Use% Mounted on

2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo analyse line /dev/mmcblk0p1 56 10 47 18% /boot
for /boot
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo pattern (/boot) found
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo use line /dev/mmcblk0p1 56 10 47 18% /boot

2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo get fs_root:/:Root
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo exec df / -m 2>/dev/null
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo recieved 2 lines
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo recieved line0 Filesystem 1M-blocks Used Available Use% Mounted on

2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo analyse line /dev/root 3599 2942 466 87% /
for /
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo pattern (/) found
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo use line /dev/root 3599 2942 466 87% /

2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo get fs_usb1:/media/usb1:USB-Stick
2014.10.02 08:10:54 5: SYSMON SYSMON_getFileSystemInfo exec df /media/usb1 -m 2>/dev/null
2014.10.02 08:10:55 5: SYSMON SYSMON_getFileSystemInfo recieved 0 lines
2014.10.02 08:10:55 5: SYSMON SYSMON_getFileSystemInfo recieved empty line
2014.10.02 08:10:55 5: Triggering sysmon (9 changes)
2014.10.02 08:10:55 5: Notify loop for sysmon fs_root: Total: 3599 MB, Used: 2942 MB, 87 %, Available: 466 MB at /
2014.10.02 08:10:57 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 30055
2014.10.02 08:11:00 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 30108
2014.10.02 08:11:00 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:11:00 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:11:04 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:11:04 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183F12A8,0007,01

2014.10.02 08:11:04 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183F12A8 IDcnt:0007
2014.10.02 08:11:07 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:11:07 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:11:07 4: BlockingCall created child (31070), uses telnetForBlockingFn to connect back
2014.10.02 08:11:07 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:11:07 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:11:08 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:11:10 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:11:10 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:11:10 4: BlockingCall created child (31123), uses telnetForBlockingFn to connect back
2014.10.02 08:11:10 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:11:13 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=3.10 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=4.77 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=2.82 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=6.59 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.826/4.324/6.590/1.506 ms
2014.10.02 08:11:29 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:11:29 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183F747F,0007,01

2014.10.02 08:11:29 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183F747F IDcnt:0007
2014.10.02 08:11:54 5: SYSMON Update
2014.10.02 08:11:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:11:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:11:55 5: Triggering sysmon (6 changes)
2014.10.02 08:11:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:11:55 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:11:55 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,183FD760,0007,01

2014.10.02 08:11:55 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:183FD760 IDcnt:0007
2014.10.02 08:12:07 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 31070
2014.10.02 08:12:10 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 31123
2014.10.02 08:12:10 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:12:10 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:12:17 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:12:17 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:12:17 4: BlockingCall created child (32082), uses telnetForBlockingFn to connect back
2014.10.02 08:12:17 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:12:17 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:12:18 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:12:20 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:12:20 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1840390E,0007,01

2014.10.02 08:12:20 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1840390E IDcnt:0007
2014.10.02 08:12:20 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:12:20 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:12:20 4: BlockingCall created child (32135), uses telnetForBlockingFn to connect back
2014.10.02 08:12:20 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:12:23 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=8.61 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=28.3 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=2.97 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=6.72 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.977/11.673/28.386/9.860 ms
2014.10.02 08:12:45 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:12:45 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18409AC5,0007,01

2014.10.02 08:12:45 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18409AC5 IDcnt:0007
2014.10.02 08:12:54 5: SYSMON Update
2014.10.02 08:12:55 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:12:55 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:12:55 5: Triggering sysmon (6 changes)
2014.10.02 08:12:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:13:10 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:13:10 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1840FC7D,0007,01

2014.10.02 08:13:10 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1840FC7D IDcnt:0007
2014.10.02 08:13:16 5: HMLAN/RAW: /E28DA68,0000,184114D3,FF,FFB8,EDA64128DA6826EC8901E800

2014.10.02 08:13:16 5: HMLAN_Parse: HMLAN1 R:E28DA68 stat:0000 t:184114D3 d:FF r:FFB8 m:ED A641 28DA68 26EC89 01E800
2014.10.02 08:13:16 5: HMLAN1 dispatch A0CEDA64128DA6826EC8901E800::-72:HMLAN1
2014.10.02 08:13:16 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 prep ACK for 01
2014.10.02 08:13:16 5: HMLAN_Send: HMLAN1 S:SCF7DBB0A stat: 00 t:00000000 d:01 r:CF7DBB0A m:ED 8002 26EC89 28DA68 0101C800
2014.10.02 08:13:16 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 protEvent:CMDs_done
2014.10.02 08:13:16 5: CUL_HM CUL_HM_HM_SEC_SC_2_28DA68 sent ACK:2
2014.10.02 08:13:16 5: Triggering CUL_HM_HM_SEC_SC_2_28DA68 (4 changes)
2014.10.02 08:13:16 5: Notify loop for CUL_HM_HM_SEC_SC_2_28DA68 trigDst_26EC89: noConfig
2014.10.02 08:13:16 5: HMLAN/RAW: /RCF7DBB0A,0002,00000000,FF,7FFF,ED800226EC8928DA680101C800

2014.10.02 08:13:16 5: HMLAN_Parse: HMLAN1 R:RCF7DBB0A stat:0002 t:00000000 d:FF r:7FFF m:ED 8002 26EC89 28DA68 0101C800
2014.10.02 08:13:17 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 32082
2014.10.02 08:13:20 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 32135
2014.10.02 08:13:20 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:13:20 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:13:27 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:13:27 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:13:27 4: BlockingCall created child (640), uses telnetForBlockingFn to connect back
2014.10.02 08:13:27 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:13:27 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:13:28 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:13:30 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:13:30 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:13:30 4: BlockingCall created child (684), uses telnetForBlockingFn to connect back
2014.10.02 08:13:30 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:13:33 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=2.68 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=2.71 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=2.06 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=7.05 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.062/3.628/7.058/1.997 ms
2014.10.02 08:13:35 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:13:35 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18415E31,0007,01

2014.10.02 08:13:35 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18415E31 IDcnt:0007
2014.10.02 08:13:39 5: HMLAN/RAW: /E27B823,0000,18416FF6,FF,FFC0,21867027B82300000000825E

2014.10.02 08:13:39 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:18416FF6 d:FF r:FFC0 m:21 8670 27B823 000000 00825E
2014.10.02 08:13:39 5: HMLAN1 dispatch A0C21867027B82300000000825E::-64:HMLAN1
2014.10.02 08:13:39 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:13:39 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:13:54 5: SYSMON Update
2014.10.02 08:13:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:13:55 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:13:55 5: Triggering sysmon (6 changes)
2014.10.02 08:13:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:14:00 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:14:00 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1841BFF0,0007,01

2014.10.02 08:14:00 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1841BFF0 IDcnt:0007
2014.10.02 08:14:25 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:14:25 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,184221A3,0007,01

2014.10.02 08:14:25 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:184221A3 IDcnt:0007
2014.10.02 08:14:27 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 640
2014.10.02 08:14:30 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 684
2014.10.02 08:14:30 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:14:30 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:14:37 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:14:37 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:14:37 4: BlockingCall created child (1626), uses telnetForBlockingFn to connect back
2014.10.02 08:14:37 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:14:37 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:14:38 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:14:40 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:14:40 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:14:40 4: BlockingCall created child (1679), uses telnetForBlockingFn to connect back
2014.10.02 08:14:40 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:14:43 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=3.18 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=2.85 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=3.02 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=1.89 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 1.894/2.738/3.182/0.505 ms
2014.10.02 08:14:50 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:14:50 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1842835B,0007,01

2014.10.02 08:14:50 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1842835B IDcnt:0007
2014.10.02 08:14:54 5: SYSMON Update
2014.10.02 08:14:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:14:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:14:55 5: Triggering sysmon (6 changes)
2014.10.02 08:14:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:15:15 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:15:15 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1842E519,0007,01

2014.10.02 08:15:15 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1842E519 IDcnt:0007
2014.10.02 08:15:29 4: Connection accepted from FHEMWEB:192.168.1.111:50615
2014.10.02 08:15:29 4: HTTP FHEMWEB:192.168.1.111:50615 GET /fhem?XHR=1&cmd=xmllist
2014.10.02 08:15:29 5: Cmd: >xmllist<
2014.10.02 08:15:30 5: SYSMON Set sysmon ?
2014.10.02 08:15:30 5: SET: Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
Unknown argument ?, choose one of dim06% dim100% dim12% dim18% dim25% dim31% dim37% dim43% dim50% dim56% dim62% dim68% dim75% dim81% dim87% dim93% dimdown dimup dimupdown off off-for-timer on on-100-for-timer-prev on-for-timer on-old-for-timer on-old-for-timer-prev on-till ramp-off-time ramp-on-time reset sendstate timer toggle dim:slider,0,6.25,100 blink intervals off-till
2014.10.02 08:15:30 4: /fhem?XHR=1&cmd=xmllist / RL:19434 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2014.10.02 08:15:30 4: Connection closed for FHEMWEB:192.168.1.111:50615
2014.10.02 08:15:37 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 1626
2014.10.02 08:15:40 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:15:40 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,184346D0,0007,01

2014.10.02 08:15:40 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:184346D0 IDcnt:0007
2014.10.02 08:15:40 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 1679
2014.10.02 08:15:40 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:15:40 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:15:47 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:15:47 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:15:47 4: BlockingCall created child (2635), uses telnetForBlockingFn to connect back
2014.10.02 08:15:47 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:15:47 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:15:49 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:15:50 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:15:50 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:15:50 4: BlockingCall created child (2688), uses telnetForBlockingFn to connect back
2014.10.02 08:15:50 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:15:53 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=4.31 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=2.14 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=4.99 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=2.39 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 2.142/3.460/4.990/1.221 ms
2014.10.02 08:15:54 5: SYSMON Update
2014.10.02 08:15:54 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:15:54 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:15:55 5: Triggering sysmon (6 changes)
2014.10.02 08:15:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:16:05 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:16:05 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1843A87D,0007,01

2014.10.02 08:16:05 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1843A87D IDcnt:0007
2014.10.02 08:16:19 5: HMLAN/RAW: /E27B823,0000,1843E20E,FF,FFC1,22867027B82300000000825E

2014.10.02 08:16:19 5: HMLAN_Parse: HMLAN1 R:E27B823 stat:0000 t:1843E20E d:FF r:FFC1 m:22 8670 27B823 000000 00825E
2014.10.02 08:16:19 5: HMLAN1 dispatch A0C22867027B82300000000825E::-63:HMLAN1
2014.10.02 08:16:19 5: Triggering CUL_HM_HM_WDS10_TH_O_27B823 (4 changes)
2014.10.02 08:16:19 5: Notify loop for CUL_HM_HM_WDS10_TH_O_27B823 temperature: 13.0
2014.10.02 08:16:30 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:16:30 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18440A32,0007,01

2014.10.02 08:16:30 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18440A32 IDcnt:0007
2014.10.02 08:16:47 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 2635
2014.10.02 08:16:50 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 2688
2014.10.02 08:16:50 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:16:50 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:16:54 5: SYSMON Update
2014.10.02 08:16:55 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:16:55 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:16:55 5: Triggering sysmon (6 changes)
2014.10.02 08:16:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:16:55 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:16:55 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18446BE3,0007,01

2014.10.02 08:16:55 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18446BE3 IDcnt:0007
2014.10.02 08:16:57 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:16:57 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:16:57 4: BlockingCall created child (3642), uses telnetForBlockingFn to connect back
2014.10.02 08:16:57 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:16:57 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:16:58 4: PRESENCE (Bluetooth) - hcitool returned: Galaxy S4
2014.10.02 08:17:00 5: PRESENCE (FritzBox7490) - stopping timer
2014.10.02 08:17:00 5: PRESENCE (FritzBox7490) - starting blocking call for mode lan-ping
2014.10.02 08:17:00 4: BlockingCall created child (3695), uses telnetForBlockingFn to connect back
2014.10.02 08:17:00 5: PRESENCE (FritzBox7490) - starting ping scan: FritzBox7490|192.168.1.1|0|4
2014.10.02 08:17:03 5: PRESENCE (FritzBox7490) - ping command returned with output:
PING 192.168.1.1 (192.168.1.1) 56(84) bytes of data.
64 bytes from 192.168.1.1: icmp_req=1 ttl=64 time=1.88 ms
64 bytes from 192.168.1.1: icmp_req=2 ttl=64 time=3.25 ms
64 bytes from 192.168.1.1: icmp_req=3 ttl=64 time=2.01 ms
64 bytes from 192.168.1.1: icmp_req=4 ttl=64 time=4.31 ms

--- 192.168.1.1 ping statistics ---
4 packets transmitted, 4 received, 0% packet loss, time 3004ms
rtt min/avg/max/mdev = 1.881/2.867/4.314/0.993 ms
2014.10.02 08:17:20 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:17:20 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,1844CDAD,0007,01

2014.10.02 08:17:20 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:1844CDAD IDcnt:0007
2014.10.02 08:17:44 4: HttpUtils url=http://weather.yahooapis.com/forecastrss?w=12832458&u=c
2014.10.02 08:17:45 4: http://weather.yahooapis.com/forecastrss?w=12832458&u=c: HTTP response code 200
2014.10.02 08:17:45 4: HttpUtils http://weather.yahooapis.com/forecastrss?w=12832458&u=c: Got data, length: 2843
2014.10.02 08:17:45 4: Weather MeinWetter: T: 14 H: 100 W: 5
2014.10.02 08:17:45 5: Triggering MeinWetter (48 changes)
2014.10.02 08:17:45 5: Notify loop for MeinWetter wind_direction: 0
2014.10.02 08:17:45 5: HMLAN_Send: HMLAN1 I:K
2014.10.02 08:17:45 5: HMLAN/RAW: /HHM-LAN-IF,03C4,LEQ0101488,26EC89,26EC89,18452F5A,0007,01

2014.10.02 08:17:45 5: HMLAN_Parse: HMLAN1 V:03C4 sNo:LEQ0101488 d:26EC89 O:26EC89 t:18452F5A IDcnt:0007
2014.10.02 08:17:54 5: SYSMON Update
2014.10.02 08:17:55 5: SYSMON SYSMON_getNetworkInfo get eth0:eth0:Ethernet
2014.10.02 08:17:55 5: SYSMON SYSMON_getNetworkInfo get wlan0:wlan0:WiFi
2014.10.02 08:17:55 5: Triggering sysmon (6 changes)
2014.10.02 08:17:55 5: Notify loop for sysmon cpu_freq: 700
2014.10.02 08:17:57 1: Timeout for PRESENCE_DoLocalBluetoothScan reached, terminated process 3642
2014.10.02 08:18:00 1: Timeout for PRESENCE_DoLocalPingScan reached, terminated process 3695
2014.10.02 08:18:00 5: Triggering FritzBox7490 (1 changes)
2014.10.02 08:18:00 5: Notify loop for FritzBox7490 timeout
2014.10.02 08:18:07 5: PRESENCE (Bluetooth) - stopping timer
2014.10.02 08:18:07 5: PRESENCE (Bluetooth) - starting blocking call for mode local-bluetooth
2014.10.02 08:18:07 4: BlockingCall created child (4628), uses telnetForBlockingFn to connect back
2014.10.02 08:18:07 5: PRESENCE (Bluetooth): found standard variant of ps command, using "ax" as parameter
2014.10.02 08:18:07 4: PRESENCE (Bluetooth): 'which hcitool' returns: /usr/bin/hcitool

2014.10.02 08:18:08 4: PRESENCE (Bluetooth)

Kampfgnom · 05 Oktober 2014, 20:49:09

Noch Irgend jemand eine Idee ?

Markus Bloch · 05 Oktober 2014, 21:31:22

Da ich bis Ende kommende Woche noch immer unterwegs bin, kann ich die Logs nicht durchsehen. Erst am kommenden Wochenende bin ich wieder zuhause und kann das in Ruhe testen und nachvollziehen

Kampfgnom · 05 Oktober 2014, 21:32:35

Alles klar. Vielen Dank.

Markus Bloch · 07 Oktober 2014, 23:17:51

Bitte kopiere mal die angehangene Version von Blocking.pm in dein Modulverzeichnis. Ich habe diese Version mit etwas Logging aufgebohrt.

Bitte poste dann nochmal einen größeren Auszug aus deinem Log.

EDIT: verbose 5 kannst du im global dann wieder auf 3 zurückschrauben, da diese Version alles auf Loglevel 1 ausgibt, das spart dann unnötige Logmeldungen

Vielen Dank

Gruß
Markus

Kampfgnom · 08 Oktober 2014, 00:16:35

Nabend,

ich habe das Modul eingespielt und den FHEM Server neu gestartet und poste morgen früh den log Auszug.

Schönen Abend noch !

Kampfgnom · 08 Oktober 2014, 09:56:41

Moin Moin,

ich habe einmal ein paar Stunden auf dem Logfile in die angehängte Datei kopiert.

Vielleicht finden Sie ja den Fehler.

Schönen Tag

Markus Bloch · 08 Oktober 2014, 21:43:17

der Grund für den Fehler ist:

Code Auswählen

2014.10.08 00:03:46 1: BlockingInformParent: Can't connect to localhost:7072: IO::Socket::INET: connect: Connection timed out

Es lässt sich keine loopback-Verbindung aufbauen. Jetzt ist die Frage warum.

Bitte ändere mal Zeile 118 in Blocking.pm in:

Code Auswählen

my $addr = "127.0.0.1:$defs{$telnetDevice}{PORT}";

Markus Bloch · 08 Oktober 2014, 21:47:57

Interessant währe es auch, wenn du apptime mitlaufen lässt.

Dazu einfach im Web Interface oben in der Kommandozeile apptime eintippen und auf enter. Dann wird im Hintergrund aufgezeichnet wie lange welcher Prozess innerhalb FHEM dauert.

Wenn du dann nochmal ein Log abziehst, einfach nochmal apptime eingeben und es erscheint eine Tabelle. Diese dann bitte auch posten.

Gruß
Markus

Kampfgnom · 08 Oktober 2014, 21:53:15

Nabend,

habe die betreffende Zeile in der Blocking.pm geändert.

Wie lang soll der Log Auszug sein ?

Markus Bloch · 08 Oktober 2014, 21:55:43

so das 2-3 Aussetzer dabei sind.

Kampfgnom · 08 Oktober 2014, 22:36:02

Nabend,

anbei das Log, und die Ausgabe von APPTIME!

Alex

Markus Bloch · 08 Oktober 2014, 22:56:57

Die Änderungen werden erst nach einem Neustart wirksam. Hab ich vergessen zu erwähnen. Sry

Markus Bloch · 08 Oktober 2014, 22:59:44

Das apptime sieht soweit gut aus.

Kampfgnom · 08 Oktober 2014, 23:02:41

Ich bin einfach einmal davon ausgegangen das ich den fhem Server neu Starten muss.

Ich habe jetzt nur Shutdown Restart genommen aber das sollte ja langen. Den ganz Pi muss ich wohl nicht neu starten oder ?

Markus Bloch · 08 Oktober 2014, 23:03:42

nein, nein, das reicht. Mal schauen was das Log nun sagt.

Kampfgnom · 08 Oktober 2014, 23:12:07

Ich bin denn mal gespannt!

Schönen Abend noch

Kampfgnom · 09 Oktober 2014, 17:35:42

Nabend $:-\$

erst einmal vielen Dank für den Support hier !
Bitte steinigt mich jetzt nicht gleich.

Da ich mit Linux doch ein wenig auf Kriegsfuß stehe habe ich den Fehler selbst gestrickt.

Ich habe in der Netzwerkkonfig /etc/network/interfaces in der ersten Zeile einen Fehler eingebaut.

Statt "auto lo" hatte ich leider "auto Lo" stehen. daran hat es gelegen.

Sch.... Case Sensitive

Ich bin halt nur ein Windows Admin ;-(

Also vielen vielen Dank an Markus Bloch für Deine Geduld und mühe !

Markus Bloch · 09 Oktober 2014, 23:47:29

Hallo Alex,

das freut mich zu hören. Kein Problem, es ist noch kein Meister vom Himmel gefallen.

Ich habe im Development-Bereich eine kleine Änderung im Blocking.pm angeregt, damit solche Sachen einfacher erkannt werden können und auch eine Logmeldung erscheint, wenn er sich nicht via loopback verbinden kann.

Viele Grüße

Markus