Botvac macht Freeze

[Robert1963]

Hallo,

hab seit einigen Jahren einen VR200 (Akt. Version 2.14), mittlerweile in Fhem intregriert.
Nur leider verursacht das Botvac-Modul beim abfragen einen 180sec Freeze vom Fhem!? (Siehe log)
Bin mit Fhem Anfang des Jahres auf einen Nuc 7i7 umgezogen, der noch sehr gelangweilt ist. Habe schon einige Tips aus dem Botvac-fred umgesetzt  (Device_Timeout 1 usw.)
Der Freeze bleib mir aber erhalten 
Wäre schön wenn jemand mal über das Log und das Device Raw schäuen könnte um mir einen Tip zu geben wo der Hebel anzusetzen ist

2019.04.11 16:44:08.370 1: [Freezemon] myFreezemon: possible freeze starting at 16:41:09, delay is 179.37 possibly caused by: tmr-BOTVAC::GetStatus(Staubi) tmr-HUEBridge_GetUpdate(hueBridge) tmr-HUEDevice_GetUpdate(Hue_1_Bewegung) tmr-HUEDevice_GetUpdate(Hue_2_Bewegung) tmr-HUEDevice_GetUpdate(Hue_1_Temp) tmr-HUEDevice_GetUpdate(Hue_2_Temperatur)
2019.04.11 16:44:08.365 4: BOTVAC Staubi: drop successors
2019.04.11 16:44:08.365 4: BOTVAC Staubi:messages/getSchedule RCV https://nucleo.ksecosys.com:4443/vendors/vorwerk/robots/46XLEEWZ-6CECEBB6D002/messages: Can't connect(2) to https://nucleo.ksecosys.com:4443:  SSL wants a read first
2019.04.11 16:44:08.365 5: BOTVAC Staubi: called function ReceiveCommand() rc: HASH(0x55879bc7da90) err: https://nucleo.ksecosys.com:4443/vendors/vorwerk/robots/46XLEEWZ-6CECEBB6D002/messages: Can't connect(2) to https://nucleo.ksecosys.com:4443:  SSL wants a read first data: 
Authorization: NEATOAPP a4996ef9c5f93d23c77f350a8f0c86644c96498162faa85d375b770cefcfe8d1
Date: Thu, 11 Apr 2019 14:41:08 GMT
Content-Type: application/json
2019.04.11 16:41:08.301 5: BOTVAC Staubi: header Accept: application/vnd.neato.nucleo.v1
2019.04.11 16:41:08.301 5: BOTVAC Staubi: POST https://nucleo.ksecosys.com:4443/vendors/vorwerk/robots/46XLEEWZ-6CECEBB6D002/messages ({"reqId":"0","cmd":"getSchedule"})
2019.04.11 16:41:08.301 4: BOTVAC Staubi: REQ messages/getSchedule
2019.04.11 16:41:08.301 5: BOTVAC Staubi: called function SendCommand()
2019.04.11 16:41:08.300 4: BOTVAC Staubi: Read password from file
2019.04.11 16:41:08.299 4: BOTVAC Staubi: RES messages/getRobotState - {"version":1,"reqId":"0","result":"ok","error":"ui_alert_invalid","data": {},"state":1,"action":0,"cleaning": {"category":2,"mode":2,"modifier":1,"spotWidth":0,"spotHeight":0},"details": {"isCharging":false,"isDocked":true,"isScheduleEnabled":false,"dockHasBeenSeen":false,"charge":90},"availableCommands": {"start":true,"stop":false,"pause":false,"resume":false,"goToBase":false},"availableServices": {"houseCleaning":"basic-1","spotCleaning":"basic-1","manualCleaning":"basic-1","easyConnect":"basic-1","schedule":"basic-1"},"meta": {"modelName":"VR200","firmware":"2.1.4"}}
2019.04.11 16:41:08.299 4: BOTVAC Staubi: successors 0: messages,getSchedule
2019.04.11 16:41:08.299 4: BOTVAC Staubi: RCV messages/getRobotState
2019.04.11 16:41:08.299 5: BOTVAC Staubi: called function ReceiveCommand() rc: HASH(0x55879bbeff18) err:  data: {"version":1,"reqId":"0","result":"ok","error":"ui_alert_invalid","data": {},"state":1,"action":0,"cleaning": {"category":2,"mode":2,"modifier":1,"spotWidth":0,"spotHeight":0},"details": {"isCharging":false,"isDocked":true,"isScheduleEnabled":false,"dockHasBeenSeen":false,"charge":90},"availableCommands": {"start":true,"stop":false,"pause":false,"resume":false,"goToBase":false},"availableServices": {"houseCleaning":"basic-1","spotCleaning":"basic-1","manualCleaning":"basic-1","easyConnect":"basic-1","schedule":"basic-1"},"meta": {"modelName":"VR200","firmware":"2.1.4"}}
Authorization: NEATOAPP 74e06a009969f35b4ef78c95eb698a59a88a78d0de2d4840aa8eaef65ad2dbb2
Date: Thu, 11 Apr 2019 14:41:08 GMT
Content-Type: application/json
2019.04.11 16:41:08.016 5: BOTVAC Staubi: header Accept: application/vnd.neato.nucleo.v1
2019.04.11 16:41:08.016 5: BOTVAC Staubi: POST https://nucleo.ksecosys.com:4443/vendors/vorwerk/robots/46XLEEWZ-6CECEBB6D002/messages ({"reqId":"0","cmd":"getRobotState"})
2019.04.11 16:41:08.016 4: BOTVAC Staubi: successors 0: messages,getSchedule
2019.04.11 16:41:08.016 4: BOTVAC Staubi: REQ messages/getRobotState
2019.04.11 16:41:08.016 5: BOTVAC Staubi: called function SendCommand()
2019.04.11 16:41:08.016 4: BOTVAC Staubi: Read password from file
2019.04.11 16:41:08.015 5: BOTVAC Staubi: called function GetStatus()
2019.04.11 16:38:22.234 3: CUL_HM set Palmenheizung_An_Aus statusRequest
2019.04.11 16:36:08.598 4: BOTVAC Staubi: RES messages/getSchedule - {"version":1,"reqId":"0","result":"ok","error":"ui_alert_invalid","data": {"type":0,"enabled":false,"events": [{"mode":1,"day":1,"startTime":"07:00"}]}}
2019.04.11 16:36:08.598 4: BOTVAC Staubi: RCV messages/getSchedule
2019.04.11 16:36:08.598 5: BOTVAC Staubi: called function ReceiveCommand() rc: HASH(0x55879bc005c0) err:  data: {"version":1,"reqId":"0","result":"ok","error":"ui_alert_invalid","data": {"type":0,"enabled":false,"events": [{"mode":1,"day":1,"startTime":"07:00"}]}}
Authorization: NEATOAPP 4d06ead8b0d211088f2c8e1426c686186fd984b563f1be88c443bcdb8ee29210
Date: Thu, 11 Apr 2019 14:36:08 GMT
Content-Type: application/json

