search

napalm-automation / napalm-ios

Apache License 2.0
31 stars 40 forks source link

load_merge_candidate & commit_config slow (takes 40 seconds) #177

Closed dirkvdplas closed 6 years ago

dirkvdplas commented 7 years ago

Description of Issue/Question

I do a small change of prefix-list (just a single entry) on a Cisco 7600 device. I do this in a 2 step proces. First load_merge_candidate, and after that commit_config. The complete operation takes 40 seconds. See output of AAA command authorization debug to get an idea of the time the different steps take. Is that normal? The C7600 is not busy at all, I have the feeling that some points in the code "a sleep or wait" timer has been built in.

Did you follow the steps from https://github.com/napalm-automation/napalm#faq

Setup

napalm-ios version

(Paste verbatim output from pip freeze | grep napalm-ios between quotes below)

napalm-ios==0.7.0

IOS version

(Paste verbatim output from show version between quotes below)

Cisco IOS Software, c7600rsp72043_rp Software (c7600rsp72043_rp-ADVIPSERVICESK9-M), Version 12.2(33)SRE9, RELEASE SOFTWARE (fc2)
Technical Support: http://www.cisco.com/techsupport
Copyright (c) 1986-2013 by Cisco Systems, Inc.
Compiled Fri 06-Sep-13 02:32 by prod_rel_team

Steps to Reproduce the Issue

script: from napalm_base import get_network_driver driver = get_network_driver('ios') device = driver('rC7600-test', 'user1', 'XXXX') device.open() device.load_merge_candidate(filename='newconfig.cfg') device.commit_config()

running the script: (automation) [plas109@slacri056:5 ~]_OT $ time ./napalm_simplified.py

real 0m40.014s <----- user 0m1.124s sys 0m0.181s

debug aaa authorization which provides over in chronological order:

