opendaylight  controller setting takes long time

Hi Michal,
We use opendaylight  controller,call home mode,setting router VTI、IPSEC、VXLAN、BRIDGE moudles, one by one.But we found it took a long time.
Time is mainly spent between module intervals.example between VTI and IPsec.
I don't know, I only setting VTI Module,why need copy all modules from candidate -> running. Because of this, the process is complicated and takes a long time. About 10+ seconds. Wish get your help,Thanks!
[13:21:22][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:23][DBG] Processing copy_config request.
[13:21:23][INF] Copy config: candidate -> running, model: (null)
[13:21:23][INF] Switch datastore request candidate -> running
[13:21:23][DBG] Data successfully written for module 'gwtt-device'
[13:21:23][DBG] Data successfully written for module 'gwtt-dhcpd'
[13:21:23][DBG] Data successfully written for module 'gwtt-ietf-ipsec'
[13:21:23][DBG] Data successfully written for module 'gwtt-ip-routing'
[13:21:23][DBG] Data successfully written for module 'gwtt-vxlan'
[13:21:24][DBG] Data successfully written for module 'ietf-interfaces'
[13:21:25][DBG] Data successfully written for module 'ietf-keystore'
[13:21:25][DBG] Data successfully written for module 'ietf-netconf-monitoring'
[13:21:25][DBG] Data successfully written for module 'ietf-netconf-server'
[13:21:25][DBG] Data successfully written for module 'ietf-system'
[13:21:25][DBG] Data successfully written for module 'ietf-yang-library'
[13:21:25][DBG] Data successfully written for module 'nc-notifications'
[13:21:25][DBG] Commit (8/10): data write succeeded
==========The following is detail info.========
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Loaded module ietf-interfaces: mtime sec=1579411000 nsec=0
[13:21:19][DBG] Usage count ietf-interfaces incremented (value=3)
[13:21:19][INF] Data file /etc/sysrepo/data/ietf-interfaces.running loaded successfully
[13:21:19][DBG] Module ietf-interfaces has been loaded
[13:21:19][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:19][DBG] Thread id=2009189744 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 135 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 131 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009046384 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/gwtt-interface:interface-generation-flag
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:19][DBG] Thread id=2009046384 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 98 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 94 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009333104 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/ietf-ip:ipv4
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:19][DBG] Thread id=2009333104 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=0, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 106 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 102 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2008903024 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/ietf-ip:ipv4/mtu
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=0, current count=1.
[13:21:19][DBG] Thread id=2008903024 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 123 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 119 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009189744 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/ietf-ip:ipv4/address[ip='<IP_ADDRESS>']
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:19][DBG] Thread id=2009189744 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 150 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 146 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009046384 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/ietf-ip:ipv4/address[ip='<IP_ADDRESS>']/netmask
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:19][DBG] Thread id=2009046384 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 111 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 107 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009333104 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/type
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:19][DBG] Thread id=2009333104 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 109 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 105 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2008903024 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/gwtt-vti:vti/vti-key
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:19][DBG] Thread id=2008903024 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 124 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 120 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009189744 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=0, count=0.
[13:21:19][DBG] Processing set_item request.
[13:21:19][INF] Set item request candidate datastore, xpath: /ietf-interfaces:interfaces/interface[name='vti1']/gwtt-vti:vti/address-family-type
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:19][DBG] Thread id=2009189744 will wait.
[13:21:19][DBG] New message enqueued into CM message queue.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:19][DBG] Sending 23 bytes of data.
[13:21:19][DBG] 23 bytes of data sent.
[13:21:19][DBG] fd 15 readable (revents 1)
[13:21:19][DBG] 23 bytes of data received on fd 15
[13:21:19][DBG] fd 15 would block
[13:21:19][DBG] New message of size 19 bytes received.
[13:21:19][DBG] Circular buffer enqueue to position=0, current count=1.
[13:21:19][DBG] Threads: active=0/4, 1 requests in queue
[13:21:19][DBG] Thread id=2009046384 signaled.
[13:21:19][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:19][DBG] Processing commit request.
[13:21:19][DBG] Commit (1/10): process started
[13:21:19][DBG] Commit (2/10): loading module dependencies succeeded
[13:21:19][DBG] Commit: In the session there are 1 / 1 modified models
[13:21:19][DBG] Usage count ietf-interfaces incremented (value=4)
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Timestamp for the model ietf-interfaces matches, ops will be skipped
[13:21:19][DBG] Usage count ietf-interfaces incremented (value=5)
[13:21:19][DBG] Usage count ietf-interfaces incremented (value=6)
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Commit (3/10): all modified models loaded successfully
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Skipping op for model ietf-interfaces
[13:21:19][DBG] Commit (4/10): replay of operation succeeded
[13:21:19][DBG] Validation succeeded for 'ietf-interfaces' module
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:19][DBG] Loaded module gwtt-dhcpd: mtime sec=1579411016 nsec=0
[13:21:19][DBG] Usage count gwtt-dhcpd incremented (value=5)
[13:21:19][INF] Data file /etc/sysrepo/data/gwtt-dhcpd.running loaded successfully
[13:21:19][DBG] Module gwtt-dhcpd has been loaded
[13:21:19][DBG] Module ietf-interfaces already loaded
[13:21:19][DBG] Data tree ietf-interfaces appended because of validation
[13:21:20][DBG] Validation succeeded for 'gwtt-dhcpd' module
[13:21:20][DBG] Usage count gwtt-dhcpd decremented (value=4)
[13:21:20][DBG] Commit (5/10): merged models validation succeeded
[13:21:20][DBG] Commit (6/10): NACM access check skipped
[13:21:20][DBG] Usage count ietf-interfaces decremented (value=5)
[13:21:20][INF] Unlock datastore request
[13:21:20][DBG] Module_name ietf-interfaces
[13:21:20][DBG] Usage count ietf-interfaces decremented (value=4)
[13:21:20][DBG] Usage count ietf-interfaces decremented (value=3)
[13:21:20][DBG] Commit (10/10): finished successfully
[13:21:20][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:20][DBG] Thread id=2009046384 will wait.
[13:21:20][DBG] New message enqueued into CM message queue.
[13:21:20][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:20][DBG] Sending 23 bytes of data.
[13:21:20][DBG] 23 bytes of data sent.
[13:21:20][DBG] fd 15 readable (revents 1)
[13:21:20][DBG] 45 bytes of data received on fd 15
[13:21:20][DBG] fd 15 would block
[13:21:20][DBG] New message of size 41 bytes received.
[13:21:20][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:20][DBG] Threads: active=0/4, 1 requests in queue
[13:21:20][DBG] Thread id=2009333104 signaled.
[13:21:20][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:20][DBG] Processing check-exec-permission request.
[13:21:20][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:20][DBG] Thread id=2009333104 will wait.
[13:21:20][DBG] New message enqueued into CM message queue.
[13:21:20][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:20][DBG] Sending 25 bytes of data.
[13:21:20][DBG] 25 bytes of data sent.
[13:21:20][DBG] fd 15 readable (revents 1)
[13:21:20][DBG] 27 bytes of data received on fd 15
[13:21:20][DBG] fd 15 would block
[13:21:20][DBG] New message of size 23 bytes received.
[13:21:20][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:20][DBG] Threads: active=0/4, 1 requests in queue
[13:21:20][DBG] Thread id=2008903024 signaled.
[13:21:20][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:20][DBG] Processing copy_config request.
[13:21:20][INF] Copy config: candidate -> running, model: (null)
[13:21:20][INF] Switch datastore request candidate -> candidate
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module nc-notifications: mtime sec=1579411000 nsec=0
[13:21:20][DBG] Usage count nc-notifications incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/nc-notifications.running is empty
[13:21:20][DBG] Module nc-notifications has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module ietf-keystore: mtime sec=1579411000 nsec=0
[13:21:20][DBG] CALLBACK FOR MODULE (null) urn:ietf:params:xml:ns:yang:ietf-keystore
[13:21:20][DBG] Usage count ietf-keystore incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/ietf-keystore.running loaded successfully
[13:21:20][DBG] Validation succeeded for 'ietf-keystore' module
[13:21:20][DBG] Module ietf-keystore has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module ietf-netconf-server: mtime sec=1579411000 nsec=0
[13:21:20][DBG] Usage count ietf-netconf-server incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/ietf-netconf-server.running loaded successfully
[13:21:20][DBG] Module ietf-keystore already loaded
[13:21:20][DBG] Data tree ietf-keystore appended because of validation
[13:21:20][DBG] Validation succeeded for 'ietf-netconf-server' module
[13:21:20][DBG] Module ietf-netconf-server has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module ietf-netconf-monitoring: mtime sec=1579411000 nsec=0
[13:21:20][DBG] Usage count ietf-netconf-monitoring incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/ietf-netconf-monitoring.running is empty
[13:21:20][DBG] Validation succeeded for 'ietf-netconf-monitoring' module
[13:21:20][DBG] Module ietf-netconf-monitoring has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module ietf-system: mtime sec=1579411000 nsec=0
[13:21:20][DBG] Usage count ietf-system incremented (value=4)
[13:21:20][INF] Data file /etc/sysrepo/data/ietf-system.running loaded successfully
[13:21:20][DBG] Module ietf-system has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module ietf-yang-library: mtime sec=1579411000 nsec=0
[13:21:20][DBG] Usage count ietf-yang-library incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/ietf-yang-library.running is empty
[13:21:20][DBG] Module ietf-yang-library has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module gwtt-device: mtime sec=1579410999 nsec=0
[13:21:20][DBG] Usage count gwtt-device incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/gwtt-device.running loaded successfully
[13:21:20][DBG] Module gwtt-device has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module gwtt-ip-routing: mtime sec=1579410999 nsec=0
[13:21:20][DBG] Usage count gwtt-ip-routing incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/gwtt-ip-routing.running loaded successfully
[13:21:20][DBG] Module gwtt-ip-routing has been loaded
[13:21:20][DBG] Module ietf-interfaces already loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module gwtt-vxlan: mtime sec=1579410999 nsec=0
[13:21:20][DBG] Usage count gwtt-vxlan incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/gwtt-vxlan.running loaded successfully
[13:21:20][DBG] Module gwtt-vxlan has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module gwtt-ietf-ipsec: mtime sec=1579410999 nsec=0
[13:21:20][DBG] Usage count gwtt-ietf-ipsec incremented (value=3)
[13:21:20][INF] Data file /etc/sysrepo/data/gwtt-ietf-ipsec.running loaded successfully
[13:21:20][DBG] Module gwtt-ietf-ipsec has been loaded
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][DBG] Loaded module gwtt-dhcpd: mtime sec=1579411016 nsec=0
[13:21:20][DBG] Usage count gwtt-dhcpd incremented (value=5)
[13:21:20][INF] Data file /etc/sysrepo/data/gwtt-dhcpd.running loaded successfully
[13:21:20][DBG] Module ietf-interfaces already loaded
[13:21:20][DBG] Data tree ietf-interfaces appended because of validation
[13:21:20][DBG] Validation succeeded for 'gwtt-dhcpd' module
[13:21:20][DBG] Module gwtt-dhcpd has been loaded
[13:21:20][INF] Switch datastore request candidate -> running
[13:21:20][DBG] Commit (1/10): process started
[13:21:20][DBG] Commit (2/10): loading module dependencies succeeded
[13:21:20][DBG] Module 'gwtt-device' persist file version matches with cached value<PHONE_NUMBER>2589374701).
[13:21:20][DBG] Returning 0 subscriptions from 'gwtt-device' persist file cache.
[13:21:20][DBG] Module 'gwtt-device' persist file version matches with cached value<PHONE_NUMBER>2589374701).
[13:21:20][DBG] Returning 1 subscriptions from 'gwtt-device' persist file cache.
[13:21:20][DBG] Module 'gwtt-dhcpd' persist file version matches with cached value (649172728).
[13:21:20][DBG] Returning 0 subscriptions from 'gwtt-dhcpd' persist file cache.
[13:21:20][DBG] Module 'gwtt-dhcpd' persist file version matches with cached value (649172728).
[13:21:20][DBG] Returning 1 subscriptions from 'gwtt-dhcpd' persist file cache.
[13:21:20][DBG] Module 'gwtt-ietf-ipsec' persist file version matches with cached value<PHONE_NUMBER>3358724788).
[13:21:20][DBG] Returning 2 subscriptions from 'gwtt-ietf-ipsec' persist file cache.
[13:21:20][DBG] Module 'gwtt-ietf-ipsec' persist file version matches with cached value<PHONE_NUMBER>3358724788).
[13:21:20][DBG] Returning 0 subscriptions from 'gwtt-ietf-ipsec' persist file cache.
[13:21:20][DBG] Module 'gwtt-ip-routing' persist file version matches with cached value<PHONE_NUMBER>3589374479).
[13:21:20][DBG] Returning 1 subscriptions from 'gwtt-ip-routing' persist file cache.
[13:21:20][DBG] Module 'gwtt-ip-routing' persist file version matches with cached value<PHONE_NUMBER>3589374479).
[13:21:20][DBG] Returning 0 subscriptions from 'gwtt-ip-routing' persist file cache.
[13:21:20][DBG] Module 'gwtt-vxlan' persist file version matches with cached value (879822841).
[13:21:20][DBG] Returning 1 subscriptions from 'gwtt-vxlan' persist file cache.
[13:21:20][DBG] Module 'gwtt-vxlan' persist file version matches with cached value (879822841).
[13:21:20][DBG] Returning 0 subscriptions from 'gwtt-vxlan' persist file cache.
[13:21:20][DBG] Module 'ietf-interfaces' persist file version matches with cached value<PHONE_NUMBER>1934670689).
[13:21:20][DBG] Returning 5 subscriptions from 'ietf-interfaces' persist file cache.
[13:21:20][DBG] Module 'ietf-interfaces' persist file version matches with cached value<PHONE_NUMBER>1934670689).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-interfaces' persist file cache.
[13:21:20][DBG] Module 'ietf-keystore' persist file version matches with cached value<PHONE_NUMBER>).
[13:21:20][DBG] Returning 2 subscriptions from 'ietf-keystore' persist file cache.
[13:21:20][DBG] Module 'ietf-keystore' persist file version matches with cached value<PHONE_NUMBER>).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-keystore' persist file cache.
[13:21:20][DBG] Module 'ietf-netconf-monitoring' persist file version matches with cached value<PHONE_NUMBER>3128527841).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-netconf-monitoring' persist file cache.
[13:21:20][DBG] Module 'ietf-netconf-monitoring' persist file version matches with cached value<PHONE_NUMBER>3128527841).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-netconf-monitoring' persist file cache.
[13:21:20][DBG] Module 'ietf-netconf-server' persist file version matches with cached value<PHONE_NUMBER>1833560841).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-netconf-server' persist file cache.
[13:21:20][DBG] Module 'ietf-netconf-server' persist file version matches with cached value<PHONE_NUMBER>1833560841).
[13:21:20][DBG] Returning 1 subscriptions from 'ietf-netconf-server' persist file cache.
[13:21:20][DBG] Module 'ietf-system' persist file version matches with cached value (124009100).
[13:21:20][DBG] Returning 1 subscriptions from 'ietf-system' persist file cache.
[13:21:20][DBG] Module 'ietf-system' persist file version matches with cached value (124009100).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-system' persist file cache.
[13:21:20][DBG] Module 'ietf-yang-library' persist file version matches with cached value<PHONE_NUMBER>).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-yang-library' persist file cache.
[13:21:20][DBG] Module 'ietf-yang-library' persist file version matches with cached value<PHONE_NUMBER>).
[13:21:20][DBG] Returning 0 subscriptions from 'ietf-yang-library' persist file cache.
[13:21:20][DBG] Module 'nc-notifications' persist file version matches with cached value<PHONE_NUMBER>3128527813).
[13:21:20][DBG] Returning 0 subscriptions from 'nc-notifications' persist file cache.
[13:21:20][DBG] Module 'nc-notifications' persist file version matches with cached value<PHONE_NUMBER>3128527813).
[13:21:20][DBG] Returning 0 subscriptions from 'nc-notifications' persist file cache.
[13:21:20][DBG] Commit: In the session there are 12 / 12 modified models
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][INF] File /etc/sysrepo/data/gwtt-device.running.lock locked by other process
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][INF] File /etc/sysrepo/data/gwtt-device.running.lock is already locked by this session
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][INF] File /etc/sysrepo/data/gwtt-dhcpd.running.lock locked by other process
[13:21:20][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:20][INF] File /etc/sysrepo/data/gwtt-dhcpd.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-ietf-ipsec.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-ietf-ipsec.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-ip-routing.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-ip-routing.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-vxlan.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/gwtt-vxlan.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-interfaces.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-interfaces.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-keystore.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-keystore.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-netconf-monitoring.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-netconf-monitoring.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-netconf-server.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-netconf-server.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-system.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-system.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-yang-library.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/ietf-yang-library.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/nc-notifications.running.lock locked by other process
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][INF] File /etc/sysrepo/data/nc-notifications.running.lock is already locked by this session
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][DBG] Timestamp for the model gwtt-device matches, ops will be skipped
[13:21:21][DBG] Usage count gwtt-device incremented (value=4)
[13:21:21][DBG] Loaded module gwtt-device: mtime sec=1579410999 nsec=0
[13:21:21][DBG] Usage count gwtt-device incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/gwtt-device.running loaded successfully
[13:21:21][DBG] Timestamp for the model gwtt-dhcpd matches, ops will be skipped
[13:21:21][DBG] Usage count gwtt-dhcpd incremented (value=6)
[13:21:21][DBG] Loaded module gwtt-dhcpd: mtime sec=1579411016 nsec=0
[13:21:21][DBG] Usage count gwtt-dhcpd incremented (value=7)
[13:21:21][INF] Data file /etc/sysrepo/data/gwtt-dhcpd.running loaded successfully
[13:21:21][DBG] Timestamp for the model gwtt-ietf-ipsec matches, ops will be skipped
[13:21:21][DBG] Usage count gwtt-ietf-ipsec incremented (value=4)
[13:21:21][DBG] Loaded module gwtt-ietf-ipsec: mtime sec=1579410999 nsec=0
[13:21:21][DBG] Usage count gwtt-ietf-ipsec incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/gwtt-ietf-ipsec.running loaded successfully
[13:21:21][DBG] Timestamp for the model gwtt-ip-routing matches, ops will be skipped
[13:21:21][DBG] Usage count gwtt-ip-routing incremented (value=4)
[13:21:21][DBG] Loaded module gwtt-ip-routing: mtime sec=1579410999 nsec=0
[13:21:21][DBG] Usage count gwtt-ip-routing incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/gwtt-ip-routing.running loaded successfully
[13:21:21][DBG] Timestamp for the model gwtt-vxlan matches, ops will be skipped
[13:21:21][DBG] Usage count gwtt-vxlan incremented (value=4)
[13:21:21][DBG] Loaded module gwtt-vxlan: mtime sec=1579410999 nsec=0
[13:21:21][DBG] Usage count gwtt-vxlan incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/gwtt-vxlan.running loaded successfully
[13:21:21][DBG] Timestamp for the model ietf-interfaces matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-interfaces incremented (value=4)
[13:21:21][DBG] Loaded module ietf-interfaces: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count ietf-interfaces incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-interfaces.running loaded successfully
[13:21:21][DBG] Timestamp for the model ietf-keystore matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-keystore incremented (value=4)
[13:21:21][DBG] Loaded module ietf-keystore: mtime sec=1579411000 nsec=0
[13:21:21][DBG] CALLBACK FOR MODULE (null) urn:ietf:params:xml:ns:yang:ietf-keystore
[13:21:21][DBG] Usage count ietf-keystore incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-keystore.running loaded successfully
[13:21:21][DBG] Timestamp for the model ietf-netconf-monitoring matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-netconf-monitoring incremented (value=4)
[13:21:21][DBG] Loaded module ietf-netconf-monitoring: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count ietf-netconf-monitoring incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-netconf-monitoring.running is empty
[13:21:21][DBG] Timestamp for the model ietf-netconf-server matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-netconf-server incremented (value=4)
[13:21:21][DBG] Loaded module ietf-netconf-server: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count ietf-netconf-server incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-netconf-server.running loaded successfully
[13:21:21][DBG] Timestamp for the model ietf-system matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-system incremented (value=5)
[13:21:21][DBG] Loaded module ietf-system: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count ietf-system incremented (value=6)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-system.running loaded successfully
[13:21:21][DBG] Timestamp for the model ietf-yang-library matches, ops will be skipped
[13:21:21][DBG] Usage count ietf-yang-library incremented (value=4)
[13:21:21][DBG] Loaded module ietf-yang-library: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count ietf-yang-library incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/ietf-yang-library.running is empty
[13:21:21][DBG] Timestamp for the model nc-notifications matches, ops will be skipped
[13:21:21][DBG] Usage count nc-notifications incremented (value=4)
[13:21:21][DBG] Loaded module nc-notifications: mtime sec=1579411000 nsec=0
[13:21:21][DBG] Usage count nc-notifications incremented (value=5)
[13:21:21][INF] Data file /etc/sysrepo/data/nc-notifications.running is empty
[13:21:21][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:21][DBG] Commit (3/10): all modified models loaded successfully
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Skipping op for model ietf-interfaces
[13:21:21][DBG] Commit (4/10): replay of operation succeeded
[13:21:21][DBG] Validation succeeded for 'gwtt-device' module
[13:21:21][DBG] Module ietf-interfaces already loaded
[13:21:21][DBG] Data tree ietf-interfaces appended because of validation
[13:21:21][DBG] Validation succeeded for 'gwtt-dhcpd' module
[13:21:21][DBG] Validation succeeded for 'gwtt-ietf-ipsec' module
[13:21:21][DBG] Validation succeeded for 'gwtt-ip-routing' module
[13:21:21][DBG] Validation succeeded for 'gwtt-vxlan' module
[13:21:21][DBG] Validation succeeded for 'ietf-interfaces' module
[13:21:21][DBG] Module gwtt-dhcpd already loaded
[13:21:21][DBG] Module ietf-interfaces already loaded
[13:21:21][DBG] Data tree ietf-interfaces appended because of validation
[13:21:21][DBG] Validation succeeded for 'gwtt-dhcpd' module
[13:21:21][DBG] Validation succeeded for 'ietf-keystore' module
[13:21:21][DBG] Module ietf-netconf-server already loaded
[13:21:21][DBG] Module ietf-keystore already loaded
[13:21:21][DBG] Data tree ietf-keystore appended because of validation
[13:21:21][DBG] Validation succeeded for 'ietf-netconf-server' module
[13:21:21][DBG] Validation succeeded for 'ietf-netconf-monitoring' module
[13:21:21][DBG] Module ietf-keystore already loaded
[13:21:21][DBG] Data tree ietf-keystore appended because of validation
[13:21:21][DBG] Validation succeeded for 'ietf-netconf-server' module
[13:21:21][DBG] Validation succeeded for 'ietf-system' module
[13:21:21][DBG] Validation succeeded for 'ietf-yang-library' module
[13:21:21][DBG] Validation succeeded for 'nc-notifications' module
[13:21:21][DBG] Commit (5/10): merged models validation succeeded
[13:21:21][DBG] Commit (6/10): NACM access check skipped
[13:21:21][DBG] Sending verify notifications about the changes made in running datastore...
[13:21:21][DBG] No changes in module gwtt-device
[13:21:21][DBG] No changes in module gwtt-dhcpd
[13:21:21][DBG] No changes in module gwtt-ietf-ipsec
[13:21:21][DBG] Created: /ietf-interfaces:interfaces
[13:21:21][DBG] No changes in module gwtt-vxlan
[13:21:21][DBG] Created: /ietf-interfaces:interfaces/interface[name='vti1']
[13:21:21][DBG] No changes in module ietf-keystore
[13:21:21][DBG] No changes in module ietf-netconf-monitoring
[13:21:21][DBG] No changes in module ietf-netconf-server
[13:21:21][DBG] Created: /ietf-netconf-acm:nacm
[13:21:21][DBG] No changes in module ietf-yang-library
[13:21:21][DBG] No changes in module nc-notifications
[13:21:21][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 780705383.
[13:21:21][DBG] Creating a new NP commit context for commit ID 391130005.
[13:21:21][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:21][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @<PHONE_NUMBER>.
[13:21:21][DBG] Circular buffer enqueue to position=9, current count=2.
[13:21:21][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 689604318.
[13:21:21][DBG] New message enqueued into CM message queue.
[13:21:21][DBG] Circular buffer enqueue to position=0, current count=3.
[13:21:21][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 397765231.
[13:21:21][DBG] Circular buffer dequeue, new buffer head=9, count=2.
[13:21:21][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:21][DBG] Circular buffer enqueue to position=1, current count=3.
[13:21:21][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 442120262.
[13:21:21][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:21][DBG] Circular buffer enqueue to position=2, current count=4.
[13:21:21][DBG] Sending 163 bytes of data.
[13:21:21][DBG] Circular buffer enqueue to position=3, current count=5.
[13:21:21][DBG] Set up commit timeout for commit id=391130005.
[13:21:21][DBG] 163 bytes of data sent.
[13:21:21][DBG] Commit (7/10): verify phase done
[13:21:21][DBG] Commit 391130005 processing paused waiting for replies from verifiers
[13:21:21][DBG] fd 31 readable
[13:21:21][INF] Switch datastore request running -> candidate
[13:21:21][DBG] Copy_config request paused, waiting for verifiers
[13:21:22][DBG] 163 bytes of data received on fd 31
[13:21:22][DBG] Circular buffer dequeue, new buffer head=0, count=4.
[13:21:22][DBG] fd 31 would block
[13:21:22][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] New message of size 159 bytes received.
[13:21:22]'.
[13:21:22][DBG] Thread id=2008903024 will wait.
[13:21:22][DBG] Received a notification for subscription id=780705383 (source address='/var/run/sysrepod.sock[DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock').
[13:21:22]'
[13:21:22][DBG] Creating a new data session at '/var/run/sysrepod.sock'.
[13:21:22][DBG] Sending 176 bytes of data.
[13:21:22][DBG] Sending session-start request.
[13:21:22][DBG] 176 bytes of data sent.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=1, count=3.
[13:21:22][DBG] session-start request sent, waiting for response.
[13:21:22][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:22][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:22][DBG] Sending 182 bytes of data.
[13:21:22][DBG] 182 bytes of data sent.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=2, count=2.
[13:21:22][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:22][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:22][DBG] Sending 176 bytes of data.
[13:21:22][DBG] 176 bytes of data sent.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=3, count=1.
[13:21:22][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:22][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:22][DBG] Sending 178 bytes of data.
[13:21:22][DBG] 178 bytes of data sent.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:22][DBG] Scheduling a delayed request for 30.000000 seconds.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] 30 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 26 bytes received.
[13:21:22][DBG] Processing session_start request (conn=0x1016cc0).
[13:21:22][DBG] Starting a new session, options=1024.
[13:21:22][INF] New session created successfully, real user=root, effective user=(null), session id=1052990431.
[13:21:22][DBG] Initiating circular buffer for 2 elements.
[13:21:22][DBG] RP session start, session id=1052990431.
[13:21:22][DBG] Sending 29 bytes of data.
[13:21:22][DBG] 29 bytes of data sent.
[13:21:22][DBG] session-start response received, processing.
[13:21:22][DBG] Calling subtree-change callback for subscription id=780705383.
[13:21:22][DBG] Sending get changes request.
[13:21:22][DBG] get changes request sent, waiting for response.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] 67 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 63 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009189744 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:22][DBG] Processing get changes request.
[13:21:22][DBG] Get changes: /ietf-interfaces:interfaces/interface limit:50 offset:0 cache miss
[13:21:22][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:22][DBG] Thread id=2009189744 will wait.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:22][DBG] Output buffer for fd=24 expanded to 2393 bytes.
[13:21:22][DBG] Sending 1369 bytes of data.
[13:21:22][DBG] 1369 bytes of data sent.
[13:21:22][DBG] get changes response received, processing.
[13:21:22][DBG] Sending 170 bytes of data.
[13:21:22][DBG] 170 bytes of data sent.
[13:21:22][DBG] fd 12 readable (revents 1)
[13:21:22][DBG] fd 31 readable
[13:21:22][DBG] 170 bytes of data received on fd 12
[13:21:22][DBG] 712 bytes of data received on fd 31
[13:21:22][DBG] fd 12 would block
[13:21:22][DBG] Input buffer for fd=31 expanded to 2048 bytes.
[13:21:22][DBG] New message of size 166 bytes received.
[13:21:22][DBG] fd 31 would block
[13:21:22][DBG] New message of size 172 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:22][DBG] Received a notification for subscription id=1603295584 (source address='/var/run/sysrepod.sock').
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Calling subtree-change callback for subscription id=1603295584.
[13:21:22][DBG] Thread id=2009046384 signaled.
[13:21:22][DBG] Sending get changes request.
[13:21:22][DBG] get changes request sent, waiting for response.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:22][DBG] Notification ACK received with result = 0.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] Thread id=2009046384 will wait.
[13:21:22][DBG] 80 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 76 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009333104 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:22][DBG] Processing get changes request.
[13:21:22][DBG] Get changes: /ietf-interfaces:interfaces/interface/gwtt-vti:vti limit:50 offset:0 cache miss
[13:21:22][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:22][DBG] Thread id=2009333104 will wait.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:22][DBG] Sending 288 bytes of data.
[13:21:22][DBG] 288 bytes of data sent.
[13:21:22][DBG] get changes response received, processing.
[13:21:22][DBG] Sending 183 bytes of data.
[13:21:22][DBG] 183 bytes of data sent.
[13:21:22][DBG] New message of size 178 bytes received.
[13:21:22][DBG] Received a notification for subscription id=689604318 (source address='/var/run/sysrepod.sock[DBG] fd 12 readable (revents 1)
[13:21:22]').
[13:21:22][DBG] 183 bytes of data received on fd 12
[13:21:22][DBG] Calling subtree-change callback for subscription id=689604318.
[13:21:22][DBG] fd 12 would block
[13:21:22][DBG] Sending get changes request.
[13:21:22][DBG] New message of size 179 bytes received.
[13:21:22][DBG] get changes request sent, waiting for response.
[13:21:22][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2008903024 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:22][DBG] Notification ACK received with result = 0.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] Thread id=2008903024 will wait.
[13:21:22][DBG] 86 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 82 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009189744 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:22][DBG] Processing get changes request.
[13:21:22][DBG] Get changes: /ietf-interfaces:interfaces/interface/gwtt-bridge:bridge limit:50 offset:0 cache miss
[13:21:22][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:22][DBG] Thread id=2009189744 will wait.
[13:21:22][DBG] Sending 24 bytes of data.
[13:21:22][DBG] 24 bytes of data sent.
[13:21:22][DBG] get changes response received, processing.
[13:21:22][DBG] No items found for xpath '/ietf-interfaces:interfaces/interface/gwtt-bridge:bridge'
[13:21:22][DBG] Sending 189 bytes of data.
[13:21:22][DBG] 189 bytes of data sent.
[13:21:22][DBG] New message of size 172 bytes received.
[13:21:22][DBG] fd 12 readable (revents 1)
[13:21:22][DBG] 189 bytes of data received on fd 12
[13:21:22][DBG] Received a notification for subscription id=397765231 (source address='/var/run/sysrepod.sock[DBG] fd 12 would block
[13:21:22]').
[13:21:22][DBG] New message of size 185 bytes received.
[13:21:22][DBG] Calling subtree-change callback for subscription id=397765231.
[13:21:22][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009046384 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:22][DBG] Notification ACK received with result = 0.
[13:21:22][DBG] Sending get changes request.
[13:21:22][DBG] Thread id=2009046384 will wait.
[13:21:22][DBG] get changes request sent, waiting for response.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] 80 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 76 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009333104 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=0, count=0.
[13:21:22][DBG] Processing get changes request.
[13:21:22][DBG] Get changes: /ietf-interfaces:interfaces/interface/ietf-ip:ipv4 limit:50 offset:0 cache miss
[13:21:22][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:22][DBG] Thread id=2009333104 will wait.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:22][DBG] Sending 699 bytes of data.
[13:21:22][DBG] 699 bytes of data sent.
[13:21:22][DBG] get changes response received, processing.
[13:21:22]uci: Invalid argument
[13:21:22][DBG] Sending 183 bytes of data.
[13:21:22][DBG] 183 bytes of data sent.
[13:21:22][DBG] New message of size 174 bytes received.
[13:21:22][DBG] Received a notification for subscription id=442120262 (source address='/var/run/sysrepod.sock').
[13:21:22][DBG] fd 12 readable (revents 1)
[13:21:22][DBG] Calling subtree-change callback for subscription id=442120262.
[13:21:22][DBG] 183 bytes of data received on fd 12
[13:21:22][DBG] fd 12 would block
[13:21:22][DBG] Sending get changes request.
[13:21:22][DBG] New message of size 179 bytes received.
[13:21:22][DBG] get changes request sent, waiting for response.
[13:21:22][DBG] Circular buffer enqueue to position=0, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2008903024 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:22][DBG] Notification ACK received with result = 0.
[13:21:22][DBG] fd 24 readable (revents 1)
[13:21:22][DBG] Thread id=2008903024 will wait.
[13:21:22][DBG] 82 bytes of data received on fd 24
[13:21:22][DBG] fd 24 would block
[13:21:22][DBG] New message of size 78 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009189744 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:22][DBG] Processing get changes request.
[13:21:22][DBG] Get changes: /ietf-interfaces:interfaces/interface/gwtt-vlan:vlan limit:50 offset:0 cache miss
[13:21:22][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:22][DBG] New message enqueued into CM message queue.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:22][DBG] Thread id=2009189744 will wait.
[13:21:22][DBG] Sending 24 bytes of data.
[13:21:22][DBG] 24 bytes of data sent.
[13:21:22][DBG] get changes response received, processing.
[13:21:22][DBG] No items found for xpath '/ietf-interfaces:interfaces/interface/gwtt-vlan:vlan'
[13:21:22][DBG] VLAN : get vlan changes all successfully, xpath=/ietf-interfaces:interfaces/interface/gwtt-vlan:vlan, line=784
[13:21:22]
[13:21:22][DBG] VLAN : vlan changes are valid, line=809
[13:21:22]
[13:21:22][DBG] Sending 185 bytes of data.
[13:21:22][DBG] 185 bytes of data sent.
[13:21:22][DBG] fd 12 readable (revents 1)
[13:21:22][DBG] 185 bytes of data received on fd 12
[13:21:22][DBG] fd 12 would block
[13:21:22][DBG] New message of size 181 bytes received.
[13:21:22][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:22][DBG] Threads: active=0/4, 1 requests in queue
[13:21:22][DBG] Thread id=2009046384 signaled.
[13:21:22][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:22][DBG] Notification ACK received with result = 0.
[13:21:22][DBG] Commit id=391130005 notifications complete.
[13:21:22][DBG] Commit context in state 7
[13:21:22][INF] Resuming copy_config with id 391130005 continue with write
[13:21:22][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:22][DBG] Threads: active=1/4, 1 requests in queue
[13:21:22][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:23][DBG] Processing copy_config request.
[13:21:23][INF] Copy config: candidate -> running, model: (null)
[13:21:23][INF] Switch datastore request candidate -> running
[13:21:23][DBG] Data successfully written for module 'gwtt-device'
[13:21:23][DBG] Data successfully written for module 'gwtt-dhcpd'
[13:21:23][DBG] Data successfully written for module 'gwtt-ietf-ipsec'
[13:21:23][DBG] Data successfully written for module 'gwtt-ip-routing'
[13:21:23][DBG] Data successfully written for module 'gwtt-vxlan'
[13:21:24][DBG] Data successfully written for module 'ietf-interfaces'
[13:21:25][DBG] Data successfully written for module 'ietf-keystore'
[13:21:25][DBG] Data successfully written for module 'ietf-netconf-monitoring'
[13:21:25][DBG] Data successfully written for module 'ietf-netconf-server'
[13:21:25][DBG] Data successfully written for module 'ietf-system'
[13:21:25][DBG] Data successfully written for module 'ietf-yang-library'
[13:21:25][DBG] Data successfully written for module 'nc-notifications'
[13:21:25][DBG] Commit (8/10): data write succeeded
[13:21:25][DBG] Sending apply notifications about the changes made in running datastore...
[13:21:25][INF] Unlock datastore request
[13:21:25][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 780705383.
[13:21:25][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:25][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @<PHONE_NUMBER>.
[13:21:25][DBG] Circular buffer enqueue to position=0, current count=2.
[13:21:25][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 689604318.
[13:21:25][DBG] New message enqueued into CM message queue.
[13:21:25][DBG] Circular buffer enqueue to position=1, current count=3.
[13:21:25][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 397765231.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=0, count=2.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Circular buffer enqueue to position=2, current count=3.
[13:21:25][DBG] Sending subtree-change notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 442120262.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] Circular buffer enqueue to position=3, current count=4.
[13:21:25][DBG] Sending 163 bytes of data.
[13:21:25][DBG] Circular buffer enqueue to position=4, current count=5.
[13:21:25][DBG] 163 bytes of data sent.
[13:21:25][DBG] Circular buffer enqueue to position=5, current count=6.
[13:21:25][DBG] Circular buffer enqueue to position=6, current count=7.
[13:21:25][DBG] fd 31 readable
[13:21:25][DBG] Circular buffer enqueue to position=7, current count=8.
[13:21:25][DBG] 163 bytes of data received on fd 31
[13:21:25][DBG] Circular buffer enqueue to position=8, current count=9.
[13:21:25][DBG] fd 31 would block
[13:21:25][DBG] New message of size 159 bytes received.
[13:21:25][DBG] Circular buffer enqueue to position=9, current count=10.
[13:21:25][DBG] Set up commit timeout for commit id=391130005.
[13:21:25][DBG] Received a notification for subscription id=780705383 (source address='/var/run/sysrepod.sock').
[13:21:25][DBG] Config changes for module gwtt-device
[13:21:25][DBG] Config changes for module gwtt-dhcpd
[13:21:25][DBG] Calling subtree-change callback for subscription id=780705383.
[13:21:25][DBG] Config changes for module gwtt-ietf-ipsec
[13:21:25][DBG] Config changes for module gwtt-ip-routing
[13:21:25][DBG] Config changes for module gwtt-vxlan
[13:21:25][DBG] Config changes for module ietf-interfaces
[13:21:25][DBG] Config changes for module ietf-keystore
[13:21:25][DBG] Config changes for module ietf-netconf-monitoring
[13:21:25][INF] Applying changes.
[13:21:25][DBG] Sending 170 bytes of data.
[13:21:25][DBG] 170 bytes of data sent.
[13:21:25][DBG] Config changes for module ietf-netconf-server
[13:21:25][DBG] Config changes for module ietf-system
[13:21:25][DBG] Config changes for module ietf-yang-library
[13:21:25][DBG] Circular buffer dequeue, new buffer head=1, count=9.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Config changes for module nc-notifications
[13:21:25][DBG] 3 instance of /ietf-netconf-notifications/netconf-config-change/edit list will be created
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] CONFIG CHANGE: create /ietf-interfaces:interfaces
[13:21:25][DBG] Sending 176 bytes of data.
[13:21:25][DBG] CONFIG CHANGE: create /ietf-interfaces:interfaces/interface[name='vti1']
[13:21:25][DBG] fd 31 readable
[13:21:25][DBG] CONFIG CHANGE: create /ietf-netconf-acm:nacm
[13:21:25][DBG] 176 bytes of data received on fd 31
[13:21:25][DBG] fd 31 would block
[13:21:25][DBG] New message of size 172 bytes received.
[13:21:25][DBG] Received a notification for subscription id=1603295584 (source address='/var/run/sysrepod.sock[DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:25]').
[13:21:25][DBG] Threads: active=1/4, 1 requests in queue
[13:21:25][DBG] Calling subtree-change callback for subscription id=1603295584.
[13:21:25][DBG] Config changed notification generated session 181709762
[13:21:25][DBG] Commit (9/10): apply notifications sent
[13:21:25][DBG] Usage count gwtt-device decremented (value=4)
[13:21:25][DBG] Usage count gwtt-dhcpd decremented (value=6)
[13:21:25][DBG] Usage count gwtt-ietf-ipsec decremented (value=4)
[13:21:25][DBG] Usage count gwtt-ip-routing decremented (value=4)
[13:21:25][DBG] Usage count gwtt-vxlan decremented (value=4)
[13:21:25][DBG] Sending 183 bytes of data.
[13:21:25][DBG] 183 bytes of data sent.
[13:21:25][DBG] Usage count ietf-interfaces decremented (value=4)
[13:21:25][DBG] Usage count ietf-keystore decremented (value=4)
[13:21:25][DBG] Usage count ietf-netconf-monitoring decremented (value=4)
[13:21:25][DBG] 176 bytes of data sent.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=2, count=8.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] Sending 182 bytes of data.
[13:21:25][DBG] 182 bytes of data sent.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=3, count=7.
[13:21:25][DBG] fd 31 readable
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] 182 bytes of data received on fd 31
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] fd 31 would block
[13:21:25]'
[13:21:25][DBG] New message of size 178 bytes received.
[13:21:25][DBG] Sending 176 bytes of data.
[13:21:25][DBG] Received a notification for subscription id=689604318 (source address='[DBG] 176 bytes of data sent.
[13:21:25]/var/run/sysrepod.sock').
[13:21:25][DBG] Circular buffer dequeue, new buffer head=4, count=6.
[13:21:25][DBG] Calling subtree-change callback for subscription id=689604318.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] Sending 178 bytes of data.
[13:21:25][DBG] 178 bytes of data sent.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=5, count=5.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Sending 189 bytes of data.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] 189 bytes of data sent.
[13:21:25]'
[13:21:25][DBG] Sending 119 bytes of data.
[13:21:25][DBG] 119 bytes of data sent.
[13:21:25][DBG] fd 31 readable
[13:21:25][DBG] Circular buffer dequeue, new buffer head=6, count=4.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] 473 bytes of data received on fd 31
[13:21:25][DBG] fd 31 would block
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] New message of size 172 bytes received.
[13:21:25][DBG] Sending 119 bytes of data.
[13:21:25][DBG] Received a notification for subscription id=397765231 (source address='/var/run/sysrepod.sock[DBG] 119 bytes of data sent.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=7, count=3.
[13:21:25][DBG] Sending a notification to '').
[13:21:25]/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Calling subtree-change callback for subscription id=397765231.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:25][DBG] Sending 119 bytes of data.
[13:21:25][DBG] 119 bytes of data sent.
[13:21:25][DBG] Circular buffer dequeue, new buffer head=8, count=2.
[13:21:25][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:25][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] Sending 183 bytes of data.
[13:21:25]'
[13:21:25][DBG] 183 bytes of data sent.
[13:21:25][DBG] Sending 119 bytes of data.
[13:21:26][DBG] 119 bytes of data sent.
[13:21:26][DBG] New message of size 174 bytes received.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=9, count=1.
[13:21:26][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:26][DBG] Received a notification for subscription id=442120262 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] Calling subtree-change callback for subscription id=442120262.
[13:21:26]'
[13:21:26][DBG] VLAN : (chip)apply interface 栊箇栊箇 config successfully, line=646
[13:21:26]
[13:21:26][DBG] Sending 119 bytes of data.
[13:21:26][DBG] 119 bytes of data sent.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=0, count=0.
[13:21:26][DBG] Scheduling a delayed request for 30.000000 seconds.
[13:21:26][DBG] New message enqueued into CM message queue.
[13:21:26][DBG] fd 12 readable (revents 1)
[13:21:26][DBG] 725 bytes of data received on fd 12
[13:21:26][DBG] Input buffer for fd=12 expanded to 2048 bytes.
[13:21:26][DBG] fd 12 would block
[13:21:26][DBG] New message of size 166 bytes received.
[13:21:26][DBG] Circular buffer enqueue to position=5, current count=2.
[13:21:26][DBG] Threads: active=1/4, 2 requests in queue
[13:21:26][DBG] New message of size 179 bytes received.
[13:21:26][DBG] Circular buffer enqueue to position=6, current count=3.
[13:21:26][DBG] Threads: active=1/4, 3 requests in queue
[13:21:26][DBG] New message of size 185 bytes received.
[13:21:26][DBG] Thread id=2009333104 signaled.
[13:21:26][DBG] Circular buffer enqueue to position=7, current count=4.
[13:21:26][DBG] Threads: active=2/4, 4 requests in queue
[13:21:26][DBG] New message of size 179 bytes received.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=5, count=3.
[13:21:26][DBG] Circular buffer enqueue to position=8, current count=4.
[13:21:26][DBG] Threads: active=2/4, 4 requests in queue
[13:21:26][DBG] Processing event notification request (/ietf-netconf-notifications:netconf-config-change).
[13:21:26][DBG] RP session start, session id=0.
[13:21:26][DBG] Usage count ietf-netconf-server decremented (value=4)
[13:21:26][DBG] Usage count ietf-system decremented (value=5)
[13:21:26][DBG] Usage count ietf-yang-library decremented (value=4)
[13:21:26][DBG] Usage count nc-notifications decremented (value=4)
[13:21:26][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:26][DBG] Commit (10/10): finished successfully
[13:21:26][INF] Unlock datastore request
[13:21:26][INF] Switch datastore request running -> candidate
[13:21:26][DBG] Circular buffer enqueue to position=0, current count=1.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=6, count=3.
[13:21:26][DBG] Notification ACK received with result = 0.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=7, count=2.
[13:21:26][DBG] Notification ACK received with result = 0.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=8, count=1.
[13:21:26][DBG] Notification ACK received with result = 0.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:26][DBG] Notification ACK received with result = 0.
[13:21:26][DBG] Thread id=2009046384 will wait.
[13:21:26][DBG] New message enqueued into CM message queue.
[13:21:26][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:26][DBG] Sending 23 bytes of data.
[13:21:26][DBG] 23 bytes of data sent.
[13:21:26][DBG] Loaded module ietf-netconf-notifications: mtime sec=1576541280 nsec=0
[13:21:26][DBG] Usage count ietf-netconf-notifications incremented (value=4)
[13:21:26][INF] Data file /etc/sysrepo/data/ietf-netconf-notifications.running is empty
[13:21:26][DBG] Validation succeeded for 'ietf-netconf-notifications' module
[13:21:26][DBG] Module ietf-netconf-notifications has been loaded
[13:21:26][DBG] Module ietf-netconf-notifications already loaded
[13:21:26][DBG] Storing notification '/ietf-netconf-notifications:netconf-config-change' generated on '1579411290'.
[13:21:26][DBG] File /etc/sysrepo/data/notifications//ietf-netconf-notifications/2020-01-19_05-20.xml has been locked
[13:21:26][DBG] Data successfully loaded from file '/etc/sysrepo/data/notifications//ietf-netconf-notifications/2020-01-19_05-20.xml'.
[13:21:26][DBG] fd 15 readable (revents 1)
[13:21:26][DBG] 45 bytes of data received on fd 15
[13:21:26][DBG] fd 15 would block
[13:21:26][DBG] New message of size 41 bytes received.
[13:21:26][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:26][DBG] Threads: active=1/4, 1 requests in queue
[13:21:26][DBG] VLAN : uci commands execute successfully, line=723
[13:21:26]
[13:21:26][DBG] Sending 185 bytes of data.
[13:21:26][DBG] 185 bytes of data sent.
[13:21:26][DBG] New message of size 115 bytes received.
[13:21:26][DBG] fd 12 readable (revents 1)
[13:21:26][DBG] Received a notification for subscription id=780705383 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] 185 bytes of data received on fd 12
[13:21:26][DBG] COMMIT-END notification received on subscription id=780705383.
[13:21:26][DBG] fd 12 would block
[13:21:26][DBG] Sending session-stop request.
[13:21:26][DBG] New message of size 181 bytes received.
[13:21:26][DBG] session-stop request sent, waiting for response.
[13:21:26][DBG] Circular buffer enqueue to position=0, current count=2.
[13:21:26][DBG] Threads: active=1/4, 2 requests in queue
[13:21:26][DBG] fd 24 readable (revents 1)
[13:21:26][DBG] 29 bytes of data received on fd 24
[13:21:26][DBG] fd 24 would block
[13:21:26][DBG] New message of size 25 bytes received.
[13:21:26][DBG] Processing session_stop request (session id=1052990431).
[13:21:26][DBG] RP session stop, session id=1052990431.
[13:21:26][DBG] RP session cleanup, session id=1052990431.
[13:21:26][INF] Unlock datastore request
[13:21:26][DBG] Sending 29 bytes of data.
[13:21:26][DBG] 29 bytes of data sent.
[13:21:26][INF] Dropping session id=1052990431.
[13:21:26][DBG] session-stop response received, processing.
[13:21:26][DBG] fd 31 readable
[13:21:26][DBG] 476 bytes of data received on fd 31
[13:21:26][DBG] fd 31 would block
[13:21:26][DBG] New message of size 115 bytes received.
[13:21:26][DBG] Received a notification for subscription id=1603295584 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] COMMIT-END notification received on subscription id=1603295584.
[13:21:26][DBG] New message of size 115 bytes received.
[13:21:26][DBG] Received a notification for subscription id=689604318 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] COMMIT-END notification received on subscription id=689604318.
[13:21:26][DBG] New message of size 115 bytes received.
[13:21:26][DBG] Received a notification for subscription id=397765231 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] COMMIT-END notification received on subscription id=397765231.
[13:21:26][DBG] New message of size 115 bytes received.
[13:21:26][DBG] Received a notification for subscription id=442120262 (source address='/var/run/sysrepod.sock').
[13:21:26][DBG] COMMIT-END notification received on subscription id=442120262.
[13:21:27][DBG] Data tree successfully saved.
[13:21:27][DBG] Notification successfully logged into 'ietf-netconf-notifications' notification store.
[13:21:27][DBG] File /etc/sysrepo/data/notifications//ietf-netconf-notifications/2020-01-19_05-20.xml (fd = 42) has been unlocked
[13:21:27][DBG] Module 'ietf-netconf-notifications' persist file version matches with cached value<PHONE_NUMBER>3653691361).
[13:21:27][DBG] Returning 1 subscriptions from 'ietf-netconf-notifications' persist file cache.
[13:21:27][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:27][DBG] Internally generated event notification /ietf-netconf-notifications:netconf-config-change response not sent
[13:21:27][DBG] RP session stop, session id=0.
[13:21:27][DBG] RP session cleanup, session id=0.
[13:21:27][INF] Unlock datastore request
[13:21:27][DBG] New message enqueued into CM message queue.
[13:21:27][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:27][DBG] Sending an event notification to '/var/run/sysrepo-subscriptions/ietf-netconf-notifications/3804.NElMcF.sock'.
[13:21:28][DBG] Processing event notification without associated session
[13:21:28][DBG] Usage count ietf-netconf-notifications decremented (value=3)
[13:21:28][DBG] Reusing existing connection on fd=21 for the event notification destination '/var/run/sysrepo-subscriptions/ietf-netconf-notifications/3804.NElMcF.sock'
[13:21:28][DBG] Circular buffer dequeue, new buffer head=0, count=1.
[13:21:28][DBG] Processing check-exec-permission request.
[13:21:28][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:28][DBG] Notification ACK received with result = 0.
[13:21:28][DBG] Releasing commit id=391130005.
[13:21:28][DBG] Commit id=391130005 notifications complete.
[13:21:28][DBG] Sending 1249 bytes of data.
[13:21:28][DBG] Commit context in state 11
[13:21:28][INF] Commit id 391130005 received all notifications
[13:21:28][DBG] 1249 bytes of data sent.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:28][DBG] Usage count gwtt-device decremented (value=3)
[13:21:28][DBG] Sending 25 bytes of data.
[13:21:28][DBG] Usage count gwtt-dhcpd decremented (value=5)
[13:21:28][DBG] Usage count gwtt-ietf-ipsec decremented (value=3)
[13:21:28][DBG] Usage count gwtt-ip-routing decremented (value=3)
[13:21:28][DBG] Usage count gwtt-vxlan decremented (value=3)
[13:21:28][DBG] 25 bytes of data sent.
[13:21:28][DBG] Usage count ietf-interfaces decremented (value=3)
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] Usage count ietf-keystore decremented (value=3)
[13:21:28][DBG] 23 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 19 bytes received.
[13:21:28][DBG] Usage count ietf-netconf-monitoring decremented (value=3)
[13:21:28][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:28][DBG] Threads: active=1/4, 1 requests in queue
[13:21:28][DBG] Usage count ietf-netconf-server decremented (value=3)
[13:21:28][DBG] Usage count ietf-system decremented (value=4)
[13:21:28][DBG] Usage count ietf-yang-library decremented (value=3)
[13:21:28][DBG] Usage count nc-notifications decremented (value=3)
[13:21:28][INF] Unlock datastore request
[13:21:28][DBG] Usage count gwtt-device decremented (value=2)
[13:21:28][DBG] Usage count gwtt-dhcpd decremented (value=4)
[13:21:28][DBG] Usage count gwtt-ietf-ipsec decremented (value=2)
[13:21:28][DBG] Usage count gwtt-ip-routing decremented (value=2)
[13:21:28][DBG] Usage count gwtt-vxlan decremented (value=2)
[13:21:28][DBG] Usage count ietf-interfaces decremented (value=2)
[13:21:28][DBG] Usage count ietf-keystore decremented (value=2)
[13:21:28][DBG] Usage count ietf-netconf-monitoring decremented (value=2)
[13:21:28][DBG] Usage count ietf-netconf-server decremented (value=2)
[13:21:28][DBG] Usage count ietf-system decremented (value=3)
[13:21:28][DBG] Usage count ietf-yang-library decremented (value=2)
[13:21:28][DBG] Usage count nc-notifications decremented (value=2)
[13:21:28][DBG] Commit context with id 391130005 removed
[13:21:28][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:28][DBG] Processing unlock request.
[13:21:28][INF] Unlock datastore request
[13:21:28][DBG] Module_name notifications
[13:21:28][DBG] Usage count notifications decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/notifications.running.lock has been unlocked
[13:21:28][DBG] Module_name nc-notifications
[13:21:28][DBG] Usage count nc-notifications decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/nc-notifications.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-netconf-acm
[13:21:28][DBG] Usage count ietf-netconf-acm decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-netconf-acm.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-netconf-notifications
[13:21:28][DBG] Usage count ietf-netconf-notifications decremented (value=2)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-netconf-notifications.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-netconf
[13:21:28][DBG] Usage count ietf-netconf decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-netconf.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-keystore
[13:21:28][DBG] Usage count ietf-keystore decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-keystore.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-netconf-server
[13:21:28][DBG] Usage count ietf-netconf-server decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-netconf-server.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-netconf-monitoring
[13:21:28][DBG] Usage count ietf-netconf-monitoring decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-netconf-monitoring.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-system
[13:21:28][DBG] Usage count ietf-system decremented (value=2)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-system.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-yang-library
[13:21:28][DBG] Usage count ietf-yang-library decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-yang-library.running.lock has been unlocked
[13:21:28][DBG] Module_name gwtt-device
[13:21:28][DBG] Usage count gwtt-device decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/gwtt-device.running.lock has been unlocked
[13:21:28][DBG] Module_name gwtt-ip-routing
[13:21:28][DBG] Usage count gwtt-ip-routing decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/gwtt-ip-routing.running.lock has been unlocked
[13:21:28][DBG] Module_name ietf-interfaces
[13:21:28][DBG] Usage count ietf-interfaces decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/ietf-interfaces.running.lock has been unlocked
[13:21:28][DBG] Module_name gwtt-vxlan
[13:21:28][DBG] Usage count gwtt-vxlan decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/gwtt-vxlan.running.lock has been unlocked
[13:21:28][DBG] Module_name gwtt-ietf-ipsec
[13:21:28][DBG] Usage count gwtt-ietf-ipsec decremented (value=1)
[13:21:28][DBG] File /etc/sysrepo/data/gwtt-ietf-ipsec.running.lock has been unlocked
[13:21:28][DBG] Module_name gwtt-dhcpd
[13:21:28][DBG] Usage count gwtt-dhcpd decremented (value=3)
[13:21:28][DBG] File /etc/sysrepo/data/gwtt-dhcpd.running.lock has been unlocked
[13:21:28][DBG] Module_name notifications
[13:21:28][DBG] Usage count notifications decremented (value=0)
[13:21:28][DBG] Module_name nc-notifications
[13:21:28][DBG] Usage count nc-notifications decremented (value=0)
[13:21:28][DBG] Module_name ietf-netconf-acm
[13:21:28][DBG] Usage count ietf-netconf-acm decremented (value=0)
[13:21:28][DBG] Module_name ietf-netconf-notifications
[13:21:28][DBG] Usage count ietf-netconf-notifications decremented (value=1)
[13:21:28][DBG] Module_name ietf-netconf
[13:21:28][DBG] Usage count ietf-netconf decremented (value=0)
[13:21:28][DBG] Module_name ietf-keystore
[13:21:28][DBG] Usage count ietf-keystore decremented (value=0)
[13:21:28][DBG] Module_name ietf-netconf-server
[13:21:28][DBG] Usage count ietf-netconf-server decremented (value=0)
[13:21:28][DBG] Module_name ietf-netconf-monitoring
[13:21:28][DBG] Usage count ietf-netconf-monitoring decremented (value=0)
[13:21:28][DBG] Module_name ietf-system
[13:21:28][DBG] Usage count ietf-system decremented (value=1)
[13:21:28][DBG] Module_name ietf-yang-library
[13:21:28][DBG] Usage count ietf-yang-library decremented (value=0)
[13:21:28][DBG] Module_name gwtt-device
[13:21:28][DBG] Usage count gwtt-device decremented (value=0)
[13:21:28][DBG] Module_name gwtt-ip-routing
[13:21:28][DBG] Usage count gwtt-ip-routing decremented (value=0)
[13:21:28][DBG] Module_name ietf-interfaces
[13:21:28][DBG] Usage count ietf-interfaces decremented (value=0)
[13:21:28][DBG] Module_name gwtt-vxlan
[13:21:28][DBG] Usage count gwtt-vxlan decremented (value=0)
[13:21:28][DBG] Module_name gwtt-ietf-ipsec
[13:21:28][DBG] Usage count gwtt-ietf-ipsec decremented (value=0)
[13:21:28][DBG] Module_name gwtt-dhcpd
[13:21:28][DBG] Usage count gwtt-dhcpd decremented (value=2)
[13:21:28][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:28][DBG] Thread id=2009333104 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:28][DBG] Sending 23 bytes of data.
[13:21:28][DBG] 23 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 23 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 19 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2008903024 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:28][DBG] Processing discard_changes request.
[13:21:28][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:28][DBG] Thread id=2008903024 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:28][DBG] Sending 23 bytes of data.
[13:21:28][DBG] 23 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 45 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 41 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2009189744 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:28][DBG] Processing check-exec-permission request.
[13:21:28][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:28][DBG] Thread id=2009189744 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:28][DBG] Sending 25 bytes of data.
[13:21:28][DBG] 25 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 24 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 20 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2009046384 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:28][DBG] Processing session_switch_ds request.
[13:21:28][INF] Switch datastore request candidate -> running
[13:21:28][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:28][DBG] Thread id=2009046384 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:28][DBG] Sending 22 bytes of data.
[13:21:28][DBG] 22 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 23 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 19 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2009333104 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:28][DBG] Processing unlock request.
[13:21:28][INF] Unlock datastore request
[13:21:28][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:28][DBG] Thread id=2009333104 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:28][DBG] Sending 23 bytes of data.
[13:21:28][DBG] 23 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 23 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 19 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2008903024 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:28][DBG] Processing discard_changes request.
[13:21:28][DBG] Usage count gwtt-dhcpd decremented (value=1)
[13:21:28][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:28][DBG] Thread id=2008903024 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:28][DBG] Sending 23 bytes of data.
[13:21:28][DBG] 23 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 43 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:28][DBG] New message of size 39 bytes received.
[13:21:28][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:28][DBG] Threads: active=0/4, 1 requests in queue
[13:21:28][DBG] Thread id=2009189744 signaled.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:28][DBG] Processing check-exec-permission request.
[13:21:28][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:28][DBG] Thread id=2009189744 will wait.
[13:21:28][DBG] New message enqueued into CM message queue.
[13:21:28][DBG] Circular buffer dequeue, new buffer head=0, count=0.
[13:21:28][DBG] Sending 25 bytes of data.
[13:21:28][DBG] 25 bytes of data sent.
[13:21:28][DBG] fd 15 readable (revents 1)
[13:21:28][DBG] 23 bytes of data received on fd 15
[13:21:28][DBG] fd 15 would block
[13:21:29][DBG] New message of size 19 bytes received.
[13:21:29][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:29][DBG] Threads: active=0/4, 1 requests in queue
[13:21:29][DBG] Thread id=2009046384 signaled.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:29][DBG] Processing lock request.
[13:21:29][INF] Lock request module: '(null)', datastore running
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/notifications.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/notifications.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/notifications.persist (fd = 26) has been unlocked
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/nc-notifications.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/nc-notifications.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/nc-notifications.persist (fd = 26) has been unlocked
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock' @ 742020692.
[13:21:29][DBG] Circular buffer enqueue to position=0, current count=1.
[13:21:29][DBG] Returning info from 'nc-notifications' persist file: module disabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock'.
[13:21:29][DBG] Reusing existing connection on fd=17 for the notification destination '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock'
[13:21:29][DBG] Sending 114 bytes of data.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] 114 bytes of data sent.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-acm.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-acm.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-acm.persist (fd = 26) has been unlocked
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-notifications.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-notifications.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-notifications.persist (fd = 26) has been unlocked
[13:21:29][DBG] Returning info from 'ietf-netconf-notifications' persist file: module disabled, 0 subtrees enabled in running, 0 features enabled.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf.persist (fd = 26) has been unlocked
[13:21:29][DBG] Returning info from 'ietf-netconf' persist file: module disabled, 0 subtrees enabled in running, 7 features enabled.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-keystore.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-keystore.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-keystore.persist (fd = 26) has been unlocked
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:29][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:29][DBG] Circular buffer enqueue to position=2, current count=2.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=2, count=1.
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'.
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:29][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'
[13:21:29][DBG] Circular buffer enqueue to position=3, current count=2.
[13:21:29][DBG] Sending 111 bytes of data.
[13:21:29][DBG] Returning info from 'ietf-keystore' persist file: module disabled, 3 subtrees enabled in running, 0 features enabled.
[13:21:29][DBG] 111 bytes of data sent.
[13:21:29][DBG] fd 10 readable
[13:21:29][DBG] Circular buffer dequeue, new buffer head=3, count=1.
[13:21:29][DBG] 111 bytes of data received on fd 10
[13:21:29][DBG] Sending a notification to '[DBG] fd 10 would block
[13:21:29][DBG] New message of size 107 bytes received.
[13:21:29]/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'.
[13:21:29][DBG] Received a notification for subscription id=1099365987 (source address='/var/run/sysrepod.sock').
[13:21:29][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock[DBG] HELLO notification received on subscription id=1099365987.
[13:21:29]'
[13:21:29][DBG] Sending 111 bytes of data.
[13:21:29][DBG] 111 bytes of data sent.
[13:21:29][DBG] fd 10 readable
[13:21:29][DBG] Circular buffer dequeue, new buffer head=4, count=0.
[13:21:29][DBG] 111 bytes of data received on fd 10
[13:21:29][DBG] fd 10 would block
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock[DBG] New message of size 107 bytes received.
[13:21:29]'.
[13:21:29][DBG] Received a notification for subscription id=1916530833 (source address='/var/run/sysrepod.sock').
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] HELLO notification received on subscription id=1916530833.
[13:21:29][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'
[13:21:29][DBG] Sending 111 bytes of data.
[13:21:29][DBG] 111 bytes of data sent.
[13:21:29][DBG] fd 10 readable
[13:21:29][DBG] 111 bytes of data received on fd 10
[13:21:29][DBG] fd 10 would block
[13:21:29][DBG] New message of size 107 bytes received.
[13:21:29][DBG] Received a notification for subscription id=1405688351 (source address='/var/run/sysrepod.sock').
[13:21:29][DBG] HELLO notification received on subscription id=1405688351.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-ssh-server.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-ssh-server.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-ssh-server.persist (fd = 26) has been unlocked
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Persist data file '/etc/sysrepo/data/ietf-tls-server.persist' does not exist.
[13:21:29][DBG] No persistent data for module 'ietf-tls-server' will be loaded.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/iana-crypt-hash.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/iana-crypt-hash.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/iana-crypt-hash.persist (fd = 26) has been unlocked
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Persist data file '/etc/sysrepo/data/ietf-x509-cert-to-name.persist' does not exist.
[13:21:29][DBG] No persistent data for module 'ietf-x509-cert-to-name' will be loaded.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-server.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-server.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-server.persist (fd = 26) has been unlocked
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-netconf-server/3804.aPLkCg.sock' @ 849701563.
[13:21:29][DBG] Circular buffer enqueue to position=4, current count=1.
[13:21:29][DBG] Returning info from 'ietf-netconf-server' persist file: module enabled, 0 subtrees enabled in running, 6 features enabled.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=5, count=0.
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-netconf-server/3804.aPLkCg.sock'.
[13:21:29][DBG] Reusing existing connection on fd=19 for the notification destination '/var/run/sysrepo-subscriptions/ietf-netconf-server/3804.aPLkCg.sock'
[13:21:29][DBG] Sending 117 bytes of data.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] 117 bytes of data sent.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-monitoring.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-monitoring.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-netconf-monitoring.persist (fd = 26) has been unlocked
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-netconf-monitoring/3804.JBdCOO.sock' @<PHONE_NUMBER>.
[13:21:29][DBG] Circular buffer enqueue to position=5, current count=1.
[13:21:29][DBG] Returning info from 'ietf-netconf-monitoring' persist file: module disabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-netconf-monitoring/3804.JBdCOO.sock'.
[13:21:29][DBG] Reusing existing connection on fd=16 for the notification destination '/var/run/sysrepo-subscriptions/ietf-netconf-monitoring/3804.JBdCOO.sock'
[13:21:29][DBG] Sending 121 bytes of data.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] 121 bytes of data sent.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Persist data file '/etc/sysrepo/data/ietf-netconf-with-defaults.persist' does not exist.
[13:21:29][DBG] No persistent data for module 'ietf-netconf-with-defaults' will be loaded.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] File /etc/sysrepo/data/ietf-system.persist has been locked
[13:21:29][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-system.persist'.
[13:21:29][DBG] File /etc/sysrepo/data/ietf-system.persist (fd = 26) has been unlocked
[13:21:29][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-system/3804.JHeFCM.sock' @<PHONE_NUMBER>.
[13:21:29][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:29][DBG] Returning info from 'ietf-system' persist file: module disabled, 1 subtrees enabled in running, 2 features enabled.
[13:21:29][DBG] New message enqueued into CM message queue.
[13:21:29][DBG] Circular buffer dequeue, new buffer head=7, count=0.
[13:21:29][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-system/3804.JHeFCM.sock'.
[13:21:29][DBG] Reusing existing connection on fd=20 for the notification destination '/var/run/sysrepo-subscriptions/ietf-system/3804.JHeFCM.sock'
[13:21:29][DBG] Sending 109 bytes of data.
[13:21:29][DBG] 109 bytes of data sent.
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:29][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] Persist data file '/etc/sysrepo/data/ietf-datastores.persist' does not exist.
[13:21:30][DBG] No persistent data for module 'ietf-datastores' will be loaded.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] File /etc/sysrepo/data/ietf-yang-library.persist has been locked
[13:21:30][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-yang-library.persist'.
[13:21:30][DBG] File /etc/sysrepo/data/ietf-yang-library.persist (fd = 26) has been unlocked
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock' @ 842059377.
[13:21:30][DBG] Circular buffer enqueue to position=7, current count=1.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock' @<PHONE_NUMBER>.
[13:21:30][DBG] Circular buffer enqueue to position=8, current count=2.
[13:21:30][DBG] Returning info from 'ietf-yang-library' persist file: module disabled, 2 subtrees enabled in running, 0 features enabled.
[13:21:30][DBG] New message enqueued into CM message queue.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=8, count=1.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock'.
[13:21:30][DBG] Reusing existing connection on fd=18 for the notification destination '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock'
[13:21:30][DBG] Sending 115 bytes of data.
[13:21:30][DBG] 115 bytes of data sent.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock'.
[13:21:30][DBG] Reusing existing connection on fd=18 for the notification destination '/var/run/sysrepo-subscriptions/ietf-yang-library/3804.kdJBNL.sock'
[13:21:30][DBG] Sending 115 bytes of data.
[13:21:30][DBG] 115 bytes of data sent.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-device.persist has been locked
[13:21:30][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/gwtt-device.persist'.
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-device.persist (fd = 26) has been unlocked
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock' @<PHONE_NUMBER>.
[13:21:30][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock' @ 284526218.
[13:21:30][DBG] Circular buffer enqueue to position=0, current count=2.
[13:21:30][DBG] Returning info from 'gwtt-device' persist file: module enabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:30][DBG] New message enqueued into CM message queue.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=0, count=1.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock'.
[13:21:30][DBG] Reusing existing connection on fd=9 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock'
[13:21:30][DBG] Sending 109 bytes of data.
[13:21:30][DBG] 109 bytes of data sent.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock'.
[13:21:30][DBG] Reusing existing connection on fd=9 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-device/3444.oelOBi.sock'
[13:21:30][DBG] fd 14 readable
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] 109 bytes of data received on fd 14
[13:21:30][DBG] Sending 109 bytes of data.
[13:21:30][DBG] fd 14 would block
[13:21:30][DBG] 109 bytes of data sent.
[13:21:30][DBG] New message of size 105 bytes received.
[13:21:30][DBG] Received a notification for subscription id=1375121731 (source address='/var/run/sysrepod.sock').
[13:21:30][DBG] HELLO notification received on subscription id=1375121731.
[13:21:30][DBG] fd 14 readable
[13:21:30][DBG] 109 bytes of data received on fd 14
[13:21:30][DBG] fd 14 would block
[13:21:30][DBG] New message of size 105 bytes received.
[13:21:30][DBG] Received a notification for subscription id=284526218 (source address='/var/run/sysrepod.sock').
[13:21:30][DBG] HELLO notification received on subscription id=284526218.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-ip-routing.persist has been locked
[13:21:30][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/gwtt-ip-routing.persist'.
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-ip-routing.persist (fd = 26) has been unlocked
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-ip-routing/3444.nlEhCC.sock' @<PHONE_NUMBER>.
[13:21:30][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:30][DBG] Returning info from 'gwtt-ip-routing' persist file: module disabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:30][DBG] New message enqueued into CM message queue.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-ip-routing/3444.nlEhCC.sock'.
[13:21:30][DBG] Reusing existing connection on fd=10 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-ip-routing/3444.nlEhCC.sock'
[13:21:30][DBG] Sending 113 bytes of data.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] fd 16 readable
[13:21:30][DBG] 113 bytes of data received on fd 16
[13:21:30][DBG] fd 16 would block
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Received a notification for subscription id=1961627364 (source address='/var/run/sysrepod.sock').
[13:21:30][DBG] HELLO notification received on subscription id=1961627364.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] File /etc/sysrepo/data/ietf-interfaces.persist has been locked
[13:21:30][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-interfaces.persist'.
[13:21:30][DBG] File /etc/sysrepo/data/ietf-interfaces.persist (fd = 26) has been unlocked
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 780705383.
[13:21:30][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @<PHONE_NUMBER>.
[13:21:30][DBG] Circular buffer enqueue to position=3, current count=2.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 689604318.
[13:21:30][DBG] New message enqueued into CM message queue.
[13:21:30][DBG] Circular buffer enqueue to position=4, current count=3.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=3, count=2.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 397765231.
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:30][DBG] Circular buffer enqueue to position=5, current count=3.
[13:21:30][DBG] Sending 113 bytes of data.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @<PHONE_NUMBER>.
[13:21:30][DBG] fd 31 readable
[13:21:30][DBG] Circular buffer dequeue, new buffer head=4, count=2.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] 113 bytes of data received on fd 31
[13:21:30]'.
[13:21:30][DBG] fd 31 would block
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:30][DBG] Received a notification for subscription id=780705383 (source address='/var/run/sysrepod.sock[DBG] Sending 113 bytes of data.
[13:21:30]').
[13:21:30][DBG] Circular buffer enqueue to position=6, current count=3.
[13:21:30][DBG] HELLO notification received on subscription id=780705383.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock' @ 442120262.
[13:21:30][DBG] fd 31 readable
[13:21:30][DBG] Circular buffer dequeue, new buffer head=5, count=2.
[13:21:30][DBG] 113 bytes of data received on fd 31
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:30][DBG] fd 31 would block
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '[DBG] New message of size 109 bytes received.
[13:21:30]/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:30][DBG] Received a notification for subscription id=1603295584 (source address='/var/run/sysrepod.sock[DBG] Circular buffer enqueue to position=7, current count=3.
[13:21:30]').
[13:21:30][DBG] Sending 113 bytes of data.
[13:21:30][DBG] HELLO notification received on subscription id=1603295584.
[13:21:30][DBG] Returning info from 'ietf-interfaces' persist file: module disabled, 6 subtrees enabled in running, 0 features enabled.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] fd 31 readable
[13:21:30][DBG] Circular buffer dequeue, new buffer head=6, count=2.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:30][DBG] 113 bytes of data received on fd 31
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] fd 31 would block
[13:21:30]'
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Sending 113 bytes of data.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] Received a notification for subscription id=689604318 (source address='/var/run/sysrepod.sock').
[13:21:30][DBG] Circular buffer dequeue, new buffer head=7, count=1.
[13:21:30][DBG] HELLO notification received on subscription id=689604318.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'.
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:30][DBG] fd 31 readable
[13:21:30][DBG] 113 bytes of data received on fd 31
[13:21:30][DBG] fd 31 would block
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Received a notification for subscription id=397765231 (source address='/var/run/sysrepod.sock[DBG] Sending 113 bytes of data.
[13:21:30]').
[13:21:30][DBG] HELLO notification received on subscription id=397765231.
[13:21:30][DBG] 113 bytes of data sent.
[13:21:30][DBG] Circular buffer dequeue, new buffer head=8, count=0.
[13:21:30][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock[DBG] fd 31 readable
[13:21:30]'.
[13:21:30][DBG] 113 bytes of data received on fd 31
[13:21:30][DBG] Reusing existing connection on fd=12 for the notification destination '/var/run/sysrepo-subscriptions/ietf-interfaces/3444.eGNDjP.sock'
[13:21:30][DBG] fd 31 would block
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Sending 113 bytes of data.
[13:21:30][DBG] Received a notification for subscription id=1953072821 (source address='/var/run/sysrepod.sock[DBG] 113 bytes of data sent.
[13:21:30]').
[13:21:30][DBG] HELLO notification received on subscription id=1953072821.
[13:21:30][DBG] New message enqueued into CM message queue.
[13:21:30][DBG] fd 31 readable
[13:21:30][DBG] 113 bytes of data received on fd 31
[13:21:30][DBG] fd 31 would block
[13:21:30][DBG] New message of size 109 bytes received.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] Received a notification for subscription id=442120262 (source address='/var/run/sysrepod.sock').
[13:21:30][DBG] HELLO notification received on subscription id=442120262.
[13:21:30][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-vxlan.persist has been locked
[13:21:30][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/gwtt-vxlan.persist'.
[13:21:30][DBG] File /etc/sysrepo/data/gwtt-vxlan.persist (fd = 26) has been unlocked
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-vxlan/3444.FJomLI.sock' @ 699663442.
[13:21:31][DBG] Circular buffer enqueue to position=8, current count=1.
[13:21:31][DBG] Returning info from 'gwtt-vxlan' persist file: module disabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] New message enqueued into CM message queue.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=9, count=0.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-vxlan/3444.FJomLI.sock'.
[13:21:31][DBG] Reusing existing connection on fd=11 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-vxlan/3444.FJomLI.sock'
[13:21:31][DBG] Sending 108 bytes of data.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] 108 bytes of data sent.
[13:21:31][DBG] fd 19 readable
[13:21:31][DBG] 108 bytes of data received on fd 19
[13:21:31][DBG] fd 19 would block
[13:21:31][DBG] New message of size 104 bytes received.
[13:21:31][DBG] Received a notification for subscription id=699663442 (source address='/var/run/sysrepod.sock').
[13:21:31][DBG] HELLO notification received on subscription id=699663442.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/gwtt-ietf-ipsec.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/gwtt-ietf-ipsec.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/gwtt-ietf-ipsec.persist (fd = 26) has been unlocked
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock' @ 169241943.
[13:21:31][DBG] Circular buffer enqueue to position=9, current count=1.
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock' @<PHONE_NUMBER>.
[13:21:31][DBG] Circular buffer enqueue to position=0, current count=2.
[13:21:31][DBG] Returning info from 'gwtt-ietf-ipsec' persist file: module disabled, 2 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] New message enqueued into CM message queue.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=0, count=1.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock'.
[13:21:31][DBG] Reusing existing connection on fd=8 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock'
[13:21:31][DBG] Sending 112 bytes of data.
[13:21:31][DBG] 112 bytes of data sent.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=1, count=0.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock'.
[13:21:31][DBG] Reusing existing connection on fd=8 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-ietf-ipsec/3444.dONbeE.sock'
[13:21:31][DBG] fd 12 readable
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] 112 bytes of data received on fd 12
[13:21:31][DBG] Sending 113 bytes of data.
[13:21:31][DBG] fd 12 would block
[13:21:31][DBG] 113 bytes of data sent.
[13:21:31][DBG] New message of size 108 bytes received.
[13:21:31][DBG] Received a notification for subscription id=169241943 (source address='/var/run/sysrepod.sock').
[13:21:31][DBG] HELLO notification received on subscription id=169241943.
[13:21:31][DBG] fd 12 readable
[13:21:31][DBG] 113 bytes of data received on fd 12
[13:21:31][DBG] fd 12 would block
[13:21:31][DBG] New message of size 109 bytes received.
[13:21:31][DBG] Received a notification for subscription id=1755443677 (source address='/var/run/sysrepod.sock').
[13:21:31][DBG] HELLO notification received on subscription id=1755443677.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/iana-if-type.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'iana-if-type' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/ietf-ip.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-ip.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/ietf-ip.persist (fd = 26) has been unlocked
[13:21:31][DBG] Returning info from 'ietf-ip' persist file: module disabled, 0 subtrees enabled in running, 1 features enabled.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/yang-ext.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'yang-ext' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/gwtt-interface.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'gwtt-interface' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/gwtt-vti.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'gwtt-vti' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/gwtt-bridge.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'gwtt-bridge' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Persist data file '/etc/sysrepo/data/gwtt-vlan.persist' does not exist.
[13:21:31][DBG] No persistent data for module 'gwtt-vlan' will be loaded.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/gwtt-dhcpd.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/gwtt-dhcpd.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/gwtt-dhcpd.persist (fd = 26) has been unlocked
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/gwtt-dhcpd/3444.IaHnnG.sock' @<PHONE_NUMBER>.
[13:21:31][DBG] Circular buffer enqueue to position=1, current count=1.
[13:21:31][DBG] Returning info from 'gwtt-dhcpd' persist file: module enabled, 0 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] New message enqueued into CM message queue.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=2, count=0.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/gwtt-dhcpd/3444.IaHnnG.sock'.
[13:21:31][DBG] Reusing existing connection on fd=13 for the notification destination '/var/run/sysrepo-subscriptions/gwtt-dhcpd/3444.IaHnnG.sock'
[13:21:31][DBG] Sending 108 bytes of data.
[13:21:31][DBG] 108 bytes of data sent.
[13:21:31][DBG] fd 35 readable
[13:21:31][DBG] 108 bytes of data received on fd 35
[13:21:31][DBG] fd 35 would block
[13:21:31][DBG] New message of size 104 bytes received.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Received a notification for subscription id=1481711558 (source address='/var/run/sysrepod.sock').
[13:21:31][DBG] HELLO notification received on subscription id=1481711558.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/notifications.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/notifications.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/notifications.persist (fd = 26) has been unlocked
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/nc-notifications.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/nc-notifications.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/nc-notifications.persist (fd = 26) has been unlocked
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock' @ 742020692.
[13:21:31][DBG] Circular buffer enqueue to position=2, current count=1.
[13:21:31][DBG] Returning info from 'nc-notifications' persist file: module disabled, 1 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] New message enqueued into CM message queue.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=3, count=0.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock'.
[13:21:31][DBG] Reusing existing connection on fd=17 for the notification destination '/var/run/sysrepo-subscriptions/nc-notifications/3804.IeeNBM.sock'
[13:21:31][DBG] Sending 114 bytes of data.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] 114 bytes of data sent.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf-acm.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-acm.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf-acm.persist (fd = 26) has been unlocked
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf-notifications.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-notifications.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf-notifications.persist (fd = 26) has been unlocked
[13:21:31][DBG] Returning info from 'ietf-netconf-notifications' persist file: module disabled, 0 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/ietf-netconf.persist (fd = 26) has been unlocked
[13:21:31][DBG] Returning info from 'ietf-netconf' persist file: module disabled, 0 subtrees enabled in running, 7 features enabled.
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:31][DBG] File /etc/sysrepo/data/ietf-keystore.persist has been locked
[13:21:31][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-keystore.persist'.
[13:21:31][DBG] File /etc/sysrepo/data/ietf-keystore.persist (fd = 26) has been unlocked
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:31][DBG] Circular buffer enqueue to position=3, current count=1.
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:31][DBG] Circular buffer enqueue to position=4, current count=2.
[13:21:31][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock' @<PHONE_NUMBER>.
[13:21:31][DBG] New message enqueued into CM message queue.
[13:21:31][DBG] Circular buffer enqueue to position=5, current count=3.
[13:21:31][DBG] Returning info from 'ietf-keystore' persist file: module disabled, 3 subtrees enabled in running, 0 features enabled.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=4, count=2.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'.
[13:21:31][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'
[13:21:31][DBG] Sending 111 bytes of data.
[13:21:31][DBG] 111 bytes of data sent.
[13:21:31][DBG] Circular buffer dequeue, new buffer head=5, count=1.
[13:21:31][DBG] Sending a notification to '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'.
[13:21:31][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'
[13:21:31][DBG] fd 10 readable
[13:21:32][DBG] Sending 111 bytes of data.
[13:21:32][DBG] 111 bytes of data sent.
[13:21:32][DBG] 111 bytes of data received on fd 10
[13:21:32][DBG] Circular buffer dequeue, new buffer head=6, count=0.
[13:21:32][DBG] 111 bytes of data received on fd 10
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] fd 10 would block
[13:21:32][DBG] Sending a notification to '[DBG] New message of size 107 bytes received.
[13:21:32]/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'.
[13:21:32][DBG] Reusing existing connection on fd=7 for the notification destination '/var/run/sysrepo-subscriptions/ietf-keystore/3444.lgbDNm.sock'
[13:21:32][DBG] Received a notification for subscription id=1099365987 (source address='/var/run/sysrepod.sock').
[13:21:32][DBG] Sending 111 bytes of data.
[13:21:32][DBG] HELLO notification received on subscription id=1099365987.
[13:21:32][DBG] 111 bytes of data sent.
[13:21:32][DBG] New message of size 107 bytes received.
[13:21:32][DBG] Received a notification for subscription id=1916530833 (source address='/var/run/sysrepod.sock').
[13:21:32][DBG] New message enqueued into CM message queue.
[13:21:32][DBG] HELLO notification received on subscription id=1916530833.
[13:21:32][DBG] fd 10 readable
[13:21:32][DBG] 111 bytes of data received on fd 10
[13:21:32][DBG] fd 10 would block
[13:21:32][DBG] New message of size 107 bytes received.
[13:21:32][DBG] Received a notification for subscription id=1405688351 (source address='/var/run/sysrepod.sock').
[13:21:32][DBG] HELLO notification received on subscription id=1405688351.
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] File /etc/sysrepo/data/ietf-ssh-server.persist has been locked
[13:21:32][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-ssh-server.persist'.
[13:21:32][DBG] File /etc/sysrepo/data/ietf-ssh-server.persist (fd = 26) has been unlocked
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Persist data file '/etc/sysrepo/data/ietf-tls-server.persist' does not exist.
[13:21:32][DBG] No persistent data for module 'ietf-tls-server' will be loaded.
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] File /etc/sysrepo/data/iana-crypt-hash.persist has been locked
[13:21:32][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/iana-crypt-hash.persist'.
[13:21:32][DBG] File /etc/sysrepo/data/iana-crypt-hash.persist (fd = 26) has been unlocked
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Persist data file '/etc/sysrepo/data/ietf-x509-cert-to-name.persist' does not exist.
[13:21:32][DBG] No persistent data for module 'ietf-x509-cert-to-name' will be loaded.
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] Switching identity to UID='0' and GID='0' (username: root).
[13:21:32][DBG] File /etc/sysrepo/data/ietf-netconf-server.persist has been locked
[13:21:32][DBG] Persist data successfully loaded from file '/etc/sysrepo/data/ietf-netconf-server.persist'.
[13:21:32][DBG] File /etc/sysrepo/data/ietf-netconf-server.persist (fd = 26) has been unlocked
[13:21:32][DBG] Sending HELLO notification to '/var/run/sysrepo-subscriptions/ietf-netconf-server/3804.aPLkCg.sock' @ 849701563.
[13:21:32][DBG] Circular buffer enqueue to position=6, current count=1.
[13:21:32][DBG] Returning info from 'ietf-netconf-server' persist file: module enabled, 0 subtrees enabled in running, 6 features enabled.