Device Staubi

defmod Staubi BOTVAC schier.robert@gmail.com  VORWERK 300
attr Staubi device_timeout 1
attr Staubi disable 0
attr Staubi event-on-change-reading .*
attr Staubi room Staubi
attr Staubi verbose 3
attr Staubi webCmd startCleaning Eco:stop:pause:sendToBase

setstate Staubi Idle
setstate Staubi 2019-04-11 09:00:27 .accessToken 2bf04a8f450a6f5e138527946a80e204
setstate Staubi 2019-01-01 13:30:08 .goToBase 0
setstate Staubi 2019-03-25 10:39:42 .pause 0
setstate Staubi 2019-01-01 13:30:08 .resume 0
setstate Staubi 2019-04-10 11:38:52 .secretKey 84B6E3BB9E4A1DBB180B2C5042C91CDB
setstate Staubi 2019-03-25 12:29:42 .start 1
setstate Staubi 2019-04-10 11:39:58 .stop 0
setstate Staubi 2019-03-25 12:29:42 action 0
setstate Staubi 2019-04-11 16:46:08 batteryPercent 89
setstate Staubi 2019-02-09 10:56:42 charge 91
setstate Staubi 2019-02-04 08:10:16 cleanCategorie 2
setstate Staubi 2017-08-28 19:44:05 cleanMode 1
setstate Staubi 2017-02-02 18:46:07 cleanModifier 1
setstate Staubi 2017-02-03 19:36:01 cleanSpotHeight 0
setstate Staubi 2017-02-03 19:36:01 cleanSpotWidth 0
setstate Staubi 2019-02-09 11:12:25 cleaningCategory house
setstate Staubi 2019-03-18 10:47:49 cleaningMode turbo
setstate Staubi 2019-02-09 11:12:25 cleaningModifier normal
setstate Staubi 2019-02-09 11:12:25 cleaningSpotHeight 0
setstate Staubi 2019-02-09 11:12:25 cleaningSpotWidth 0
setstate Staubi 2019-03-05 13:05:41 dockHasBeenSeen 0
setstate Staubi 2019-03-25 11:19:42 error ui_alert_invalid
setstate Staubi 2019-02-09 11:12:25 event0day Monday
setstate Staubi 2019-02-09 11:12:25 event0mode eco
setstate Staubi 2017-02-02 18:46:08 event0startTime 07:00
setstate Staubi 2019-04-10 11:39:58 firmware 2.1.4
setstate Staubi 2019-04-11 09:31:08 isCharging 0
setstate Staubi 2019-03-25 10:39:42 isDocked 1
setstate Staubi 2019-01-01 13:30:08 isScheduleEnabled 0
setstate Staubi 2017-02-02 18:46:05 macAddr 6cecebb6d002
setstate Staubi 2017-02-02 18:46:07 model VR200
setstate Staubi 2017-02-02 18:46:05 name Staubi
setstate Staubi 2019-02-10 00:00:17 nucleoUrl https://nucleo.ksecosys.com:4443
setstate Staubi 2019-03-19 07:06:15 result ok
setstate Staubi 2017-02-02 18:46:05 robot 0
setstate Staubi 2019-01-01 13:30:09 scheduleEnabled 0
setstate Staubi 2017-02-02 18:46:08 scheduleType 0
setstate Staubi 2017-02-02 18:46:05 serial 46XLEEWZ-6CECEBB6D002
setstate Staubi 2017-02-02 18:46:07 srv_easyConnect basic-1
setstate Staubi 2017-02-02 18:46:07 srv_houseCleaning basic-1
setstate Staubi 2017-02-02 18:46:07 srv_manualCleaning basic-1
setstate Staubi 2017-02-02 18:46:07 srv_schedule basic-1
setstate Staubi 2017-02-02 18:46:07 srv_spotCleaning basic-1
setstate Staubi 2019-04-11 06:49:58 state Idle
setstate Staubi 2019-03-25 12:29:42 stateId 1
setstate Staubi 2017-02-02 18:46:07 version 1

