06:34:24.456 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:24.457 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:24.459 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:24.598 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=0, name=ZCL Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:34:24.600 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=38, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[0]]] 06:34:25.427 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:25.428 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:25.430 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:25.458 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:25.460 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:25.461 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:26.302 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2 06:34:26.304 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=36, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0/0 -> 56846/1, cluster=0019, TID=--, identifiers=[2]]] 06:34:26.431 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:26.433 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:26.435 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:26.463 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:26.464 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:26.466 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:27.437 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:27.439 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:27.441 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:27.469 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:27.471 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:27.472 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:28.195 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: running NEIGHBORS 06:34:28.197 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=39, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 57079/0, cluster=0031, TID=--, startIndex=0]] 06:34:28.442 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:28.444 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:28.446 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:28.474 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:28.476 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:28.477 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:29.448 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:29.450 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:29.451 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:29.480 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:29.481 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:29.482 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:29.708 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=6, name=Date Code, dataType=CHARACTER_STRING, lastValue=null] 06:34:29.711 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=37, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[6]]] 06:34:30.017 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2 06:34:30.019 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=40, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0/0 -> 57079/1, cluster=0019, TID=--, identifiers=[2]]] 06:34:30.454 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:30.456 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:30.457 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:30.485 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:30.487 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:30.488 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:31.458 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:31.460 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:31.462 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:31.490 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:31.492 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:31.493 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:32.465 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:32.467 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:32.468 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:32.496 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:32.498 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:32.499 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:33.470 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:33.472 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:33.474 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:33.502 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:33.504 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:33.505 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:34.221 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:34:34.223 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: request NEIGHBORS failed. Retry 7, wait 12810ms before retry. 06:34:34.423 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=1, name=Application Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:34:34.425 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=41, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[1]]] 06:34:34.475 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:34.477 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:34.478 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:34.507 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:34.508 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:34.509 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:35.278 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFAD43: Data store: Deferring write for 250ms. 06:34:35.280 [INFO ] [gbee.discovery.ZigBeeDiscoveryService] - 00158D0001FFAD43: Starting ZigBee device discovery 06:34:35.282 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=38, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[5, 4, 3, 2, 6, 0, 1]]] 06:34:35.372 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Deferring write for 250ms. 06:34:35.482 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:35.483 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:35.485 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:35.513 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:35.515 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:35.516 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:35.530 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFAD43: Data store: Writing node. 06:34:35.636 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Writing node. 06:34:36.487 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:36.489 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:36.490 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:36.519 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:36.520 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:36.522 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:36.602 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=6, name=Date Code, dataType=CHARACTER_STRING, lastValue=null] 06:34:36.604 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=42, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[6]]] 06:34:37.492 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:37.493 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:37.495 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:37.523 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:37.525 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:37.526 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:38.306 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFAD43: Data store: Deferring write for 250ms. 06:34:38.507 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:38.509 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:38.511 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:38.539 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:38.540 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:38.542 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:38.558 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFAD43: Data store: Writing node. 06:34:39.513 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:39.514 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:39.516 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:39.544 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:39.546 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:39.547 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:40.199 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:34:40.200 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: request NEIGHBORS failed. Retry 7, wait 10560ms before retry. 06:34:40.519 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:40.521 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:40.522 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:40.550 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:40.552 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:40.553 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:41.524 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:41.526 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:41.527 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:41.555 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:41.557 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:41.558 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:41.715 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2 06:34:41.717 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=39, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0/0 -> 56846/1, cluster=0019, TID=--, identifiers=[2]]] 06:34:42.020 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Deferring write for 250ms. 06:34:42.271 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Writing node. 06:34:42.532 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:42.535 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:42.537 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:42.566 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:42.570 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:42.571 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:43.496 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30000/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1189997} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 06:34:43.498 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30002/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1189999} idle timeout expired 06:34:43.499 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onFail FillInterest@122d65{null} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:43.505 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@1d8d953{IDLE}->null java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:43.512 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30016/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1190013} 06:34:43.542 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:43.544 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:43.545 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:43.573 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:43.575 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:43.576 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:44.549 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:44.550 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:44.552 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:44.580 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:44.582 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:44.583 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:45.555 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:45.556 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:45.558 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:45.586 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:45.588 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:45.589 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:46.243 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 06:34:46.244 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout expired 06:34:46.246 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onFail FillInterest@173da22{AC.ReadCB@840d7a{HttpConnection@840d7a::SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30003/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0}}} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.252 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - close HttpParser{s=CLOSE,0 of -1} 06:34:46.255 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - CLOSE --> CLOSE 06:34:46.261 [DEBUG] [g.eclipse.jetty.io.AbstractConnection] - HttpConnection@840d7a::SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30018/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed {} 06:34:46.266 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - close SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30023/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.268 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - close(null) SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30025/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.275 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - doClose SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30032/30000}{io=1/1,kio=1,kro=1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.278 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@1394f5a{IDLE}->null java.nio.channels.ClosedChannelException: null at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:521) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:354) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:214) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractConnection.onFillInterestedFailed(AbstractConnection.java:176) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.server.HttpConnection.onFillInterestedFailed(HttpConnection.java:502) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.failed(AbstractConnection.java:317) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.FillInterest.onFail(FillInterest.java:138) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.onIdleExpired(AbstractEndPoint.java:407) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.284 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onClose FillInterest@173da22{null} 06:34:46.288 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Wakeup ManagedSelector@aabe05{STARTED} id=0 keys=3 selected=0 updates=0 06:34:46.290 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db woken with none selected 06:34:46.292 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db woken up from select, 0/0/2 selected 06:34:46.293 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db processing 0 keys, 0 updates 06:34:46.294 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@16d8126 in QueuedThreadPool[qtp26832574]@1996ebe{STARTED,2<=9<=50,i=3,r=1,q=0}[ReservedThreadExecutor@1a1b49a{s=1/1,p=0}] 06:34:46.299 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Destroyed SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30056/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.301 [DEBUG] [g.eclipse.jetty.io.AbstractConnection] - onClose HttpConnection@840d7a::SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30058/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.308 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@16d8126 startThread=false 06:34:46.309 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - updateable 0 06:34:46.316 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@1394f5a{IDLE}->null java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.316 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@16d8126 in QueuedThreadPool[qtp26832574]@1996ebe{STARTED,2<=9<=50,i=3,r=1,q=0}[ReservedThreadExecutor@1a1b49a{s=1/1,p=0}] 06:34:46.319 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - updates 0 06:34:46.323 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db waiting with 2 keys 06:34:46.325 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SocketChannelEndPoint@1c07779{/0:0:0:0:0:0:0:1:40662<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30082/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@840d7a[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@c5dd2c{s=START}]=>HttpChannelOverHttp@1f81be5{r=18,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.427 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=2, name=Stack Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:34:46.429 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=43, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[2]]] 06:34:46.559 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:46.561 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:46.563 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:46.592 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:46.593 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:46.595 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:46.856 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 30000 ms, remaining: 0 ms 06:34:46.858 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} idle timeout expired 06:34:46.860 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onFail FillInterest@955ade{AC.ReadCB@158e8c4{HttpConnection@158e8c4::SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=FI,flush=-,to=30003/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0}}} java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.862 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - close HttpParser{s=CLOSE,0 of -1} 06:34:46.864 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - CLOSE --> CLOSE 06:34:46.865 [DEBUG] [g.eclipse.jetty.io.AbstractConnection] - HttpConnection@158e8c4::SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30008/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} onFillInterestedFailed {} 06:34:46.867 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - close SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30010/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.872 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - close(null) SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,OSHUT,fill=-,flush=-,to=30016/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.874 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - doClose SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30017/30000}{io=1/1,kio=1,kro=1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.876 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@df2025{IDLE}->null java.nio.channels.ClosedChannelException: null at org.eclipse.jetty.io.WriteFlusher.onClose(WriteFlusher.java:521) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.onClose(AbstractEndPoint.java:354) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.ChannelEndPoint.onClose(ChannelEndPoint.java:214) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.doOnClose(AbstractEndPoint.java:225) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:192) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.close(AbstractEndPoint.java:175) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractConnection.onFillInterestedFailed(AbstractConnection.java:176) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.server.HttpConnection.onFillInterestedFailed(HttpConnection.java:502) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.failed(AbstractConnection.java:317) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.FillInterest.onFail(FillInterest.java:138) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.AbstractEndPoint.onIdleExpired(AbstractEndPoint.java:407) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.879 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onClose FillInterest@955ade{null} 06:34:46.880 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Wakeup ManagedSelector@aabe05{STARTED} id=0 keys=2 selected=0 updates=0 06:34:46.892 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db woken with none selected 06:34:46.894 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db woken up from select, 0/0/1 selected 06:34:46.895 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db processing 0 keys, 0 updates 06:34:46.896 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - updateable 0 06:34:46.898 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - queue org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@372d21 startThread=false 06:34:46.897 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - run org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@372d21 in QueuedThreadPool[qtp26832574]@1996ebe{STARTED,2<=9<=50,i=3,r=1,q=0}[ReservedThreadExecutor@1a1b49a{s=1/1,p=0}] 06:34:46.900 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Destroyed SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30044/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.901 [DEBUG] [g.eclipse.jetty.io.AbstractConnection] - onClose HttpConnection@158e8c4::SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30045/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:46.903 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@df2025{IDLE}->null java.util.concurrent.TimeoutException: Idle timeout expired: 30000/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:34:46.905 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - updates 0 06:34:46.904 [DEBUG] [se.jetty.util.thread.QueuedThreadPool] - ran org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@372d21 in QueuedThreadPool[qtp26832574]@1996ebe{STARTED,2<=9<=50,i=3,r=1,q=0}[ReservedThreadExecutor@1a1b49a{s=1/1,p=0}] 06:34:46.912 [DEBUG] [org.eclipse.jetty.io.ManagedSelector ] - Selector sun.nio.ch.EPollSelectorImpl@15864db waiting with 1 keys 06:34:46.915 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SocketChannelEndPoint@24f51a{/0:0:0:0:0:0:0:1:40664<->/0:0:0:0:0:0:0:1:8080,CLOSED,fill=-,flush=-,to=30058/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@158e8c4[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@cb4450{s=START}]=>HttpChannelOverHttp@7b1877{r=2,c=false,c=false/false,a=IDLE,uri=null,age=0} 06:34:47.034 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: running NEIGHBORS 06:34:47.036 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=40, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 56846/0, cluster=0031, TID=--, startIndex=0]] 06:34:47.287 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=4, name=Manufacturer Name, dataType=CHARACTER_STRING, lastValue=null] 06:34:47.289 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=41, transaction=ZigBeeTransaction [queueTime=1, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[4]]] 06:34:47.565 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:47.567 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:47.568 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:47.596 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:47.598 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:47.599 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:48.569 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:48.572 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:48.573 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:48.601 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:48.603 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:48.604 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:48.607 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2 06:34:48.608 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=44, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0/0 -> 57079/1, cluster=0019, TID=--, identifiers=[2]]] 06:34:49.016 [DEBUG] [org.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 06:34:49.575 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:49.576 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:49.578 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:49.606 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:49.608 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:49.609 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:50.412 [DEBUG] [org.apache.felix.configadmin ] - getConfiguration(pid=org.openhab.addons) 06:34:50.414 [DEBUG] [org.apache.felix.configadmin ] - Found cached configuration org.openhab.addons bound to mvn:org.openhab.core.bundles/org.openhab.core.karaf/2.5.0-SNAPSHOT 06:34:50.416 [DEBUG] [org.apache.felix.configadmin ] - getConfiguration(pid=org.ops4j.pax.url.mvn, location=null) 06:34:50.417 [DEBUG] [org.apache.felix.configadmin ] - Found cached configuration org.ops4j.pax.url.mvn bound to ? 06:34:50.419 [DEBUG] [org.apache.felix.configadmin ] - getConfiguration(pid=org.ops4j.pax.url.mvn, location=null) 06:34:50.420 [DEBUG] [org.apache.felix.configadmin ] - Found cached configuration org.ops4j.pax.url.mvn bound to ? 06:34:50.579 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:50.581 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:50.582 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:50.611 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:50.612 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:50.613 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:50.762 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: running NEIGHBORS 06:34:50.763 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=45, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 57079/0, cluster=0031, TID=--, startIndex=0]] 06:34:51.584 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:51.586 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:51.587 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:51.615 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:51.617 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:51.618 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:52.589 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:52.590 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:52.592 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:52.620 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:52.621 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:52.623 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:53.593 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:53.595 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:53.597 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:53.625 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:53.627 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:53.628 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:53.725 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zigbee:device:04000EB9:00158d0001ffad43' has been updated. 06:34:53.729 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=DirectByteBuffer@143e2e3[p=0,l=1010,c=32768,r=1010]={<<>>body>\n null [HeapByteBuffer@1c37e81[p=0,l=7,c=1024,r=7]={<<<\r\n3F2\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@143e2e3[p=0,l=1010,c=32768,r=1010]={<<>>body>\n null:IDLE-->WRITING 06:34:53.739 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=42, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Power Configuration: 0/0 -> 56846/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:34:53.746 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 1017 SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=10228/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1200247} 06:34:53.749 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=1017 remaining=0 WriteFlusher@1d8d953{WRITING}->null 06:34:53.750 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@1d8d953{IDLE}->null:WRITING-->IDLE 06:34:53.752 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1b780b8[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@eaf105] generate: DONE (null,[p=1010,l=1010,c=32768,r=0],false)@COMMITTED 06:34:54.598 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:54.600 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:54.602 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:54.630 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:54.631 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:54.633 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:55.603 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:55.605 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:55.606 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:55.635 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:55.636 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:55.637 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:56.608 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:56.609 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:56.611 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:56.639 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:56.641 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:56.642 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:57.612 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:57.614 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:57.615 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:57.644 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:57.645 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:57.646 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:58.430 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=0, name=ZCL Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:34:58.432 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=46, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[0]]] 06:34:58.617 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:58.619 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:58.621 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:58.649 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:58.651 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:58.652 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:34:59.038 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:34:59.040 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: request NEIGHBORS failed. Retry 8, wait 12810ms before retry. 06:34:59.290 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=5, name=Model Identifier, dataType=CHARACTER_STRING, lastValue=null] 06:34:59.293 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=43, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[5]]] 06:34:59.623 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:34:59.624 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:34:59.626 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:34:59.654 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:34:59.656 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:34:59.657 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:00.613 [INFO ] [smarthome.event.ThingUpdatedEvent ] - Thing 'zigbee:device:04000EB9:00158d0001ffadc1' has been updated. 06:35:00.616 [DEBUG] [org.eclipse.jetty.server.HttpChannel ] - sendResponse info=null content=DirectByteBuffer@143e2e3[p=0,l=1010,c=32768,r=1010]={<<>>body>\n null [HeapByteBuffer@1c37e81[p=0,l=7,c=1024,r=7]={<<<\r\n3F2\r\n>>>\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00...\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@143e2e3[p=0,l=1010,c=32768,r=1010]={<<>>body>\n 57079/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:00.624 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@1d8d953{WRITING}->null:IDLE-->WRITING 06:35:00.628 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:00.631 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:00.632 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:00.638 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint ] - flushed 1017 SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=6888/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1207138} 06:35:00.639 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=1017 remaining=0 WriteFlusher@1d8d953{WRITING}->null 06:35:00.645 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@1d8d953{IDLE}->null:WRITING-->IDLE 06:35:00.647 [DEBUG] [g.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@1b780b8[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@f51087] generate: DONE (null,[p=1010,l=1010,c=32768,r=0],false)@COMMITTED 06:35:00.661 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:00.662 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:00.664 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:01.634 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:01.635 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:01.637 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:01.665 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:01.667 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:01.668 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:02.639 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:02.641 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:02.643 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:02.671 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:02.672 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:02.674 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:02.765 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:35:02.767 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: request NEIGHBORS failed. Retry 8, wait 19008ms before retry. 06:35:03.644 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:03.647 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:03.648 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:03.676 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:03.678 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:03.679 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:04.650 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:04.651 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:04.653 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:04.681 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:04.682 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:04.684 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:05.654 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:05.656 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:05.657 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:05.685 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:05.687 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:05.688 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:05.752 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=44, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Power Configuration: 0/0 -> 56846/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:06.659 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:06.660 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:06.662 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:06.690 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:06.692 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:06.693 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:07.663 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:07.665 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:07.666 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:07.695 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:07.696 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:07.698 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:08.668 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:08.670 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:08.672 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:08.700 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:08.701 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:08.703 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:09.673 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:09.675 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:09.676 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:09.704 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:09.706 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:09.707 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:10.434 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@f6bb32, id=6, name=Date Code, dataType=CHARACTER_STRING, lastValue=null] 06:35:10.436 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=48, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 57079/1, cluster=0000, TID=--, identifiers=[6]]] 06:35:10.677 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:10.679 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:10.681 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:10.709 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:10.711 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:10.712 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:11.294 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=3, name=HW Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:35:11.296 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=45, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[3]]] 06:35:11.682 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:11.684 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:11.685 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:11.713 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:11.715 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:11.717 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:11.851 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: running NEIGHBORS 06:35:11.853 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=46, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 56846/0, cluster=0031, TID=--, startIndex=0]] 06:35:12.642 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=49, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Power Configuration: 0/0 -> 57079/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:12.686 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:12.688 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:12.690 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:12.718 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:12.720 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:12.721 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:13.517 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=12877/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1220018} idle timeout check, elapsed: 12877 ms, remaining: 17123 ms 06:35:13.691 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:13.693 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:13.695 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:13.723 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:13.725 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:13.726 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:14.554 [DEBUG] [org.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 06:35:14.696 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:14.698 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:14.699 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:14.727 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:14.729 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:14.730 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:15.701 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:15.702 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:15.704 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:15.732 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:15.733 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:15.735 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:16.705 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:16.707 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:16.708 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:16.736 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:16.738 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:16.739 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:17.710 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:17.711 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:17.713 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:17.741 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:17.742 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:17.744 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:17.758 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=47, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Power Configuration: 0/0 -> 56846/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:18.714 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:18.732 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:18.735 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:18.764 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:18.768 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:18.769 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:19.737 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:19.739 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:19.741 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:19.769 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:19.770 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:19.772 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:20.742 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:20.744 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:20.745 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:20.773 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:20.775 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:20.776 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:21.746 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:21.748 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:21.750 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:21.776 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: running NEIGHBORS 06:35:21.779 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=50, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 57079/0, cluster=0031, TID=--, startIndex=0]] 06:35:21.780 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:21.782 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:21.783 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:22.437 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: 2 06:35:22.439 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=51, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Ota Upgrade: 0/0 -> 57079/1, cluster=0019, TID=--, identifiers=[2]]] 06:35:22.751 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:22.753 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:22.754 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:22.782 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:22.784 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:22.785 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:23.298 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=1, name=Application Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:35:23.300 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=48, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[1]]] 06:35:23.755 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:23.758 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:23.759 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:23.788 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:23.794 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:23.795 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:23.854 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:35:23.856 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: request NEIGHBORS failed. Retry 9, wait 12810ms before retry. 06:35:24.648 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=52, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Power Configuration: 0/0 -> 57079/1, cluster=0001, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:24.761 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:24.763 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:24.764 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:24.792 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:24.794 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:24.795 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:25.765 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:25.767 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:25.769 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:25.797 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:25.798 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:25.800 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:26.770 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:26.772 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:26.773 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:26.801 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:26.803 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:26.804 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:27.774 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:27.776 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:27.778 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:27.806 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:27.810 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:27.812 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:28.779 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:28.781 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:28.783 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:28.811 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:28.812 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:28.814 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:29.764 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=49, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Thermostat: 0/0 -> 56846/1, cluster=0201, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:29.784 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:29.786 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:29.787 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:29.815 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:29.817 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:29.818 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:30.642 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30002/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1237143} idle timeout check, elapsed: 30002 ms, remaining: -2 ms 06:35:30.644 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30004/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1237145} idle timeout expired 06:35:30.646 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onFail FillInterest@122d65{null} java.util.concurrent.TimeoutException: Idle timeout expired: 30002/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:35:30.648 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@1d8d953{IDLE}->null java.util.concurrent.TimeoutException: Idle timeout expired: 30002/30000 ms at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:171) [bundleFile:9.4.20.v20190813] at org.eclipse.jetty.io.IdleTimeout.idleCheck(IdleTimeout.java:113) [bundleFile:9.4.20.v20190813] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:834) [?:?] 06:35:30.650 [DEBUG] [org.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SocketChannelEndPoint@93f180{/0:0:0:0:0:0:0:1:40622<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30010/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1691a19[p=HttpParser{s=END,0 of -1},g=HttpGenerator@17f245{s=COMMITTED}]=>HttpChannelOverHttp@541022{r=7,c=true,c=false/false,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=1237151} 06:35:30.789 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:30.794 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:30.796 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:30.824 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:30.825 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:30.827 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:31.797 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:31.799 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:31.800 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:31.829 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:31.830 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:31.831 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:32.802 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:32.803 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:32.805 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:32.833 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:32.834 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:32.836 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:33.782 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: ManagementLqiRequest response CommandResult [TIMEOUT] 06:35:33.784 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFADC1: Node SVC Discovery: request NEIGHBORS failed. Retry 9, wait 14784ms before retry. 06:35:33.806 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:33.807 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:33.809 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:33.836 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:33.838 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:33.839 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:34.441 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Deferring write for 250ms. 06:35:34.693 [DEBUG] [database.ZigBeeNetworkDatabaseManager] - 00158D0001FFADC1: Data store: Writing node. 06:35:34.814 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:34.815 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:34.817 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:34.845 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:34.846 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:34.848 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:35.302 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=2, name=Stack Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:35:35.303 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=50, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[2]]] 06:35:35.818 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:35.820 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:35.821 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:35.849 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:35.851 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:35.852 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:36.653 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFADC1: Added transaction to queue, len=53, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=DiscoverAttributesCommand [Thermostat: 0/0 -> 57079/1, cluster=0201, TID=--, startAttributeIdentifier=0, maximumAttributeIdentifiers=10]] 06:35:36.668 [DEBUG] [discovery.ZigBeeNodeServiceDiscoverer] - 00158D0001FFAD43: Node SVC Discovery: running NEIGHBORS 06:35:36.670 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=51, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ManagementLqiRequest [0/0 -> 56846/0, cluster=0031, TID=--, startIndex=0]] 06:35:36.822 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:36.824 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:36.825 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:36.853 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:36.854 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:36.856 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:37.826 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:37.833 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:37.835 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:37.863 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:37.865 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:37.866 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:38.649 [DEBUG] [org.eclipse.jetty.server.session ] - node0 scavenging sessions 06:35:38.651 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler17255270==dftMaxIdleSec=600 scavenging sessions 06:35:38.652 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler17255270==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.656 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@1892ef3[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.658 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler26339008==dftMaxIdleSec=600 scavenging sessions 06:35:38.659 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler26339008==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.664 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@3d18c2[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.665 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler16766669==dftMaxIdleSec=600 scavenging sessions 06:35:38.667 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler16766669==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.668 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@584432[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.670 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7734596==dftMaxIdleSec=600 scavenging sessions 06:35:38.671 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7734596==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.672 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@a2e9f[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.674 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler14127155==dftMaxIdleSec=600 scavenging sessions 06:35:38.675 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler14127155==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.681 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@dcf03a[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.683 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7099099==dftMaxIdleSec=600 scavenging sessions 06:35:38.685 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7099099==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.686 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@1b7aff0[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.687 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler6234312==dftMaxIdleSec=600 scavenging sessions 06:35:38.688 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler6234312==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.689 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@148061b[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.690 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler31602922==dftMaxIdleSec=600 scavenging sessions 06:35:38.691 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler31602922==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.692 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@f21dc4[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.693 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler13668506==dftMaxIdleSec=600 scavenging sessions 06:35:38.694 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler13668506==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.695 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@1f777b3[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.696 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7314821==dftMaxIdleSec=600 scavenging sessions 06:35:38.697 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler7314821==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.698 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@467e5[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.699 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler19346700==dftMaxIdleSec=600 scavenging sessions 06:35:38.700 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler19346700==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.701 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@162f537[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.702 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler20334137==dftMaxIdleSec=600 scavenging sessions 06:35:38.703 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.SessionHandler20334137==dftMaxIdleSec=600 scavenging session ids [] 06:35:38.703 [DEBUG] [org.eclipse.jetty.server.session ] - org.eclipse.jetty.server.session.DefaultSessionCache@8b1747[evict=-1,removeUnloadable=false,saveOnCreate=false,saveOnInactiveEvict=false] checking expiration on [] 06:35:38.836 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:38.838 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:38.840 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:38.868 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:38.870 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:38.871 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:39.172 [DEBUG] [org.quartz.core.QuartzSchedulerThread] - batch acquisition of 0 triggers 06:35:39.841 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:39.843 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:39.844 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:39.873 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:39.874 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:39.875 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:40.846 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:40.847 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:40.849 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:40.877 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:40.879 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:40.880 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:41.766 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclThermostatCluster@53ac02, id=18, name=Occupied Heating Setpoint, dataType=SIGNED_16_BIT_INTEGER, lastValue=null] 06:35:41.769 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=52, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Thermostat: 0/0 -> 56846/1, cluster=0201, TID=--, identifiers=[18]]] 06:35:41.850 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:41.852 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:41.853 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:41.883 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:41.885 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:41.886 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:42.855 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:42.856 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:42.858 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:42.886 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:42.888 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:42.889 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:43.862 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:43.864 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:43.865 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:43.894 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:43.895 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:43.897 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:44.868 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:44.870 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:44.871 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:44.900 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:44.901 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:44.906 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:45.873 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:45.874 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:45.876 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:45.904 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:45.906 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:45.907 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:46.878 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:46.880 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:46.881 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:46.909 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:46.911 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:46.913 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:47.305 [DEBUG] [m.zsmartsystems.zigbee.zcl.ZclCluster] - readSync request: ZclAttribute [cluster=com.zsmartsystems.zigbee.zcl.clusters.ZclBasicCluster@bb31e7, id=0, name=ZCL Version, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=null] 06:35:47.308 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - 00158D0001FFAD43: Added transaction to queue, len=53, transaction=ZigBeeTransaction [queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Basic: 0/0 -> 56846/1, cluster=0000, TID=--, identifiers=[0]]] 06:35:47.882 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis queue: 1 06:35:47.884 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis: TelegesisDisplayNetworkInformationCommand [device=null, channel=null, power=null, panId=null, epanId=null] 06:35:47.886 [DEBUG] [egesis.internal.TelegesisFrameHandler] - TX Telegesis Data:AT+N? 06:35:47.914 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:+N=COO,11,08,D138,DF7ACB1D0A20B001 06:35:47.916 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis Data:OK 06:35:47.923 [DEBUG] [egesis.internal.TelegesisFrameHandler] - RX Telegesis: TelegesisDisplayNetworkInformationCommand [device=COO, channel=11, power=8, panId=53560, epanId=DF7ACB1D0A20B001, status=SUCCESS] 06:35:48.027 [DEBUG] [org.apache.felix.configadmin ] - getConfiguration(pid=org.ops4j.pax.logging, location=null) 06:35:48.029 [DEBUG] [le.osgi.LoggingCommandSessionListener] - Command: 'log:tail' failed: java.lang.InterruptedException 06:35:48.031 [DEBUG] [org.apache.felix.configadmin ] - Found cached configuration org.ops4j.pax.logging bound to ? 06:35:48.032 [DEBUG] [shell.impl.console.ConsoleSessionImpl] - Console session is closed 06:35:48.057 [DEBUG] [org.apache.felix.configadmin ] - update(properties={felix.fileinstall.filename=file:/var/lib/openhab2/etc/org.ops4j.pax.logging.cfg, log4j2.appender.audit.append=true, log4j2.appender.audit.fileName=/var/log/openhab2/audit.log, log4j2.appender.audit.filePattern=/var/log/openhab2/audit.log.%i, log4j2.appender.audit.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.audit.layout.type=PatternLayout, log4j2.appender.audit.name=AUDIT, log4j2.appender.audit.policies.size.size=8MB, log4j2.appender.audit.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.audit.policies.type=Policies, log4j2.appender.audit.type=RollingRandomAccessFile, log4j2.appender.console.layout.pattern=%d{HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.console.layout.type=PatternLayout, log4j2.appender.console.name=STDOUT, log4j2.appender.console.type=Console, log4j2.appender.event.append=true, log4j2.appender.event.fileName=/var/log/openhab2/events.log, log4j2.appender.event.filePattern=/var/log/openhab2/events.log.%i, log4j2.appender.event.immediateFlush=true, log4j2.appender.event.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-26.26c] - %m%n, log4j2.appender.event.layout.type=PatternLayout, log4j2.appender.event.name=EVENT, log4j2.appender.event.policies.size.size=16MB, log4j2.appender.event.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.event.policies.type=Policies, log4j2.appender.event.type=RollingRandomAccessFile, log4j2.appender.osgi.filter=*, log4j2.appender.osgi.name=OSGI, log4j2.appender.osgi.type=PaxOsgi, log4j2.appender.out.append=true, log4j2.appender.out.fileName=/var/log/openhab2/openhab.log, log4j2.appender.out.filePattern=/var/log/openhab2/openhab.log.%i, log4j2.appender.out.immediateFlush=true, log4j2.appender.out.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.out.layout.type=PatternLayout, log4j2.appender.out.name=LOGFILE, log4j2.appender.out.policies.size.size=16MB, log4j2.appender.out.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.out.policies.type=Policies, log4j2.appender.out.type=RollingRandomAccessFile, log4j2.logger.audit.additivity=false, log4j2.logger.audit.appenderRef.audit.ref=AUDIT, log4j2.logger.audit.appenderRefs=audit, log4j2.logger.audit.level=INFO, log4j2.logger.audit.name=org.apache.karaf.jaas.modules.audit, log4j2.logger.events.additivity=false, log4j2.logger.events.appenderRef.event.ref=EVENT, log4j2.logger.events.appenderRef.osgi.ref=OSGI, log4j2.logger.events.appenderRefs=event, log4j2.logger.events.level=INFO, log4j2.logger.events.name=smarthome.event, log4j2.logger.javaxmail.level=ERROR, log4j2.logger.javaxmail.name=javax.mail, log4j2.logger.jmdns.level=ERROR, log4j2.logger.jmdns.name=javax.jmdns, log4j2.logger.jupnp.level=ERROR, log4j2.logger.jupnp.name=org.jupnp, log4j2.logger.karservice.level=ERROR, log4j2.logger.karservice.name=org.apache.karaf.kar.internal.KarServiceImpl, log4j2.logger.lsp4j.level=OFF, log4j2.logger.lsp4j.name=org.eclipse.lsp4j, log4j2.logger.openhab.level=INFO, log4j2.logger.openhab.name=org.openhab, log4j2.logger.org_apache_sshd.level=ERROR, log4j2.logger.org_apache_sshd.name=org.apache.sshd, log4j2.logger.paperui.level=WARN, log4j2.logger.paperui.name=org.openhab.ui.paper, log4j2.logger.paperuiint.level=INFO, log4j2.logger.paperuiint.name=org.openhab.ui.paper.internal, log4j2.logger.paxurl.level=ERROR, log4j2.logger.paxurl.name=org.ops4j.pax.url.mvn.internal.AetherBasedResolver, log4j2.logger.paxweb.level=OFF, log4j2.logger.paxweb.name=org.ops4j.pax.web.pax-web-runtime, log4j2.logger.shell.appenderRef.stdout.ref=STDOUT, log4j2.logger.shell.appenderRefs=stdout, log4j2.logger.shell.level=OFF, log4j2.logger.shell.name=org.apache.karaf.shell.support, log4j2.logger.smarthome.level=INFO, log4j2.logger.smarthome.name=org.eclipse.smarthome, log4j2.logger.smarthomeInboxUpdatedEvent.level=ERROR, log4j2.logger.smarthomeInboxUpdatedEvent.name=smarthome.event.InboxUpdatedEvent, log4j2.logger.smarthomeItemAddedEvent.level=ERROR, log4j2.logger.smarthomeItemAddedEvent.name=smarthome.event.ItemAddedEvent, log4j2.logger.smarthomeItemRemovedEvent.level=ERROR, log4j2.logger.smarthomeItemRemovedEvent.name=smarthome.event.ItemRemovedEvent, log4j2.logger.smarthomeItemStateEvent.level=ERROR, log4j2.logger.smarthomeItemStateEvent.name=smarthome.event.ItemStateEvent, log4j2.logger.smarthomeThingAddedEvent.level=ERROR, log4j2.logger.smarthomeThingAddedEvent.name=smarthome.event.ThingAddedEvent, log4j2.logger.smarthomeThingRemovedEvent.level=ERROR, log4j2.logger.smarthomeThingRemovedEvent.name=smarthome.event.ThingRemovedEvent, log4j2.logger.smarthomeThingStatusInfoEvent.level=ERROR, log4j2.logger.smarthomeThingStatusInfoEvent.name=smarthome.event.ThingStatusInfoEvent, log4j2.logger.sshutils.level=ERROR, log4j2.logger.sshutils.name=org.apache.karaf.shell.ssh.SshUtils, log4j2.rootLogger.appenderRef.osgi.ref=OSGI, log4j2.rootLogger.appenderRef.out.ref=LOGFILE, log4j2.rootLogger.appenderRefs=out, osgi, log4j2.rootLogger.level=DEBUG, service.pid=org.ops4j.pax.logging}) ^C~ openhab> 06:35:48.080 [DEBUG] [org.apache.felix.configadmin ] - Updating config org.ops4j.pax.logging with {felix.fileinstall.filename=file:/var/lib/openhab2/etc/org.ops4j.pax.logging.cfg, log4j2.appender.audit.append=true, log4j2.appender.audit.fileName=/var/log/openhab2/audit.log, log4j2.appender.audit.filePattern=/var/log/openhab2/audit.log.%i, log4j2.appender.audit.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.audit.layout.type=PatternLayout, log4j2.appender.audit.name=AUDIT, log4j2.appender.audit.policies.size.size=8MB, log4j2.appender.audit.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.audit.policies.type=Policies, log4j2.appender.audit.type=RollingRandomAccessFile, log4j2.appender.console.layout.pattern=%d{HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.console.layout.type=PatternLayout, log4j2.appender.console.name=STDOUT, log4j2.appender.console.type=Console, log4j2.appender.event.append=true, log4j2.appender.event.fileName=/var/log/openhab2/events.log, log4j2.appender.event.filePattern=/var/log/openhab2/events.log.%i, log4j2.appender.event.immediateFlush=true, log4j2.appender.event.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-26.26c] - %m%n, log4j2.appender.event.layout.type=PatternLayout, log4j2.appender.event.name=EVENT, log4j2.appender.event.policies.size.size=16MB, log4j2.appender.event.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.event.policies.type=Policies, log4j2.appender.event.type=RollingRandomAccessFile, log4j2.appender.osgi.filter=*, log4j2.appender.osgi.name=OSGI, log4j2.appender.osgi.type=PaxOsgi, log4j2.appender.out.append=true, log4j2.appender.out.fileName=/var/log/openhab2/openhab.log, log4j2.appender.out.filePattern=/var/log/openhab2/openhab.log.%i, log4j2.appender.out.immediateFlush=true, log4j2.appender.out.layout.pattern=%d{yyyy-MM-dd HH:mm:ss.SSS} [%-5.5p] [%-36.36c] - %m%n, log4j2.appender.out.layout.type=PatternLayout, log4j2.appender.out.name=LOGFILE, log4j2.appender.out.policies.size.size=16MB, log4j2.appender.out.policies.size.type=SizeBasedTriggeringPolicy, log4j2.appender.out.policies.type=Policies, log4j2.appender.out.type=RollingRandomAccessFile, log4j2.logger.audit.additivity=false, log4j2.logger.audit.appenderRef.audit.ref=AUDIT, log4j2.logger.audit.appenderRefs=audit, log4j2.logger.audit.level=INFO, log4j2.logger.audit.name=org.apache.karaf.jaas.modules.audit, log4j2.logger.events.additivity=false, log4j2.logger.events.appenderRef.event.ref=EVENT, log4j2.logger.events.appenderRef.osgi.ref=OSGI, log4j2.logger.events.appenderRefs=event, log4j2.logger.events.level=INFO, log4j2.logger.events.name=smarthome.event, log4j2.logger.javaxmail.level=ERROR, log4j2.logger.javaxmail.name=javax.mail, log4j2.logger.jmdns.level=ERROR, log4j2.logger.jmdns.name=javax.jmdns, log4j2.logger.jupnp.level=ERROR, log4j2.logger.jupnp.name=org.jupnp, log4j2.logger.karservice.level=ERROR, log4j2.logger.karservice.name=org.apache.karaf.kar.internal.KarServiceImpl, log4j2.logger.lsp4j.level=OFF, log4j2.logger.lsp4j.name=org.eclipse.lsp4j, log4j2.logger.openhab.level=INFO, log4j2.logger.openhab.name=org.openhab, log4j2.logger.org_apache_sshd.level=ERROR, log4j2.logger.org_apache_sshd.name=org.apache.sshd, log4j2.logger.paperui.level=WARN, log4j2.logger.paperui.name=org.openhab.ui.paper, log4j2.logger.paperuiint.level=INFO, log4j2.logger.paperuiint.name=org.openhab.ui.paper.internal, log4j2.logger.paxurl.level=ERROR, log4j2.logger.paxurl.name=org.ops4j.pax.url.mvn.internal.AetherBasedResolver, log4j2.logger.paxweb.level=OFF, log4j2.logger.paxweb.name=org.ops4j.pax.web.pax-web-runtime, log4j2.logger.shell.appenderRef.stdout.ref=STDOUT, log4j2.logger.shell.appenderRefs=stdout, log4j2.logger.shell.level=OFF, log4j2.logger.shell.name=org.apache.karaf.shell.support, log4j2.logger.smarthome.level=INFO, log4j2.logger.smarthome.name=org.eclipse.smarthome, log4j2.logger.smarthomeInboxUpdatedEvent.level=ERROR, log4j2.logger.smarthomeInboxUpdatedEvent.name=smarthome.event.InboxUpdatedEvent, log4j2.logger.smarthomeItemAddedEvent.level=ERROR, log4j2.logger.smarthomeItemAddedEvent.name=smarthome.event.ItemAddedEvent, log4j2.logger.smarthomeItemRemovedEvent.level=ERROR, log4j2.logger.smarthomeItemRemovedEvent.name=smarthome.event.ItemRemovedEvent, log4j2.logger.smarthomeItemStateEvent.level=ERROR, log4j2.logger.smarthomeItemStateEvent.name=smarthome.event.ItemStateEvent, log4j2.logger.smarthomeThingAddedEvent.level=ERROR, log4j2.logger.smarthomeThingAddedEvent.name=smarthome.event.ThingAddedEvent, log4j2.logger.smarthomeThingRemovedEvent.level=ERROR, log4j2.logger.smarthomeThingRemovedEvent.name=smarthome.event.ThingRemovedEvent, log4j2.logger.smarthomeThingStatusInfoEvent.level=ERROR, log4j2.logger.smarthomeThingStatusInfoEvent.name=smarthome.event.ThingStatusInfoEvent, log4j2.logger.sshutils.level=ERROR, log4j2.logger.sshutils.name=org.apache.karaf.shell.ssh.SshUtils, log4j2.rootLogger.appenderRef.osgi.ref=OSGI, log4j2.rootLogger.appenderRef.out.ref=LOGFILE, log4j2.rootLogger.appenderRefs=out, osgi, log4j2.rootLogger.level=DEBUG, service.pid=org.ops4j.pax.logging} 06:35:48.110 [DEBUG] [org.apache.felix.configadmin ] - No SynchronousConfigurationListeners to send CM_UPDATED event to. 06:35:48.115 [DEBUG] [org.apache.felix.configadmin ] - Scheduling task Fire ConfigurationEvent: pid=org.ops4j.pax.logging 06:35:48.118 [DEBUG] [org.apache.felix.configadmin ] - Running task Fire ConfigurationEvent: pid=org.ops4j.pax.logging 06:35:48.123 [DEBUG] [org.apache.felix.configadmin ] - Sending CM_UPDATED event for org.ops4j.pax.logging to [org.osgi.service.cm.ConfigurationListener, id=41, bundle=13/mvn:org.apache.karaf.shell/org.apache.karaf.shell.core/4.2.7] 06:35:48.129 [DEBUG] [org.apache.felix.configadmin ] - Sending CM_UPDATED event for org.ops4j.pax.logging to [org.osgi.service.cm.ConfigurationListener, id=122, bundle=9/mvn:org.apache.felix/org.apache.felix.configadmin/1.9.16] 06:35:48.131 [DEBUG] [org.apache.felix.configadmin ] - Scheduling task Update: pid=org.ops4j.pax.logging 06:35:48.136 [DEBUG] [org.apache.felix.configadmin ] - Sending CM_UPDATED event for org.ops4j.pax.logging to [org.osgi.service.cm.ConfigurationListener, org.apache.felix.fileinstall.ArtifactListener, org.apache.felix.fileinstall.ArtifactInstaller, id=40, bundle=10/mvn:org.apache.felix/org.apache.felix.fileinstall/3.6.4] 06:35:48.138 [DEBUG] [org.apache.felix.configadmin ] - UpdateConfiguration(org.ops4j.pax.logging) scheduled 06:35:48.138 [DEBUG] [org.apache.felix.configadmin ] - Running task Update: pid=org.ops4j.pax.logging 06:35:48.142 [DEBUG] [org.apache.felix.configadmin ] - Updating configuration org.ops4j.pax.logging to revision #10 06:35:48.144 [DEBUG] [org.apache.felix.configadmin ] - canReceive=true; bundle=mvn:org.ops4j.pax.logging/pax-logging-log4j2/1.11.2; configuration=? (no SecurityManager) 06:35:48.140 [DEBUG] [org.apache.felix.configadmin ] - getConfiguration(pid=org.ops4j.pax.logging, location=?) openhab>