12:07:08,088 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/running-config/startup-config/CR 12:07:10,853 [DEBUG] *************attempting to session to cue************ 12:07:10,853 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:07:13,479 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:07:14,885 [DEBUG] Current CUE transport timeout =90000 12:07:14,885 [DEBUG] Current CUE deliver timeout =600 12:07:16,291 [DEBUG] Session clear command sent 12:07:16,979 [DEBUG] Session command = service-module Integrated-Service-Engine 0/0 session 12:07:20,620 [DEBUG] Logged into CUE. 12:07:22,416 [DEBUG] Learned CUE Prompt = se-192-168-90-2# 12:07:23,682 [DEBUG] SetPrivExecMode() done - CUE Prompt = se-192-168-90-2# 12:07:25,635 [DEBUG] Set CUE transport timeout =90000 12:07:25,635 [DEBUG] Set CUE deliver timeout =90000 12:07:25,635 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:07:25,635 [DEBUG] *************attempting to session successful************ 12:07:25,635 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:07:26,041 [DEBUG] Executing the CUE CLI: write memory 12:07:29,291 [DEBUG] Executing the CUE CLI: 12:07:30,572 [DEBUG] Executing the CUE CLI: 12:07:33,198 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable got request 12:07:33,198 [INFO ] TftpAddressMgrImplementation:TestCoordinator(): ip = 10.1.1.100 12:07:33,275 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): receivedRequest id = test57753115635923244211489453653198-6931724347770245749 12:07:33,275 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): connectedAddress = 10.1.1.100 12:07:33,275 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable returning currently tested address: 10.1.1.100 12:07:33,275 [DEBUG] serverIp is: 10.1.1.100 12:07:33,323 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/tftp:\/\/10.1.1.100\/test57753115635923244211489453653198-6931724347770245749/test57753115635923244211489453653198-6931724347770245749/CR 12:07:33,447 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/startup-config/tftp:\/\/10.1.1.100\/2978907306559/CR 12:07:33,651 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/vlan.dat/tftp:\/\/10.1.1.100\/2978907307186/CR 12:07:33,682 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/speeddial.xml/tftp:\/\/10.1.1.100\/2978907307313/CR 12:07:33,682 [DEBUG] result in PCBUBackupRestore: Not a VLAN Message: Is it a genuine error? %Error opening flash:speeddial.xml (File not found) 12:07:33,682 [DEBUG] No vlan.dat file on UC560IGNORED! 12:07:33,682 [DEBUG] No vlan.dat file on the device. 12:07:33,729 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/bacdprompts/CR 12:07:33,776 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/ 12:07:33,869 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/\/create/tftp:\/\/10.1.1.100\/2978907307564/bacdprompts/CR 12:07:40,823 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/media/CR 12:07:40,870 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/ 12:07:40,964 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/\/create/tftp:\/\/10.1.1.100\/2978907321753/media/CR 12:07:48,760 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/Desktops/CR 12:07:48,792 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/ 12:07:48,901 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/\/create/tftp:\/\/10.1.1.100\/2978907337598/Desktops/CR 12:07:53,385 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/ringtones/CR 12:07:53,432 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/ 12:07:53,526 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/\/create/tftp:\/\/10.1.1.100\/2978907346879/ringtones/CR 12:07:55,869 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/applications/CR 12:07:55,948 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/English_United_States/CR 12:07:56,182 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/its/CR 12:07:56,307 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/ 12:07:56,417 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/archive/tar/\/create/tftp:\/\/10.1.1.100\/2978907352630/its/CR 12:08:13,057 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/dir/webvpn/CR 12:08:13,073 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable got request 12:08:13,073 [INFO ] TftpAddressMgrImplementation:TestCoordinator(): ip = 10.1.1.100 12:08:13,119 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): receivedRequest id = test28618225420364669911489453693073-7946795965525463514 12:08:13,119 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): connectedAddress = 10.1.1.100 12:08:13,119 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable returning currently tested address: 10.1.1.100 12:08:13,198 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/tftp:\/\/10.1.1.100\/test28618225420364669911489453693073-7946795965525463514/test28618225420364669911489453693073-7946795965525463514/CR 12:08:14,245 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable got request 12:08:14,245 [INFO ] TftpAddressMgrImplementation:TestCoordinator(): ip = 10.1.1.100 12:08:14,276 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): receivedRequest id = test-70330048426025919131489453694245-8941731141435805521 12:08:14,276 [INFO ] TftpAddressMgrImplementation:TestRequestGenerator(): connectedAddress = 10.1.1.100 12:08:14,276 [INFO ] https://10.1.1.5:443/ deviceIsTftpReachable returning currently tested address: 10.1.1.100 12:08:14,276 [DEBUG] *************attempting to session to cue************ 12:08:14,276 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:08:14,338 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/copy/tftp:\/\/10.1.1.100\/test-70330048426025919131489453694245-8941731141435805521/test-70330048426025919131489453694245-8941731141435805521/CR 12:08:17,385 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:08:18,901 [DEBUG] Current CUE transport timeout =90000 12:08:18,901 [DEBUG] Current CUE deliver timeout =600 12:08:20,276 [DEBUG] Session clear command sent 12:08:20,963 [DEBUG] Session command = service-module Integrated-Service-Engine 0/0 session 12:08:24,651 [DEBUG] Logged into CUE. 12:08:26,432 [DEBUG] Learned CUE Prompt = se-192-168-90-2# 12:08:27,717 [DEBUG] SetPrivExecMode() done - CUE Prompt = se-192-168-90-2# 12:08:29,667 [DEBUG] Set CUE transport timeout =90000 12:08:29,667 [DEBUG] Set CUE deliver timeout =90000 12:08:29,667 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:08:29,667 [DEBUG] *************attempting to session successful************ 12:08:29,667 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:08:30,088 [DEBUG] Executing the submode CUE CLI: configure terminal 12:08:31,869 [DEBUG] Executing the CUE CLI: backup server url ftp://10.1.1.100/cue/ username cisco password cisco 12:08:33,620 [DEBUG] Executing the CUE CLI: backup revisions 1 12:09:05,151 [DEBUG] Executing the exit submode CUE CLI 12:09:13,244 [DEBUG] Issuing show backup server command. 12:09:13,244 [DEBUG] *************attempting to session to cue************ 12:09:13,244 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:09:15,542 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:09:16,963 [DEBUG] Current CUE transport timeout =90000 12:09:16,963 [DEBUG] Current CUE deliver timeout =600 12:09:18,338 [DEBUG] Session clear command sent 12:09:19,042 [DEBUG] Session command = service-module Integrated-Service-Engine 0/0 session 12:09:22,682 [DEBUG] Logged into CUE. 12:09:24,479 [DEBUG] Learned CUE Prompt = se-192-168-90-2# 12:09:25,744 [DEBUG] SetPrivExecMode() done - CUE Prompt = se-192-168-90-2# 12:09:27,464 [DEBUG] Set CUE transport timeout =90000 12:09:27,464 [DEBUG] Set CUE deliver timeout =90000 12:09:27,464 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:09:27,464 [DEBUG] *************attempting to session successful************ 12:09:27,464 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:09:28,713 [DEBUG] 12:09:48,713 [DEBUG] se-192-168-90-2# 12:10:08,791 [DEBUG] 12:10:28,792 [DEBUG] Backup Restore: Unable to connect to backup server se-192-168-90-2# se-192-168-90-2# 12:10:51,104 [ERROR] doCueRestore(): Backup server communication failed. 12:10:51,104 [ERROR] com.cisco.cpnm.legacy.device.CLIException: Failed to communicate with CUE backup server 12:10:51,104 [ERROR] at com.cisco.cpnm.features.mcebu.defn.backrest.MCEBUArchiveConfiguration.doCueBackup(MCEBUArchiveConfiguration.java:317) 12:10:51,104 [ERROR] at com.cisco.cpnm.features.mcebu.defn.backrest.MCEBUArchiveConfiguration.conductModuleBackup(MCEBUArchiveConfiguration.java:77) 12:10:51,104 [ERROR] at com.cisco.cpnm.features.defn.backrest.CommonArchiveConfiguration.conductBackup(CommonArchiveConfiguration.java:350) 12:10:51,104 [ERROR] at com.cisco.cpnm.features.defn.backrest.CommonArchiveConfiguration.conductBackup(CommonArchiveConfiguration.java:262) 12:10:51,104 [ERROR] at com.cisco.cpnm.features.defn.backrest.BackupRestoreConfigTask$BackupWorkerThread.run(BackupRestoreConfigTask.java:1267) 12:10:51,104 [ERROR] at com.cisco.cpnm.frmwrk.gen.util.ThreadPool$WorkerThread.run(ThreadPool.java:1010) 12:10:51,104 [DEBUG] Issuing backup command. 12:10:56,104 [DEBUG] *************attempting to session to cue************ 12:10:56,119 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:10:58,760 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:11:00,197 [DEBUG] Current CUE transport timeout =90000 12:11:00,197 [DEBUG] Current CUE deliver timeout =600 12:11:02,120 [DEBUG] Session clear command sent 12:11:02,948 [DEBUG] Session command = service-module Integrated-Service-Engine 0/0 session 12:11:06,589 [DEBUG] Logged into CUE. 12:11:08,369 [DEBUG] Learned CUE Prompt = se-192-168-90-2# 12:11:09,620 [DEBUG] SetPrivExecMode() done - CUE Prompt = se-192-168-90-2# 12:11:11,589 [DEBUG] Set CUE transport timeout =90000 12:11:11,589 [DEBUG] Set CUE deliver timeout =90000 12:11:11,589 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:11:11,589 [DEBUG] *************attempting to session successful************ 12:11:11,589 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:11:32,682 [DEBUG] received offline# 12:11:48,760 [DEBUG] WDTask::setHierarchy .AboutDialogTask 12:11:48,823 [DEBUG] Duration for [create() @ com.cisco.cpnm.features.defn.about.AboutDialogTask] = [47] msec. 12:11:50,728 [DEBUG] WDTask::setHierarchy .AboutDialogTask 12:11:53,932 [DEBUG] Obtaining the output of CLI: backup category all 12:11:59,948 [DEBUG] WDTask::setHierarchy .EnchancedDevicePropertiesTask 12:12:00,010 [CLI ] CLIEngine: Executed: https://10.1.1.5/exec/show/version/|/inc/IOS/Software/CR 12:12:00,010 [DEBUG] Duration for [create() @ com.cisco.cpnm.legacy.l2.EnchancedDevicePropertiesTask] = [47] msec. 12:12:50,073 [DEBUG] WDTask::setHierarchy .EnchancedDevicePropertiesTask 12:13:16,089 [DEBUG] received CUE# 12:14:07,542 [INFO ] [cmd: backup category all, status: 0, output: [4lBackup Restore: Unable to connect to backup server se-192-168-90-2(offline)#] 12:14:07,542 [ERROR] doCueBackup(): Did not find 'Backup Complete' in response. 12:14:07,542 [DEBUG] *************attempting to session to cue************ 12:14:07,542 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:14:09,901 [DEBUG] AuthGrp@a449e0: getAuthCreds() called for: telnet://10.1.1.5:23; realm: null 12:14:11,276 [DEBUG] Current CUE transport timeout =90000 12:14:11,276 [DEBUG] Current CUE deliver timeout =600 12:14:12,760 [DEBUG] Session clear command sent 12:14:13,432 [DEBUG] Session command = service-module Integrated-Service-Engine 0/0 session 12:14:17,089 [DEBUG] Logged into CUE. 12:14:18,855 [DEBUG] Learned CUE Prompt = se-192-168-90-2# 12:14:20,135 [DEBUG] SetPrivExecMode() done - CUE Prompt = se-192-168-90-2# 12:14:22,089 [DEBUG] Set CUE transport timeout =90000 12:14:22,089 [DEBUG] Set CUE deliver timeout =90000 12:14:22,089 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:14:22,089 [DEBUG] *************attempting to session successful************ 12:14:22,089 [DEBUG] *************CUE prompt: se-192-168-90-2# ************* 12:14:22,494 [DEBUG] Executing the CUE CLI: show backup history 12:14:25,355 [INFO ] Response from show backup history : 12:14:25,355 [INFO ] ***************** begin ******************** 12:14:25,355 [INFO ] [cmd: show backup history, status: 0, output: ] 12:14:25,355 [INFO ] ***************** end ******************** 12:14:25,355 [ERROR] com.cisco.cpnm.features.defn.backrest.BackrestException: Backup CUE Configuration failed. 12:14:25,355 [ERROR] at com.cisco.cpnm.features.defn.backrest.CommonArchiveConfiguration.conductBackup(CommonArchiveConfiguration.java:361) 12:14:25,355 [ERROR] at com.cisco.cpnm.features.defn.backrest.CommonArchiveConfiguration.conductBackup(CommonArchiveConfiguration.java:262) 12:14:25,355 [ERROR] at com.cisco.cpnm.features.defn.backrest.BackupRestoreConfigTask$BackupWorkerThread.run(BackupRestoreConfigTask.java:1267) 12:14:25,355 [ERROR] at com.cisco.cpnm.frmwrk.gen.util.ThreadPool$WorkerThread.run(ThreadPool.java:1010) 12:14:25,355 [DEBUG] Backup Failed for UC560: Backup CUE Configuration failed. 12:14:25,401 [DEBUG] Duration for [upd Mirror<--Device() @ com.cisco.cpnm.features.defn.backrest.BackupRestoreConfigTask] = [437516] msec. 12:17:10,682 [DEBUG] Duration for [upd Mirror<--Device() @ com.cisco.cpnm.features.defn.backrest.BackupRestoreConfigTask$RestoreConfigTask] = [16] msec.