[2023-03-18 06:38:41][DEBUG] : Send: 0x04420007000000
[2023-03-18 06:38:41][DEBUG] : Frame received: 0x04420013000c002e0e02000000000000000000
[2023-03-18 06:38:41][DEBUG] : APS data confirm response for request with id 14: 00
[2023-03-18 06:38:41][DEBUG] : Request id: 0x0e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:38:41][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:38:41][DEBUG] : Send: 0x1743000800010001
[2023-03-18 06:38:41][DEBUG] : Frame received: 0x17430021001a002602000000020000000000020003000f000000afdd653c00001e
[2023-03-18 06:38:41][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'\x0f\x00\x00', 0, 175, 221, 101, 60, 0, 0, 30]
[2023-03-18 06:38:41][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x0f\x00\x00'
[2023-03-18 06:38:41][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 06:38:41][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 06:38:41][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'0f0000'
[2023-03-18 06:38:41][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:38:41][DEBUG] : Send: 0x1744000800010001
[2023-03-18 06:38:41][DEBUG] : Frame received: 0x1744050800010026
[2023-03-18 06:38:41][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:38:41][DEBUG] : Send: 0x04450007000000
[2023-03-18 06:38:41][DEBUG] : Frame received: 0x04450013000c00221002000000000000000000
[2023-03-18 06:38:41][DEBUG] : APS data confirm response for request with id 16: 00
[2023-03-18 06:38:41][DEBUG] : Request id: 0x10 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:38:41][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:38:41][DEBUG] : Send: 0x04460007000000
[2023-03-18 06:38:41][DEBUG] : Frame received: 0x0446050800010022
[2023-03-18 06:39:09][DEBUG] : Tries remaining: 1
[2023-03-18 06:39:09][INFO] : [0x0000] Requesting 'Node Descriptor'
[2023-03-18 06:39:09][DEBUG] : Tries remaining: 2
[2023-03-18 06:39:09][DEBUG] : [0x0000] Extending timeout for 0x11 request
[2023-03-18 06:39:09][DEBUG] : Sending Zigbee request with tsn 17 under 18 request id, data: b'110000'
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_request (18, 18, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'\x11\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:39:09][DEBUG] : Send: 0x12470019001200120002000000000002000003001100000200
[2023-03-18 06:39:09][DEBUG] : Tries remaining: 1
[2023-03-18 06:39:09][INFO] : [0x0000] Requesting 'Node Descriptor'
[2023-03-18 06:39:09][DEBUG] : Tries remaining: 2
[2023-03-18 06:39:09][DEBUG] : [0x0000] Extending timeout for 0x13 request
[2023-03-18 06:39:09][DEBUG] : Sending Zigbee request with tsn 19 under 20 request id, data: b'130000'
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x124700090002002212
[2023-03-18 06:39:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 18]
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x0e48000700ae00
[2023-03-18 06:39:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_request (18, 20, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'\x13\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:39:09][DEBUG] : Send: 0x12480019001200140002000000000002000003001300000200
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x124800090002002e14
[2023-03-18 06:39:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, 20]
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x0e49000700ae00
[2023-03-18 06:39:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:09][DEBUG] : Send: 0x1749000800010001
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x17490021001a002e020000000200000000000200030011000000afdd673c00001e
[2023-03-18 06:39:09][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'\x11\x00\x00', 0, 175, 221, 103, 60, 0, 0, 30]
[2023-03-18 06:39:09][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x11\x00\x00'
[2023-03-18 06:39:09][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 06:39:09][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 06:39:09][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'110000'
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:09][DEBUG] : Send: 0x044a0007000000
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x044a0013000c002e1202000000000000000000
[2023-03-18 06:39:09][DEBUG] : APS data confirm response for request with id 18: 00
[2023-03-18 06:39:09][DEBUG] : Request id: 0x12 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:09][DEBUG] : Send: 0x174b000800010001
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x174b0021001a0026020000000200000000000200030013000000afdd673c00001e
[2023-03-18 06:39:09][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'\x13\x00\x00', 0, 175, 221, 103, 60, 0, 0, 30]
[2023-03-18 06:39:09][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x13\x00\x00'
[2023-03-18 06:39:09][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 06:39:09][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 06:39:09][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'130000'
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:09][DEBUG] : Send: 0x174c000800010001
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x174c050800010026
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:09][DEBUG] : Send: 0x044d0007000000
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x044d0013000c00221402000000000000000000
[2023-03-18 06:39:09][DEBUG] : APS data confirm response for request with id 20: 00
[2023-03-18 06:39:09][DEBUG] : Request id: 0x14 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:39:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:09][DEBUG] : Send: 0x044e0007000000
[2023-03-18 06:39:09][DEBUG] : Frame received: 0x044e050800010022
[2023-03-18 06:39:38][DEBUG] : Tries remaining: 1
[2023-03-18 06:39:38][DEBUG] : [0x0000] Extending timeout for 0x15 request
[2023-03-18 06:39:38][DEBUG] : Sending Zigbee request with tsn 21 under 22 request id, data: b'150000'
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_request (18, 22, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'\x15\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:39:38][DEBUG] : Send: 0x124f0019001200160002000000000002000003001500000200
[2023-03-18 06:39:38][DEBUG] : Tries remaining: 1
[2023-03-18 06:39:38][DEBUG] : [0x0000] Extending timeout for 0x17 request
[2023-03-18 06:39:38][DEBUG] : Sending Zigbee request with tsn 23 under 24 request id, data: b'170000'
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x124f00090002002216
[2023-03-18 06:39:38][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 22]
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x0e50000700ae00
[2023-03-18 06:39:38][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_request (18, 24, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'\x17\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:39:38][DEBUG] : Send: 0x12500019001200180002000000000002000003001700000200
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x125000090002002e18
[2023-03-18 06:39:38][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, 24]
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x0e51000700ae00
[2023-03-18 06:39:38][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:38][DEBUG] : Send: 0x1751000800010001
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x17510021001a002e020000000200000000000200030015000000afdd683c00001e
[2023-03-18 06:39:38][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 46>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'\x15\x00\x00', 0, 175, 221, 104, 60, 0, 0, 30]
[2023-03-18 06:39:38][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x15\x00\x00'
[2023-03-18 06:39:38][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 06:39:38][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 06:39:38][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'150000'
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:38][DEBUG] : Send: 0x04520007000000
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x04520013000c002e1602000000000000000000
[2023-03-18 06:39:38][DEBUG] : APS data confirm response for request with id 22: 00
[2023-03-18 06:39:38][DEBUG] : Request id: 0x16 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:38][DEBUG] : Send: 0x1753000800010001
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x17530021001a0026020000000200000000000200030017000000afdd683c00001e
[2023-03-18 06:39:38][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'\x17\x00\x00', 0, 175, 221, 104, 60, 0, 0, 30]
[2023-03-18 06:39:38][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'\x17\x00\x00'
[2023-03-18 06:39:38][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 06:39:38][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 06:39:38][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'170000'
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 06:39:38][DEBUG] : Send: 0x1754000800010001
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x1754050800010026
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:38][DEBUG] : Send: 0x04550007000000
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x04550013000c00221802000000000000000000
[2023-03-18 06:39:38][DEBUG] : APS data confirm response for request with id 24: 00
[2023-03-18 06:39:38][DEBUG] : Request id: 0x18 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 06:39:38][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:39:38][DEBUG] : Send: 0x04560007000000
[2023-03-18 06:39:38][DEBUG] : Frame received: 0x0456050800010022
[2023-03-18 06:40:06][DEBUG] : Sending Zigbee broadcast with tsn 25 under 26 request id, data: b'190000'
[2023-03-18 06:40:06][DEBUG] : Command Command.aps_data_request (18, 26, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=65532 endpoint=0>, 0, <ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, 0, b'\x19\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:40:06][DEBUG] : Send: 0x125700190012001a0002fcff00000036000003001900000200
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x12570009000200221a
[2023-03-18 06:40:06][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 26]
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0e58000700a600
[2023-03-18 06:40:06][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2023-03-18 06:40:06][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:40:06][DEBUG] : Send: 0x04580007000000
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x04580013000c00221a02fcff0000e100000000
[2023-03-18 06:40:06][DEBUG] : APS data confirm response for request with id 26: e1
[2023-03-18 06:40:06][DEBUG] : Request id: 0x1a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xfffc endpoint=0>, status: 0xe1
[2023-03-18 06:40:06][DEBUG] : Error while sending 26 req id broadcast: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2023-03-18 06:40:06][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\x00')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0b5900090002002100
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0b59000800010021
[2023-03-18 06:40:06][DEBUG] : Write parameter permit_join: SUCCESS
[2023-03-18 06:40:06][WARNING] : Device is partially initialized: <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False>
[2023-03-18 06:40:06][INFO] : [00:21:2e:ff:ff:09:c2:6a][listener.device_initialized] new=False
[2023-03-18 06:40:06][INFO] : [start_zigbee] Init and start http server : {'database_path': '/var/www/html/plugins/zigbee/data/1/network_deconz.db', 'device': {'path': '/dev/ttyACM1'}, 'network': {'channel': 15}}
[2023-03-18 06:40:06][INFO] : [start_zigbee] Start zigbee network
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.mac_address: 1>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5a000800010001
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5a0010000900016ac209ffff2e2100
[2023-03-18 06:40:06][DEBUG] : Read parameter mac_address response: [00:21:2e:ff:ff:09:c2:6a]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.aps_designed_coordinator: 9>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5b000800010009
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5b00090002000901
[2023-03-18 06:40:06][DEBUG] : Read parameter aps_designed_coordinator response: [1]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.nwk_address: 7>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5c000800010007
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5c000a000300070000
[2023-03-18 06:40:06][DEBUG] : Read parameter nwk_address response: [0x0000]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.nwk_panid: 5>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5d000800010005
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5d000a00030005409f
[2023-03-18 06:40:06][DEBUG] : Read parameter nwk_panid response: [0x9f40]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.aps_extended_panid: 11>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5e00080001000b
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5e00100009000b0000000000000000
[2023-03-18 06:40:06][DEBUG] : Read parameter aps_extended_panid response: [00:00:00:00:00:00:00:00]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.nwk_extended_panid: 8>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a5f000800010008
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a5f0010000900086ac209ffff2e2100
[2023-03-18 06:40:06][DEBUG] : Read parameter nwk_extended_panid response: [00:21:2e:ff:ff:09:c2:6a]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.current_channel: 28>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a6000080001001c
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a6000090002001c19
[2023-03-18 06:40:06][DEBUG] : Read parameter current_channel response: [25]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.channel_mask: 10>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a6100080001000a
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a61000c0005000a00000002
[2023-03-18 06:40:06][DEBUG] : Read parameter channel_mask response: [<Channels.CHANNEL_25: 33554432>]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.nwk_update_id: 36>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a62000800010024
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a6200090002002400
[2023-03-18 06:40:06][DEBUG] : Read parameter nwk_update_id response: [0]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (2, <NetworkParameter.network_key: 24>, b'\x00')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a6300090002001800
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a63001900120018001fadd3cb165f1c4ec29018ceaec9f4ef
[2023-03-18 06:40:06][DEBUG] : Read parameter network_key response: [0, [31, 173, 211, 203, 22, 95, 28, 78, 194, 144, 24, 206, 174, 201, 244, 239]]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.nwk_frame_counter: 39>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a64000800010027
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a64000c000500276c3c0100
[2023-03-18 06:40:06][DEBUG] : Read parameter nwk_frame_counter response: [81004]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.trust_center_address: 14>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a6500080001000e
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a6500100009000e6ac209ffff2e2100
[2023-03-18 06:40:06][DEBUG] : Read parameter trust_center_address response: [00:21:2e:ff:ff:09:c2:6a]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (9, <NetworkParameter.link_key: 25>, b'j\xc2	\xff\xff.!\x00')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a660010000900196ac209ffff2e2100
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a660020001900196ac209ffff2e21005a6967426565416c6c69616e63653039
[2023-03-18 06:40:06][DEBUG] : Read parameter link_key response: [00:21:2e:ff:ff:09:c2:6a, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57]]
[2023-03-18 06:40:06][DEBUG] : Command Command.read_parameter (1, <NetworkParameter.security_mode: 16>, b'')
[2023-03-18 06:40:06][DEBUG] : Send: 0x0a67000800010010
[2023-03-18 06:40:06][DEBUG] : Frame received: 0x0a6700090002001003
[2023-03-18 06:40:06][DEBUG] : Read parameter security_mode response: [3]
[2023-03-18 06:40:06][DEBUG] : Adding a new backup NetworkBackup(backup_time=datetime.datetime(2023, 3, 18, 5, 40, 6, 405648, tzinfo=datetime.timezone.utc), network_info=NetworkInfo(extended_pan_id=00:21:2e:ff:ff:09:c2:6a, pan_id=0x9f40, nwk_update_id=0, nwk_manager_id=0x0000, channel=25, channel_mask=<Channels.CHANNEL_25: 33554432>, security_level=5, network_key=Key(key=[31, 173, 211, 203, 22, 95, 28, 78, 194, 144, 24, 206, 174, 201, 244, 239], tx_counter=81004, rx_counter=0, seq=0, partner_ieee=ff:ff:ff:ff:ff:ff:ff:ff), tc_link_key=Key(key=[90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57], tx_counter=0, rx_counter=0, seq=0, partner_ieee=00:21:2e:ff:ff:09:c2:6a), key_table=[], children=[], nwk_addresses={}, stack_specific={}, metadata={'deconz': {'version': 645007104}}, source='zigpy-deconz@0.18.1'), node_info=NodeInfo(nwk=0x0000, ieee=00:21:2e:ff:ff:09:c2:6a, logical_type=<LogicalType.Coordinator: 0>))
[2023-03-18 06:40:06][DEBUG] : Waiting for 86400s before backing up again
[2023-03-18 06:40:06][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'DELETE FROM network_backups_v10 WHERE backup_json=?', ('{"backup_time": "2023-03-18T04:42:56.779448+00:00", "network_info": {"extended_pan_id": "00:21:2e:ff:ff:09:c2:6a", "pan_id": "9F40", "nwk_update_id": 0, "nwk_manager_id": "0000", "channel": 25, "channel_mask": [25], "security_level": 5, "network_key": {"key": "1f:ad:d3:cb:16:5f:1c:4e:c2:90:18:ce:ae:c9:f4:ef", "tx_counter": 80745, "rx_counter": 0, "seq": 0, "partner_ieee": "ff:ff:ff:ff:ff:ff:ff:ff"}, "tc_link_key": {"key": "5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39", "tx_counter": 0, "rx_counter": 0, "seq": 0, "partner_ieee": "00:21:2e:ff:ff:09:c2:6a"}, "key_table": [], "children": [], "nwk_addresses": {}, "stack_specific": {}, "metadata": {"deconz": {"version": 645007104}}, "source": "zigpy-deconz@0.18.1"}, "node_info": {"nwk": "0000", "ieee": "00:21:2e:ff:ff:09:c2:6a", "logical_type": "coordinator"}}',))
[2023-03-18 06:40:06][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'DELETE FROM network_backups_v10 WHERE backup_json=?', ('{"backup_time": "2023-03-18T04:42:56.779448+00:00", "network_info": {"extended_pan_id": "00:21:2e:ff:ff:09:c2:6a", "pan_id": "9F40", "nwk_update_id": 0, "nwk_manager_id": "0000", "channel": 25, "channel_mask": [25], "security_level": 5, "network_key": {"key": "1f:ad:d3:cb:16:5f:1c:4e:c2:90:18:ce:ae:c9:f4:ef", "tx_counter": 80745, "rx_counter": 0, "seq": 0, "partner_ieee": "ff:ff:ff:ff:ff:ff:ff:ff"}, "tc_link_key": {"key": "5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39", "tx_counter": 0, "rx_counter": 0, "seq": 0, "partner_ieee": "00:21:2e:ff:ff:09:c2:6a"}, "key_table": [], "children": [], "nwk_addresses": {}, "stack_specific": {}, "metadata": {"deconz": {"version": 645007104}}, "source": "zigpy-deconz@0.18.1"}, "node_info": {"nwk": "0000", "ieee": "00:21:2e:ff:ff:09:c2:6a", "logical_type": "coordinator"}}',)) completed
[2023-03-18 06:40:06][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>)
[2023-03-18 06:40:06][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>) completed
[2023-03-18 06:40:06][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'INSERT INTO network_backups_v10 VALUES (?, ?)
                    ON CONFLICT (id)
                    DO UPDATE SET
                        backup_json=excluded.backup_json', (None, '{"backup_time": "2023-03-18T05:40:06.405648+00:00", "network_info": {"extended_pan_id": "00:21:2e:ff:ff:09:c2:6a", "pan_id": "9F40", "nwk_update_id": 0, "nwk_manager_id": "0000", "channel": 25, "channel_mask": [25], "security_level": 5, "network_key": {"key": "1f:ad:d3:cb:16:5f:1c:4e:c2:90:18:ce:ae:c9:f4:ef", "tx_counter": 81004, "rx_counter": 0, "seq": 0, "partner_ieee": "ff:ff:ff:ff:ff:ff:ff:ff"}, "tc_link_key": {"key": "5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39", "tx_counter": 0, "rx_counter": 0, "seq": 0, "partner_ieee": "00:21:2e:ff:ff:09:c2:6a"}, "key_table": [], "children": [], "nwk_addresses": {}, "stack_specific": {}, "metadata": {"deconz": {"version": 645007104}}, "source": "zigpy-deconz@0.18.1"}, "node_info": {"nwk": "0000", "ieee": "00:21:2e:ff:ff:09:c2:6a", "logical_type": "coordinator"}}'))
[2023-03-18 06:40:06][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'INSERT INTO network_backups_v10 VALUES (?, ?)
                    ON CONFLICT (id)
                    DO UPDATE SET
                        backup_json=excluded.backup_json', (None, '{"backup_time": "2023-03-18T05:40:06.405648+00:00", "network_info": {"extended_pan_id": "00:21:2e:ff:ff:09:c2:6a", "pan_id": "9F40", "nwk_update_id": 0, "nwk_manager_id": "0000", "channel": 25, "channel_mask": [25], "security_level": 5, "network_key": {"key": "1f:ad:d3:cb:16:5f:1c:4e:c2:90:18:ce:ae:c9:f4:ef", "tx_counter": 81004, "rx_counter": 0, "seq": 0, "partner_ieee": "ff:ff:ff:ff:ff:ff:ff:ff"}, "tc_link_key": {"key": "5a:69:67:42:65:65:41:6c:6c:69:61:6e:63:65:30:39", "tx_counter": 0, "rx_counter": 0, "seq": 0, "partner_ieee": "00:21:2e:ff:ff:09:c2:6a"}, "key_table": [], "children": [], "nwk_addresses": {}, "stack_specific": {}, "metadata": {"deconz": {"version": 645007104}}, "source": "zigpy-deconz@0.18.1"}, "node_info": {"nwk": "0000", "ieee": "00:21:2e:ff:ff:09:c2:6a", "logical_type": "coordinator"}}')) completed
[2023-03-18 06:40:06][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>)
[2023-03-18 06:40:06][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>) completed
[2023-03-18 06:40:09][INFO] : [ApplicationHandler.prepare] Json arg : {'duration': 180}
[2023-03-18 06:40:09][DEBUG] : Sending Zigbee broadcast with tsn 27 under 28 request id, data: b'1bb400'
[2023-03-18 06:40:09][DEBUG] : Command Command.aps_data_request (18, 28, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=65532 endpoint=0>, 0, <ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, 0, b'\x1b\xb4\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:40:09][DEBUG] : Send: 0x126800190012001c0002fcff00000036000003001bb4000200
[2023-03-18 06:40:09][DEBUG] : Frame received: 0x12680009000200221c
[2023-03-18 06:40:09][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 28]
[2023-03-18 06:40:09][DEBUG] : Frame received: 0x0e69000700a600
[2023-03-18 06:40:09][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2023-03-18 06:40:09][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:40:09][DEBUG] : Send: 0x04690007000000
[2023-03-18 06:40:09][DEBUG] : Frame received: 0x04690013000c00221c02fcff0000e100000000
[2023-03-18 06:40:09][DEBUG] : APS data confirm response for request with id 28: e1
[2023-03-18 06:40:09][DEBUG] : Request id: 0x1c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xfffc endpoint=0>, status: 0xe1
[2023-03-18 06:40:09][DEBUG] : Error while sending 28 req id broadcast: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2023-03-18 06:40:09][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xb4')
[2023-03-18 06:40:09][DEBUG] : Send: 0x0b6a000900020021b4
[2023-03-18 06:40:09][DEBUG] : Frame received: 0x0b6a000800010021
[2023-03-18 06:40:09][DEBUG] : Write parameter permit_join: SUCCESS
[2023-03-18 06:40:09][INFO] : [ApplicationHandler.put.include] No ZGP endpoint found, create it
[2023-03-18 06:40:09][INFO] : [ApplicationHandler.put.include] Permit ZGP include
[2023-03-18 06:40:09][INFO] : [zgp.permit] Permit green power pairing for 180 s
[2023-03-18 06:40:09][INFO] : 200 PUT /application/include (127.0.0.1) 119.94ms
[2023-03-18 06:44:46][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 06:44:46][DEBUG] : Send: 0x0b6b000c0005002658020000
[2023-03-18 06:44:46][DEBUG] : Frame received: 0x0b6b000800010026
[2023-03-18 06:44:46][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2023-03-18 06:45:02][INFO] : [DeviceHandler.prepare] Json arg : None
[2023-03-18 06:45:02][INFO] : [00:21:2e:ff:ff:09:c2:6a][zdevices.serialize] Serialize device with attributes : 1
[2023-03-18 06:45:02][INFO] : 200 GET /device/all (127.0.0.1) 3.66ms
[2023-03-18 06:50:23][INFO] : [ApplicationHandler.prepare] Json arg : {'duration': 180}
[2023-03-18 06:50:23][DEBUG] : Sending Zigbee broadcast with tsn 29 under 30 request id, data: b'1db400'
[2023-03-18 06:50:23][DEBUG] : Command Command.aps_data_request (18, 30, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=65532 endpoint=0>, 0, <ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, 0, b'\x1d\xb4\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 06:50:23][DEBUG] : Send: 0x126c00190012001e0002fcff00000036000003001db4000200
[2023-03-18 06:50:23][DEBUG] : Frame received: 0x126c0009000200221e
[2023-03-18 06:50:23][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 30]
[2023-03-18 06:50:23][DEBUG] : Frame received: 0x0e6d000700a600
[2023-03-18 06:50:23][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2023-03-18 06:50:23][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 06:50:23][DEBUG] : Send: 0x046d0007000000
[2023-03-18 06:50:23][DEBUG] : Frame received: 0x046d0013000c00221e02fcff0000e100000000
[2023-03-18 06:50:23][DEBUG] : APS data confirm response for request with id 30: e1
[2023-03-18 06:50:23][DEBUG] : Request id: 0x1e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xfffc endpoint=0>, status: 0xe1
[2023-03-18 06:50:23][DEBUG] : Error while sending 30 req id broadcast: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2023-03-18 06:50:23][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xb4')
[2023-03-18 06:50:23][DEBUG] : Send: 0x0b6e000900020021b4
[2023-03-18 06:50:23][DEBUG] : Frame received: 0x0b6e000800010021
[2023-03-18 06:50:23][DEBUG] : Write parameter permit_join: SUCCESS
[2023-03-18 06:50:23][INFO] : [ApplicationHandler.put.include] Permit ZGP include
[2023-03-18 06:50:23][INFO] : [zgp.permit] Permit green power pairing for 180 s
[2023-03-18 06:50:23][INFO] : 200 PUT /application/include (127.0.0.1) 111.97ms
[2023-03-18 06:52:16][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 06:52:16][DEBUG] : Send: 0x0b6f000c0005002658020000
[2023-03-18 06:52:16][DEBUG] : Frame received: 0x0b6f000800010026
[2023-03-18 06:52:16][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2023-03-18 06:59:46][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 06:59:46][DEBUG] : Send: 0x0b70000c0005002658020000
[2023-03-18 06:59:46][DEBUG] : Frame received: 0x0b70000800010026
[2023-03-18 06:59:46][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2023-03-18 07:00:03][INFO] : [DeviceHandler.prepare] Json arg : None
[2023-03-18 07:00:03][INFO] : [00:21:2e:ff:ff:09:c2:6a][zdevices.serialize] Serialize device with attributes : 1
[2023-03-18 07:00:03][INFO] : 200 GET /device/all (127.0.0.1) 3.75ms
[2023-03-18 07:00:47][INFO] : [ApplicationHandler.prepare] Json arg : {'duration': 180}
[2023-03-18 07:00:47][DEBUG] : Sending Zigbee broadcast with tsn 31 under 32 request id, data: b'1fb400'
[2023-03-18 07:00:47][DEBUG] : Command Command.aps_data_request (18, 32, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=65532 endpoint=0>, 0, <ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, 0, b'\x1f\xb4\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:00:47][DEBUG] : Send: 0x12710019001200200002fcff00000036000003001fb4000200
[2023-03-18 07:00:47][DEBUG] : Frame received: 0x127100090002002220
[2023-03-18 07:00:47][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 32]
[2023-03-18 07:00:47][DEBUG] : Frame received: 0x0e72000700a600
[2023-03-18 07:00:47][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 166>, 0]
[2023-03-18 07:00:47][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:00:47][DEBUG] : Send: 0x04720007000000
[2023-03-18 07:00:47][DEBUG] : Frame received: 0x04720013000c00222002fcff0000e100000000
[2023-03-18 07:00:47][DEBUG] : APS data confirm response for request with id 32: e1
[2023-03-18 07:00:47][DEBUG] : Request id: 0x20 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0xfffc endpoint=0>, status: 0xe1
[2023-03-18 07:00:47][DEBUG] : Error while sending 32 req id broadcast: TXStatus.MAC_CHANNEL_ACCESS_FAILURE
[2023-03-18 07:00:47][DEBUG] : Command Command.write_parameter (2, <NetworkParameter.permit_join: 33>, b'\xb4')
[2023-03-18 07:00:47][DEBUG] : Send: 0x0b73000900020021b4
[2023-03-18 07:00:47][DEBUG] : Frame received: 0x0b73000800010021
[2023-03-18 07:00:47][DEBUG] : Write parameter permit_join: SUCCESS
[2023-03-18 07:00:47][INFO] : [ApplicationHandler.put.include] Permit ZGP include
[2023-03-18 07:00:47][INFO] : [zgp.permit] Permit green power pairing for 180 s
[2023-03-18 07:00:47][INFO] : 200 PUT /application/include (127.0.0.1) 149.69ms
[2023-03-18 07:03:54][INFO] : [DeviceHandler.prepare] Json arg : None
[2023-03-18 07:03:54][INFO] : [00:21:2e:ff:ff:09:c2:6a][zdevices.serialize] Serialize device with attributes : 2
[2023-03-18 07:03:54][INFO] : 200 GET /device/all?with_attributes=2 (127.0.0.1) 4.69ms
[2023-03-18 07:03:54][INFO] : [GroupHandler.prepare] Json arg : None
[2023-03-18 07:03:54][INFO] : 200 GET /group/all (127.0.0.1) 3.23ms
[2023-03-18 07:03:58][INFO] : [DeviceHandler.prepare] Json arg : None
[2023-03-18 07:03:58][INFO] : [00:21:2e:ff:ff:09:c2:6a][zdevices.serialize] Serialize device with attributes : 0
[2023-03-18 07:03:58][INFO] : [ApplicationHandler.prepare] Json arg : None
[2023-03-18 07:03:58][INFO] : 200 GET /application/info (127.0.0.1) 2.75ms
[2023-03-18 07:03:58][INFO] : 200 GET /device/all?with_attributes=0 (127.0.0.1) 7.44ms
[2023-03-18 07:03:58][INFO] : [NetworkHandler.prepare] Json arg : None
[2023-03-18 07:03:58][INFO] : [map.scan] Done scanning. Total 0 neighbours
[2023-03-18 07:03:58][INFO] : 200 GET /network/map (127.0.0.1) 5.30ms
[2023-03-18 07:03:59][INFO] : [NetworkHandler.prepare] Json arg : None
[2023-03-18 07:03:59][INFO] : [map.scan] Done scanning. Total 0 neighbours
[2023-03-18 07:03:59][INFO] : 200 GET /network/map (127.0.0.1) 2.15ms
[2023-03-18 07:05:06][DEBUG] : Tries remaining: 3
[2023-03-18 07:05:06][DEBUG] : [0x0000] Extending timeout for 0x21 request
[2023-03-18 07:05:06][DEBUG] : Sending Zigbee request with tsn 33 under 34 request id, data: b'2100'
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_request (17, 34, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Mgmt_Lqi_req: 0x0031>, 0, b'!\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:05:06][DEBUG] : Send: 0x127400180011002200020000000000310000020021000200
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x127400090002002222
[2023-03-18 07:05:06][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 34]
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x0e75000700ae00
[2023-03-18 07:05:06][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:05:06][DEBUG] : Send: 0x1775000800010001
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x17750023001c00260200000002000000000031800500210000000000afddc43700001e
[2023-03-18 07:05:06][DEBUG] : APS data indication response: [28, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 32817, b'!\x00\x00\x00\x00', 0, 175, 221, 196, 55, 0, 0, 30]
[2023-03-18 07:05:06][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 32817: b'!\x00\x00\x00\x00'
[2023-03-18 07:05:06][DEBUG] : [0x0000] Scheduling initialization
[2023-03-18 07:05:06][DEBUG] : Tries remaining: 3
[2023-03-18 07:05:06][INFO] : [0x0000] Requesting 'Node Descriptor'
[2023-03-18 07:05:06][DEBUG] : Tries remaining: 2
[2023-03-18 07:05:06][DEBUG] : [0x0000] Extending timeout for 0x23 request
[2023-03-18 07:05:06][DEBUG] : Sending Zigbee request with tsn 35 under 36 request id, data: b'230000'
[2023-03-18 07:05:06][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x8031, data: b'2100000000'
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:05:06][DEBUG] : Send: 0x04760007000000
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x04760013000c00222202000000000000000000
[2023-03-18 07:05:06][DEBUG] : APS data confirm response for request with id 34: 00
[2023-03-18 07:05:06][DEBUG] : Request id: 0x22 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_request (18, 36, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'#\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:05:06][DEBUG] : Send: 0x12770019001200240002000000000002000003002300000200
[2023-03-18 07:05:06][DEBUG] : [0x0000] request status: Status.SUCCESS. response: Neighbors(entries=0, start_index=0, neighbor_table_list=[])
[2023-03-18 07:05:06][DEBUG] : [0x0000] Done scanning. Total 0 neighbours
[2023-03-18 07:05:06][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'DELETE FROM neighbors_v10 WHERE device_ieee = ?', (00:21:2e:ff:ff:09:c2:6a,))
[2023-03-18 07:05:06][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x7f95076e30>, 'DELETE FROM neighbors_v10 WHERE device_ieee = ?', (00:21:2e:ff:ff:09:c2:6a,)) completed
[2023-03-18 07:05:06][DEBUG] : executing functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f95076e30>, 'INSERT INTO neighbors_v10 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', [])
[2023-03-18 07:05:06][DEBUG] : operation functools.partial(<built-in method executemany of sqlite3.Connection object at 0x7f95076e30>, 'INSERT INTO neighbors_v10 VALUES (?,?,?,?,?,?,?,?,?,?,?,?)', []) completed
[2023-03-18 07:05:06][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>)
[2023-03-18 07:05:06][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x7f95076e30>) completed
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x127700090002002224
[2023-03-18 07:05:06][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 36]
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x0e78000700ae00
[2023-03-18 07:05:06][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:05:06][DEBUG] : Send: 0x1778000800010001
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x17780021001a0026020000000200000000000200030023000000afddc43700001e
[2023-03-18 07:05:06][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'#\x00\x00', 0, 175, 221, 196, 55, 0, 0, 30]
[2023-03-18 07:05:06][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'#\x00\x00'
[2023-03-18 07:05:06][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:05:06][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:05:06][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'230000'
[2023-03-18 07:05:06][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:05:06][DEBUG] : Send: 0x04790007000000
[2023-03-18 07:05:06][DEBUG] : Frame received: 0x04790013000c00222402000000000000000000
[2023-03-18 07:05:06][DEBUG] : APS data confirm response for request with id 36: 00
[2023-03-18 07:05:06][DEBUG] : Request id: 0x24 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:05:34][DEBUG] : Tries remaining: 1
[2023-03-18 07:05:34][DEBUG] : [0x0000] Extending timeout for 0x25 request
[2023-03-18 07:05:34][DEBUG] : Sending Zigbee request with tsn 37 under 38 request id, data: b'250000'
[2023-03-18 07:05:34][DEBUG] : Command Command.aps_data_request (18, 38, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'%\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:05:34][DEBUG] : Send: 0x127a0019001200260002000000000002000003002500000200
[2023-03-18 07:05:34][DEBUG] : Frame received: 0x127a00090002002226
[2023-03-18 07:05:34][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 38]
[2023-03-18 07:05:34][DEBUG] : Frame received: 0x0e7b000700ae00
[2023-03-18 07:05:34][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:05:34][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:05:34][DEBUG] : Send: 0x177b000800010001
[2023-03-18 07:05:34][DEBUG] : Frame received: 0x177b0021001a0026020000000200000000000200030025000000afdde43c00001e
[2023-03-18 07:05:34][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'%\x00\x00', 0, 175, 221, 228, 60, 0, 0, 30]
[2023-03-18 07:05:34][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'%\x00\x00'
[2023-03-18 07:05:34][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:05:34][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:05:34][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'250000'
[2023-03-18 07:05:34][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:05:34][DEBUG] : Send: 0x047c0007000000
[2023-03-18 07:05:34][DEBUG] : Frame received: 0x047c0013000c00222602000000000000000000
[2023-03-18 07:05:34][DEBUG] : APS data confirm response for request with id 38: 00
[2023-03-18 07:05:34][DEBUG] : Request id: 0x26 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:06:02][DEBUG] : Tries remaining: 2
[2023-03-18 07:06:02][INFO] : [0x0000] Requesting 'Node Descriptor'
[2023-03-18 07:06:02][DEBUG] : Tries remaining: 2
[2023-03-18 07:06:02][DEBUG] : [0x0000] Extending timeout for 0x27 request
[2023-03-18 07:06:02][DEBUG] : Sending Zigbee request with tsn 39 under 40 request id, data: b'270000'
[2023-03-18 07:06:02][DEBUG] : Command Command.aps_data_request (18, 40, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b"'\x00\x00", <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:06:02][DEBUG] : Send: 0x127d0019001200280002000000000002000003002700000200
[2023-03-18 07:06:02][DEBUG] : Frame received: 0x127d00090002002228
[2023-03-18 07:06:02][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 40]
[2023-03-18 07:06:02][DEBUG] : Frame received: 0x0e7e000700ae00
[2023-03-18 07:06:02][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:06:02][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:06:02][DEBUG] : Send: 0x177e000800010001
[2023-03-18 07:06:02][DEBUG] : Frame received: 0x177e0021001a0026020000000200000000000200030027000000afdde63c00001e
[2023-03-18 07:06:02][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b"'\x00\x00", 0, 175, 221, 230, 60, 0, 0, 30]
[2023-03-18 07:06:02][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b"'\x00\x00"
[2023-03-18 07:06:02][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:06:02][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:06:02][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'270000'
[2023-03-18 07:06:02][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:06:02][DEBUG] : Send: 0x047f0007000000
[2023-03-18 07:06:02][DEBUG] : Frame received: 0x047f0013000c00222802000000000000000000
[2023-03-18 07:06:02][DEBUG] : APS data confirm response for request with id 40: 00
[2023-03-18 07:06:02][DEBUG] : Request id: 0x28 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:06:30][DEBUG] : Tries remaining: 1
[2023-03-18 07:06:30][DEBUG] : [0x0000] Extending timeout for 0x29 request
[2023-03-18 07:06:30][DEBUG] : Sending Zigbee request with tsn 41 under 42 request id, data: b'290000'
[2023-03-18 07:06:30][DEBUG] : Command Command.aps_data_request (18, 42, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b')\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:06:30][DEBUG] : Send: 0x128000190012002a0002000000000002000003002900000200
[2023-03-18 07:06:30][DEBUG] : Frame received: 0x12800009000200222a
[2023-03-18 07:06:30][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 42]
[2023-03-18 07:06:30][DEBUG] : Frame received: 0x0e81000700ae00
[2023-03-18 07:06:30][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:06:30][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:06:30][DEBUG] : Send: 0x1781000800010001
[2023-03-18 07:06:30][DEBUG] : Frame received: 0x17810021001a0026020000000200000000000200030029000000afdde83c00001e
[2023-03-18 07:06:30][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b')\x00\x00', 0, 175, 221, 232, 60, 0, 0, 30]
[2023-03-18 07:06:30][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b')\x00\x00'
[2023-03-18 07:06:30][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:06:30][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:06:30][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'290000'
[2023-03-18 07:06:30][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:06:30][DEBUG] : Send: 0x04820007000000
[2023-03-18 07:06:30][DEBUG] : Frame received: 0x04820013000c00222a02000000000000000000
[2023-03-18 07:06:30][DEBUG] : APS data confirm response for request with id 42: 00
[2023-03-18 07:06:30][DEBUG] : Request id: 0x2a 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:06:59][DEBUG] : Tries remaining: 1
[2023-03-18 07:06:59][INFO] : [0x0000] Requesting 'Node Descriptor'
[2023-03-18 07:06:59][DEBUG] : Tries remaining: 2
[2023-03-18 07:06:59][DEBUG] : [0x0000] Extending timeout for 0x2b request
[2023-03-18 07:06:59][DEBUG] : Sending Zigbee request with tsn 43 under 44 request id, data: b'2b0000'
[2023-03-18 07:06:59][DEBUG] : Command Command.aps_data_request (18, 44, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'+\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:06:59][DEBUG] : Send: 0x128300190012002c0002000000000002000003002b00000200
[2023-03-18 07:06:59][DEBUG] : Frame received: 0x12830009000200222c
[2023-03-18 07:06:59][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 44]
[2023-03-18 07:06:59][DEBUG] : Frame received: 0x0e84000700ae00
[2023-03-18 07:06:59][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:06:59][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:06:59][DEBUG] : Send: 0x1784000800010001
[2023-03-18 07:06:59][DEBUG] : Frame received: 0x17840021001a002602000000020000000000020003002b000000afddeb3c00001e
[2023-03-18 07:06:59][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'+\x00\x00', 0, 175, 221, 235, 60, 0, 0, 30]
[2023-03-18 07:06:59][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'+\x00\x00'
[2023-03-18 07:06:59][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:06:59][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:06:59][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'2b0000'
[2023-03-18 07:06:59][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:06:59][DEBUG] : Send: 0x04850007000000
[2023-03-18 07:06:59][DEBUG] : Frame received: 0x04850013000c00222c02000000000000000000
[2023-03-18 07:06:59][DEBUG] : APS data confirm response for request with id 44: 00
[2023-03-18 07:06:59][DEBUG] : Request id: 0x2c 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:07:16][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 07:07:16][DEBUG] : Send: 0x0b86000c0005002658020000
[2023-03-18 07:07:16][DEBUG] : Frame received: 0x0b86000800010026
[2023-03-18 07:07:16][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2023-03-18 07:07:27][DEBUG] : Tries remaining: 1
[2023-03-18 07:07:27][DEBUG] : [0x0000] Extending timeout for 0x2d request
[2023-03-18 07:07:27][DEBUG] : Sending Zigbee request with tsn 45 under 46 request id, data: b'2d0000'
[2023-03-18 07:07:27][DEBUG] : Command Command.aps_data_request (18, 46, <DeconzSendDataFlags.NONE: 0>, <DeconzAddressEndpoint address_mode=2 address=0x0000 endpoint=0>, 0, <ZDOCmd.Node_Desc_req: 0x0002>, 0, b'-\x00\x00', <DeconzTransmitOptions.USE_NWK_KEY_SECURITY: 2>, 0)
[2023-03-18 07:07:27][DEBUG] : Send: 0x128700190012002e0002000000000002000003002d00000200
[2023-03-18 07:07:27][DEBUG] : Frame received: 0x12870009000200222e
[2023-03-18 07:07:27][DEBUG] : APS data request response: [2, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|2: 34>, 46]
[2023-03-18 07:07:27][DEBUG] : Frame received: 0x0e88000700ae00
[2023-03-18 07:07:27][DEBUG] : Device state changed response: [<DeviceState.128|APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_INDICATION|APSDE_DATA_CONFIRM|2: 174>, 0]
[2023-03-18 07:07:27][DEBUG] : Command Command.aps_data_indication (1, 1)
[2023-03-18 07:07:27][DEBUG] : Send: 0x1788000800010001
[2023-03-18 07:07:27][DEBUG] : Frame received: 0x17880021001a002602000000020000000000020003002d000000afdded3c00001e
[2023-03-18 07:07:27][DEBUG] : APS data indication response: [26, <DeviceState.APSDE_DATA_REQUEST_SLOTS_AVAILABLE|APSDE_DATA_CONFIRM|2: 38>, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, 0, 0, 2, b'-\x00\x00', 0, 175, 221, 237, 60, 0, 0, 30]
[2023-03-18 07:07:27][DEBUG] : Received frame on uninitialized device <DeconzDevice model='RaspBee II' manuf='dresden elektronik' nwk=0x0000 ieee=00:21:2e:ff:ff:09:c2:6a is_initialized=False> from ep 0 to ep 0, cluster 2: b'-\x00\x00'
[2023-03-18 07:07:27][DEBUG] : [0x0000:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000]
[2023-03-18 07:07:27][DEBUG] : [0x0000:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000])
[2023-03-18 07:07:27][DEBUG] : 'aps_data_indication' response from <DeconzAddress address_mode=ADDRESS_MODE.NWK address=0x0000>, ep: 0, profile: 0x0000, cluster_id: 0x0002, data: b'2d0000'
[2023-03-18 07:07:27][DEBUG] : Command Command.aps_data_confirm (0,)
[2023-03-18 07:07:27][DEBUG] : Send: 0x04890007000000
[2023-03-18 07:07:27][DEBUG] : Frame received: 0x04890013000c00222e02000000000000000000
[2023-03-18 07:07:27][DEBUG] : APS data confirm response for request with id 46: 00
[2023-03-18 07:07:27][DEBUG] : Request id: 0x2e 'aps_data_confirm' for <DeconzAddressEndpoint address_mode=ADDRESS_MODE.NWK address=0x0000 endpoint=0>, status: 0x00
[2023-03-18 07:14:46][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 07:14:46][DEBUG] : Send: 0x0b8a000c0005002658020000
[2023-03-18 07:14:46][DEBUG] : Frame received: 0x0b8a000800010026
[2023-03-18 07:14:46][DEBUG] : Write parameter watchdog_ttl: SUCCESS
[2023-03-18 07:15:02][INFO] : [DeviceHandler.prepare] Json arg : None
[2023-03-18 07:15:02][INFO] : [00:21:2e:ff:ff:09:c2:6a][zdevices.serialize] Serialize device with attributes : 1
[2023-03-18 07:15:02][INFO] : 200 GET /device/all (127.0.0.1) 3.01ms
[2023-03-18 07:22:16][DEBUG] : Command Command.write_parameter (5, <NetworkParameter.watchdog_ttl: 38>, b'X\x02\x00\x00')
[2023-03-18 07:22:16][DEBUG] : Send: 0x0b8b000c0005002658020000
[2023-03-18 07:22:16][DEBUG] : Frame received: 0x0b8b000800010026
[2023-03-18 07:22:16][DEBUG] : Write parameter watchdog_ttl: SUCCESS