fhem startet Prozesse die nicht beendet werden (solved)

Begonnen von RockSteadyBeat, 11 Oktober 2016, 17:45:40

Vorheriges Thema - Nächstes Thema

RockSteadyBeat

Hallo zusammen,

meine fhem-Installation produziert seit dem update auf 10.12 durch das PRESENCE und das FRITZBOX Modul ständig neue Prozesse, die nicht beendet werden.
Ich habe bereits in global attr blockingCallMax auf 10 gesetzt, trotz allem wird fleißig geöffnet...
Erst wenn ich die PRESENCE und FRITZBOX Definitionen deaktiviere werden keine zusätzlichen Prozesse mehr geöffnet, kann das jemand bestätigen oder hat vielleicht einen Lösungsansatz?

aktuell läuft fhem auf der perl5.16 Version...
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Kannst du bitte ein Log mit "attr global verbose 4" hier anhaengen?

RockSteadyBeat

Hallo Rudolf,

gern, hier sind 6min mit eingeschalteten FRITZBOX-Modul, den refresh des Moduls habe ich auf 60sec gestellt, damit auch was kommt...  ;)


2016.10.11 20:38:39 4: WEB_192.168.253.28_64477 GET /fhem?detail=global&fw_id=; BUFLEN:0
2016.10.11 20:38:39 4: name: /fhem?detail=global&fw_id= / RL:4242 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:38:40 4: WEB_192.168.253.28_64477 GET /fhem?cmd={AttrVal(%22global%22,%22room%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:38:40 4: name: /fhem?cmd={AttrVal(%22global%22,%22room%22,%22%22)}&XHR=1 / RL:27 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:38:40 4: WEB_192.168.253.28_64477 GET /fhem?XHR=1&inform=type=status;filter=global;since=1476211118;fmt=JSON&fw_id=145×tamp=1476211122455; BUFLEN:0
2016.10.11 20:38:40 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:38:40 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:38:40 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:38:40 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:38:40 4: parse status message for HUEDevice1
2016.10.11 20:38:40 4: parse status message for HUEDevice3
2016.10.11 20:38:40 4: parse status message for HUEDevice2
2016.10.11 20:38:41 4: WEB_192.168.253.28_64478 POST /fhem?cmd=save&XHR=1&fw_id=145; BUFLEN:0
2016.10.11 20:38:41 4: name: /fhem?cmd=save&XHR=1&fw_id=145 / RL:52 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:38:47 4: WEB_192.168.253.28_64478 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 20:38:48 4: WEB_192.168.253.28_64478 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476211126;fmt=JSON&fw_id=146×tamp=1476211131190; BUFLEN:0
2016.10.11 20:39:07 4: Connection closed for WEB_192.168.253.28_64477: EOF
2016.10.11 20:39:07 4: WEB_192.168.253.28_64479 GET /fhem?room=fhem%5fdevices; BUFLEN:0
2016.10.11 20:39:07 4: name: /fhem?room=fhem%5fdevices / RL:34527 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:07 4: WEB_192.168.253.28_64479 GET /fhem?XHR=1&inform=type=status;filter=room=fhem%5fdevices;since=1476211146;fmt=JSON&fw_id=148×tamp=1476211147642; BUFLEN:0
2016.10.11 20:39:08 4: Connection closed for WEB_192.168.253.28_64479: EOF
2016.10.11 20:39:08 4: WEB_192.168.253.28_64480 GET /fhem?detail=fritzbox; BUFLEN:0
2016.10.11 20:39:08 4: name: /fhem?detail=fritzbox / RL:5220 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:08 4: WEB_192.168.253.28_64480 GET /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:39:08 4: name: /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:08 4: WEB_192.168.253.28_64480 GET /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:39:08 4: name: /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1 / RL:33 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:08 4: WEB_192.168.253.28_64480 GET /fhem?XHR=1&inform=type=status;filter=fritzbox;since=1476211147;fmt=JSON&fw_id=147×tamp=1476211148865; BUFLEN:0
2016.10.11 20:39:13 4: using HttpUtils_NonblockingGet: GET
2016.10.11 20:39:13 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9
2016.10.11 20:39:13 4: Connection accepted from WEB_192.168.253.28_64488
2016.10.11 20:39:13 4: WEB_192.168.253.28_64488 GET /fhem?cmd={AttrVal(%22fritzbox%22,%22disable%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:39:13 4: name: /fhem?cmd={AttrVal(%22fritzbox%22,%22disable%22,%22%22)}&XHR=1 / RL:22 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:13 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9: HTTP response code 200
2016.10.11 20:39:13 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9: Got data, length: 3908
2016.10.11 20:39:13 4: parse status message for HuE
2016.10.11 20:39:13 4: HuE: message for unknow sensor received: HuE-S1
2016.10.11 20:39:13 4: parse status message for HUEDevice1
2016.10.11 20:39:13 4: parse status message for HUEDevice3
2016.10.11 20:39:13 4: parse status message for HUEDevice2
2016.10.11 20:39:16 4: Connection closed for WEB_192.168.253.28_64480: EOF
2016.10.11 20:39:16 4: WEB_192.168.253.28_64488 POST /fhem&detail=fritzbox&dev.attrfritzbox=fritzbox&cmd.attrfritzbox=attr&arg.attrfritzbox=disable&val.attrfritzbox=0; BUFLEN:0
2016.10.11 20:39:16 4: WEB_192.168.253.28_64488 GET /fhem?detail=fritzbox&fw_id=; BUFLEN:0
2016.10.11 20:39:16 4: name: /fhem?detail=fritzbox&fw_id= / RL:5240 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:16 4: WEB_192.168.253.28_64488 GET /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:39:16 4: name: /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:16 4: Connection accepted from WEB_192.168.253.28_64489
2016.10.11 20:39:16 4: WEB_192.168.253.28_64489 GET /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 20:39:16 4: name: /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1 / RL:33 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:16 4: WEB_192.168.253.28_64488 GET /fhem?XHR=1&inform=type=status;filter=fritzbox;since=1476211155;fmt=JSON&fw_id=149×tamp=1476211156223; BUFLEN:0
2016.10.11 20:39:18 4: WEB_192.168.253.28_64489 POST /fhem?cmd=save&XHR=1&fw_id=149; BUFLEN:0
2016.10.11 20:39:18 4: name: /fhem?cmd=save&XHR=1&fw_id=149 / RL:52 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 20:39:23 4: BlockingCall (FRITZBOX_API_Check_Run): created child (23985), uses tPortLocal to connect back
2016.10.11 20:39:23 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_API_Check_Run
2016.10.11 20:39:23 4: FRITZBOX fritzbox: API_Check_Run.720 FRITZBOX modul runs in remote mode.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.740 API webcm does not exist (404 Not Found)
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.748 API luaQuery found (200).
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.764 API TR-064 found.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: TR064_Init.4295 Open TR-064 connection and ask for security port
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.769 TR-064-SecurePort is 49443.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.791 Created m3u file './www/images/fritzbox.m3u'.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.799 Try to get my IP address.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.805 Try to get a FHEMWEB port.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.817 Try to get 'http://192.168.253.254:8083/fhem/images/fritzbox.m3u'
2016.10.11 20:39:24 4: Connection accepted from WEB_192.168.253.254_49810
2016.10.11 20:39:24 4: WEB_192.168.253.254_49810 GET /fhem/images/fritzbox.m3u; BUFLEN:0
2016.10.11 20:39:24 4: Connection closed for WEB_192.168.253.254_49810: EOF
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.887 Could not open telnet connection to 192.168.253.1: Connection refused
2016.10.11 20:39:24 4: FRITZBOX fritzbox: API_Check_Run.901 Captured 14 values
2016.10.11 20:39:24 4: Connection accepted from tPortLocal_127.0.0.1_49813
2016.10.11 20:39:24 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:39:24 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 13 readouts.
2016.10.11 20:39:24 4: FRITZBOX fritzbox: Readout_Process.1743 13 values captured in 1.73 s
2016.10.11 20:39:33 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (24001), uses tPortLocal to connect back
2016.10.11 20:39:33 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 20:39:33 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 20:39:33 4: FRITZBOX fritzbox: Web_OpenCon.4350 Open Web connection to 192.168.253.1
2016.10.11 20:39:33 4: HttpUtils url=http://192.168.253.1/login_sid.lua
2016.10.11 20:39:33 4: http://192.168.253.1/login_sid.lua: HTTP response code 200
2016.10.11 20:39:33 4: HttpUtils http://192.168.253.1/login_sid.lua: Got data, length: 165
2016.10.11 20:39:33 4: HttpUtils url=http://192.168.253.1/login_sid.lua?username=fhem
2016.10.11 20:39:33 4: http://192.168.253.1/login_sid.lua?username=fhem: HTTP response code 200
2016.10.11 20:39:33 4: HttpUtils http://192.168.253.1/login_sid.lua?username=fhem: Got data, length: 348
2016.10.11 20:39:33 4: FRITZBOX fritzbox: Web_OpenCon.4354 Web session opened with 653af0a47c3aa352
2016.10.11 20:39:34 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 20:39:34 4: Connection accepted from tPortLocal_127.0.0.1_49820
2016.10.11 20:39:34 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:39:34 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 20:39:34 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 1.02 s
2016.10.11 20:39:34 4: olli_iphone exec {
if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
{     
fhem("set rr_Olli absent")
}
  else
  {
    fhem("set rr_Olli home")
  }
}
2016.10.11 20:39:34 2: ROOMMATE set rr_Olli home
2016.10.11 20:39:34 4: ROOMMATE rr_Olli: implicit mood change caused by state home
2016.10.11 20:39:34 4: ROOMMATE rr_Olli: implicit location change caused by state home
2016.10.11 20:39:34 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 20:39:34 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 20:39:43 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:39:43 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:39:43 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:39:43 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:39:43 4: parse status message for HUEDevice1
2016.10.11 20:39:43 4: parse status message for HUEDevice3
2016.10.11 20:39:43 4: parse status message for HUEDevice2
2016.10.11 20:39:50 4: Connection closed for WEB_192.168.253.28_64489: EOF
2016.10.11 20:40:16 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:40:16 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:40:16 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:40:16 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:40:16 4: parse status message for HUEDevice1
2016.10.11 20:40:16 4: parse status message for HUEDevice3
2016.10.11 20:40:16 4: parse status message for HUEDevice2
2016.10.11 20:40:18 1: Timeout for FRITZBOX_API_Check_Run reached, terminated process 23985
2016.10.11 20:40:18 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 20:40:28 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 24001
2016.10.11 20:40:28 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 20:40:33 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (24030), uses tPortLocal to connect back
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 20:40:33 4: Connection accepted from tPortLocal_127.0.0.1_49854
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 20:40:33 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.71 s
2016.10.11 20:40:33 4: olli_iphone exec {
if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
{     
fhem("set rr_Olli absent")
}
  else
  {
    fhem("set rr_Olli home")
  }
}
2016.10.11 20:40:33 2: ROOMMATE set rr_Olli home
2016.10.11 20:40:33 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 20:40:33 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 20:40:46 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:40:46 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:40:46 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:40:46 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:40:46 4: parse status message for HUEDevice1
2016.10.11 20:40:46 4: parse status message for HUEDevice3
2016.10.11 20:40:46 4: parse status message for HUEDevice2
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/playstatusupdate?revision-number=1&session-id=204431471: Got data, length: 307
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/getproperty?properties=dmcp.volume&session-id=204431471: Got data, length: 32
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: Got data, length: 0
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: Zero length data, header follows...
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: HTTP/1.1 204 No Content
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: Date: Tue, 11 Oct 2016 18:41:13 GMT
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: DAAP-Server: iTunes/12.5.1.21 (OS X)
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: Content-Type: application/x-dmap-tagged
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=204431471: Content-Length: 0
2016.10.11 20:41:13 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/getspeakers?session-id=204431471: Got data, length: 371
2016.10.11 20:41:19 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:41:19 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:41:19 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:41:19 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:41:19 4: parse status message for HUEDevice1
2016.10.11 20:41:19 4: parse status message for HUEDevice3
2016.10.11 20:41:19 4: parse status message for HUEDevice2
2016.10.11 20:41:28 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 24030
2016.10.11 20:41:28 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 20:41:33 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (24040), uses tPortLocal to connect back
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 20:41:33 4: Connection closed for WEB_192.168.253.28_64478: EOF
2016.10.11 20:41:33 4: Connection accepted from WEB_192.168.253.28_64547
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: Connection accepted from WEB_192.168.253.28_64549
2016.10.11 20:41:33 4: Connection accepted from WEB_192.168.253.28_64548
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/style.css?v=1476201061; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:41:33 4: Connection accepted from WEB_192.168.253.28_64550
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:41:33 4: Connection accepted from WEB_192.168.253.28_64551
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64551 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64551 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64551 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64551 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 20:41:33 4: WEB_192.168.253.28_64547 => 304 Not Modified
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 20:41:33 4: Connection accepted from tPortLocal_127.0.0.1_49888
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 20:41:33 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.67 s
2016.10.11 20:41:33 4: olli_iphone exec {
if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
{     
fhem("set rr_Olli absent")
}
  else
  {
    fhem("set rr_Olli home")
  }
}
2016.10.11 20:41:33 2: ROOMMATE set rr_Olli home
2016.10.11 20:41:33 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 20:41:33 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 20:41:34 4: WEB_192.168.253.28_64547 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476211292;fmt=JSON&fw_id=155×tamp=1476211294000; BUFLEN:0
2016.10.11 20:41:49 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:41:49 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:41:49 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:41:49 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:41:49 4: parse status message for HUEDevice1
2016.10.11 20:41:49 4: parse status message for HUEDevice3
2016.10.11 20:41:49 4: parse status message for HUEDevice2
2016.10.11 20:42:14 4: Connection closed for WEB_192.168.253.28_64547: EOF
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/style.css?v=1476201061; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64550 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: Connection accepted from WEB_192.168.253.28_64555
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64549 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64555 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64555 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64551 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 => 304 Not Modified
2016.10.11 20:42:14 4: WEB_192.168.253.28_64548 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476211333;fmt=JSON&fw_id=157×tamp=1476211334736; BUFLEN:0
2016.10.11 20:42:22 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:42:22 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:42:22 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:42:22 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:42:22 4: parse status message for HUEDevice1
2016.10.11 20:42:22 4: parse status message for HUEDevice3
2016.10.11 20:42:22 4: parse status message for HUEDevice2
2016.10.11 20:42:28 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 24040
2016.10.11 20:42:28 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 20:42:33 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (24051), uses tPortLocal to connect back
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 20:42:33 4: Connection accepted from tPortLocal_127.0.0.1_49900
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 20:42:33 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.67 s
2016.10.11 20:42:33 4: olli_iphone exec {
if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
{     
fhem("set rr_Olli absent")
}
  else
  {
    fhem("set rr_Olli home")
  }
}
2016.10.11 20:42:33 2: ROOMMATE set rr_Olli home
2016.10.11 20:42:33 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 20:42:33 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 20:42:52 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:42:52 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:42:52 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:42:52 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:42:52 4: parse status message for HUEDevice1
2016.10.11 20:42:52 4: parse status message for HUEDevice3
2016.10.11 20:42:52 4: parse status message for HUEDevice2
2016.10.11 20:42:54 4: Connection closed for WEB_192.168.253.28_64549: EOF
2016.10.11 20:42:54 4: Connection closed for WEB_192.168.253.28_64555: EOF
2016.10.11 20:42:54 4: Connection closed for WEB_192.168.253.28_64550: EOF
2016.10.11 20:42:54 4: Connection closed for WEB_192.168.253.28_64551: EOF
2016.10.11 20:43:25 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:43:25 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:43:25 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:43:25 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:43:25 4: parse status message for HUEDevice1
2016.10.11 20:43:25 4: parse status message for HUEDevice3
2016.10.11 20:43:25 4: parse status message for HUEDevice2
2016.10.11 20:43:28 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 24051
2016.10.11 20:43:28 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 20:43:33 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (24077), uses tPortLocal to connect back
2016.10.11 20:43:33 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 20:43:33 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 20:43:33 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 20:43:34 4: Connection accepted from tPortLocal_127.0.0.1_49914
2016.10.11 20:43:34 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 20:43:34 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 20:43:34 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.75 s
2016.10.11 20:43:34 4: olli_iphone exec {
if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
{     
fhem("set rr_Olli absent")
}
  else
  {
    fhem("set rr_Olli home")
  }
}
2016.10.11 20:43:34 2: ROOMMATE set rr_Olli home
2016.10.11 20:43:34 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 20:43:34 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 20:43:55 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 20:43:55 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 20:43:55 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 20:43:55 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 20:43:55 4: parse status message for HUEDevice1
2016.10.11 20:43:55 4: parse status message for HUEDevice3
2016.10.11 20:43:55 4: parse status message for HUEDevice2
2016.10.11 20:44:09 4: Connection closed for WEB_192.168.253.28_64548: EOF
2016.10.11 20:44:09 4: Connection accepted from WEB_192.168.253.28_64562
2016.10.11 20:44:09 4: WEB_192.168.253.28_64562 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Ich habe hier 6 Starts gesehen, grob einmal die Minute, weiss nicht, ob das ok ist oder nicht.
Kannst du bitte folgende Zeile
Log 1, "BC: MAX:$max / ALIVE:$chld_alive";
in FHEM/Blocking.pm, Zeile 110 einbauen (zwischen den beiden if Bloecken), FHEM stoppen, alle Kindprozesse terminieren, FHEM neu starten, und die ALIVE Werte mit der Anzahl der laufenden Prozessen vergleichen? Und bei Problemen das verbose 4 log hier anhaengen.

RockSteadyBeat

Ja, die 6 Starts sind gewollt, da ich für das Log was ich Dir präsentiert habe möglichst viele Einträge wollte...

ich habe deine Anpassung an das Log gemacht, meines Erachtens passt da aber was nicht, BC wird korrekt mit 5 angegeben, ALIVE jedoch zeigt ständig 0 und das stimmt nicht mit htop überein...
(im Anhang ein kleiner Screenshot der Kindprozesse)

hier das aktuelle Log[code]
2016.10.11 21:24:56 3: [UtilsHourCounter] Init Done with Version 1.02 - 17.03.2014 (john)
2016.10.11 21:24:58 1: Including fhem.cfg
2016.10.11 21:24:58 3: telnetPort: port 7072 opened
2016.10.11 21:24:58 3: WEB: port 8083 opened
2016.10.11 21:24:58 3: WEBphone: port 8084 opened
2016.10.11 21:24:58 3: WEBtablet: port 8085 opened
2016.10.11 21:24:58 3: WEBui: port 8086 opened
2016.10.11 21:24:59 3: WEBserver: port 8087 opened
2016.10.11 21:24:59 2: eventTypes: loaded 1177 events from ./log/eventTypes.txt
2016.10.11 21:24:59 3: Opening CUL_0 device /dev/tty.usbmodem1421
2016.10.11 21:25:00 3: CUL_0: Possible commands: BbCFiAZEGMKUYRTVWXefmltux
2016.10.11 21:25:00 3: CUL_0 device opened
2016.10.11 21:25:00 1: HMLAN_Parse: hmlan1 new condition disconnected
2016.10.11 21:25:00 3: Opening hmlan1 device 192.168.253.246:1000
2016.10.11 21:25:00 1: HMLAN_Parse: hmlan1 new condition init
2016.10.11 21:25:00 3: hmlan1 device opened
2016.10.11 21:25:00 3: HUEDevice3: I/O device is HuE
2016.10.11 21:25:00 3: HUEDevice1: I/O device is HuE
2016.10.11 21:25:00 3: HUEDevice2: I/O device is HuE
2016.10.11 21:25:00 3: HUEGroup0: I/O device is HuE
2016.10.11 21:25:01 4: define wifi_LED WifiLight RGB LW12:192.168.253.245
2016.10.11 21:25:01 4: wifi_LED attrib userattr set building building_map lightSceneParamsToSave lightSceneRestoreOnlyIfChanged:1,0 room_map structexclude
2016.10.11 21:25:01 4: wifi_LED attrib alias set Bett LED
2016.10.11 21:25:01 4: wifi_LED attrib building set homeLights
2016.10.11 21:25:01 4: wifi_LED attrib colorCast set 0, -20, -20, -25, 0, -10
2016.10.11 21:25:01 4: wifi_LED attrib fp_Roonstrasse set 141,346,7, ,wifi_LED
2016.10.11 21:25:01 4: wifi_LED attrib genericDeviceType set light
2016.10.11 21:25:01 4: wifi_LED attrib group set Licht
2016.10.11 21:25:01 4: wifi_LED attrib icon set light_led_stripe_rgb
2016.10.11 21:25:01 4: wifi_LED attrib room set Schlafzimmer,color_devices,homekit_devices
2016.10.11 21:25:01 4: wifi_LED attrib webCmd set RGB:on:off
2016.10.11 21:25:01 4: wifi_LED attrib whitePoint set 1, 0.75, 0.25
2016.10.11 21:25:01 4: wifi_LED attrib widgetOverride set RGB:colorpicker,RGB
2016.10.11 21:25:01 4: wifi_LED attrib building set homeLights
2016.10.11 21:25:01 4: wifi_LED attrib building set homeLights
2016.10.11 21:25:01 4: wifi_LED attrib widgetOverride set RGB:colorpicker,RGB
2016.10.11 21:25:01 4: RESIDENTS roonstr4: rr_Olli registered
2016.10.11 21:25:01 4: ROOMMATE rr_Olli: Registered at RESIDENTS device roonstr4
2016.10.11 21:25:01 4: RESIDENTS roonstr4: rr_Mone registered
2016.10.11 21:25:01 4: ROOMMATE rr_Mone: Registered at RESIDENTS device roonstr4
2016.10.11 21:25:03 3: tPortLocal: port 7073 opened
2016.10.11 21:25:03 1: Including ./log/fhem.save
2016.10.11 21:25:03 3: Device HM_1F4D86 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_1F4D86 is alive
2016.10.11 21:25:03 3: Device HM_222623 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_222623 is alive
2016.10.11 21:25:03 3: Device HM_224169 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_224169 is alive
2016.10.11 21:25:03 3: Device HM_2483BD added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_2483BD is alive
2016.10.11 21:25:03 3: Device HM_249163 added to ActionDetector with 000:20 time
2016.10.11 21:25:03 4: Device HM_249163 is alive
2016.10.11 21:25:03 3: Device HM_251579 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_251579 is alive
2016.10.11 21:25:03 3: Device HM_25F9C4 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_25F9C4 is alive
2016.10.11 21:25:03 3: Device HM_2C7B82 added to ActionDetector with 000:10 time
2016.10.11 21:25:03 4: Device HM_2C7B82 is alive
2016.10.11 21:25:03 4: HttpUtils url=http://192.168.253.251/description.xml
2016.10.11 21:25:03 4: http://192.168.253.251/description.xml: HTTP response code 200
2016.10.11 21:25:03 4: HttpUtils http://192.168.253.251/description.xml: Got data, length: 1079
2016.10.11 21:25:03 4: using HttpUtils_BlockingGet: GET config
2016.10.11 21:25:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/config
2016.10.11 21:25:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/config: HTTP response code 200
2016.10.11 21:25:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/config: Got data, length: 1762
2016.10.11 21:25:03 4: using HttpUtils_NonblockingGet: GET
2016.10.11 21:25:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9
2016.10.11 21:25:03 4: using HttpUtils_BlockingGet: GET lights
2016.10.11 21:25:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:25:07 2: HuE: empty answer received for http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:25:07 4: using HttpUtils_BlockingGet: GET groups
2016.10.11 21:25:07 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups
2016.10.11 21:25:08 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups: HTTP response code 200
2016.10.11 21:25:08 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups: Got data, length: 2
2016.10.11 21:25:08 3: fhemMailCheck: connected to imap.strato.de
2016.10.11 21:25:08 3: fhemMailCheck: logged in to fhem@os-idevice.de
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/login?pairing-guid=0x33eeb3bf60ebd6c1: Got data, length: 32
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/server-info?session-id=1354394481: Got data, length: 434
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/databases?session-id=1354394481: Got data, length: 210
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/playstatusupdate?revision-number=1&session-id=1354394481: Got data, length: 307
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/getproperty?properties=dmcp.volume&session-id=1354394481: Got data, length: 32
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Got data, length: 0
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Zero length data, header follows...
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: HTTP/1.1 204 No Content
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Date: Tue, 11 Oct 2016 19:25:08 GMT
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: DAAP-Server: iTunes/12.5.1.21 (OS X)
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Content-Type: application/x-dmap-tagged
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Content-Length: 0
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/getspeakers?session-id=1354394481: Got data, length: 371
2016.10.11 21:25:08 4: iTunes: updater connected to 127.0.0.1:3689
2016.10.11 21:25:08 4: initialUsbCheck exec usb create
2016.10.11 21:25:08 1: usb create starting
2016.10.11 21:25:08 4: ### cu.usbmodem1421: checking if it is a CUL
2016.10.11 21:25:08 3: Probing CUL device /dev/cu.usbmodem1421
2016.10.11 21:25:08 3: Can't open /dev/cu.usbmodem1421: Resource busy
2016.10.11 21:25:08 4: cannot open the device
2016.10.11 21:25:08 1: usb create end
2016.10.11 21:25:08 4: wifi_LED RGB LW12 set h:13, s:65, v:0
2016.10.11 21:25:08 2: SecurityCheck:  WEB,WEBphone,WEBserver,WEBtablet,WEBui has no associated allowed device with basicAuth. tPortLocal,telnetPort has no associated allowed device with password/globalpassword.  Restart FHEM for a new check if the problem is fixed, or set the global attribute motd to none to supress this message.
2016.10.11 21:25:08 0: Featurelevel: 5.7
2016.10.11 21:25:08 0: Server started with 123 defined entities (fhem.pl:12311/2016-10-10 perl:5.016000 os:darwin user:itunesos-idevice pid:25079)
2016.10.11 21:25:08 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:25:08 4: BlockingCall (FRITZBOX_API_Check_Run): created child (25085), uses tPortLocal to connect back
2016.10.11 21:25:08 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_API_Check_Run
2016.10.11 21:25:08 4: FRITZBOX fritzbox: API_Check_Run.720 FRITZBOX modul runs in remote mode.
2016.10.11 21:25:08 3: CUL_HM set HM_2BEC70_Sw statusRequest
2016.10.11 21:25:08 4: Connection accepted from WEB_192.168.253.28_64813
2016.10.11 21:25:08 1: HMLAN_Parse: hmlan1 new condition ok
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Got data, length: 0
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Zero length data, header follows...
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: HTTP/1.1 204 No Content
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Date: Tue, 11 Oct 2016 19:25:08 GMT
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: DAAP-Server: iTunes/12.5.1.21 (OS X)
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Content-Type: application/x-dmap-tagged
2016.10.11 21:25:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/nowplayingartwork?mw=320&mh=320&session-id=1354394481: Content-Length: 0
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.740 API webcm does not exist (404 Not Found)
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.748 API luaQuery found (200).
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.764 API TR-064 found.
2016.10.11 21:25:09 4: FRITZBOX fritzbox: TR064_Init.4295 Open TR-064 connection and ask for security port
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.769 TR-064-SecurePort is 49443.
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.791 Created m3u file './www/images/fritzbox.m3u'.
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.799 Try to get my IP address.
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.805 Try to get a FHEMWEB port.
2016.10.11 21:25:09 4: FRITZBOX fritzbox: API_Check_Run.817 Try to get 'http://192.168.253.254:8083/fhem/images/fritzbox.m3u'
2016.10.11 21:25:11 3: CUL_HM set HM_2BEC70_Sw1_V_01 statusRequest
2016.10.11 21:25:11 4: using HttpUtils_NonblockingGet: GET lights/3
2016.10.11 21:25:11 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/3
2016.10.11 21:25:11 4: using HttpUtils_NonblockingGet: GET lights/1
2016.10.11 21:25:11 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/1
2016.10.11 21:25:11 4: using HttpUtils_NonblockingGet: GET lights/2
2016.10.11 21:25:11 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/2
2016.10.11 21:25:11 4: using HttpUtils_NonblockingGet: GET groups/0
2016.10.11 21:25:11 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups/0
2016.10.11 21:25:11 4: Connection accepted from WEB_192.168.253.28_64814
2016.10.11 21:25:12 4: WEB_192.168.253.28_64813 GET /fhem?XHR=1&inform=type=status;filter=fritzbox;since=1476213661.362;fmt=JSON&fw_id=149×tamp=1476213900933; BUFLEN:0
2016.10.11 21:25:12 4: CUL_HM HM_2BEC70 dupe: dont process
2016.10.11 21:25:12 4: iTunes: updater connected to 127.0.0.1:3689
2016.10.11 21:25:12 2: HuE: http request failed: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9: empty answer received
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.254_50933
2016.10.11 21:25:12 4: Connection closed for WEB_192.168.253.28_64813: EOF
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem?room=fhem%5fdevices; BUFLEN:0
2016.10.11 21:25:12 4: name: /fhem?room=fhem%5fdevices / RL:34528 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 21:25:12 4: WEB_192.168.253.254_50933 GET /fhem/images/fritzbox.m3u; BUFLEN:0
2016.10.11 21:25:12 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/3: HTTP response code 200
2016.10.11 21:25:12 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/3: Got data, length: 309
2016.10.11 21:25:12 4: parse status message for HUEDevice3
2016.10.11 21:25:12 4: FRITZBOX fritzbox: API_Check_Run.887 Could not open telnet connection to 192.168.253.1: Connection refused
2016.10.11 21:25:12 4: FRITZBOX fritzbox: API_Check_Run.901 Captured 14 values
2016.10.11 21:25:12 4: Connection accepted from tPortLocal_127.0.0.1_50945
2016.10.11 21:25:12 4: Connection closed for WEB_192.168.253.254_50933: EOF
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.28_64815
2016.10.11 21:25:12 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/1: HTTP response code 200
2016.10.11 21:25:12 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/1: Got data, length: 226
2016.10.11 21:25:12 4: parse status message for HUEDevice1
2016.10.11 21:25:12 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups/0: HTTP response code 200
2016.10.11 21:25:12 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/groups/0: Got data, length: 247
2016.10.11 21:25:12 4: parse status message for HUEGroup0
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.28_64816
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 => 304 Not Modified
2016.10.11 21:25:12 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:25:12 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 13 readouts.
2016.10.11 21:25:12 4: FRITZBOX fritzbox: Readout_Process.1743 13 values captured in 3.74 s
2016.10.11 21:25:12 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/2: HTTP response code 200
2016.10.11 21:25:12 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights/2: Got data, length: 226
2016.10.11 21:25:12 4: parse status message for HUEDevice2
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 => 304 Not Modified
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.28_64817
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 => 304 Not Modified
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.28_64818
2016.10.11 21:25:12 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.28_64819
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 => 304 Not Modified
2016.10.11 21:25:12 4: CUL_HM HM_2BEC70 dupe: dont process
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64816 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64819 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64819 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64815 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 => 304 Not Modified
2016.10.11 21:25:12 4: WEB_192.168.253.28_64814 GET /fhem?XHR=1&inform=type=status;filter=room=fhem%5fdevices;since=1476213911;fmt=JSON&fw_id=137×tamp=1476213912660; BUFLEN:0
2016.10.11 21:25:12 3: CUL_HM set HM_2BEC70_Sw1_V_02 statusRequest
2016.10.11 21:25:12 4: Connection accepted from WEB_192.168.253.254_50946
2016.10.11 21:25:12 4: WEB_192.168.253.254_50946 GET /fhem.pl?XHR=1&inform=type=status;addglobal=1;filter=.*;since=1476213620.273;fmt=JSON×tamp=1476213912711; BUFLEN:0
2016.10.11 21:25:13 4: CUL_HM HM_2BEC70 dupe: dont process
2016.10.11 21:25:13 3: CUL_HM set HM_2CB52C_Sw statusRequest
2016.10.11 21:25:14 4: Connection closed for WEB_192.168.253.28_64814: EOF
2016.10.11 21:25:14 4: WEB_192.168.253.28_64815 GET /fhem?detail=fritzbox; BUFLEN:0
2016.10.11 21:25:14 4: name: /fhem?detail=fritzbox / RL:5460 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 21:25:14 3: CUL_HM set HM_2CB52C_Sw1_V_01 statusRequest
2016.10.11 21:25:14 4: WEB_192.168.253.28_64815 GET /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 21:25:14 4: name: /fhem?cmd={ReadingsVal(%22fritzbox%22,%22call%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 21:25:14 4: WEB_192.168.253.28_64816 GET /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1; BUFLEN:0
2016.10.11 21:25:14 4: name: /fhem?cmd={AttrVal(%22fritzbox%22,%22room%22,%22%22)}&XHR=1 / RL:33 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.10.11 21:25:14 4: WEB_192.168.253.28_64815 GET /fhem?XHR=1&inform=type=status;filter=fritzbox;since=1476213913;fmt=JSON&fw_id=140×tamp=1476213914865; BUFLEN:0
2016.10.11 21:25:15 4: WEB_192.168.253.28_64816 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476211448.154;fmt=JSON&fw_id=164×tamp=1476213915265; BUFLEN:0
2016.10.11 21:25:15 3: CUL_HM set HM_2CB52C_Sw1_V_02 statusRequest
2016.10.11 21:25:18 4: CUL_HM_Resend: HM_2CB52C nr 2
2016.10.11 21:25:18 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:25:18 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (25088), uses tPortLocal to connect back
2016.10.11 21:25:18 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 21:25:18 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 21:25:18 4: FRITZBOX fritzbox: Web_OpenCon.4350 Open Web connection to 192.168.253.1
2016.10.11 21:25:18 4: HttpUtils url=http://192.168.253.1/login_sid.lua
2016.10.11 21:25:18 4: http://192.168.253.1/login_sid.lua: HTTP response code 200
2016.10.11 21:25:18 4: HttpUtils http://192.168.253.1/login_sid.lua: Got data, length: 165
2016.10.11 21:25:18 4: HttpUtils url=http://192.168.253.1/login_sid.lua?username=fhem
2016.10.11 21:25:18 4: http://192.168.253.1/login_sid.lua?username=fhem: HTTP response code 200
2016.10.11 21:25:18 4: HttpUtils http://192.168.253.1/login_sid.lua?username=fhem: Got data, length: 348
2016.10.11 21:25:18 4: FRITZBOX fritzbox: Web_OpenCon.4354 Web session opened with e994cfbfd5f3005e
2016.10.11 21:25:19 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 21:25:19 4: Connection accepted from tPortLocal_127.0.0.1_50950
2016.10.11 21:25:19 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:25:19 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 21:25:19 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.99 s
2016.10.11 21:25:19 4: olli_iphone exec {
   if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
   {     
   fhem("set rr_Olli absent")
   }
     else
     {
    fhem("set rr_Olli home")
  }
}
2016.10.11 21:25:19 2: ROOMMATE set rr_Olli home
2016.10.11 21:25:19 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 21:25:19 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 21:25:22 4: Connection closed for WEB_192.168.253.28_64816: EOF
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:22 4: Connection accepted from WEB_192.168.253.28_64820
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64820 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64820 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:22 4: Connection accepted from WEB_192.168.253.28_64821
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64819 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64820 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64820 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64821 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64821 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64818 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 => 304 Not Modified
2016.10.11 21:25:22 4: WEB_192.168.253.28_64817 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476213921;fmt=JSON&fw_id=142×tamp=1476213922572; BUFLEN:0
2016.10.11 21:25:24 4: CUL_HM_Resend: HM_2CB52C nr 3
2016.10.11 21:25:28 4: CUL_HM_Resend: HM_2CB52C nr 4
2016.10.11 21:25:33 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:25:33 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:25:33 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:25:33 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:25:33 4: parse status message for HUEDevice1
2016.10.11 21:25:33 4: parse status message for HUEDevice3
2016.10.11 21:25:33 4: parse status message for HUEDevice2
2016.10.11 21:25:38 4: HMLAN_ack: timeout - clear queue
2016.10.11 21:26:03 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:26:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:26:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:26:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:26:03 4: parse status message for HUEDevice1
2016.10.11 21:26:03 4: parse status message for HUEDevice3
2016.10.11 21:26:03 4: parse status message for HUEDevice2
2016.10.11 21:26:03 1: Timeout for FRITZBOX_API_Check_Run reached, terminated process 25085
2016.10.11 21:26:03 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 21:26:10 4: Connection closed for WEB_192.168.253.28_64818: EOF
2016.10.11 21:26:10 4: Connection closed for WEB_192.168.253.28_64819: EOF
2016.10.11 21:26:10 4: Connection closed for WEB_192.168.253.28_64820: EOF
2016.10.11 21:26:10 4: Connection closed for WEB_192.168.253.28_64821: EOF
2016.10.11 21:26:16 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 25088
2016.10.11 21:26:16 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 21:26:18 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:26:18 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (25099), uses tPortLocal to connect back
2016.10.11 21:26:18 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 21:26:18 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 21:26:19 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 21:26:19 4: Connection accepted from tPortLocal_127.0.0.1_50966
2016.10.11 21:26:19 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:26:19 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 21:26:19 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.66 s
2016.10.11 21:26:19 4: olli_iphone exec {
   if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
   {     
   fhem("set rr_Olli absent")
   }
     else
     {
    fhem("set rr_Olli home")
  }
}
2016.10.11 21:26:19 2: ROOMMATE set rr_Olli home
2016.10.11 21:26:19 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 21:26:19 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 21:26:33 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:26:33 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:26:33 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:26:33 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:26:33 4: parse status message for HUEDevice1
2016.10.11 21:26:33 4: parse status message for HUEDevice3
2016.10.11 21:26:33 4: parse status message for HUEDevice2
2016.10.11 21:26:44 4: Connection closed for WEB_192.168.253.28_64817: EOF
2016.10.11 21:26:44 4: Connection accepted from WEB_192.168.253.28_64828
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: Connection accepted from WEB_192.168.253.28_64829
2016.10.11 21:26:44 4: Connection accepted from WEB_192.168.253.28_64830
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:26:44 4: Connection accepted from WEB_192.168.253.28_64831
2016.10.11 21:26:44 4: Connection accepted from WEB_192.168.253.28_64832
2016.10.11 21:26:44 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64831 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 => 304 Not Modified
2016.10.11 21:26:44 4: WEB_192.168.253.28_64828 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214003;fmt=JSON&fw_id=150×tamp=1476214004827; BUFLEN:0
2016.10.11 21:27:03 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:27:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:27:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:27:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:27:03 4: parse status message for HUEDevice1
2016.10.11 21:27:03 4: parse status message for HUEDevice3
2016.10.11 21:27:03 4: parse status message for HUEDevice2
2016.10.11 21:27:13 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 25099
2016.10.11 21:27:13 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 21:27:19 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:27:19 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (25111), uses tPortLocal to connect back
2016.10.11 21:27:19 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 21:27:19 4: WEB_192.168.253.28_64829 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:27:19 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 21:27:19 4: Connection closed for WEB_192.168.253.28_64828: EOF
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: Connection accepted from WEB_192.168.253.28_64834
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 => 304 Not Modified
2016.10.11 21:27:20 4: WEB_192.168.253.28_64829 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214038;fmt=JSON&fw_id=151×tamp=1476214040355; BUFLEN:0
2016.10.11 21:27:20 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 21:27:20 4: Connection accepted from tPortLocal_127.0.0.1_51006
2016.10.11 21:27:20 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:27:20 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 21:27:20 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.66 s
2016.10.11 21:27:20 4: olli_iphone exec {
   if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
   {     
   fhem("set rr_Olli absent")
   }
     else
     {
    fhem("set rr_Olli home")
  }
}
2016.10.11 21:27:20 2: ROOMMATE set rr_Olli home
2016.10.11 21:27:20 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 21:27:20 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 21:27:33 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:27:33 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:27:33 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:27:33 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:27:33 4: parse status message for HUEDevice1
2016.10.11 21:27:33 4: parse status message for HUEDevice3
2016.10.11 21:27:33 4: parse status message for HUEDevice2
2016.10.11 21:27:56 4: Connection closed for WEB_192.168.253.28_64829: EOF
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:56 4: Connection accepted from WEB_192.168.253.28_64838
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 => 304 Not Modified
2016.10.11 21:27:56 4: WEB_192.168.253.28_64830 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214075;fmt=JSON&fw_id=152×tamp=1476214076919; BUFLEN:0
2016.10.11 21:28:03 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:28:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:28:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:28:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:28:03 4: parse status message for HUEDevice1
2016.10.11 21:28:03 4: parse status message for HUEDevice3
2016.10.11 21:28:03 4: parse status message for HUEDevice2
2016.10.11 21:28:14 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 25111
2016.10.11 21:28:14 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 21:28:22 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:28:22 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (25127), uses tPortLocal to connect back
2016.10.11 21:28:22 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 21:28:22 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 21:28:23 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 21:28:23 4: Connection accepted from tPortLocal_127.0.0.1_51038
2016.10.11 21:28:23 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:28:23 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 21:28:23 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.68 s
2016.10.11 21:28:23 4: olli_iphone exec {
   if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
   {     
   fhem("set rr_Olli absent")
   }
     else
     {
    fhem("set rr_Olli home")
  }
}
2016.10.11 21:28:23 2: ROOMMATE set rr_Olli home
2016.10.11 21:28:23 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 21:28:23 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 21:28:26 4: Connection closed for WEB_192.168.253.28_64830: EOF
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:26 4: Connection accepted from WEB_192.168.253.28_64839
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64839 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64839 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 => 304 Not Modified
2016.10.11 21:28:26 4: WEB_192.168.253.28_64831 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214105;fmt=JSON&fw_id=153×tamp=1476214106894; BUFLEN:0
2016.10.11 21:28:33 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:28:33 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:28:33 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:28:33 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:28:33 4: parse status message for HUEDevice1
2016.10.11 21:28:33 4: parse status message for HUEDevice3
2016.10.11 21:28:33 4: parse status message for HUEDevice2
2016.10.11 21:28:54 4: Connection closed for WEB_192.168.253.28_64831: EOF
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:54 4: Connection accepted from WEB_192.168.253.28_64843
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64843 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64843 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64834 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64839 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64843 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64843 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64838 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:28:54 4: WEB_192.168.253.28_64832 => 304 Not Modified
2016.10.11 21:28:55 4: WEB_192.168.253.28_64832 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214133;fmt=JSON&fw_id=154×tamp=1476214135140; BUFLEN:0
2016.10.11 21:29:03 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:29:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:29:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:29:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:29:03 4: parse status message for HUEDevice1
2016.10.11 21:29:03 4: parse status message for HUEDevice3
2016.10.11 21:29:03 4: parse status message for HUEDevice2
2016.10.11 21:29:17 1: Timeout for FRITZBOX_Readout_Run_Web reached, terminated process 25127
2016.10.11 21:29:17 1: FRITZBOX fritzbox: Readout_Aborted.1757 Error: Timeout when reading Fritz!Box data.
2016.10.11 21:29:25 1: BC: MAX:5 / ALIVE:0
2016.10.11 21:29:25 4: BlockingCall (FRITZBOX_Readout_Run_Web): created child (25138), uses tPortLocal to connect back
2016.10.11 21:29:25 4: FRITZBOX fritzbox: Readout_Start.671 Fork process FRITZBOX_Readout_Run_Web
2016.10.11 21:29:25 4: FRITZBOX fritzbox: Readout_Run_Web.1253 Prepare query string for luaQuery.
2016.10.11 21:29:26 4: FRITZBOX fritzbox: Readout_Run_Web.1578 Captured 188 values
2016.10.11 21:29:26 4: Connection accepted from tPortLocal_127.0.0.1_51067
2016.10.11 21:29:26 4: FRITZBOX fritzbox: Readout_Done.1597 Back at main process
2016.10.11 21:29:26 4: FRITZBOX fritzbox: Readout_Process.1619 Processing 183 readouts.
2016.10.11 21:29:26 4: FRITZBOX fritzbox: Readout_Process.1743 185 values captured in 0.66 s
2016.10.11 21:29:26 4: olli_iphone exec {
   if (ReadingsVal("fritzbox", "mac_28_ED_6A_5A_AE_1F", "inactive") eq "inactive")   
   {     
   fhem("set rr_Olli absent")
   }
     else
     {
    fhem("set rr_Olli home")
  }
}
2016.10.11 21:29:26 2: ROOMMATE set rr_Olli home
2016.10.11 21:29:26 4: RESIDENTS roonstr4: rr_Olli: notify about change to home
2016.10.11 21:29:26 4: RESIDENTS roonstr4: calculation result - residentsTotal:2 residentsTotalRoommates:2 residentsTotalRoommatesPresent:1 residentsTotalRoommatesAbsent:1 residentsTotalGuests:0 residentsTotalGuestsPresent:0 residentsTotalGuestsAbsent:0 residentsTotalPresent:1 residentsTotalAbsent:1 residentsHome:1 residentsGotosleep:0 residentsAsleep:0 residentsAwoken:0 residentsAbsent:0 residentsGone:1 presence:present state:home
2016.10.11 21:29:33 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:29:33 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:29:33 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:29:33 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:29:33 4: parse status message for HUEDevice1
2016.10.11 21:29:33 4: parse status message for HUEDevice3
2016.10.11 21:29:33 4: parse status message for HUEDevice2
2016.10.11 21:29:44 4: Connection closed for WEB_192.168.253.28_64834: EOF
2016.10.11 21:29:44 4: Connection closed for WEB_192.168.253.28_64839: EOF
2016.10.11 21:29:44 4: Connection closed for WEB_192.168.253.28_64843: EOF
2016.10.11 21:29:44 4: Connection closed for WEB_192.168.253.28_64838: EOF
2016.10.11 21:29:46 4: Connection closed for WEB_192.168.253.28_64832: EOF
2016.10.11 21:29:46 4: Connection accepted from WEB_192.168.253.28_64844
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-10-11.log; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 GET /fhem/pgm2/style.css?v=1476213898; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:46 4: Connection accepted from WEB_192.168.253.28_64845
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 GET /fhem/pgm2/jquery-ui.min.js; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:46 4: WEB_192.168.253.28_64845 GET /fhem/pgm2/jquery-ui.min.css; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64845 => 304 Not Modified
2016.10.11 21:29:46 4: Connection accepted from WEB_192.168.253.28_64846
2016.10.11 21:29:46 4: Connection accepted from WEB_192.168.253.28_64847
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 GET /fhem/pgm2/fhemweb_knob.js; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:46 4: WEB_192.168.253.28_64845 GET /fhem/pgm2/fhemweb_readingsGroup.js; BUFLEN:0
2016.10.11 21:29:46 4: WEB_192.168.253.28_64845 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 GET /fhem/pgm2/fhemweb.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64847 GET /fhem/pgm2/fhemweb_colorpicker.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64847 => 304 Not Modified
2016.10.11 21:29:47 4: Connection accepted from WEB_192.168.253.28_64848
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 GET /fhem/pgm2/fhemweb_fbcalllist.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 GET /fhem/pgm2/darkCommon.css; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 GET /fhem/pgm2/jquery.min.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 GET /fhem/pgm2/fhemweb_uzsu.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64845 GET /fhem/pgm2/fhemweb_readingsHistory.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64845 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 GET /fhem/pgm2/fhemweb_weekprofile.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64847 GET /fhem/pgm2/fhemweb_sortable.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64847 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 GET /fhem/pgm2/dashboard_darkstyle.css; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64848 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 GET /fhem/codemirror/fhem_codemirror.js; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64846 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 GET /fhem/images/default/icoEverything.png; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 GET /fhem/images/default/fhemicon_dark.png; BUFLEN:0
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 => 304 Not Modified
2016.10.11 21:29:47 4: WEB_192.168.253.28_64844 GET /fhem/FileLog_logWrapper?XHR=1&inform=type=status;filter=;since=1476214185;fmt=JSON&fw_id=162×tamp=1476214187234; BUFLEN:0
2016.10.11 21:30:03 4: using HttpUtils_NonblockingGet: GET lights
2016.10.11 21:30:03 4: HttpUtils url=http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights
2016.10.11 21:30:03 4: http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: HTTP response code 200
2016.10.11 21:30:03 4: HttpUtils http://192.168.253.251/api/6c6c7557164ccdb1017c2093294bb8e9/lights: Got data, length: 777
2016.10.11 21:30:03 4: parse status message for HUEDevice1
2016.10.11 21:30:03 4: parse status message for HUEDevice3
2016.10.11 21:30:03 4: parse status message for HUEDevice2
2016.10.11 21:30:08 4: iTunes_HTTP_Request http://127.0.0.1:3689/ctrl-int/1/playstatusupdate?revision-number=1&session-id=1354394481: Got data, length: 307
2016.10.11 21:30:08 4: iTunes_HTTP_Request http://1
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Versuchs mal bitte mit dem angehaengten Blocking.pm, normalen verbose (3).
Nach 4-5 Prozessen brauche ich auch nochmal die Kindprozesse, und output des Logs.

Bitte darauf achten, dass Beitraege nicht beliebig lang sein koennen, und wenn
abgeschnitten wird, alles sehr unleserlich ist. Grosse Logs lieber anhaengen.

RockSteadyBeat

Sorry für den zuvor langen Post... ;)

anbei nen Ausschnitt des Log´s nach dem Tausch der Blocking.pm...

Ich hoffe der Screenshot mit den Kindprozessen reicht?
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Ich fuechte wir brauchen noch eine Runde, weil die Probleme wohl nicht da auftreten, wo ich sie vermutet habe.
Ich habe eine neue Version mit mehr debug Ausgaben angehaengt.

RockSteadyBeat

Guten Morgen Rudi,

anbei die nächsten Ausschnitte...


edit: Zufällig scheint das fhem versucht die Prozesse zu beenden, dies jedoch nicht angenommen wird...?!
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Nachdem im Log alles normal ausschaut, habe ich die htop-Ausgabe naeher angeschaut: Status aller gestarteten Prozesse ist Z, was fuer Zombie steht, d.h. die sind schon alle tot, bloss noch nicht vom Haupt-FHEM-Prozess weggeraeumt. Kannst du bitte im telnet oder FHEMWEB {$SIG{CHLD}} eingeben, und die Ausgabe hier posten? Bei 'IGNORE' muesste das Wegraeumen automatisch passieren, evtl. macht OSX 10.11 was anders.

RockSteadyBeat

Hallo Rudi,

du hast recht, mit {$SIG{CHLD}} kommt IGNORE als Ausgabe, leider ändert es nicht´s an den Zombie-Prozessen...

Sie summieren sich nach wie vor...

Also, warum kann Fhem die Prozesse nicht bereinigen, aufheben? Liegt es evtl. an Berechtigungen?
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Falls SIGCHLD auf IGNORE steht, dann muss dann kann das OS die Zombies ungefragt abraeumen.
D.h. entweder luegt Perl, oder deine OS-Version hat einen Bug, was vmtl. nur unter bestimmten Umstaenden auftritt.
Kannst du mit einem anderen Perl Version (z.Bsp. von ActiveX) testen, ob das Problem damit auch vorhanden ist?

RockSteadyBeat

danke für deine Mühen Rudi,

ich werde mal mit perlbrew von 5.16 auf 5.22 wechseln, dauert halt etwas, da ich alle Module nachinstallieren muss.

gebe dann Rückmeldung...
fhem on OSX
HMLAN,
CUL433

RockSteadyBeat

Hallo Rudi,

ich habe Deinen Rat befolgt und die perl Version gewechselt, von 5.16 auf 5.22 über ´perlbrew switch perl-xxxx´
leider ohne Erfolg...

Die Zombie-Prozesse vermehren sich weiterhin fleißig mit aktiven FRITZBOX oder PRESENCE Modulen...

fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Ich habe zwar keine Erklaerung fuer dein Problem, habe aber ein Workaround eingebaut, indem zusaetzlich waitpid(-1, WNOHANG) aufgerufen wird.
Da ich in Blocking.pm deswegen ein bisschen umgebauen musste, kann es sein, dass die Aenderung Nebenwirkungen hat.
Habs mit default $SIG{CHLD} getestet, was de-facto deinem Setup entsprechen sollte, aber danach auch mit IGNORE.
Habs unter osx/10.9 extensiver, unter Linux und Win nur kurz getestet.

Komisch unter osx: direkt nach einem kill 9 existiert der Prozess eine Weile, d.h. ein "sofort" darauffolgender kill 0 liefert OK zurueck und waitpid will auch nichts abraeumen.

RockSteadyBeat

Hallo Rudi,

hast Du dann dementsprechend eine geänderte Blocking.pm für mich?
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Ist eingecheckt (siehe sourceforge SVN), kommt morgen per upload.

RockSteadyBeat

Danke Rudi,

mit der "neuen" Blocking.pm, keine Zombie-Prozesse mehr wenn das FRITZBOX-Modul aktiv ist... :D

Aber nun beendet sich fhem selbst wenn ich PRESENCE wieder enable...
ich benutze zwei PRESENCE-Arten, einmal lan-ping und einmal function...
bei beiden beendet fhem sich nach ca. 6sec.

im Log ist folgendes zu lesen:
Can't kill a non-numeric process ID at FHEM/Blocking.pm line 110.
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Wuesste gerne, was PRESENCE da treibt.
Kannst du bitte in Blocking.pm die Zeile
    if($h->{pid} !~ m/:/) {
durch
    if($h->{pid} =~ m/^\d+$/) {

ersetzen?

RockSteadyBeat

nachdem ich deine Änderung eingebracht habe, lief erstmal alles normal...

dann habe ich stück für stück das PRESENCE wieder aktiviert, 8 devices frage ich ab, beim 7. kam
im Log: Undefined subroutine &main:: called at FHEM/Blocking.pm line 148. und die Prozesse mit "perl fhem.pl fhem.cfg"
vervielfachten sich... (alle Prozesse jedoch mit W in htop, nicht Z)
das ging nun soweit das ich keinen Terminal mehr öffnen konnte...

...hoffe der initiierte Neustart funktioniert, da ich über VPN auf dem Mac war... :-[
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Habe wieder eine neue Version eingecheckt, bitte probieren.

RockSteadyBeat

so, neue Blocking.pm scheint soweit zu laufen, PRESENCE aktiv (alle 8), FRITZBOX aktiv...
keine Z-Prozesse...
nur ein W-Prozess dauerhaft...

FRITZBOX-Modul nun auch ohne "Error: Timeout when reading Fritz!Box data."

was ist so unglaublich anders an perl on Mac als perl on Debian o.ä.?

Danke Rudi für deine Geduld und know how...
fhem on OSX
HMLAN,
CUL433

rudolfkoenig

Eigentlich entwickele ich die ganze Zeit auf einem Mac, und perl ist da "normal".
Kann dein Problem auch nicht nachstellen.

Nobby1805

Zitat von: rudolfkoenig am 13 Oktober 2016, 10:27:07
Da ich in Blocking.pm deswegen ein bisschen umgebauen musste, kann es sein, dass die Aenderung Nebenwirkungen hat.
Kann es sein, dass diese Nebeneffekte genau dieses auslösen https://forum.fhem.de/index.php/topic,60430.0.html ? Ich muss leider sagen, dass ich die Änderungen nach dem 8.8. nicht verstanden habe
FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)