{"action":"created","issue":{"url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/39","repository_url":"https://api.github.com/repos/Cacti/plugin_routerconfigs","labels_url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/39/labels{/name}","comments_url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/39/comments","events_url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/39/events","html_url":"https://github.com/Cacti/plugin_routerconfigs/issues/39","id":300383755,"number":39,"title":"Debugging Version 1.3.3","user":{"login":"rtripon","id":28921178,"avatar_url":"https://avatars1.githubusercontent.com/u/28921178?v=4","gravatar_id":"","url":"https://api.github.com/users/rtripon","html_url":"https://github.com/rtripon","followers_url":"https://api.github.com/users/rtripon/followers","following_url":"https://api.github.com/users/rtripon/following{/other_user}","gists_url":"https://api.github.com/users/rtripon/gists{/gist_id}","starred_url":"https://api.github.com/users/rtripon/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/rtripon/subscriptions","organizations_url":"https://api.github.com/users/rtripon/orgs","repos_url":"https://api.github.com/users/rtripon/repos","events_url":"https://api.github.com/users/rtripon/events{/privacy}","received_events_url":"https://api.github.com/users/rtripon/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":47,"created_at":"2018-02-26T20:26:39Z","updated_at":"2018-03-03T20:31:30Z","closed_at":null,"author_association":"NONE","body":"Ok, so I've installed new version, and manually backups are working...but only for some devices, its pretty weird. I have 8 devices, 5 are working, 3 arent. Same configs, same user/pass, just different IP.\r\n\r\n2018/02/26 22:16:04 - RCONFIG NOTICE: Starting automatic backup\r\n2018/02/26 22:16:05 - RCONFIG STATS: Backup now running since 2018-02-26 22:16:04\r\n2018/02/26 22:16:05 - RCONFIG DEBUG: SQL: SELECT *                              FROM plugin_routerconfigs_devices                               WHERE enabled = 'on'\r\n2018/02/26 22:16:05 - RCONFIG DEBUG: Attempting download for c2960-POP-Atrium\r\n2018/02/26 22:16:05 - RCONFIG DEBUG: Base64 decoded: a:3:{i:1421755990;i:10712997;s:8:\"password\";s:16:\"(16: chars)\";i:62731792;i:352409267;}\r\n2018/02/26 22:16:05 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:05 - RCONFIG 172.25.232.56 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:05 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.56, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:05 - RCONFIG 172.25.232.56 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:05 - RCONFIG 172.25.232.56 (Telnet) -> Attempting to open socked to 172.25.232.56:23\r\n2018/02/26 22:16:06 - RCONFIG 172.25.232.56 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:08 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:08 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:16:09 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:16:09 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:16:10 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:10 - RCONFIG 172.25.232.56 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:16:10 - RCONFIG 172.25.232.56 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:16:12 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:12 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> Process is  enabled\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/c2960-POP-Atrium\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Attempting to open file at /var/lib/tftpboot/c2960-POP-Atrium\r\n2018/02/26 22:16:13 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Configuration end check successful\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Configuration Data Length 8202\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Checking backup directory exists: /var/lib/tftpboot/\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Attempting to backup to filename '/var/lib/tftpboot/c2960-POP-Atrium-2018-02-26-2216'\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.56 (Telnet) -> DEBUG: Backed up\r\n2018/02/26 22:16:14 - RCONFIG NOTICE: Download successful for c2960-POP-Atrium\r\n2018/02/26 22:16:14 - RCONFIG DEBUG: Attempting download for Gi2\r\n2018/02/26 22:16:14 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:16:14 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.36 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.36 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.36, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.36 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:14 - RCONFIG 172.25.232.36 (Telnet) -> Attempting to open socked to 172.25.232.36:23\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.36 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.36 (Telnet) -> ERROR: Failed to find username prompt\r\n2018/02/26 22:16:15 - RCONFIG ERROR: Failed to Connect to Device 'Gi2' using connection type: telnet\r\n2018/02/26 22:16:15 - RCONFIG NOTICE: Failed to download for Gi2\r\n2018/02/26 22:16:15 - RCONFIG DEBUG: Attempting download for ro-ar01a-c2960S-uav\r\n2018/02/26 22:16:15 - RCONFIG DEBUG: Base64 decoded: a:3:{i:1421755990;i:10712997;s:8:\"password\";s:16:\"(16: chars)\";i:62731792;i:352409267;}\r\n2018/02/26 22:16:15 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.55 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.55, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.55 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:15 - RCONFIG 172.25.232.55 (Telnet) -> Attempting to open socked to 172.25.232.55:23\r\n2018/02/26 22:16:16 - RCONFIG 172.25.232.55 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:18 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:18 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:16:19 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:16:19 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:16:20 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:20 - RCONFIG 172.25.232.55 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:16:20 - RCONFIG 172.25.232.55 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:16:22 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:22 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:16:23 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:23 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:16:23 - RCONFIG 172.25.232.55 (Telnet) -> Process is  enabled\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/ro-ar01a-c2960S-uav\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Attempting to open file at /var/lib/tftpboot/ro-ar01a-c2960S-uav\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Configuration end check successful\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Configuration Data Length 12895\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Checking backup directory exists: /var/lib/tftpboot/\r\n2018/02/26 22:16:24 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Attempting to backup to filename '/var/lib/tftpboot/ro-ar01a-c2960S-uav-2018-02-26-2216'\r\n2018/02/26 22:16:25 - RCONFIG 172.25.232.55 (Telnet) -> DEBUG: Backed up\r\n2018/02/26 22:16:25 - RCONFIG NOTICE: Download successful for ro-ar01a-c2960S-uav\r\n2018/02/26 22:16:25 - RCONFIG DEBUG: Attempting download for SW-Pecica\r\n2018/02/26 22:16:25 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:16:25 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:25 - RCONFIG 172.25.232.38 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:25 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.38, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:25 - RCONFIG 172.25.232.38 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:25 - RCONFIG 172.25.232.38 (Telnet) -> Attempting to open socked to 172.25.232.38:23\r\n2018/02/26 22:16:26 - RCONFIG 172.25.232.38 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:28 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:28 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:16:29 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:16:29 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:16:31 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:31 - RCONFIG 172.25.232.38 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:16:31 - RCONFIG 172.25.232.38 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:16:33 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:33 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> Process is  enabled\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/SW-Pecica\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Attempting to open file at /var/lib/tftpboot/SW-Pecica\r\n2018/02/26 22:16:34 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Configuration end check successful\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Configuration Data Length 11831\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Checking backup directory exists: /var/lib/tftpboot/\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Attempting to backup to filename '/var/lib/tftpboot/SW-Pecica-2018-02-26-2216'\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.38 (Telnet) -> DEBUG: Backed up\r\n2018/02/26 22:16:36 - RCONFIG NOTICE: Download successful for SW-Pecica\r\n2018/02/26 22:16:36 - RCONFIG DEBUG: Attempting download for gi11-\r\n2018/02/26 22:16:36 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:16:36 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.39 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.39, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.39 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:36 - RCONFIG 172.25.232.39 (Telnet) -> Attempting to open socked to 172.25.232.39:23\r\n2018/02/26 22:16:37 - RCONFIG 172.25.232.39 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:39 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:39 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:16:41 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:16:41 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:16:42 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:44 - RCONFIG 172.25.232.39 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:16:45 - RCONFIG 172.25.232.39 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:16:47 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:47 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:16:48 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:48 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:16:48 - RCONFIG 172.25.232.39 (Telnet) -> Process is  enabled\r\n2018/02/26 22:16:48 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Prompt match (1) returned 1 matches\r\n2018/02/26 22:16:48 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Sending confirmation: y\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Result: (0), Line: 0$tp://217.156.98.102/gi11-\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Result: (0), Line: !!!!\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Result: (0), Line: 10460 bytes copied in 1.448 secs (7224 bytes/sec)\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Result: (0), Line: gi11-#y\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Result: (0), Line:\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: TFP TRANSFER SUCCESSFUL\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/gi11-\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Attempting to open file at /var/lib/tftpboot/gi11-\r\n2018/02/26 22:16:50 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Configuration end check successful\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Configuration Data Length 10876\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Checking backup directory exists: /var/lib/tftpboot/\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Attempting to backup to filename '/var/lib/tftpboot/gi11-2018-02-26-2216'\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.39 (Telnet) -> DEBUG: Backed up\r\n2018/02/26 22:16:51 - RCONFIG NOTICE: Download successful for gi11-\r\n2018/02/26 22:16:51 - RCONFIG DEBUG: Attempting download for GI9\r\n2018/02/26 22:16:51 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:16:51 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.47 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.47 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.47, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.47 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:51 - RCONFIG 172.25.232.47 (Telnet) -> Attempting to open socked to 172.25.232.47:23\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.47 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.47 (Telnet) -> ERROR: Failed to find username prompt\r\n2018/02/26 22:16:52 - RCONFIG ERROR: Failed to Connect to Device 'GI9' using connection type: telnet\r\n2018/02/26 22:16:52 - RCONFIG NOTICE: Failed to download for GI9\r\n2018/02/26 22:16:52 - RCONFIG DEBUG: Attempting download for ro-ar01a-sw13\r\n2018/02/26 22:16:52 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:16:52 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.45 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.45, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.45 (Telnet) -> Disconnect()\r\n2018/02/26 22:16:52 - RCONFIG 172.25.232.45 (Telnet) -> Attempting to open socked to 172.25.232.45:23\r\n2018/02/26 22:16:53 - RCONFIG 172.25.232.45 (Telnet) -> Looking for Username:\r\n2018/02/26 22:16:55 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:16:55 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:16:56 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:16:56 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:16:58 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:16:58 - RCONFIG 172.25.232.45 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:16:58 - RCONFIG 172.25.232.45 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:17:00 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:17:00 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:17:01 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:17:01 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:17:01 - RCONFIG 172.25.232.45 (Telnet) -> Process is  enabled\r\n2018/02/26 22:17:01 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Prompt match (1) returned 1 matches\r\n2018/02/26 22:17:01 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Sending confirmation: y\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Result: (0), Line: 0$tp://217.156.98.102/ro-ar01a-sw13\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Result: (0), Line: !!\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Result: (0), Line: 15961 bytes copied in 0.980 secs (16287 bytes/sec)\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Result: (0), Line: ro-ar01a-sw13#y\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Result: (0), Line:\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: TFP TRANSFER SUCCESSFUL\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/ro-ar01a-sw13\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Attempting to open file at /var/lib/tftpboot/ro-ar01a-sw13\r\n2018/02/26 22:17:02 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Configuration end check successful\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Configuration Data Length 16647\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Checking backup directory exists: /var/lib/tftpboot/\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Attempting to backup to filename '/var/lib/tftpboot/ro-ar01a-sw13-2018-02-26-2217'\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.45 (Telnet) -> DEBUG: Backed up\r\n2018/02/26 22:17:03 - RCONFIG NOTICE: Download successful for ro-ar01a-sw13\r\n2018/02/26 22:17:03 - RCONFIG DEBUG: Attempting download for ro-ar01a-ra\r\n2018/02/26 22:17:03 - RCONFIG DEBUG: Base64 decoded: a:3:{i:935581429;i:6942385;s:8:\"password\";s:16:\"(16: chars)\";i:1423103999;i:907308044;}\r\n2018/02/26 22:17:03 - RCONFIG DEBUG: Base64 decoded:\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.33 -> DEBUG: Attempting to connect via Telnet\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: SSH->Connect(Server: 172.25.232.33, User: rtripon, Password: (16 chars), Enablepw: (Not Set), Devicetype: {\"id\":\"1\",\"name\":\"Cisco IOS\",\"username\":\"Username:\",\"password\":\"Password:\",\"copytftp\":\"copy run tftp:\\/\\/%SERVER%\\/%FILE%\",\"version\":\"show version\",\"confirm\":\"y\",\"forceconfirm\":\"on\",\"checkendinconfig\":\"on\"}\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.33 (Telnet) -> Disconnect()\r\n2018/02/26 22:17:03 - RCONFIG 172.25.232.33 (Telnet) -> Attempting to open socked to 172.25.232.33:23\r\n2018/02/26 22:17:04 - RCONFIG 172.25.232.33 (Telnet) -> Looking for Username:\r\n2018/02/26 22:17:06 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:17:06 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Sending username: rtripon\r\n2018/02/26 22:17:07 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Found Prompt (Password)\r\n2018/02/26 22:17:07 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Found prompt 'Password:', Sending password: (16 chars)\r\n2018/02/26 22:17:09 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:17:09 - RCONFIG 172.25.232.33 (Telnet) -> NOTICE: Connected via telnet\r\n2018/02/26 22:17:09 - RCONFIG 172.25.232.33 (Telnet) -> NOTICE: Ensuring process is enabled\r\n2018/02/26 22:17:11 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n2018/02/26 22:17:11 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Attempt 0 of 10 to find enabled prompt\r\n2018/02/26 22:17:12 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:17:12 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Attempt 1 of 10 to find enabled prompt\r\n2018/02/26 22:17:12 - RCONFIG 172.25.232.33 (Telnet) -> Process is  enabled\r\n2018/02/26 22:17:12 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: CHECKING FOR valid file at /var/lib/tftpboot/ro-ar01a-ra\r\n2018/02/26 22:17:12 - RCONFIG 172.25.232.33 (Telnet) -> ERROR: Failed to find file at /var/lib/tftpboot/ro-ar01a-ra\r\n2018/02/26 22:17:13 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Found Prompt (Enabled)\r\n2018/02/26 22:17:13 - RCONFIG NOTICE: Failed to download for ro-ar01a-ra\r\n2018/02/26 22:17:13 - RCONFIG WARNING: 5 Devices Backed Up and 3 Devices Failed in 68 seconds\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: 5 devices backed up successfully.\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: 3 devices failed to backup.\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: These devices failed to backup\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: ------------------------------\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: Gi2 - ERROR: Failed to Connect to Device 'Gi2' using connection type: telnet\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: GI9 - ERROR: Failed to Connect to Device 'GI9' using connection type: telnet\r\n2018/02/26 22:17:13 - RCONFIG DEBUG: ro-ar01a-ra - ERROR: TELNET no Config uploaded from Router\r\n2018/02/26 22:17:14 - RCONFIG STATS: Time:69.35 Downloaded:5 Failed:3\r\n\r\n\r\nFor at least 2 hosts(Gi2+Gi9) I think one issue may be the fact that the \"Username\" prompt appears after few seconds(due to a tacacs server prolly), and its timed out.\r\n\r\n Looking for Username:\r\n2018/02/26 22:17:06 - RCONFIG 172.25.232.33 (Telnet) -> DEBUG: Timeout of 1 seconds has been reached\r\n\r\nBut I dont know why the last host(ro-ar01a-ra) isnt working.\r\n"},"comment":{"url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/comments/370177514","html_url":"https://github.com/Cacti/plugin_routerconfigs/issues/39#issuecomment-370177514","issue_url":"https://api.github.com/repos/Cacti/plugin_routerconfigs/issues/39","id":370177514,"user":{"login":"netniV","id":9052188,"avatar_url":"https://avatars0.githubusercontent.com/u/9052188?v=4","gravatar_id":"","url":"https://api.github.com/users/netniV","html_url":"https://github.com/netniV","followers_url":"https://api.github.com/users/netniV/followers","following_url":"https://api.github.com/users/netniV/following{/other_user}","gists_url":"https://api.github.com/users/netniV/gists{/gist_id}","starred_url":"https://api.github.com/users/netniV/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/netniV/subscriptions","organizations_url":"https://api.github.com/users/netniV/orgs","repos_url":"https://api.github.com/users/netniV/repos","events_url":"https://api.github.com/users/netniV/events{/privacy}","received_events_url":"https://api.github.com/users/netniV/received_events","type":"User","site_admin":false},"created_at":"2018-03-03T20:31:30Z","updated_at":"2018-03-03T20:31:30Z","author_association":"CONTRIBUTOR","body":"It should retry several times to wait for the username response.  Do you have the debug of that ?"}}
{"actor":{"display_login":"netniV"}}