Bin mit der Firmeware und Fhem, Perl etc. aktuell

Viele Grüße,
Robert

[vuffiraa]

Hallo Robert,

was ich im Log sehe, ist die Meldung: SSL wants a read first.

Dazu gab es bereits Threads, z.B. https://forum.fhem.de/index.php/topic,43794.msg400862.html#msg400862
Die Mutmaßung dazu ist, dass der SSL Handshake nicht rechtzeitig abgeschlossen werden kann.

Tritt der Fehler bei dir permanent auf? Vielleicht kannst du das verbose-Level dann noch für HttpUtils oder Fhem gesamt hochsetzen. Dann sollte man noch besser sehen, wodurch die Blockade verursacht wird.

Gruß VuffiRaa

[Robert1963]

Hallo,

der Fehler tritt leider sehr unregelmäßig auf.
Gehe davon aus das die Erreichbarkeit des " https://nucleo.ksecosys.com:4443" nicht immer gegeben ist.
Könnte man das Timeout hochsetzen?
Blöd ist nur das die Abfrage mein ganzes System stilllegt. (Was ja auch nicht sein dürfte)
Werde mal nachher HttpUtils auf verbose 5 stellen und dann berichten.

Vielen Dank schon mal fürs mitdenken,
Gruß,
Robert

[schnuddel]

Hallo Robert,

ist zwar schon eine Weile her, aber ich habe wohl das gleiche oder zumindest ein sehr ähnliches Problem. Hast Du damals eine Lösung gefunden?

Grüße
schnuddel