[2020-08-22 17:10:04][INFO] : node info all
[2020-08-22 17:10:04][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.36ms
[2020-08-22 17:10:06][INFO] : node info all
[2020-08-22 17:10:06][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.28ms
[2020-08-22 17:10:09][INFO] : node info all
[2020-08-22 17:10:09][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.38ms
[2020-08-22 17:10:11][INFO] : node info all
[2020-08-22 17:10:11][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.29ms
[2020-08-22 17:10:13][INFO] : node info all
[2020-08-22 17:10:13][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.27ms
[2020-08-22 17:10:16][INFO] : node info all
[2020-08-22 17:10:16][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.80ms
[2020-08-22 17:10:18][INFO] : node info all
[2020-08-22 17:10:18][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.28ms
[2020-08-22 17:10:20][INFO] : node info all
[2020-08-22 17:10:20][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.36ms
[2020-08-22 17:10:23][INFO] : node info all
[2020-08-22 17:10:23][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.25ms
[2020-08-22 17:10:25][INFO] : node info all
[2020-08-22 17:10:25][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.34ms
[2020-08-22 17:10:27][INFO] : node info all
[2020-08-22 17:10:27][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.36ms
[2020-08-22 17:10:30][INFO] : node info all
[2020-08-22 17:10:30][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.26ms
[2020-08-22 17:10:32][INFO] : node info all
[2020-08-22 17:10:32][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.41ms
[2020-08-22 17:10:35][INFO] : node info all
[2020-08-22 17:10:35][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.29ms
[2020-08-22 17:10:37][INFO] : node info all
[2020-08-22 17:10:37][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.28ms
[2020-08-22 17:10:40][INFO] : node info all
[2020-08-22 17:10:40][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.28ms
[2020-08-22 17:10:42][INFO] : node info all
[2020-08-22 17:10:42][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.37ms
[2020-08-22 17:10:44][INFO] : node info all
[2020-08-22 17:10:44][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.21ms
[2020-08-22 17:10:47][INFO] : node info all
[2020-08-22 17:10:47][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 5.52ms
[2020-08-22 17:10:49][INFO] : node info all
[2020-08-22 17:10:49][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.21ms
[2020-08-22 17:10:51][INFO] : node info all
[2020-08-22 17:10:51][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.30ms
[2020-08-22 17:10:54][INFO] : node info all
[2020-08-22 17:10:54][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.07ms
[2020-08-22 17:10:56][INFO] : node info all
[2020-08-22 17:10:56][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.34ms
[2020-08-22 17:10:58][INFO] : node info all
[2020-08-22 17:10:58][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.34ms
[2020-08-22 17:11:01][INFO] : node info all
[2020-08-22 17:11:01][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.91ms
[2020-08-22 17:11:03][INFO] : node info all
[2020-08-22 17:11:03][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.41ms
[2020-08-22 17:11:05][INFO] : node info all
[2020-08-22 17:11:05][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.44ms
[2020-08-22 17:11:08][INFO] : node info all
[2020-08-22 17:11:08][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.41ms
[2020-08-22 17:11:10][INFO] : node info all
[2020-08-22 17:11:10][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.77ms
[2020-08-22 17:11:13][INFO] : node info all
[2020-08-22 17:11:13][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 10.21ms
[2020-08-22 17:11:15][INFO] : node info all
[2020-08-22 17:11:15][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.30ms
[2020-08-22 17:11:17][INFO] : node info all
[2020-08-22 17:11:17][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.21ms
[2020-08-22 17:11:19][INFO] : node info all
[2020-08-22 17:11:19][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.35ms
[2020-08-22 17:11:22][INFO] : node info all
[2020-08-22 17:11:22][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.28ms
[2020-08-22 17:11:24][INFO] : node info all
[2020-08-22 17:11:24][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.26ms
[2020-08-22 17:11:26][INFO] : node info all
[2020-08-22 17:11:26][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.30ms
[2020-08-22 17:11:29][INFO] : node info all
[2020-08-22 17:11:29][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.30ms
[2020-08-22 17:11:31][INFO] : node info all
[2020-08-22 17:11:31][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.36ms
[2020-08-22 17:11:34][INFO] : node info all
[2020-08-22 17:11:34][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.33ms
[2020-08-22 17:11:36][INFO] : node info all
[2020-08-22 17:11:36][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.29ms
[2020-08-22 17:11:39][INFO] : node info all
[2020-08-22 17:11:39][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.32ms
[2020-08-22 17:11:41][INFO] : node info all
[2020-08-22 17:11:41][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.37ms
[2020-08-22 17:11:43][INFO] : node info all
[2020-08-22 17:11:43][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.34ms
[2020-08-22 17:11:46][INFO] : node info all
[2020-08-22 17:11:46][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.24ms
[2020-08-22 17:11:46][ERROR] : RequestHandler Controller is busy
[2020-08-22 17:11:46][INFO] : 200 GET /node?node_id=1&type=action&action=requestNodeNeighboursUpdate&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.61ms
[2020-08-22 17:11:48][INFO] : node info all
[2020-08-22 17:11:48][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.34ms
[2020-08-22 17:11:50][INFO] : node info all
[2020-08-22 17:11:50][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.27ms
[2020-08-22 17:11:58][INFO] : 200 GET /network?type=info&info=getStatus&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.47ms
[2020-08-22 17:12:00][INFO] : Controller action cancelCommand
[2020-08-22 17:12:00][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:00][INFO] : 200 GET /controller?type=action&action=cancelCommand&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.84ms
[2020-08-22 17:12:00][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:00][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:00][DEBUG] : zwcallback args=[{'controllerState': 'Cancel', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 2, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command was cancelled.'}]
[2020-08-22 17:12:00][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Cancel', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 2, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command was cancelled.'}
[2020-08-22 17:12:00][INFO] : The command was cancelled. (Cancel)
[2020-08-22 17:12:00][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:00][DEBUG] : notif_callback : end
[2020-08-22 17:12:00][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:00][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:00][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:00][DEBUG] : zwcallback args=[{'controllerState': 'Completed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 7, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has completed successfully.'}]
[2020-08-22 17:12:00][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Completed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 7, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has completed successfully.'}
[2020-08-22 17:12:00][INFO] : The command has completed successfully. (Completed)
[2020-08-22 17:12:00][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:00][DEBUG] : notif_callback : end
[2020-08-22 17:12:00][DEBUG] : Send to jeedom : {'controller': {'state': {'value': 0}}}
[2020-08-22 17:12:00][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2020-08-22 17:12:00][DEBUG] : http://127.0.0.1:80 "POST /plugins/openzwave/core/php/jeeZwave.php?apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 HTTP/1.1" 200 0
[2020-08-22 17:12:04][INFO] : 200 GET /network?type=info&info=getNodesList&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 5.67ms
[2020-08-22 17:12:04][INFO] : node info getNodeStatistics
[2020-08-22 17:12:04][INFO] : 200 GET /node?node_id=1&type=info&info=getNodeStatistics&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.99ms
[2020-08-22 17:12:05][INFO] : node info all
[2020-08-22 17:12:05][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.22ms
[2020-08-22 17:12:06][INFO] : node action healNode
[2020-08-22 17:12:06][INFO] : Heal network node (1) by requesting the node rediscover their neighbors
[2020-08-22 17:12:06][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:06][INFO] : 200 GET /node?node_id=1&type=action&action=healNode&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.69ms
[2020-08-22 17:12:06][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:06][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:06][DEBUG] : zwcallback args=[{'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}]
[2020-08-22 17:12:06][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}
[2020-08-22 17:12:06][INFO] : The command is starting. (Starting)
[2020-08-22 17:12:06][DEBUG] : The controller is busy ? True
[2020-08-22 17:12:06][DEBUG] : notif_callback : end
[2020-08-22 17:12:06][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:06][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:06][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:06][DEBUG] : zwcallback args=[{'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}]
[2020-08-22 17:12:06][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}
[2020-08-22 17:12:06][INFO] : The command has failed. (Failed)
[2020-08-22 17:12:06][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:06][DEBUG] : notif_callback : end
[2020-08-22 17:12:07][INFO] : node info all
[2020-08-22 17:12:07][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.43ms
[2020-08-22 17:12:09][INFO] : node info all
[2020-08-22 17:12:09][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.27ms
[2020-08-22 17:12:12][INFO] : node info all
[2020-08-22 17:12:12][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.45ms
[2020-08-22 17:12:14][INFO] : node info all
[2020-08-22 17:12:14][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.52ms
[2020-08-22 17:12:19][INFO] : 200 GET /network?type=info&info=getNodesList&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.40ms
[2020-08-22 17:12:19][INFO] : node info getNodeStatistics
[2020-08-22 17:12:19][INFO] : 200 GET /node?node_id=1&type=info&info=getNodeStatistics&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.19ms
[2020-08-22 17:12:19][INFO] : node info all
[2020-08-22 17:12:19][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.76ms
[2020-08-22 17:12:22][INFO] : node info all
[2020-08-22 17:12:22][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.56ms
[2020-08-22 17:12:24][INFO] : node info all
[2020-08-22 17:12:24][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.00ms
[2020-08-22 17:12:27][INFO] : node info all
[2020-08-22 17:12:27][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.06ms
[2020-08-22 17:12:29][INFO] : node info all
[2020-08-22 17:12:29][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.42ms
[2020-08-22 17:12:32][INFO] : node info all
[2020-08-22 17:12:32][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 5.94ms
[2020-08-22 17:12:32][INFO] : node action healNode
[2020-08-22 17:12:32][INFO] : Heal network node (1) by requesting the node rediscover their neighbors
[2020-08-22 17:12:32][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:32][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:32][INFO] : 200 GET /node?node_id=1&type=action&action=healNode&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.61ms
[2020-08-22 17:12:32][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:32][DEBUG] : zwcallback args=[{'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}]
[2020-08-22 17:12:32][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}
[2020-08-22 17:12:32][INFO] : The command is starting. (Starting)
[2020-08-22 17:12:32][DEBUG] : The controller is busy ? True
[2020-08-22 17:12:32][DEBUG] : notif_callback : end
[2020-08-22 17:12:32][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:32][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:32][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:32][DEBUG] : zwcallback args=[{'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}]
[2020-08-22 17:12:32][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}
[2020-08-22 17:12:32][INFO] : The command has failed. (Failed)
[2020-08-22 17:12:32][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:32][DEBUG] : notif_callback : end
[2020-08-22 17:12:34][INFO] : node info all
[2020-08-22 17:12:34][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.39ms
[2020-08-22 17:12:36][INFO] : node info all
[2020-08-22 17:12:36][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.42ms
[2020-08-22 17:12:39][INFO] : node info all
[2020-08-22 17:12:39][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.07ms
[2020-08-22 17:12:41][INFO] : node info all
[2020-08-22 17:12:41][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.18ms
[2020-08-22 17:12:44][INFO] : node info all
[2020-08-22 17:12:44][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 5.88ms
[2020-08-22 17:12:46][INFO] : node info all
[2020-08-22 17:12:46][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.21ms
[2020-08-22 17:12:49][INFO] : node info all
[2020-08-22 17:12:49][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 5.99ms
[2020-08-22 17:12:51][INFO] : node info all
[2020-08-22 17:12:51][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.35ms
[2020-08-22 17:12:52][INFO] : node action requestNodeNeighboursUpdate
[2020-08-22 17:12:52][INFO] : 200 GET /node?node_id=1&type=action&action=requestNodeNeighboursUpdate&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.29ms
[2020-08-22 17:12:53][INFO] : node info all
[2020-08-22 17:12:53][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.86ms
[2020-08-22 17:12:54][INFO] : node action healNode
[2020-08-22 17:12:54][INFO] : Heal network node (1) by requesting the node rediscover their neighbors
[2020-08-22 17:12:54][INFO] : 200 GET /node?node_id=1&type=action&action=healNode&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.92ms
[2020-08-22 17:12:54][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:54][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:54][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:54][DEBUG] : zwcallback args=[{'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}]
[2020-08-22 17:12:54][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}
[2020-08-22 17:12:54][INFO] : The command is starting. (Starting)
[2020-08-22 17:12:54][DEBUG] : The controller is busy ? True
[2020-08-22 17:12:54][DEBUG] : notif_callback : end
[2020-08-22 17:12:54][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:54][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:54][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:54][DEBUG] : zwcallback args=[{'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}]
[2020-08-22 17:12:54][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}
[2020-08-22 17:12:54][INFO] : The command has failed. (Failed)
[2020-08-22 17:12:54][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:54][DEBUG] : notif_callback : end
[2020-08-22 17:12:56][INFO] : node info all
[2020-08-22 17:12:56][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.25ms
[2020-08-22 17:12:58][INFO] : node action healNode
[2020-08-22 17:12:58][INFO] : Heal network node (1) by requesting the node rediscover their neighbors
[2020-08-22 17:12:58][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:58][INFO] : 200 GET /node?node_id=1&type=action&action=healNode&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.51ms
[2020-08-22 17:12:58][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:58][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:58][DEBUG] : zwcallback args=[{'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}]
[2020-08-22 17:12:58][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Starting', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 1, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command is starting.'}
[2020-08-22 17:12:58][INFO] : The command is starting. (Starting)
[2020-08-22 17:12:58][DEBUG] : The controller is busy ? True
[2020-08-22 17:12:58][DEBUG] : notif_callback : end
[2020-08-22 17:12:58][DEBUG] : notif_callback : new notification
[2020-08-22 17:12:58][DEBUG] : notif_callback : Notification type : 28, nodeId : 0
[2020-08-22 17:12:58][DEBUG] : notif_callback : call callback context
[2020-08-22 17:12:58][DEBUG] : zwcallback args=[{'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}]
[2020-08-22 17:12:58][DEBUG] : Z-Wave ControllerCommand : {'controllerState': 'Failed', 'controllerError': 'None', 'controllerErrorInt': 0, 'notificationType': 'ControllerCommand', 'nodeId': 0, 'controllerStateInt': 8, 'controllerErrorDoc': 'None.', 'homeId': 3222060786L, 'controllerStateDoc': 'The command has failed.'}
[2020-08-22 17:12:58][INFO] : The command has failed. (Failed)
[2020-08-22 17:12:58][DEBUG] : The controller is busy ? False
[2020-08-22 17:12:58][DEBUG] : notif_callback : end
[2020-08-22 17:12:58][INFO] : node info all
[2020-08-22 17:12:58][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.00ms
[2020-08-22 17:13:01][INFO] : node info all
[2020-08-22 17:13:01][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.30ms
[2020-08-22 17:13:03][INFO] : node info all
[2020-08-22 17:13:03][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.11ms
[2020-08-22 17:13:06][INFO] : node info all
[2020-08-22 17:13:06][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.01ms
[2020-08-22 17:13:08][INFO] : node info all
[2020-08-22 17:13:08][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.42ms
[2020-08-22 17:13:10][INFO] : node info all
[2020-08-22 17:13:10][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 9.39ms
[2020-08-22 17:13:13][INFO] : node info all
[2020-08-22 17:13:13][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.87ms
[2020-08-22 17:13:15][INFO] : node info all
[2020-08-22 17:13:15][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.53ms
[2020-08-22 17:13:18][INFO] : node info all
[2020-08-22 17:13:18][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.62ms
[2020-08-22 17:13:20][INFO] : node info all
[2020-08-22 17:13:20][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 13.40ms
[2020-08-22 17:13:23][INFO] : node info all
[2020-08-22 17:13:23][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.55ms
[2020-08-22 17:13:25][INFO] : node info all
[2020-08-22 17:13:25][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 4.61ms
[2020-08-22 17:13:28][INFO] : node info all
[2020-08-22 17:13:28][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.09ms
[2020-08-22 17:13:30][INFO] : node info all
[2020-08-22 17:13:30][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.57ms
[2020-08-22 17:13:39][INFO] : node info all
[2020-08-22 17:13:39][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.26ms
[2020-08-22 17:13:46][INFO] : set_device_name node_id:1 new name ; '1Z-Stick Gen5 (ZW090)'. Is enable: 1
[2020-08-22 17:13:46][DEBUG] : notif_callback : new notification
[2020-08-22 17:13:46][INFO] : 200 GET /node?node_id=1&type=setDeviceName&location=aucun&name=1Z-Stick+Gen5+%28ZW090%29&is_enable=1&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.84ms
[2020-08-22 17:13:46][DEBUG] : notif_callback : Notification type : 9, nodeId : 1
[2020-08-22 17:13:46][DEBUG] : notif_callback : call callback context
[2020-08-22 17:13:46][DEBUG] : zwcallback args=[{'homeId': 3222060786L, 'notificationType': 'NodeNaming', 'nodeId': 1}]
[2020-08-22 17:13:46][DEBUG] : Z-Wave Notification NodeNaming : {'homeId': 3222060786L, 'notificationType': 'NodeNaming', 'nodeId': 1}
[2020-08-22 17:13:46][DEBUG] : Z-Wave Notification Node : home_id: [0xc00cbef2] id: [1] name: [1Z-Stick Gen5 (ZW090)] model: [ZW090 Z-Stick Gen5 EU]
[2020-08-22 17:13:46][DEBUG] : notif_callback : end
[2020-08-22 17:13:46][DEBUG] : notif_callback : new notification
[2020-08-22 17:13:46][DEBUG] : notif_callback : Notification type : 9, nodeId : 1
[2020-08-22 17:13:46][DEBUG] : notif_callback : call callback context
[2020-08-22 17:13:46][DEBUG] : zwcallback args=[{'homeId': 3222060786L, 'notificationType': 'NodeNaming', 'nodeId': 1}]
[2020-08-22 17:13:46][DEBUG] : Z-Wave Notification NodeNaming : {'homeId': 3222060786L, 'notificationType': 'NodeNaming', 'nodeId': 1}
[2020-08-22 17:13:46][DEBUG] : Z-Wave Notification Node : home_id: [0xc00cbef2] id: [1] name: [1Z-Stick Gen5 (ZW090)] model: [ZW090 Z-Stick Gen5 EU]
[2020-08-22 17:13:46][DEBUG] : notif_callback : end
[2020-08-22 17:13:51][INFO] : 200 GET /network?type=info&info=getNodesList&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.10ms
[2020-08-22 17:13:51][INFO] : node info getNodeStatistics
[2020-08-22 17:13:51][INFO] : 200 GET /node?node_id=1&type=info&info=getNodeStatistics&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 3.04ms
[2020-08-22 17:13:51][INFO] : node info all
[2020-08-22 17:13:51][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.07ms
[2020-08-22 17:13:53][INFO] : node info all
[2020-08-22 17:13:53][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 7.42ms
[2020-08-22 17:13:56][INFO] : node info all
[2020-08-22 17:13:56][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 8.48ms
[2020-08-22 17:13:58][INFO] : node info all
[2020-08-22 17:13:58][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 6.10ms
[2020-08-22 17:14:01][INFO] : node info all
[2020-08-22 17:14:01][INFO] : 200 GET /node?node_id=1&type=info&info=all&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 13.12ms
[2020-08-22 17:42:20][INFO] : 200 GET /network?type=info&info=getStatus&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 2.05ms
[2020-08-22 17:42:20][INFO] : 200 GET /network?type=info&info=getStatus&apikey=l9ZKsUx80OhcBniXKf06MQR519BXhyC3 (127.0.0.1) 1.81ms