C7600-test# Jul 14 14:31:02.626 CEST: AAA/BIND(0000003B): Bind i/f Jul 14 14:31:02.678 CEST: AAA/AUTHOR (0x3B): Pick method list 'default' Jul 14 14:31:02.682 CEST: AAA/AUTHOR/EXEC(0000003B): processing AV cmd= Jul 14 14:31:02.682 CEST: AAA/AUTHOR/EXEC(0000003B): processing AV priv-lvl=15 Jul 14 14:31:02.682 CEST: AAA/AUTHOR/EXEC(0000003B): Authorization successful C7600-test# Jul 14 14:31:06.190 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 1 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:06.190 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:06.190 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:06.190 CEST: AAA/MEMORY: create_user (0x132C68A0) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=1 initial_task_id='0', vrf= (id=0) Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): Port='tty1' list='' service=CMD Jul 14 14:31:06.190 CEST: AAA/AUTHOR/CMD: tty1 (2257129272) user='user1' Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): send AV service=shell Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): send AV cmd=terminal Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): send AV cmd-arg=length Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): send AV cmd-arg=0 Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): send AV cmd-arg= Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD(2257129272): found list "default" Jul 14 14:31:06.190 CEST: tty1 AAA/AUTHOR/CMD (2257129272): Method=ACS (tacacs+) C7600-test# Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): user=user1 Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): send AV service=shell Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): send AV cmd=terminal Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): send AV cmd-arg=length Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): send AV cmd-arg=0 Jul 14 14:31:06.190 CEST: AAA/AUTHOR/TAC+: (2257129272): send AV cmd-arg= Jul 14 14:31:06.390 CEST: AAA/AUTHOR (2257129272): Post authorization status = PASS_ADD Jul 14 14:31:06.390 CEST: AAA/MEMORY: free_user (0x132C68A0) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=1 vrf= (id=0) Jul 14 14:31:06.398 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 1 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:06.398 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:06.398 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:06.398 CEST: AAA/MEMORY: create_user (0x1E01F45C) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=1 initial_task_id='0', vrf= (id=0) Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): Port='tty1' list='' service=CMD Jul 14 14:31:06.398 CEST: AAA/AUTHOR/CMD: tty1 (224633223) user='user1' Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): send AV service=shell Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): send AV cmd=terminal Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): send AV cmd-arg=width Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): send AV cmd-arg=511 Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): send AV cmd-arg= Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD(224633223): found list "default" Jul 14 14:31:06.398 CEST: tty1 AAA/AUTHOR/CMD (224633223): Method=ACS (tacacs+) Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): user=user1 Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): send AV service=shell Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): send AV cmd=terminal Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): send AV cmd-arg=width C7600-test# Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): send AV cmd-arg=511 Jul 14 14:31:06.398 CEST: AAA/AUTHOR/TAC+: (224633223): send AV cmd-arg= Jul 14 14:31:06.598 CEST: AAA/AUTHOR (224633223): Post authorization status = PASS_ADD Jul 14 14:31:06.598 CEST: AAA/MEMORY: free_user (0x1E01F45C) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=1 vrf= (id=0) Jul 14 14:31:07.306 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:07.306 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:07.306 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:07.306 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD (3388346871): Port='tty1' list='' service=CMD Jul 14 14:31:07.306 CEST: AAA/AUTHOR/CMD: tty1 (3388346871) user='user1' Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD (3388346871): send AV service=shell Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD (3388346871): send AV cmd=dir Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD (3388346871): send AV cmd-arg= Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD(3388346871): found list "default" Jul 14 14:31:07.306 CEST: tty1 AAA/AUTHOR/CMD (3388346871): Method=ACS (tacacs+) Jul 14 14:31:07.306 CEST: AAA/AUTHOR/TAC+: (3388346871): user=user1 Jul 14 14:31:07.306 CEST: AAA/AUTHOR/TAC+: (3388346871): send AV service=shell Jul 14 14:31:07.306 CEST: AAA/AUTHOR/TAC+: (3388346871): send AV cmd=dir Jul 14 14:31:07.306 CEST: AAA/AUTHOR/TAC+: (3388346871): send AV cmd-arg= Jul 14 14:31:07.506 CEST: AAA/AUTHOR (3388346871): Post authorization status = PASS_ADD Jul 14 14:31:07.506 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:08.114 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:08.114 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:08.114 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:08.114 CEST: AAA/MEMORY: create_user (0x1E01F45C) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): Port='tty1' list='' service=CMD Jul 14 14:31:08.114 CEST: AAA/AUTHOR/CMD: tty1 (2447911875) user='user1' Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): send AV service=shell Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): send AV cmd=dir Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): send AV cmd-arg=disk0: C7600-test# Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): send AV cmd-arg= Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD(2447911875): found list "default" Jul 14 14:31:08.114 CEST: tty1 AAA/AUTHOR/CMD (2447911875): Method=ACS (tacacs+) Jul 14 14:31:08.114 CEST: AAA/AUTHOR/TAC+: (2447911875): user=user1 Jul 14 14:31:08.114 CEST: AAA/AUTHOR/TAC+: (2447911875): send AV service=shell Jul 14 14:31:08.114 CEST: AAA/AUTHOR/TAC+: (2447911875): send AV cmd=dir Jul 14 14:31:08.114 CEST: AAA/AUTHOR/TAC+: (2447911875): send AV cmd-arg=disk0: Jul 14 14:31:08.114 CEST: AAA/AUTHOR/TAC+: (2447911875): send AV cmd-arg= C7600-test# Jul 14 14:31:08.314 CEST: AAA/AUTHOR (2447911875): Post authorization status = PASS_ADD Jul 14 14:31:08.314 CEST: AAA/MEMORY: free_user (0x1E01F45C) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:09.098 CEST: AAA/BIND(0000003C): Bind i/f Jul 14 14:31:09.558 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:09.558 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:09.558 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:09.558 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): Port='tty1' list='' service=CMD Jul 14 14:31:09.558 CEST: AAA/AUTHOR/CMD: tty1 (3232288536) user='user1' Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): send AV service=shell Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): send AV cmd=dir Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): send AV cmd-arg= Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD(3232288536): found list "default" Jul 14 14:31:09.558 CEST: tty1 AAA/AUTHOR/CMD (3232288536): Method=ACS (tacacs+) Jul 14 14:31:09.558 CEST: AAA/AUTHOR/TAC+: (3232288536): user=user1 C7600-test# Jul 14 14:31:09.558 CEST: AAA/AUTHOR/TAC+: (3232288536): send AV service=shell Jul 14 14:31:09.558 CEST: AAA/AUTHOR/TAC+: (3232288536): send AV cmd=dir Jul 14 14:31:09.558 CEST: AAA/AUTHOR/TAC+: (3232288536): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:09.558 CEST: AAA/AUTHOR/TAC+: (3232288536): send AV cmd-arg= Jul 14 14:31:09.762 CEST: AAA/AUTHOR (3232288536): Post authorization status = PASS_ADD Jul 14 14:31:09.762 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:11.166 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:11.166 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:11.166 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:11.166 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): Port='tty1' list='' service=CMD Jul 14 14:31:11.166 CEST: AAA/AUTHOR/CMD: tty1 (2427873186) user='user1' Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): send AV service=shell Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): send AV cmd=verify Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): send AV cmd-arg=/md5 Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): send AV cmd-arg=disk0:merge_config.txt Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): send AV cmd-arg= Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD(2427873186): found list "default" Jul 14 14:31:11.166 CEST: tty1 AAA/AUTHOR/CMD (2427873186): Method=ACS (tacacs+) C7600-test# Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): user=user1 Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): send AV service=shell Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): send AV cmd=verify Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): send AV cmd-arg=/md5 Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): send AV cmd-arg=disk0:merge_config.txt Jul 14 14:31:11.166 CEST: AAA/AUTHOR/TAC+: (2427873186): send AV cmd-arg= Jul 14 14:31:11.366 CEST: AAA/AUTHOR (2427873186): Post authorization status = PASS_ADD Jul 14 14:31:11.366 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:12.166 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:12.166 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:12.166 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:12.166 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): Port='tty1' list='' service=CMD Jul 14 14:31:12.166 CEST: AAA/AUTHOR/CMD: tty1 (842556852) user='user1' Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): send AV service=shell Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): send AV cmd=dir Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): send AV cmd-arg=disk0: C7600-test# Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): send AV cmd-arg= Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD(842556852): found list "default" Jul 14 14:31:12.166 CEST: tty1 AAA/AUTHOR/CMD (842556852): Method=ACS (tacacs+) Jul 14 14:31:12.166 CEST: AAA/AUTHOR/TAC+: (842556852): user=user1 Jul 14 14:31:12.166 CEST: AAA/AUTHOR/TAC+: (842556852): send AV service=shell Jul 14 14:31:12.166 CEST: AAA/AUTHOR/TAC+: (842556852): send AV cmd=dir Jul 14 14:31:12.166 CEST: AAA/AUTHOR/TAC+: (842556852): send AV cmd-arg=disk0: Jul 14 14:31:12.166 CEST: AAA/AUTHOR/TAC+: (842556852): send AV cmd-arg= Jul 14 14:31:12.366 CEST: AAA/AUTHOR (842556852): Post authorization status = PASS_ADD Jul 14 14:31:12.366 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:12.570 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:12.570 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:12.570 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:12.570 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): Port='tty1' list='' service=CMD Jul 14 14:31:12.570 CEST: AAA/AUTHOR/CMD: tty1 (2762857844) user='user1' Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): send AV service=shell Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): send AV cmd=configure Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): send AV cmd-arg=terminal Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): send AV cmd-arg= Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD(2762857844): found list "default" Jul 14 14:31:12.570 CEST: tty1 AAA/AUTHOR/CMD (2762857844): Method=ACS (tacacs+) Jul 14 14:31:12.570 CEST: AAA/AUTHOR/TAC+: (2762857844): user=user1 Jul 14 14:31:12.570 CEST: AAA/AUTHOR/TAC+: (2762857844): send AV service=shell Jul 14 14:31:12.570 CEST: AAA/AUTHOR/TAC+: (2762857844): send AV cmd=configure Jul 14 14:31:12.570 CEST: AAA/AUTHOR/TAC+: (2762857844): send AV cmd-arg=terminal Jul 14 14:31:12.570 CEST: AAA/AUTHOR/TAC+: (2762857844): send AV cmd-arg= C7600-test# Jul 14 14:31:12.770 CEST: AAA/AUTHOR (2762857844): Post authorization status = PASS_ADD Jul 14 14:31:12.770 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:12.878 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:12.878 CEST: AAA/AUTHOR: config command authorization not enabled C7600-test# Jul 14 14:31:15.686 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 0 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:15.686 CEST: %SYS-5-CONFIG_I: Configured from console by user1 on vty0 (10.108.14.103) C7600-test# Jul 14 14:31:15.894 CEST: AAA/AUTHOR (0x3C): Pick method list 'default' Jul 14 14:31:15.898 CEST: AAA/AUTHOR/EXEC(0000003C): processing AV cmd= Jul 14 14:31:15.898 CEST: AAA/AUTHOR/EXEC(0000003C): processing AV priv-lvl=15 Jul 14 14:31:15.898 CEST: AAA/AUTHOR/EXEC(0000003C): Authorization successful Jul 14 14:31:15.898 CEST: AAA/AUTHOR (0000003C): Method list id=0 not configured. Skip author Jul 14 14:31:17.238 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:17.238 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:17.238 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:17.238 CEST: AAA/MEMORY: create_user (0x1E01EA54) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): Port='tty1' list='' service=CMD Jul 14 14:31:17.238 CEST: AAA/AUTHOR/CMD: tty1 (1811995599) user='user1' Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): send AV service=shell Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): send AV cmd=verify Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): send AV cmd-arg=/md5 Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): send AV cmd-arg=disk0:merge_config.txt Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): send AV cmd-arg= Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD(1811995599): found list "default" Jul 14 14:31:17.238 CEST: tty1 AAA/AUTHOR/CMD (1811995599): Method=ACS (tacacs+) C7600-test# Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): user=user1 Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): send AV service=shell Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): send AV cmd=verify Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): send AV cmd-arg=/md5 Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): send AV cmd-arg=disk0:merge_config.txt Jul 14 14:31:17.238 CEST: AAA/AUTHOR/TAC+: (1811995599): send AV cmd-arg= Jul 14 14:31:17.442 CEST: AAA/AUTHOR (1811995599): Post authorization status = PASS_ADD Jul 14 14:31:17.442 CEST: AAA/MEMORY: free_user (0x1E01EA54) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:17.942 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:17.942 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:17.942 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:17.942 CEST: AAA/MEMORY: create_user (0x132C28F4) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): Port='tty1' list='' service=CMD Jul 14 14:31:17.942 CEST: AAA/AUTHOR/CMD: tty1 (4094945311) user='user1' Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): send AV service=shell Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): send AV cmd=configure Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): send AV cmd-arg=terminal Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): send AV cmd-arg= Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD(4094945311): found list "default" Jul 14 14:31:17.942 CEST: tty1 AAA/AUTHOR/CMD (4094945311): Method=ACS (tacacs+) Jul 14 14:31:17.942 CEST: AAA/AUTHOR/TAC+: (4094945311): user=user1 Jul 14 14:31:17.942 CEST: AAA/AUTHOR/TAC+: (4094945311): send AV service=shell Jul 14 14:31:17.942 CEST: AAA/AUTHOR/TAC+: (4094945311): send AV cmd=configure Jul 14 14:31:17.942 CEST: AAA/AUTHOR/TAC+: (4094945311): send AV cmd-arg=terminal Jul 14 14:31:17.942 CEST: AAA/AUTHOR/TAC+: (4094945311): send AV cmd-arg= C7600-test# Jul 14 14:31:18.142 CEST: AAA/AUTHOR (4094945311): Post authorization status = PASS_ADD Jul 14 14:31:18.142 CEST: AAA/MEMORY: free_user (0x132C28F4) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:18.250 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:18.250 CEST: AAA/AUTHOR: config command authorization not enabled C7600-test# Jul 14 14:31:20.954 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 0 list= '' AUTHOR-TYPE= 'command' C7600-test# Jul 14 14:31:20.954 CEST: %SYS-5-CONFIG_I: Configured from console by user1 on vty0 (10.108.14.103) Jul 14 14:31:21.466 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:21.466 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:21.466 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:21.466 CEST: AAA/MEMORY: create_user (0x1333CFFC) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): Port='tty1' list='' service=CMD Jul 14 14:31:21.466 CEST: AAA/AUTHOR/CMD: tty1 (3264187787) user='user1' Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): send AV service=shell Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): send AV cmd=copy Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): send AV cmd-arg=running-config Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): send AV cmd-arg=disk0:/rollback_config.txt Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): send AV cmd-arg= Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD(3264187787): found list "default" Jul 14 14:31:21.466 CEST: tty1 AAA/AUTHOR/CMD (3264187787): Method=ACS (tacacs+) Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): user=user1 Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): send AV service=shell Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): send AV cmd=copy Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): send AV cmd-arg=running-config Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): send AV cmd-arg=disk0:/rollback_config.txt Jul 14 14:31:21.466 CEST: AAA/AUTHOR/TAC+: (3264187787): send AV cmd-arg= Jul 14 14:31:21.670 CEST: AAA/AUTHOR (3264187787): Post authorization status = PASS_ADD C7600-test# Jul 14 14:31:21.670 CEST: AAA/MEMORY: free_user (0x1333CFFC) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) C7600-test# Jul 14 14:31:24.574 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:24.574 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:24.574 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:24.574 CEST: AAA/MEMORY: create_user (0x132C28F4) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): Port='tty1' list='' service=CMD Jul 14 14:31:24.574 CEST: AAA/AUTHOR/CMD: tty1 (455790946) user='user1' Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): send AV service=shell Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): send AV cmd=configure Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): send AV cmd-arg=terminal Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): send AV cmd-arg= Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD(455790946): found list "default" Jul 14 14:31:24.574 CEST: tty1 AAA/AUTHOR/CMD (455790946): Method=ACS (tacacs+) Jul 14 14:31:24.574 CEST: AAA/AUTHOR/TAC+: (455790946): user=user1 Jul 14 14:31:24.574 CEST: AAA/AUTHOR/TAC+: (455790946): send AV service=shell Jul 14 14:31:24.574 CEST: AAA/AUTHOR/TAC+: (455790946): send AV cmd=configure Jul 14 14:31:24.574 CEST: AAA/AUTHOR/TAC+: (455790946): send AV cmd-arg=terminal Jul 14 14:31:24.574 CEST: AAA/AUTHOR/TAC+: (455790946): send AV cmd-arg= Jul 14 14:31:24.778 CEST: AAA/AUTHOR (455790946): Post authorization status = PASS_ADD Jul 14 14:31:24.778 CEST: AAA/MEMORY: free_user (0x132C28F4) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) C7600-test# Jul 14 14:31:24.782 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:24.782 CEST: AAA/AUTHOR: config command authorization not enabled C7600-test# Jul 14 14:31:27.590 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 0 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:27.590 CEST: %SYS-5-CONFIG_I: Configured from console by user1 on vty0 (10.108.14.103) Jul 14 14:31:28.102 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:28.102 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:28.102 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:28.102 CEST: AAA/MEMORY: create_user (0x1333CFFC) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): Port='tty1' list='' service=CMD Jul 14 14:31:28.102 CEST: AAA/AUTHOR/CMD: tty1 (987568852) user='user1' Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): send AV service=shell Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): send AV cmd=dir Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): send AV cmd-arg= Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD(987568852): found list "default" Jul 14 14:31:28.102 CEST: tty1 AAA/AUTHOR/CMD (987568852): Method=ACS (tacacs+) Jul 14 14:31:28.102 CEST: AAA/AUTHOR/TAC+: (987568852): user=user1 C7600-test# Jul 14 14:31:28.102 CEST: AAA/AUTHOR/TAC+: (987568852): send AV service=shell Jul 14 14:31:28.102 CEST: AAA/AUTHOR/TAC+: (987568852): send AV cmd=dir Jul 14 14:31:28.102 CEST: AAA/AUTHOR/TAC+: (987568852): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:28.102 CEST: AAA/AUTHOR/TAC+: (987568852): send AV cmd-arg= Jul 14 14:31:28.302 CEST: AAA/AUTHOR (987568852): Post authorization status = PASS_ADD Jul 14 14:31:28.302 CEST: AAA/MEMORY: free_user (0x1333CFFC) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:28.606 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:28.606 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:28.606 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:28.606 CEST: AAA/MEMORY: create_user (0x1E01F45C) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): Port='tty1' list='' service=CMD Jul 14 14:31:28.606 CEST: AAA/AUTHOR/CMD: tty1 (1597548938) user='user1' Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): send AV service=shell Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): send AV cmd=configure Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): send AV cmd-arg=terminal Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): send AV cmd-arg= Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD(1597548938): found list "default" Jul 14 14:31:28.606 CEST: tty1 AAA/AUTHOR/CMD (1597548938): Method=ACS (tacacs+) Jul 14 14:31:28.606 CEST: AAA/AUTHOR/TAC+: (1597548938): user=user1 Jul 14 14:31:28.606 CEST: AAA/AUTHOR/TAC+: (1597548938): send AV service=shell Jul 14 14:31:28.606 CEST: AAA/AUTHOR/TAC+: (1597548938): send AV cmd=configure Jul 14 14:31:28.606 CEST: AAA/AUTHOR/TAC+: (1597548938): send AV cmd-arg=terminal Jul 14 14:31:28.606 CEST: AAA/AUTHOR/TAC+: (1597548938): send AV cmd-arg= Jul 14 14:31:28.806 CEST: AAA/AUTHOR (1597548938): Post authorization status = PASS_ADD Jul 14 14:31:28.806 CEST: AAA/MEMORY: free_user (0x1E01F45C) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) C7600-test# Jul 14 14:31:28.814 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:28.814 CEST: AAA/AUTHOR: config command authorization not enabled C7600-test# Jul 14 14:31:31.618 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 0 list= '' AUTHOR-TYPE= 'command' C7600-test# Jul 14 14:31:31.618 CEST: %SYS-5-CONFIG_I: Configured from console by user1 on vty0 (10.108.14.103) Jul 14 14:31:32.434 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:32.434 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:32.434 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:32.434 CEST: AAA/MEMORY: create_user (0x132C68A0) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): Port='tty1' list='' service=CMD Jul 14 14:31:32.434 CEST: AAA/AUTHOR/CMD: tty1 (2318783220) user='user1' Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): send AV service=shell Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): send AV cmd=copy Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): send AV cmd-arg=running-config Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): send AV cmd-arg= Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD(2318783220): found list "default" Jul 14 14:31:32.434 CEST: tty1 AAA/AUTHOR/CMD (2318783220): Method=ACS (tacacs+) Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): user=user1 Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): send AV service=shell Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): send AV cmd=copy Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): send AV cmd-arg=disk0:/merge_config.txt Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): send AV cmd-arg=running-config Jul 14 14:31:32.434 CEST: AAA/AUTHOR/TAC+: (2318783220): send AV cmd-arg= Jul 14 14:31:32.634 CEST: AAA/AUTHOR (2318783220): Post authorization status = PASS_ADD Jul 14 14:31:32.634 CEST: AAA/MEMORY: free_user (0x132C68A0) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:33.134 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:33.134 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:33.134 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:33.134 CEST: AAA/MEMORY: create_user (0x1DFBF368) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): Port='tty1' list='' service=CMD Jul 14 14:31:33.134 CEST: AAA/AUTHOR/CMD: tty1 (1208377141) user='user1' Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): send AV service=shell Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): send AV cmd=configure Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): send AV cmd-arg=terminal C7600-test# Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): send AV cmd-arg= Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD(1208377141): found list "default" Jul 14 14:31:33.134 CEST: tty1 AAA/AUTHOR/CMD (1208377141): Method=ACS (tacacs+) Jul 14 14:31:33.134 CEST: AAA/AUTHOR/TAC+: (1208377141): user=user1 Jul 14 14:31:33.134 CEST: AAA/AUTHOR/TAC+: (1208377141): send AV service=shell Jul 14 14:31:33.134 CEST: AAA/AUTHOR/TAC+: (1208377141): send AV cmd=configure Jul 14 14:31:33.134 CEST: AAA/AUTHOR/TAC+: (1208377141): send AV cmd-arg=terminal Jul 14 14:31:33.134 CEST: AAA/AUTHOR/TAC+: (1208377141): send AV cmd-arg= C7600-test# Jul 14 14:31:33.338 CEST: AAA/AUTHOR (1208377141): Post authorization status = PASS_ADD Jul 14 14:31:33.338 CEST: AAA/MEMORY: free_user (0x1DFBF368) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) Jul 14 14:31:33.342 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:33.346 CEST: AAA/AUTHOR: config command authorization not enabled C7600-test# Jul 14 14:31:36.050 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 0 list= '' AUTHOR-TYPE= 'command' C7600-test# Jul 14 14:31:36.050 CEST: %SYS-5-CONFIG_I: Configured from console by user1 on vty0 (10.108.14.103) Jul 14 14:31:36.658 CEST: AAA/AUTHOR: auth_need : user= 'user1' ruser= 'C7600-test'rem_addr= '10.108.14.103' priv= 15 list= '' AUTHOR-TYPE= 'command' Jul 14 14:31:36.658 CEST: AAA: parse name=tty1 idb type=-1 tty=-1 Jul 14 14:31:36.658 CEST: AAA: name=tty1 flags=0x11 type=5 shelf=0 slot=0 adapter=0 port=1 channel=0 Jul 14 14:31:36.658 CEST: AAA/MEMORY: create_user (0x132C28F4) user='user1' ruser='C7600-test' ds0=0 port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 initial_task_id='0', vrf= (id=0) Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): Port='tty1' list='' service=CMD Jul 14 14:31:36.658 CEST: AAA/AUTHOR/CMD: tty1 (3256843710) user='user1' Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): send AV service=shell Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): send AV cmd=write Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): send AV cmd-arg=memory Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): send AV cmd-arg= Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD(3256843710): found list "default" Jul 14 14:31:36.658 CEST: tty1 AAA/AUTHOR/CMD (3256843710): Method=ACS (tacacs+) Jul 14 14:31:36.658 CEST: AAA/AUTHOR/TAC+: (3256843710): user=user1 C7600-test# Jul 14 14:31:36.658 CEST: AAA/AUTHOR/TAC+: (3256843710): send AV service=shell Jul 14 14:31:36.658 CEST: AAA/AUTHOR/TAC+: (3256843710): send AV cmd=write Jul 14 14:31:36.658 CEST: AAA/AUTHOR/TAC+: (3256843710): send AV cmd-arg=memory Jul 14 14:31:36.658 CEST: AAA/AUTHOR/TAC+: (3256843710): send AV cmd-arg= Jul 14 14:31:36.858 CEST: AAA/AUTHOR (3256843710): Post authorization status = PASS_ADD Jul 14 14:31:36.858 CEST: AAA/MEMORY: free_user (0x132C28F4) user='user1' ruser='C7600-test' port='tty1' rem_addr='10.108.14.103' authen_type=ASCII service=NONE priv=15 vrf= (id=0) C7600-test# Jul 14 14:31:41.158 CEST: %SYS-6-EXIT_CONFIG: User user1 has exited tty session 1(10.108.14.103) C7600-test#

