2024-12-09 23:13:05.937 - info: host.iobtest "system.adapter.hmip.0" enabled
2024-12-09 23:13:06.174 - info: host.iobtest instance system.adapter.hmip.0 in version "1.26.1" started with pid 171768
2024-12-09 23:13:06.553 - debug: hmip.0 (171768) Redis Objects: Use Redis connection: 127.0.0.1:9001
2024-12-09 23:13:06.564 - debug: hmip.0 (171768) Objects client ready ... initialize now
2024-12-09 23:13:06.564 - debug: hmip.0 (171768) Objects create System PubSub Client
2024-12-09 23:13:06.565 - debug: hmip.0 (171768) Objects create User PubSub Client
2024-12-09 23:13:06.576 - debug: hmip.0 (171768) Objects client initialize lua scripts
2024-12-09 23:13:06.579 - debug: hmip.0 (171768) Objects connected to redis: 127.0.0.1:9001
2024-12-09 23:13:06.587 - debug: hmip.0 (171768) Redis States: Use Redis connection: 127.0.0.1:9000
2024-12-09 23:13:06.591 - debug: hmip.0 (171768) States create System PubSub Client
2024-12-09 23:13:06.591 - debug: hmip.0 (171768) States create User PubSub Client
2024-12-09 23:13:06.638 - debug: hmip.0 (171768) States connected to redis: 127.0.0.1:9000
2024-12-09 23:13:06.649 - debug: hmip.0 (171768) Plugin sentry Initialize Plugin (enabled=true)
2024-12-09 23:13:06.759 - info: hmip.0 (171768) starting. Version 1.26.1 in /opt/iobroker/node_modules/iobroker.hmip, node: v20.18.1, js-controller: 7.0.6
2024-12-09 23:13:06.766 - debug: hmip.0 (171768) ready
2024-12-09 23:13:06.777 - info: hmip.0 (171768) token not yet created
2024-12-09 23:13:33.342 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":480,"ack":false,"time":1733782413341},"_id":50299336}
2024-12-09 23:13:53.227 - debug: hmip.0 (171768) message received - {"command":"requestToken","message":{"accessPointSgtin":"3014-F711-A000-03DF-298*****","clientId":"","pin":"********","deviceName":"iobtest"},"from":"system.adapter.admin.0","callback":{"message":{"accessPointSgtin":"3014-F711-A000-03DF-298*****","clientId":"","pin":"********","deviceName":"iobtest"},"id":481,"ack":false,"time":1733782433226},"_id":50299337}
2024-12-09 23:13:53.228 - info: hmip.0 (171768) started token request
2024-12-09 23:13:53.391 - info: hmip.0 (171768) auth step 1
2024-12-09 23:13:53.517 - info: hmip.0 (171768) auth step 2
2024-12-09 23:13:53.536 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":482,"ack":false,"time":1733782433536},"_id":50299338}
2024-12-09 23:13:53.846 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":483,"ack":false,"time":1733782433845},"_id":50299339}
2024-12-09 23:13:54.159 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":484,"ack":false,"time":1733782434158},"_id":50299340}
2024-12-09 23:13:54.468 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":485,"ack":false,"time":1733782434467},"_id":50299341}
2024-12-09 23:13:54.777 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":486,"ack":false,"time":1733782434776},"_id":50299342}
2024-12-09 23:13:55.084 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":487,"ack":false,"time":1733782435084},"_id":50299343}
2024-12-09 23:13:55.392 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":488,"ack":false,"time":1733782435391},"_id":50299344}
2024-12-09 23:13:55.700 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":489,"ack":false,"time":1733782435699},"_id":50299345}
2024-12-09 23:13:56.007 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":490,"ack":false,"time":1733782436007},"_id":50299346}
2024-12-09 23:13:56.315 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":491,"ack":false,"time":1733782436314},"_id":50299347}
2024-12-09 23:13:56.622 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":492,"ack":false,"time":1733782436622},"_id":50299348}
2024-12-09 23:13:56.930 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":493,"ack":false,"time":1733782436929},"_id":50299349}
2024-12-09 23:13:57.237 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":494,"ack":false,"time":1733782437237},"_id":50299350}
2024-12-09 23:13:57.546 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":495,"ack":false,"time":1733782437545},"_id":50299351}
2024-12-09 23:13:57.855 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":496,"ack":false,"time":1733782437854},"_id":50299352}
2024-12-09 23:13:58.165 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":497,"ack":false,"time":1733782438164},"_id":50299353}
2024-12-09 23:13:58.472 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":498,"ack":false,"time":1733782438471},"_id":50299354}
2024-12-09 23:13:58.781 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":499,"ack":false,"time":1733782438781},"_id":50299355}
2024-12-09 23:13:59.089 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":500,"ack":false,"time":1733782439088},"_id":50299356}
2024-12-09 23:13:59.396 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":501,"ack":false,"time":1733782439396},"_id":50299357}
2024-12-09 23:13:59.703 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":502,"ack":false,"time":1733782439702},"_id":50299358}
2024-12-09 23:14:00.010 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":503,"ack":false,"time":1733782440010},"_id":50299359}
2024-12-09 23:14:00.318 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":504,"ack":false,"time":1733782440318},"_id":50299360}
2024-12-09 23:14:00.628 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":505,"ack":false,"time":1733782440628},"_id":50299361}
2024-12-09 23:14:00.936 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":506,"ack":false,"time":1733782440935},"_id":50299362}
2024-12-09 23:14:01.244 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":507,"ack":false,"time":1733782441243},"_id":50299363}
2024-12-09 23:14:01.551 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":508,"ack":false,"time":1733782441550},"_id":50299364}
2024-12-09 23:14:01.858 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":509,"ack":false,"time":1733782441857},"_id":50299365}
2024-12-09 23:14:02.170 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":510,"ack":false,"time":1733782442169},"_id":50299366}
2024-12-09 23:14:02.476 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":511,"ack":false,"time":1733782442475},"_id":50299367}
2024-12-09 23:14:02.785 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":512,"ack":false,"time":1733782442784},"_id":50299368}
2024-12-09 23:14:03.092 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":513,"ack":false,"time":1733782443092},"_id":50299369}
2024-12-09 23:14:03.401 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":514,"ack":false,"time":1733782443400},"_id":50299370}
2024-12-09 23:14:03.708 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":515,"ack":false,"time":1733782443708},"_id":50299371}
2024-12-09 23:14:04.017 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":516,"ack":false,"time":1733782444016},"_id":50299372}
2024-12-09 23:14:04.324 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":517,"ack":false,"time":1733782444324},"_id":50299373}
2024-12-09 23:14:04.632 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":518,"ack":false,"time":1733782444631},"_id":50299374}
2024-12-09 23:14:04.941 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":519,"ack":false,"time":1733782444940},"_id":50299375}
2024-12-09 23:14:05.248 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":520,"ack":false,"time":1733782445248},"_id":50299376}
2024-12-09 23:14:05.556 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":521,"ack":false,"time":1733782445555},"_id":50299377}
2024-12-09 23:14:05.863 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":522,"ack":false,"time":1733782445863},"_id":50299378}
2024-12-09 23:14:06.173 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":523,"ack":false,"time":1733782446172},"_id":50299379}
2024-12-09 23:14:06.481 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":524,"ack":false,"time":1733782446481},"_id":50299380}
2024-12-09 23:14:06.789 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":525,"ack":false,"time":1733782446788},"_id":50299381}
2024-12-09 23:14:07.097 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":526,"ack":false,"time":1733782447096},"_id":50299382}
2024-12-09 23:14:07.404 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":527,"ack":false,"time":1733782447404},"_id":50299383}
2024-12-09 23:14:07.712 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":528,"ack":false,"time":1733782447711},"_id":50299384}
2024-12-09 23:14:08.018 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":529,"ack":false,"time":1733782448018},"_id":50299385}
2024-12-09 23:14:08.325 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":530,"ack":false,"time":1733782448324},"_id":50299386}
2024-12-09 23:14:08.635 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":531,"ack":false,"time":1733782448635},"_id":50299387}
2024-12-09 23:14:08.943 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":532,"ack":false,"time":1733782448942},"_id":50299388}
2024-12-09 23:14:09.250 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":533,"ack":false,"time":1733782449250},"_id":50299389}
2024-12-09 23:14:09.558 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":534,"ack":false,"time":1733782449557},"_id":50299390}
2024-12-09 23:14:09.866 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":535,"ack":false,"time":1733782449865},"_id":50299391}
2024-12-09 23:14:10.176 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":536,"ack":false,"time":1733782450176},"_id":50299392}
2024-12-09 23:14:10.484 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":537,"ack":false,"time":1733782450483},"_id":50299393}
2024-12-09 23:14:10.791 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":538,"ack":false,"time":1733782450791},"_id":50299394}
2024-12-09 23:14:11.098 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":539,"ack":false,"time":1733782451097},"_id":50299395}
2024-12-09 23:14:11.406 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":540,"ack":false,"time":1733782451405},"_id":50299396}
2024-12-09 23:14:11.713 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":541,"ack":false,"time":1733782451712},"_id":50299397}
2024-12-09 23:14:12.021 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":542,"ack":false,"time":1733782452020},"_id":50299398}
2024-12-09 23:14:12.331 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":543,"ack":false,"time":1733782452331},"_id":50299399}
2024-12-09 23:14:12.638 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":544,"ack":false,"time":1733782452637},"_id":50299400}
2024-12-09 23:14:12.946 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":545,"ack":false,"time":1733782452945},"_id":50299401}
2024-12-09 23:14:13.253 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":546,"ack":false,"time":1733782453252},"_id":50299402}
2024-12-09 23:14:13.561 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":547,"ack":false,"time":1733782453560},"_id":50299403}
2024-12-09 23:14:13.868 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":548,"ack":false,"time":1733782453867},"_id":50299404}
2024-12-09 23:14:14.177 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":549,"ack":false,"time":1733782454177},"_id":50299405}
2024-12-09 23:14:14.484 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":550,"ack":false,"time":1733782454483},"_id":50299406}
2024-12-09 23:14:14.793 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":551,"ack":false,"time":1733782454792},"_id":50299407}
2024-12-09 23:14:15.100 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":552,"ack":false,"time":1733782455100},"_id":50299408}
2024-12-09 23:14:15.408 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":553,"ack":false,"time":1733782455407},"_id":50299409}
2024-12-09 23:14:15.717 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":554,"ack":false,"time":1733782455716},"_id":50299410}
2024-12-09 23:14:16.024 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":555,"ack":false,"time":1733782456023},"_id":50299411}
2024-12-09 23:14:16.333 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":556,"ack":false,"time":1733782456332},"_id":50299412}
2024-12-09 23:14:16.641 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":557,"ack":false,"time":1733782456640},"_id":50299413}
2024-12-09 23:14:16.951 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":558,"ack":false,"time":1733782456950},"_id":50299414}
2024-12-09 23:14:17.262 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":559,"ack":false,"time":1733782457261},"_id":50299415}
2024-12-09 23:14:17.569 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":560,"ack":false,"time":1733782457569},"_id":50299416}
2024-12-09 23:14:17.881 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":561,"ack":false,"time":1733782457880},"_id":50299417}
2024-12-09 23:14:18.188 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":562,"ack":false,"time":1733782458188},"_id":50299418}
2024-12-09 23:14:18.495 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":563,"ack":false,"time":1733782458494},"_id":50299419}
2024-12-09 23:14:18.801 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":564,"ack":false,"time":1733782458801},"_id":50299420}
2024-12-09 23:14:19.109 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":565,"ack":false,"time":1733782459108},"_id":50299421}
2024-12-09 23:14:19.417 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":566,"ack":false,"time":1733782459417},"_id":50299422}
2024-12-09 23:14:19.725 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":567,"ack":false,"time":1733782459724},"_id":50299423}
2024-12-09 23:14:20.035 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":568,"ack":false,"time":1733782460034},"_id":50299424}
2024-12-09 23:14:20.342 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":569,"ack":false,"time":1733782460341},"_id":50299425}
2024-12-09 23:14:20.568 - info: hmip.0 (171768) auth step 3
2024-12-09 23:14:20.649 - debug: hmip.0 (171768) message received - {"command":"requestTokenState","message":null,"from":"system.adapter.admin.0","callback":{"message":null,"id":570,"ack":false,"time":1733782460648},"_id":50299426}