Error Traceback

(Paste the complete traceback of the exception between quotes below)

ktbyers commented 7 years ago

@dirkvdplas Yes, I suspect it is so slow because of your AAA authorization.

It would be interesting to see how long it took if you are not doing this. It would still be slow, but just not this slow (at least that is my guess).

dirkvdplas commented 7 years ago

@ktbyers I disabled TACACS and do everything local, still slow (37 seconds). My AAA config looks like: aaa new-model aaa authentication fail-message ^CCCC aaa authentication login default local aaa authentication enable default none aaa authorization exec default local if-authenticated aaa session-id common

What are the commands executed 'under the hood' for load_merge_candidate & commit_config?

ktbyers commented 7 years ago

@dirkvdplas Okay interesting.

You can see all the CLI commands that get executed here (this will create a log file named 'test.log')

import logging
logging.basicConfig(filename='test.log', level=logging.DEBUG)
logger = logging.getLogger("netmiko")
mirceaulinic commented 6 years ago

Hi @dirkvdplas - we are currently in the process of reunification, please check https://napalm-automation.net/reunification/. For the time being, we have moved this issue to https://github.com/napalm-automation/napalm/issues/466 so we can discuss further. Going forward, we'd like to ask you to submit Pull Requests and Issues to the main repository: https://github.com/napalm-automation/napalm

Thanks for understanding!