diff options
| author | rebortg <github@ghlr.de> | 2023-06-07 10:08:33 +0200 |
|---|---|---|
| committer | rebortg <github@ghlr.de> | 2023-06-07 10:08:33 +0200 |
| commit | 5a56b9211478248dbea583dd911d2a87b4a8de08 (patch) | |
| tree | 844981bf726b6a1d055077dfceacae6e3ca6add1 | |
| parent | 4b64172c342148db2f6f51d180958a81f0c8a24a (diff) | |
| download | vyos-documentation-5a56b9211478248dbea583dd911d2a87b4a8de08.tar.gz vyos-documentation-5a56b9211478248dbea583dd911d2a87b4a8de08.zip | |
autotest: tunnelbroker update version
| -rw-r--r-- | docs/configexamples/autotest/tunnelbroker/tunnelbroker.log | 1395 | ||||
| -rw-r--r-- | docs/configexamples/autotest/tunnelbroker/tunnelbroker.rst | 37 |
2 files changed, 798 insertions, 634 deletions
diff --git a/docs/configexamples/autotest/tunnelbroker/tunnelbroker.log b/docs/configexamples/autotest/tunnelbroker/tunnelbroker.log index cc7a8b99..b4ff3ea6 100644 --- a/docs/configexamples/autotest/tunnelbroker/tunnelbroker.log +++ b/docs/configexamples/autotest/tunnelbroker/tunnelbroker.log @@ -1,623 +1,786 @@ -2022-10-09 21:11:31,294 p=88471 u=rob n=ansible | [WARNING]: While constructing a mapping from /Users/rob/git/_vyos/vyos-eveng/roles/eve-ng-lab-test/defaults/main.yml, line 4, column 1, found a -duplicate dict key (upgrade_iso). Using last defined value only. +2023-06-07 09:52:49,845 p=78339 u=rob n=ansible | PLAY [Automatic VyOS Lab test] ******************************************************************************************************************************** +2023-06-07 09:52:49,889 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: fail if node_template_iso is empty] ********************************************************************************** +2023-06-07 09:52:49,910 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:49,910 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:49,911 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:49,915 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:49,919 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: fail if node_template_version is empty] ****************************************************************************** +2023-06-07 09:52:49,932 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:49,938 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:49,941 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:49,945 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:49,948 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: install requirements] ************************************************************************************************ +2023-06-07 09:52:49,967 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:49,969 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:49,974 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:51,704 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:51,710 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Login to EVE-NG and get Cookie] ************************************************************************************** +2023-06-07 09:52:51,735 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:51,738 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:51,743 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:52,424 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:52,427 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: get template facts] ************************************************************************************************** +2023-06-07 09:52:52,444 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:52,447 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:52,451 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:52,843 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:52,847 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Register path status] ************************************************************************************************ +2023-06-07 09:52:52,867 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:52,868 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:52,873 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,081 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:53,085 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: create path] ********************************************************************************************************* +2023-06-07 09:52:53,106 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,108 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,108 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,112 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,115 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Upload iso to eve-ng] ************************************************************************************************ +2023-06-07 09:52:53,130 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,135 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,137 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,142 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,146 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: create virtioa.qcow2 file] ******************************************************************************************* +2023-06-07 09:52:53,159 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,167 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,168 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,173 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,176 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Login to EVE-NG and get Cookie] ************************************************************************************** +2023-06-07 09:52:53,189 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,195 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,198 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,202 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,205 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: create lab for node install] ***************************************************************************************** +2023-06-07 09:52:53,221 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,226 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,228 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,232 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,236 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: open lab] ************************************************************************************************************ +2023-06-07 09:52:53,248 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,255 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,257 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,262 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,265 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : set_fact] ************************************************************************************************************************** +2023-06-07 09:52:53,284 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,286 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,286 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,291 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,294 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: add node to lab] ***************************************************************************************************** +2023-06-07 09:52:53,307 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,314 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,316 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,320 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,323 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: start node] ********************************************************************************************************** +2023-06-07 09:52:53,336 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,343 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,346 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,349 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,352 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : debug] ***************************************************************************************************************************** +2023-06-07 09:52:53,365 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,371 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,373 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,378 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,381 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: get node infos] ****************************************************************************************************** +2023-06-07 09:52:53,394 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,401 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,403 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,407 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,410 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : set_fact] ************************************************************************************************************************** +2023-06-07 09:52:53,430 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,431 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,431 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,436 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,439 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: copy file] *********************************************************************************************************** +2023-06-07 09:52:53,458 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,459 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,460 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,464 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,467 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: run expect script] *************************************************************************************************** +2023-06-07 09:52:53,481 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,488 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,490 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,494 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,498 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Login to EVE-NG and get Cookie (due timeout)] ************************************************************************ +2023-06-07 09:52:53,517 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,519 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,520 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,524 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,527 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: stop node] *********************************************************************************************************** +2023-06-07 09:52:53,540 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,546 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,548 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,552 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,555 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: Pause to wait node is shutdown] ************************************************************************************** +2023-06-07 09:52:53,565 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,568 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: delete iso] ********************************************************************************************************** +2023-06-07 09:52:53,587 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,587 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,589 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,593 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,596 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: commit virtioa.qcow2] ************************************************************************************************ +2023-06-07 09:52:53,609 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,616 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,618 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,623 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,626 p=78339 u=rob n=ansible | TASK [eve-ng-create-node : tunnelbroker: delete lab for node install] ***************************************************************************************** +2023-06-07 09:52:53,639 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:53,646 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:53,648 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:53,652 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:53,661 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : Get env file content] ***************************************************************************************************************** +2023-06-07 09:52:54,002 p=78339 u=rob n=ansible | ok: [vyos-wan -> localhost] +2023-06-07 09:52:54,002 p=78339 u=rob n=ansible | ok: [client -> localhost] +2023-06-07 09:52:54,002 p=78339 u=rob n=ansible | ok: [vyos-oobm -> localhost] +2023-06-07 09:52:54,002 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 09:52:54,006 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Load facts] ************************************************************************************************************* +2023-06-07 09:52:54,024 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:55,161 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 09:52:55,163 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko -2022-10-09 21:11:31,295 p=88471 u=rob n=ansible | [WARNING]: While constructing a mapping from /Users/rob/git/_vyos/vyos-eveng/roles/eve-ng-lab-test/defaults/main.yml, line 4, column 1, found a -duplicate dict key (upgrade_iso_version). Using last defined value only. +2023-06-07 09:52:55,163 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 09:52:55,165 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 09:52:55,166 p=78339 u=rob n=ansible | ok: [vyos-oobm] +2023-06-07 09:52:55,166 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko -2022-10-09 21:11:31,328 p=88471 u=rob n=ansible | PLAY [prepare node and Lab] ************************************************************************************************************************** -2022-10-09 21:11:31,347 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : fail] ********************************************************************************************************************* -2022-10-09 21:11:31,363 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:31,369 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:31,371 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:31,373 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:31,376 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : fail] ********************************************************************************************************************* -2022-10-09 21:11:31,390 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:31,396 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:31,398 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:31,401 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:31,403 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : install requirements] ***************************************************************************************************** -2022-10-09 21:11:31,422 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:31,424 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:31,430 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:33,481 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:33,488 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Login to EVE-NG and get Cookie] ******************************************************************************************* -2022-10-09 21:11:33,516 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:33,519 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:33,525 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:34,254 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:34,259 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : get template facts] ******************************************************************************************************* -2022-10-09 21:11:34,288 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:34,290 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:34,296 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:34,809 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:34,815 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Register path status] ***************************************************************************************************** -2022-10-09 21:11:34,842 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:34,844 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:34,849 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,185 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:35,189 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : create path] ************************************************************************************************************** -2022-10-09 21:11:35,205 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,213 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,213 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,219 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,222 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Download iso] ************************************************************************************************************* -2022-10-09 21:11:35,236 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,242 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,243 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,248 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,251 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : create virtioa.qcow2 file] ************************************************************************************************ -2022-10-09 21:11:35,264 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,270 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,272 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,276 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,279 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Login to EVE-NG and get Cookie] ******************************************************************************************* -2022-10-09 21:11:35,291 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,297 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,299 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,303 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,305 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : create lab for node install] ********************************************************************************************** -2022-10-09 21:11:35,317 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,323 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,325 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,329 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,331 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : open lab] ***************************************************************************************************************** -2022-10-09 21:11:35,344 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,350 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,351 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,355 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,358 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : set_fact] ***************************************************************************************************************** -2022-10-09 21:11:35,371 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,376 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,378 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,383 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,385 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : add node to lab] ********************************************************************************************************** -2022-10-09 21:11:35,396 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,402 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,404 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,407 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,409 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : start node] *************************************************************************************************************** -2022-10-09 21:11:35,420 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,426 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,428 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,432 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,434 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : get node infos] *********************************************************************************************************** -2022-10-09 21:11:35,446 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,452 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,453 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,457 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,459 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : set_fact] ***************************************************************************************************************** -2022-10-09 21:11:35,471 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,476 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,478 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,482 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,484 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : copy file] **************************************************************************************************************** -2022-10-09 21:11:35,496 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,501 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,504 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,506 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,508 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : run expect script] ******************************************************************************************************** -2022-10-09 21:11:35,519 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,525 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,527 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,530 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,532 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Login to EVE-NG and get Cookie (due timeout)] ***************************************************************************** -2022-10-09 21:11:35,543 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,549 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,551 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,554 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,555 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : stop node] **************************************************************************************************************** -2022-10-09 21:11:35,567 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,572 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,575 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,577 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,580 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : Pause to wait node is shutdown] ******************************************************************************************* -2022-10-09 21:11:35,588 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,589 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : delete iso] *************************************************************************************************************** -2022-10-09 21:11:35,601 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,607 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,609 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,612 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,614 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : commit virtioa.qcow2] ***************************************************************************************************** -2022-10-09 21:11:35,631 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,632 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,632 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,636 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,638 p=88471 u=rob n=ansible | TASK [eve-ng-create-node : delete lab for node install] ********************************************************************************************** -2022-10-09 21:11:35,649 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:35,655 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:35,657 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:35,659 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:35,666 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Get env file content] ******************************************************************************************************** -2022-10-09 21:11:35,874 p=88471 u=rob n=ansible | ok: [vyos-oobm -> localhost] -2022-10-09 21:11:35,874 p=88471 u=rob n=ansible | ok: [vyos-wan -> localhost] -2022-10-09 21:11:35,874 p=88471 u=rob n=ansible | ok: [client -> localhost] -2022-10-09 21:11:35,875 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:11:35,876 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Load facts] ****************************************************************************************************************** -2022-10-09 21:11:35,895 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:35,898 p=88471 u=rob n=ansible | ok: [vyos-oobm] -2022-10-09 21:11:36,766 p=88717 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:11:36,766 p=88718 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:11:36,774 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:11:36,774 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:11:36,781 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : fail] ************************************************************************************************************************ -2022-10-09 21:11:36,805 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:36,814 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:36,816 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:36,821 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:36,824 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:11:36,846 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:36,848 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:36,852 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:37,502 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:37,507 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : get running lab list] ******************************************************************************************************** -2022-10-09 21:11:37,536 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:37,538 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:37,543 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:38,027 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:38,032 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : delete existing lab] ********************************************************************************************************* -2022-10-09 21:11:38,061 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:38,062 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:38,067 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:38,536 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:38,542 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Ensure labtest is present] *************************************************************************************************** -2022-10-09 21:11:38,571 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:38,573 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:38,579 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:38,911 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:38,916 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Create tunnelbroker Lab] ***************************************************************************************************** -2022-10-09 21:11:38,940 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:38,941 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:38,945 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:39,668 p=88471 u=rob n=ansible | changed: [eveng] -2022-10-09 21:11:39,672 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:11:39,697 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:39,699 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:39,703 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:40,360 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:40,365 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : start vyos-oobm] ************************************************************************************************************* -2022-10-09 21:11:40,403 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:11:40,417 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:40,420 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:11:41,402 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:11:41,409 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Wait for vyos-oobm] ********************************************************************************************************** -2022-10-09 21:11:41,429 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:11:41,439 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:11:41,444 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:12:24,232 p=88471 u=rob n=ansible | ok: [vyos-oobm] -2022-10-09 21:12:24,238 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:12:24,264 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:12:24,266 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:12:24,272 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:12:24,934 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:12:24,938 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : get lab status] ************************************************************************************************************** -2022-10-09 21:12:24,966 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:12:24,968 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:12:24,974 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:12:25,455 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:12:25,459 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : start all nodes] ************************************************************************************************************* -2022-10-09 21:12:25,485 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:12:25,488 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:12:25,495 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:12:26,438 p=88471 u=rob n=ansible | ok: [eveng] => (item=4) -2022-10-09 21:12:27,004 p=88471 u=rob n=ansible | ok: [eveng] => (item=1) -2022-10-09 21:12:28,621 p=88471 u=rob n=ansible | ok: [eveng] => (item=2) -2022-10-09 21:12:28,630 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Wait for vyos nodes] ********************************************************************************************************* -2022-10-09 21:12:28,648 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:12:28,656 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:12:44,540 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Exception: Error reading SSH protocol banner -2022-10-09 21:12:44,542 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:12:44,542 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise socket.timeout() -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | socket.timeout -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:12:44,543 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | self._check_banner() -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise SSHException( -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:12:44,544 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:12:44,548 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Exception: Error reading SSH protocol banner -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf += self._read_timeout(timeout) -2022-10-09 21:12:44,549 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise socket.timeout() -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | socket.timeout -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:12:44,550 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | self._check_banner() -2022-10-09 21:12:44,551 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:12:44,551 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise SSHException( -2022-10-09 21:12:44,551 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:12:44,551 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:00,577 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Exception: Error reading SSH protocol banner -2022-10-09 21:13:00,578 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:13:00,578 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:13:00,578 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) -2022-10-09 21:13:00,578 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf += self._read_timeout(timeout) -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise socket.timeout() -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | socket.timeout -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:13:00,579 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:13:00,580 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | self._check_banner() -2022-10-09 21:13:00,580 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:13:00,580 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise SSHException( -2022-10-09 21:13:00,580 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:13:00,580 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:00,582 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Exception: Error reading SSH protocol banner -2022-10-09 21:13:00,582 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:13:00,582 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:13:00,582 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise socket.timeout() -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | socket.timeout -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: -2022-10-09 21:13:00,583 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | self._check_banner() -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise SSHException( -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:13:00,584 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:16,605 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Exception: Error reading SSH protocol banner -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | buf += self._read_timeout(timeout) -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:13:16,606 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise socket.timeout() -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | socket.timeout -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Traceback (most recent call last): -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | self._check_banner() -2022-10-09 21:13:16,607 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:13:16,608 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | raise SSHException( -2022-10-09 21:13:16,608 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:13:16,608 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | -2022-10-09 21:13:16,616 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Exception: Error reading SSH protocol banner -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2211, in _check_banner -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 380, in readline -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 622, in _read_timeout -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise socket.timeout() -2022-10-09 21:13:16,617 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | socket.timeout -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2039, in run -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | self._check_banner() -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2215, in _check_banner -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | raise SSHException( -2022-10-09 21:13:16,618 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner -2022-10-09 21:13:16,619 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | -2022-10-09 21:13:18,375 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:13:18,377 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:13:18,465 p=88981 u=rob n=p=88981 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:13:18,467 p=88980 u=rob n=p=88980 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:13:19,366 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:13:19,366 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:13:19,368 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : include tunnelbroker lab_config.yml] ***************************************************************************************** -2022-10-09 21:13:19,382 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:13:19,388 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:13:19,400 p=88471 u=rob n=ansible | included: /Users/rob/git/_vyos/vyos-eveng/labs/tunnelbroker/lab_config.yml for vyos-wan, client -2022-10-09 21:13:19,427 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : setup nodes] ***************************************************************************************************************** -2022-10-09 21:13:21,142 p=88724 u=rob n=p=88724 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:13:21,155 p=88726 u=rob n=p=88726 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:13:21,204 p=88724 u=rob n=p=88724 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:13:21,211 p=88726 u=rob n=p=88726 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:13:25,245 p=88471 u=rob n=ansible | [WARNING]: To ensure idempotency and correct diff the input configuration lines should be similar to how they appear if present in the running -configuration on device including the indentation +2023-06-07 09:52:55,166 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko -2022-10-09 21:13:25,246 p=88471 u=rob n=ansible | changed: [client] -2022-10-09 21:13:25,352 p=88471 u=rob n=ansible | changed: [vyos-wan] -2022-10-09 21:13:25,359 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Register external IP in Tunnelbroker] **************************************************************************************** -2022-10-09 21:13:25,375 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:13:34,525 p=88471 u=rob n=ansible | ok: [vyos-wan -> eveng] -2022-10-09 21:13:34,534 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : get ipv4 address of vyos-wan (eth1)] ***************************************************************************************** -2022-10-09 21:13:34,554 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:13:35,744 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:13:35,749 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] *************************************************************************************************************** -2022-10-09 21:13:35,762 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:13:36,582 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:13:36,594 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : setup vyos-wan] ************************************************************************************************************** -2022-10-09 21:13:36,616 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:13:43,034 p=88471 u=rob n=ansible | changed: [vyos-wan] -2022-10-09 21:13:43,039 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:13:43,063 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:13:43,065 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:13:43,070 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:13:43,735 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:13:43,741 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : stop nodes id] *************************************************************************************************************** -2022-10-09 21:13:43,787 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after stop] ************************************************************************************************************* -2022-10-09 21:13:43,797 p=89167 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:13:43,797 p=89167 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:13:48,803 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:13:48,810 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : start nodes id] ************************************************************************************************************** -2022-10-09 21:13:48,887 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after start] ************************************************************************************************************ -2022-10-09 21:13:48,929 p=89174 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:13:48,929 p=89174 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:13:53,934 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:13:53,941 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait, b/c the ping often failed without a short break] *********************************************************************** -2022-10-09 21:13:53,958 p=89177 u=rob n=ansible | Pausing for 30 seconds -2022-10-09 21:13:53,959 p=89177 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:14:23,965 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:14:23,976 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : do ping test] **************************************************************************************************************** -2022-10-09 21:14:24,012 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:24,012 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:29,355 p=88471 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) -2022-10-09 21:14:29,609 p=88471 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) -2022-10-09 21:14:29,613 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : execute test commands] ******************************************************************************************************* -2022-10-09 21:14:29,636 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:29,637 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:29,639 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:14:29,644 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:14:29,647 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : register stdout commands] **************************************************************************************************** -2022-10-09 21:14:29,664 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:29,665 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:33,930 p=88471 u=rob n=ansible | ok: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:14:33,932 p=88471 u=rob n=ansible | ok: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:14:43,393 p=88471 u=rob n=ansible | ok: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) -2022-10-09 21:14:43,398 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] *************************************************************************************************************** -2022-10-09 21:14:43,421 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:43,422 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:44,271 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:14:44,272 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:14:44,279 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Reboot vyos] ***************************************************************************************************************** -2022-10-09 21:14:44,309 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:44,317 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:45,582 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:14:45,582 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:14:45,585 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait while shutdown] ********************************************************************************************************* -2022-10-09 21:14:45,596 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:45,598 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Wait for vyos nodes] ********************************************************************************************************* -2022-10-09 21:14:45,613 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:14:45,619 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:14:47,178 p=89336 u=rob n=p=89336 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:14:47,185 p=89337 u=rob n=p=89337 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:14:47,281 p=89337 u=rob n=p=89337 u=rob | paramiko [client] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' -2022-10-09 21:14:47,330 p=89336 u=rob n=p=89336 u=rob | paramiko [vyos-wan] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' -2022-10-09 21:16:45,825 p=88724 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. +2023-06-07 09:52:55,172 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:52:55,172 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 09:52:55,177 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : debug] ******************************************************************************************************************************** +2023-06-07 09:52:55,194 p=78339 u=rob n=ansible | ok: [eveng] => { + "msg": "vyos-1.3.3-amd64" +} +2023-06-07 09:52:56,312 p=78339 u=rob n=ansible | ok: [vyos-oobm] => { + "msg": "vyos-1.3.3-amd64" +} +2023-06-07 09:52:56,312 p=78339 u=rob n=ansible | ok: [vyos-wan] => { + "msg": "vyos-1.3.3-amd64" +} +2023-06-07 09:52:56,312 p=78339 u=rob n=ansible | ok: [client] => { + "msg": "vyos-1.3.3-amd64" +} +2023-06-07 09:52:56,315 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: fail if node_template_version is empty] ********************************************************************************* +2023-06-07 09:52:56,329 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:56,338 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:56,340 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:56,344 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:56,348 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 09:52:56,366 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:56,369 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:56,373 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:57,009 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:57,013 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: get running lab list] *************************************************************************************************** +2023-06-07 09:52:57,034 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:57,037 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:57,042 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:57,432 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:57,436 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: kill running lab] ******************************************************************************************************* +2023-06-07 09:52:57,452 p=78339 u=rob n=ansible | [WARNING]: conditional statements should not include jinja2 templating delimiters such as {{ }} or {% %}. Found: item.labname == "{{ eve_ng_folder_name }}/{{ +lab }}" + +2023-06-07 09:52:57,454 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:57,456 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:57,458 p=78339 u=rob n=ansible | skipping: [eveng] => (item={'podid': '0', 'username': 'admin', 'online': '1', 'uuid': '0fc5edef-8cf2-4400-9a1c-0c4c41a1a881', 'size': 0.1996, 'sat': '0', 'sat_name': 'master', 'labid': 1001, 'labname': '/ecmp wireguard\n', 'cpu': 0, 'mem': 0}) +2023-06-07 09:52:57,462 p=78339 u=rob n=ansible | skipping: [eveng] => (item={'podid': '0', 'username': 'admin', 'online': '1', 'uuid': '9785926c-63ec-42c0-a1ca-a386b9013151', 'size': 0.4115, 'sat': '0', 'sat_name': 'master', 'labid': 1002, 'labname': '/layer2 via IPSec\n', 'cpu': 0, 'mem': 0}) +2023-06-07 09:52:57,464 p=78339 u=rob n=ansible | skipping: [eveng] => (item={'podid': '0', 'username': 'admin', 'online': '1', 'uuid': 'aa98095e-3b64-45aa-b883-e2b7fdfac08c', 'size': 0.7722, 'sat': '0', 'sat_name': 'master', 'labid': 1003, 'labname': '/ospf\n', 'cpu': 0, 'mem': 0}) +2023-06-07 09:52:57,466 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:52:57,467 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:57,470 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: delete existing lab] **************************************************************************************************** +2023-06-07 09:52:57,492 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:57,492 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:57,498 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:58,041 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:58,046 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Ensure labtest is present] ********************************************************************************************** +2023-06-07 09:52:58,070 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:58,073 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:58,079 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:58,287 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:58,292 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Create tunnelbroker Lab] ************************************************************************************************ +2023-06-07 09:52:58,311 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:58,313 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:58,319 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:58,796 p=78339 u=rob n=ansible | changed: [eveng] +2023-06-07 09:52:58,799 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 09:52:58,819 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:58,822 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:58,827 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:52:59,457 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:52:59,463 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: start vyos-oobm] ******************************************************************************************************** +2023-06-07 09:52:59,484 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:52:59,486 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:52:59,492 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:53:00,483 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:53:00,487 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Wait for vyos-oobm] ***************************************************************************************************** +2023-06-07 09:53:00,509 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:53:00,510 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:53:00,516 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:53:01,575 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 09:53:31,609 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:53:31,613 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Traceback (most recent call last): +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | buf = self.packetizer.readline(timeout) +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | buf += self._read_timeout(timeout) +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:53:31,614 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | raise socket.timeout() +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | socket.timeout +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | During handling of the above exception, another exception occurred: +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Traceback (most recent call last): +2023-06-07 09:53:31,615 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:53:31,616 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | self._check_banner() +2023-06-07 09:53:31,616 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:53:31,616 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | raise SSHException( +2023-06-07 09:53:31,616 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:53:31,616 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:54:02,632 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:54:02,635 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Traceback (most recent call last): +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | buf = self.packetizer.readline(timeout) +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | buf += self._read_timeout(timeout) +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:54:02,636 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | raise socket.timeout() +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | socket.timeout +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | During handling of the above exception, another exception occurred: +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Traceback (most recent call last): +2023-06-07 09:54:02,637 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:54:02,638 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | self._check_banner() +2023-06-07 09:54:02,638 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:54:02,638 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | raise SSHException( +2023-06-07 09:54:02,638 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:54:02,638 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | +2023-06-07 09:54:04,045 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:54:04,277 p=78683 u=rob n=p=78683 u=rob | paramiko [vyos-oobm] | Authentication (publickey) successful! +2023-06-07 09:54:05,394 p=78339 u=rob n=ansible | ok: [vyos-oobm] +2023-06-07 09:54:05,398 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 09:54:05,419 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:54:05,421 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:54:05,427 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:54:06,156 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:54:06,160 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: get lab status] ********************************************************************************************************* +2023-06-07 09:54:06,181 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:54:06,182 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:54:06,187 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:54:06,615 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:54:06,618 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: start all nodes] ******************************************************************************************************** +2023-06-07 09:54:06,640 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:54:06,643 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:54:06,648 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:54:07,556 p=78339 u=rob n=ansible | ok: [eveng] => (item=4) +2023-06-07 09:54:08,065 p=78339 u=rob n=ansible | ok: [eveng] => (item=1) +2023-06-07 09:54:09,294 p=78339 u=rob n=ansible | ok: [eveng] => (item=2) +2023-06-07 09:54:09,299 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Wait for vyos nodes] **************************************************************************************************** +2023-06-07 09:54:09,314 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:54:09,322 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:54:10,441 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 09:54:10,441 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 09:54:40,453 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:54:40,456 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:54:40,457 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | raise socket.timeout() +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | socket.timeout +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 09:54:40,458 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:54:40,459 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | self._check_banner() +2023-06-07 09:54:40,459 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:54:40,459 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | raise SSHException( +2023-06-07 09:54:40,459 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:54:40,459 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:54:40,477 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:54:40,480 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 09:54:40,480 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:54:40,480 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | buf += self._read_timeout(timeout) +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | raise socket.timeout() +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | socket.timeout +2023-06-07 09:54:40,481 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | self._check_banner() +2023-06-07 09:54:40,482 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:54:40,483 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | raise SSHException( +2023-06-07 09:54:40,483 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:54:40,483 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:55:00,951 p=78572 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. See the timeout setting options in the Network Debug and Troubleshooting Guide. -2022-10-09 21:16:45,826 p=88726 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. +2023-06-07 09:55:01,057 p=78572 u=rob n=ansible | shutdown complete +2023-06-07 09:55:11,494 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:55:11,494 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 09:55:11,494 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | raise socket.timeout() +2023-06-07 09:55:11,495 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | socket.timeout +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:55:11,496 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | self._check_banner() +2023-06-07 09:55:11,497 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:55:11,497 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | raise SSHException( +2023-06-07 09:55:11,497 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:55:11,497 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | +2023-06-07 09:55:11,504 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Exception (client): Error reading SSH protocol banner +2023-06-07 09:55:11,504 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 09:55:11,505 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 09:55:11,505 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) +2023-06-07 09:55:11,505 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 09:55:11,505 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | buf += self._read_timeout(timeout) +2023-06-07 09:55:11,505 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | raise socket.timeout() +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | socket.timeout +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:55:11,506 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | self._check_banner() +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | raise SSHException( +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 09:55:11,507 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | +2023-06-07 09:55:13,091 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:55:13,099 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:55:13,329 p=78784 u=rob n=p=78784 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 09:55:13,329 p=78785 u=rob n=p=78785 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 09:55:14,484 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:55:14,484 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 09:55:14,486 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : setup nodes] ************************************************************************************************************************** +2023-06-07 09:55:14,501 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:14,508 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:15,647 p=78569 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 09:55:15,656 p=78574 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 09:55:16,096 p=78574 u=rob n=p=78574 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:55:16,107 p=78569 u=rob n=p=78569 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:55:16,360 p=78574 u=rob n=p=78574 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 09:55:16,362 p=78569 u=rob n=p=78569 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 09:55:20,861 p=78339 u=rob n=ansible | [WARNING]: To ensure idempotency and correct diff the input configuration lines should be similar to how they appear if present in the running configuration +on device including the indentation + +2023-06-07 09:55:20,862 p=78339 u=rob n=ansible | changed: [client] +2023-06-07 09:55:21,231 p=78339 u=rob n=ansible | changed: [vyos-wan] +2023-06-07 09:55:21,241 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : Register external IP in Tunnelbroker] ************************************************************************************************* +2023-06-07 09:55:21,268 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:21,277 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:21,287 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:23,221 p=78339 u=rob n=ansible | ok: [vyos-wan -> eveng(eve-ng)] +2023-06-07 09:55:23,228 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : get ipv4 address of vyos-wan (eth1)] ************************************************************************************************** +2023-06-07 09:55:23,247 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:23,255 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:23,263 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:24,452 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:55:24,458 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] ************************************************************************************************************************ +2023-06-07 09:55:24,479 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:24,485 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:24,494 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:25,564 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:55:25,571 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : setup vyos-wan] *********************************************************************************************************************** +2023-06-07 09:55:25,589 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:25,599 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:25,608 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:32,809 p=78339 u=rob n=ansible | changed: [vyos-wan] +2023-06-07 09:55:32,819 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 09:55:32,848 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:32,851 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:55:32,858 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:33,466 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:55:33,470 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: stop nodes id] ********************************************************************************************************** +2023-06-07 09:55:33,491 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:33,495 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:33,500 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:55:33,506 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:33,510 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after stop] ******************************************************************************************************** +2023-06-07 09:55:33,522 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 09:55:33,523 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:55:38,531 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:55:38,536 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: start nodes id] ********************************************************************************************************* +2023-06-07 09:55:38,560 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:55:38,566 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:55:38,570 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:55:38,574 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:55:38,578 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after start] ******************************************************************************************************* +2023-06-07 09:55:38,590 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 09:55:38,591 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:55:43,598 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:55:43,603 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait, b/c the ping often failed without a short break] ****************************************************************** +2023-06-07 09:55:43,616 p=78339 u=rob n=ansible | Pausing for 30 seconds +2023-06-07 09:55:43,617 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:56:13,627 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:56:13,637 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: do ping test] *********************************************************************************************************** +2023-06-07 09:56:13,668 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:13,671 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:19,045 p=78339 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) +2023-06-07 09:56:19,311 p=78339 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) +2023-06-07 09:56:19,317 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: execute test commands] ************************************************************************************************** +2023-06-07 09:56:19,338 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:19,340 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:19,346 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:56:19,352 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:56:19,356 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: register stdout commands] *********************************************************************************************** +2023-06-07 09:56:19,379 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:19,382 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:23,793 p=78339 u=rob n=ansible | ok: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 09:56:23,799 p=78339 u=rob n=ansible | ok: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 09:56:28,367 p=78339 u=rob n=ansible | ok: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) +2023-06-07 09:56:28,375 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Set variables] ********************************************************************************************************** +2023-06-07 09:56:28,400 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:28,404 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:29,513 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 09:56:29,513 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:56:29,519 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Reboot vyos] ************************************************************************************************************ +2023-06-07 09:56:29,535 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:29,542 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:30,864 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:56:30,871 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 09:56:30,875 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait while shutdown] **************************************************************************************************** +2023-06-07 09:56:30,886 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:30,889 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Wait for vyos nodes] **************************************************************************************************** +2023-06-07 09:56:30,902 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:56:30,909 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:56:32,014 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 09:56:32,014 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 09:56:32,468 p=78958 u=rob n=p=78958 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:56:32,471 p=78959 u=rob n=p=78959 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:56:32,693 p=78959 u=rob n=p=78959 u=rob | paramiko [client] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' +2023-06-07 09:56:32,806 p=78958 u=rob n=p=78958 u=rob | paramiko [vyos-wan] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' +2023-06-07 09:58:31,383 p=78569 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. See the timeout setting options in the Network Debug and Troubleshooting Guide. -2022-10-09 21:16:45,929 p=88724 u=rob n=ansible | shutdown complete -2022-10-09 21:16:45,929 p=88726 u=rob n=ansible | shutdown complete -2022-10-09 21:16:48,915 p=89336 u=rob n=p=89336 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:16:48,916 p=89337 u=rob n=p=89337 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:16:49,004 p=89337 u=rob n=p=89337 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:16:49,068 p=89336 u=rob n=p=89336 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:16:50,173 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:16:50,191 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:16:50,193 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:16:50,212 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:16:50,214 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:16:50,218 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:16:51,402 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:16:51,409 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : stop nodes id] *************************************************************************************************************** -2022-10-09 21:16:51,456 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after stop] ************************************************************************************************************* -2022-10-09 21:16:51,465 p=89477 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:16:51,465 p=89477 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:16:56,476 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:16:56,483 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : start nodes id] ************************************************************************************************************** -2022-10-09 21:16:56,532 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after start] ************************************************************************************************************ -2022-10-09 21:16:56,541 p=89492 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:16:56,542 p=89492 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:17:01,551 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:17:01,558 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait, b/c the ping often failed without a short break] *********************************************************************** -2022-10-09 21:17:01,572 p=89493 u=rob n=ansible | Pausing for 30 seconds -2022-10-09 21:17:01,573 p=89493 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:17:31,581 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:17:31,588 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : do ping test] **************************************************************************************************************** -2022-10-09 21:17:31,620 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:17:31,621 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:32,508 p=89513 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:17:32,508 p=89514 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:17:33,435 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:33,436 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:33,517 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:17:33,619 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:17:39,191 p=88471 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) -2022-10-09 21:17:39,562 p=88471 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) -2022-10-09 21:17:39,567 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : execute test commands] ******************************************************************************************************* -2022-10-09 21:17:39,586 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:17:39,593 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:39,597 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:17:39,603 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:17:39,605 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : register stdout commands] **************************************************************************************************** -2022-10-09 21:17:39,626 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:17:39,626 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:39,634 p=88471 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:17:39,634 p=88471 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) -2022-10-09 21:17:39,638 p=88471 u=rob n=ansible | skipping: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:17:39,641 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] *************************************************************************************************************** -2022-10-09 21:17:39,658 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:17:39,659 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:39,661 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:17:39,667 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:17:39,670 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : make sure tmp dir exist] ***************************************************************************************************** -2022-10-09 21:17:39,693 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:39,695 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:17:39,701 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:17:39,869 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:17:39,872 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : register status of tmp/vyos-1.4-rolling-202210050218-amd64.iso] ************************************************************** -2022-10-09 21:17:39,890 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:39,892 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:17:39,897 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:17:40,160 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:17:40,165 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Download upgrade_iso] ******************************************************************************************************** -2022-10-09 21:17:40,194 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:40,196 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:17:40,203 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:17:42,889 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:17:42,895 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Copy iso to host] ************************************************************************************************************ -2022-10-09 21:17:42,911 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:17:42,918 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:17:44,477 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:44,485 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:44,555 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:17:44,600 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:17:45,392 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:45,442 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:17:45,478 p=89521 u=rob n=p=89521 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:17:45,552 p=89522 u=rob n=p=89522 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:18:08,437 p=88471 u=rob n=ansible | changed: [client] -2022-10-09 21:18:08,748 p=88471 u=rob n=ansible | changed: [vyos-wan] -2022-10-09 21:18:08,750 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : upgrade vyos] **************************************************************************************************************** -2022-10-09 21:18:08,764 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:18:08,771 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:18:19,147 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:18:19,158 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:18:19,162 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Reboot vyos] ***************************************************************************************************************** -2022-10-09 21:18:19,177 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:18:19,183 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:18:20,662 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:18:20,666 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:18:20,669 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait while shutdown] ********************************************************************************************************* -2022-10-09 21:18:20,680 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:18:20,683 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Wait for vyos nodes] ********************************************************************************************************* -2022-10-09 21:18:20,696 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:18:20,702 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:18:22,336 p=89761 u=rob n=p=89761 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:18:22,344 p=89762 u=rob n=p=89762 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) -2022-10-09 21:18:22,452 p=89762 u=rob n=p=89762 u=rob | paramiko [client] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' -2022-10-09 21:18:22,513 p=89761 u=rob n=p=89761 u=rob | paramiko [vyos-wan] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' -2022-10-09 21:20:20,908 p=89522 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. +2023-06-07 09:58:31,383 p=78574 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. See the timeout setting options in the Network Debug and Troubleshooting Guide. -2022-10-09 21:20:20,912 p=89521 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. +2023-06-07 09:58:31,487 p=78569 u=rob n=ansible | shutdown complete +2023-06-07 09:58:31,487 p=78574 u=rob n=ansible | shutdown complete +2023-06-07 09:58:34,263 p=78959 u=rob n=p=78959 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:58:34,268 p=78958 u=rob n=p=78958 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:58:34,502 p=78959 u=rob n=p=78959 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 09:58:34,531 p=78958 u=rob n=p=78958 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 09:58:35,773 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 09:58:35,810 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 09:58:35,814 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 09:58:35,835 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:58:35,837 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:58:35,843 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:58:36,797 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:58:36,807 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: stop nodes id] ********************************************************************************************************** +2023-06-07 09:58:36,840 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:58:36,844 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:58:36,850 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:58:36,855 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:58:36,859 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after stop] ******************************************************************************************************** +2023-06-07 09:58:36,873 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 09:58:36,874 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:58:41,890 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:58:41,898 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: start nodes id] ********************************************************************************************************* +2023-06-07 09:58:41,925 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:58:41,932 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:58:41,933 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:58:41,936 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:58:41,939 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after start] ******************************************************************************************************* +2023-06-07 09:58:41,953 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 09:58:41,953 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:58:46,964 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:58:46,977 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait, b/c the ping often failed without a short break] ****************************************************************** +2023-06-07 09:58:46,999 p=78339 u=rob n=ansible | Pausing for 30 seconds +2023-06-07 09:58:47,000 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 09:59:17,021 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:59:17,034 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: do ping test] *********************************************************************************************************** +2023-06-07 09:59:17,067 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:59:17,070 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:18,228 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 09:59:18,228 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko + +2023-06-07 09:59:18,229 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 09:59:18,229 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko + +2023-06-07 09:59:18,234 p=79082 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 09:59:18,234 p=79083 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 09:59:18,776 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:59:18,779 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 09:59:19,015 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 09:59:19,071 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 09:59:24,641 p=78339 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) +2023-06-07 09:59:25,923 p=78339 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) +2023-06-07 09:59:25,933 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: execute test commands] ************************************************************************************************** +2023-06-07 09:59:25,958 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:59:25,961 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:25,967 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:25,976 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:59:25,981 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: register stdout commands] *********************************************************************************************** +2023-06-07 09:59:26,003 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:59:26,006 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:26,013 p=78339 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 09:59:26,017 p=78339 u=rob n=ansible | skipping: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 09:59:26,019 p=78339 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) +2023-06-07 09:59:26,021 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:59:26,021 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:26,025 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Set variables] ********************************************************************************************************** +2023-06-07 09:59:26,044 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 09:59:26,047 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:26,051 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:26,056 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:59:26,060 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: ake sure tmp dir exist] ************************************************************************************************* +2023-06-07 09:59:26,085 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:26,087 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:26,093 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:59:26,333 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 09:59:26,338 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: register status of tmp/vyos-1.4-rolling-202306070317-amd64.iso] ********************************************************* +2023-06-07 09:59:26,357 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:26,359 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:26,366 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 09:59:26,496 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 09:59:26,505 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: ownload upgrade_iso] **************************************************************************************************** +2023-06-07 09:59:26,534 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 09:59:26,534 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 09:59:26,541 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:00:14,897 p=78339 u=rob n=ansible | changed: [eveng -> localhost] +2023-06-07 10:00:14,902 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Copy iso to host] ******************************************************************************************************* +2023-06-07 10:00:14,917 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:00:14,924 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:00:16,550 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:16,556 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:16,782 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 10:00:16,837 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 10:00:17,340 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:17,520 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:17,570 p=79082 u=rob n=p=79082 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 10:00:17,773 p=79083 u=rob n=p=79083 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 10:00:35,590 p=78339 u=rob n=ansible | changed: [client] +2023-06-07 10:00:35,785 p=78339 u=rob n=ansible | changed: [vyos-wan] +2023-06-07 10:00:35,793 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: upgrade vyos] *********************************************************************************************************** +2023-06-07 10:00:35,819 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:00:35,829 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:00:47,182 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 10:00:47,225 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 10:00:47,229 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Reboot vyos] ************************************************************************************************************ +2023-06-07 10:00:47,246 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:00:47,254 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:00:48,881 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 10:00:48,911 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 10:00:48,917 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait while shutdown] **************************************************************************************************** +2023-06-07 10:00:48,935 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:00:48,939 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Wait for vyos nodes] **************************************************************************************************** +2023-06-07 10:00:48,954 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:00:48,962 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:00:50,092 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 10:00:50,093 p=78339 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use ansible.utils.display.Display.verbosity instead. This feature will be removed in version 2.18. + Deprecation warnings can be disabled by setting deprecation_warnings=False in ansible.cfg. +2023-06-07 10:00:50,799 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:50,803 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_7.9p1) +2023-06-07 10:00:51,058 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' +2023-06-07 10:00:51,101 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Auth banner: b'System is going down. Unprivileged users are not permitted to log in anymore. For technical details, see pam_nologin(8).\n\n' +2023-06-07 10:02:49,456 p=79083 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. See the timeout setting options in the Network Debug and Troubleshooting Guide. -2022-10-09 21:20:21,014 p=89522 u=rob n=ansible | shutdown complete -2022-10-09 21:20:21,015 p=89521 u=rob n=ansible | shutdown complete -2022-10-09 21:20:24,287 p=89761 u=rob n=p=89761 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_8.4p1) -2022-10-09 21:20:24,288 p=89762 u=rob n=p=89762 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_8.4p1) -2022-10-09 21:20:24,355 p=89762 u=rob n=p=89762 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:20:24,405 p=89761 u=rob n=p=89761 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:20:25,466 p=88471 u=rob n=ansible | ok: [client] -2022-10-09 21:20:25,489 p=88471 u=rob n=ansible | ok: [vyos-wan] -2022-10-09 21:20:25,492 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : clear tmp dir] *************************************************************************************************************** -2022-10-09 21:20:25,500 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:20:25,502 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Login to EVE-NG and get Cookie] ********************************************************************************************** -2022-10-09 21:20:25,524 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:20:25,526 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:20:25,530 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:20:26,778 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:20:26,782 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : stop nodes id] *************************************************************************************************************** -2022-10-09 21:20:26,826 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after stop] ************************************************************************************************************* -2022-10-09 21:20:26,836 p=89875 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:20:26,836 p=89875 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:20:31,844 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:20:31,850 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : start nodes id] ************************************************************************************************************** -2022-10-09 21:20:31,898 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait after start] ************************************************************************************************************ -2022-10-09 21:20:31,908 p=89880 u=rob n=ansible | Pausing for 5 seconds -2022-10-09 21:20:31,908 p=89880 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:20:36,917 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:20:36,924 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : wait, b/c the ping often failed without a short break] *********************************************************************** -2022-10-09 21:20:36,941 p=89882 u=rob n=ansible | Pausing for 30 seconds -2022-10-09 21:20:36,942 p=89882 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
-2022-10-09 21:21:06,949 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:21:06,956 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : do ping test] **************************************************************************************************************** -2022-10-09 21:21:06,987 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:21:06,988 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:07,863 p=89901 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:21:07,863 p=89902 u=rob n=ansible | network_os is set to vyos -2022-10-09 21:21:08,477 p=89910 u=rob n=p=89910 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_8.4p1) -2022-10-09 21:21:08,484 p=89908 u=rob n=p=89908 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_8.4p1) -2022-10-09 21:21:08,555 p=89910 u=rob n=p=89910 u=rob | paramiko [client] | Authentication (publickey) successful! -2022-10-09 21:21:08,587 p=89908 u=rob n=p=89908 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! -2022-10-09 21:21:14,311 p=88471 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) -2022-10-09 21:21:14,628 p=88471 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) -2022-10-09 21:21:14,633 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : execute test commands] ******************************************************************************************************* -2022-10-09 21:21:14,656 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:21:14,658 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:14,661 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:14,667 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:14,670 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : register stdout commands] **************************************************************************************************** -2022-10-09 21:21:14,691 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:21:14,692 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:14,700 p=88471 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:21:14,700 p=88471 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) -2022-10-09 21:21:14,703 p=88471 u=rob n=ansible | skipping: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) -2022-10-09 21:21:14,706 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] *************************************************************************************************************** -2022-10-09 21:21:14,724 p=88471 u=rob n=ansible | skipping: [eveng] -2022-10-09 21:21:14,725 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:14,728 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:14,733 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:14,736 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : make sure output dir exist] ************************************************************************************************** -2022-10-09 21:21:14,758 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:14,759 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:14,764 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:14,871 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:21:14,874 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : make sure output include dir exist] ****************************************************************************************** -2022-10-09 21:21:14,896 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:14,897 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:14,901 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:15,004 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:21:15,006 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Get timestamp from the system] *********************************************************************************************** -2022-10-09 21:21:15,026 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:15,027 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:15,031 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:15,361 p=88471 u=rob n=ansible | changed: [eveng] -2022-10-09 21:21:15,365 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : Set variables] *************************************************************************************************************** -2022-10-09 21:21:15,386 p=88471 u=rob n=ansible | ok: [eveng] -2022-10-09 21:21:15,388 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:15,388 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:15,392 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:15,396 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : generate lab rst file] ******************************************************************************************************* -2022-10-09 21:21:15,419 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:15,420 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:15,425 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:15,648 p=88471 u=rob n=ansible | changed: [eveng -> localhost] -2022-10-09 21:21:15,651 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : find all *.conf files in Lab] ************************************************************************************************ -2022-10-09 21:21:15,672 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:15,673 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:15,677 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:15,841 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:21:15,850 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : copy all *.conf files] ******************************************************************************************************* -2022-10-09 21:21:15,874 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:15,876 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:15,881 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:16,153 p=88471 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/vyos-wan_tun0.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 950, 'inode': 52954916, 'dev': 16777231, 'nlink': 1, 'atime': 1657653703.607407, 'mtime': 1657653702.1468472, 'ctime': 1657653702.1468472, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) -2022-10-09 21:21:16,376 p=88471 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/client.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 50, 'inode': 43214260, 'dev': 16777231, 'nlink': 1, 'atime': 1648155112.2819037, 'mtime': 1648155110.8026924, 'ctime': 1648155110.8026924, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) -2022-10-09 21:21:16,598 p=88471 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/vyos-wan.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 42, 'inode': 43214267, 'dev': 16777231, 'nlink': 1, 'atime': 1648155112.2896059, 'mtime': 1648155110.8037791, 'ctime': 1648155110.8037791, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) -2022-10-09 21:21:16,820 p=88471 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/transport.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 107, 'inode': 43214264, 'dev': 16777231, 'nlink': 1, 'atime': 1648155112.290648, 'mtime': 1648155110.8034177, 'ctime': 1648155110.8034177, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) -2022-10-09 21:21:16,824 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : find all *.png files in Lab] ************************************************************************************************* -2022-10-09 21:21:16,845 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:16,846 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:16,850 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:16,952 p=88471 u=rob n=ansible | ok: [eveng -> localhost] -2022-10-09 21:21:16,956 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : copy all *.png files] ******************************************************************************************************** -2022-10-09 21:21:16,981 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:16,983 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:16,988 p=88471 u=rob n=ansible | skipping: [client] -2022-10-09 21:21:17,185 p=88471 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/topology.png', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 34614, 'inode': 43214263, 'dev': 16777231, 'nlink': 1, 'atime': 1648155124.1539645, 'mtime': 1648155110.8032978, 'ctime': 1648155110.8032978, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) -2022-10-09 21:21:17,190 p=88471 u=rob n=ansible | TASK [eve-ng-lab-test : copy ansible log files] ****************************************************************************************************** -2022-10-09 21:21:17,210 p=88471 u=rob n=ansible | skipping: [vyos-oobm] -2022-10-09 21:21:17,211 p=88471 u=rob n=ansible | skipping: [vyos-wan] -2022-10-09 21:21:17,215 p=88471 u=rob n=ansible | skipping: [client] +2023-06-07 10:02:49,456 p=79082 u=rob n=ansible | persistent connection idle timeout triggered, timeout value is 120 secs. +See the timeout setting options in the Network Debug and Troubleshooting Guide. +2023-06-07 10:02:49,561 p=79083 u=rob n=ansible | shutdown complete +2023-06-07 10:02:49,561 p=79082 u=rob n=ansible | shutdown complete +2023-06-07 10:03:22,120 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Exception (client): Error reading SSH protocol banner +2023-06-07 10:03:22,120 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Exception (client): Error reading SSH protocol banner +2023-06-07 10:03:22,124 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 10:03:22,124 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 10:03:22,124 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 10:03:22,124 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2270, in _check_banner +2023-06-07 10:03:22,124 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | buf = self.packetizer.readline(timeout) +2023-06-07 10:03:22,124 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | buf = self.packetizer.readline(timeout) +2023-06-07 10:03:22,124 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 10:03:22,124 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 374, in readline +2023-06-07 10:03:22,125 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | buf += self._read_timeout(timeout) +2023-06-07 10:03:22,125 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | buf += self._read_timeout(timeout) +2023-06-07 10:03:22,125 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 10:03:22,125 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/packet.py", line 611, in _read_timeout +2023-06-07 10:03:22,125 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | raise socket.timeout() +2023-06-07 10:03:22,125 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | raise socket.timeout() +2023-06-07 10:03:22,125 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | socket.timeout +2023-06-07 10:03:22,125 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | socket.timeout +2023-06-07 10:03:22,125 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | +2023-06-07 10:03:22,125 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | During handling of the above exception, another exception occurred: +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | During handling of the above exception, another exception occurred: +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Traceback (most recent call last): +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Traceback (most recent call last): +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2093, in run +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | self._check_banner() +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | self._check_banner() +2023-06-07 10:03:22,126 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 10:03:22,126 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | File "/Users/rob/.local/share/virtualenvs/vyos-eveng-b9X2mBdh/lib/python3.9/site-packages/paramiko/transport.py", line 2274, in _check_banner +2023-06-07 10:03:22,127 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | raise SSHException( +2023-06-07 10:03:22,127 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | raise SSHException( +2023-06-07 10:03:22,127 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 10:03:22,127 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | paramiko.ssh_exception.SSHException: Error reading SSH protocol banner +2023-06-07 10:03:22,127 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | +2023-06-07 10:03:22,127 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | +2023-06-07 10:03:23,576 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_9.2p1) +2023-06-07 10:03:23,592 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_9.2p1) +2023-06-07 10:03:23,817 p=79200 u=rob n=p=79200 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 10:03:23,839 p=79199 u=rob n=p=79199 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 10:03:24,923 p=78339 u=rob n=ansible | ok: [vyos-wan] +2023-06-07 10:03:25,293 p=78339 u=rob n=ansible | ok: [client] +2023-06-07 10:03:25,297 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: clear tmp dir] ********************************************************************************************************** +2023-06-07 10:03:25,309 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:03:25,312 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Login to EVE-NG and get Cookie] ***************************************************************************************** +2023-06-07 10:03:25,331 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:03:25,333 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:03:25,339 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:03:26,336 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 10:03:26,340 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: stop nodes id] ********************************************************************************************************** +2023-06-07 10:03:26,369 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:03:26,369 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:03:26,373 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:03:26,382 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:03:26,386 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after stop] ******************************************************************************************************** +2023-06-07 10:03:26,401 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 10:03:26,401 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 10:03:31,417 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 10:03:31,429 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: start nodes id] ********************************************************************************************************* +2023-06-07 10:03:31,457 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:03:31,460 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:03:31,466 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:03:31,470 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:03:31,474 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait after start] ******************************************************************************************************* +2023-06-07 10:03:31,488 p=78339 u=rob n=ansible | Pausing for 5 seconds +2023-06-07 10:03:31,488 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 10:03:36,514 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 10:03:36,523 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: wait, b/c the ping often failed without a short break] ****************************************************************** +2023-06-07 10:03:36,553 p=78339 u=rob n=ansible | Pausing for 30 seconds +2023-06-07 10:03:36,554 p=78339 u=rob n=ansible | (ctrl+C then 'C' = continue early, ctrl+C then 'A' = abort)
+2023-06-07 10:04:06,570 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 10:04:06,583 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: do ping test] *********************************************************************************************************** +2023-06-07 10:04:06,619 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:04:06,622 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:07,790 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 10:04:07,790 p=78339 u=rob n=ansible | network_os is set to vyos +2023-06-07 10:04:07,790 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko + +2023-06-07 10:04:07,790 p=78339 u=rob n=ansible | [WARNING]: ansible-pylibssh not installed, falling back to paramiko + +2023-06-07 10:04:07,795 p=79337 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 10:04:07,795 p=79336 u=rob n=ansible | [DEPRECATION WARNING]: PlayContext.verbosity is deprecated, use +ansible.utils.display.Display.verbosity instead. This feature will be removed +in version 2.18. Deprecation warnings can be disabled by setting +deprecation_warnings=False in ansible.cfg. +2023-06-07 10:04:08,284 p=79336 u=rob n=p=79336 u=rob | paramiko [client] | Connected (version 2.0, client OpenSSH_9.2p1) +2023-06-07 10:04:08,302 p=79337 u=rob n=p=79337 u=rob | paramiko [vyos-wan] | Connected (version 2.0, client OpenSSH_9.2p1) +2023-06-07 10:04:08,551 p=79336 u=rob n=p=79336 u=rob | paramiko [client] | Authentication (publickey) successful! +2023-06-07 10:04:08,608 p=79337 u=rob n=p=79337 u=rob | paramiko [vyos-wan] | Authentication (publickey) successful! +2023-06-07 10:04:14,474 p=78339 u=rob n=ansible | ok: [client] => (item=2001:470:20::2) +2023-06-07 10:04:14,629 p=78339 u=rob n=ansible | ok: [vyos-wan] => (item=tunnelbroker.net) +2023-06-07 10:04:14,636 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: execute test commands] ************************************************************************************************** +2023-06-07 10:04:14,658 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:04:14,662 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:14,665 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:14,672 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:14,675 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: register stdout commands] *********************************************************************************************** +2023-06-07 10:04:14,698 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:04:14,702 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:14,707 p=78339 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 10:04:14,710 p=78339 u=rob n=ansible | skipping: [client] => (item={'name': 'ping_ip', 'command': 'ping 2001:470:20::2 count 4'}) +2023-06-07 10:04:14,713 p=78339 u=rob n=ansible | skipping: [vyos-wan] => (item={'name': 'ping_name', 'command': 'ping tunnelbroker.net count 4'}) +2023-06-07 10:04:14,714 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:14,715 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:14,719 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Set variables] ********************************************************************************************************** +2023-06-07 10:04:14,739 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:04:14,742 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:14,746 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:14,751 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:14,756 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: make sure output dir exist] ********************************************************************************************* +2023-06-07 10:04:14,781 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:14,783 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:14,788 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:15,039 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 10:04:15,044 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: make sure output include dir exist] ************************************************************************************* +2023-06-07 10:04:15,069 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:15,071 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:15,077 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:15,331 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 10:04:15,335 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Get timestamp from the system] ****************************************************************************************** +2023-06-07 10:04:15,354 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:15,356 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:15,362 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:15,560 p=78339 u=rob n=ansible | changed: [eveng] +2023-06-07 10:04:15,564 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: Set variables] ********************************************************************************************************** +2023-06-07 10:04:15,584 p=78339 u=rob n=ansible | ok: [eveng] +2023-06-07 10:04:15,584 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:15,586 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:15,591 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:15,595 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: generate lab rst file] ************************************************************************************************** +2023-06-07 10:04:15,617 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:15,619 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:15,625 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:16,123 p=78339 u=rob n=ansible | changed: [eveng -> localhost] +2023-06-07 10:04:16,128 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: find all *.conf files in Lab] ******************************************************************************************* +2023-06-07 10:04:16,152 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:16,155 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:16,160 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:16,470 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 10:04:16,477 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: copy all *.conf files] ************************************************************************************************** +2023-06-07 10:04:16,502 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:16,504 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:16,510 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:16,974 p=78339 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/vyos-wan_tun0.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 950, 'inode': 11035499, 'dev': 16777229, 'nlink': 1, 'atime': 1686123270.5003128, 'mtime': 1686123269.0263066, 'ctime': 1686123269.0263066, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) +2023-06-07 10:04:17,450 p=78339 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/client.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 50, 'inode': 362570, 'dev': 16777229, 'nlink': 1, 'atime': 1675370047.0673313, 'mtime': 1648155110.0, 'ctime': 1675368464.8110585, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) +2023-06-07 10:04:17,918 p=78339 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/vyos-wan.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 42, 'inode': 362571, 'dev': 16777229, 'nlink': 1, 'atime': 1675370047.0698297, 'mtime': 1648155110.0, 'ctime': 1675368464.8110874, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) +2023-06-07 10:04:18,391 p=78339 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/transport.conf', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 107, 'inode': 362574, 'dev': 16777229, 'nlink': 1, 'atime': 1675370047.104907, 'mtime': 1648155110.0, 'ctime': 1675368464.8111699, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) +2023-06-07 10:04:18,397 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: find all *.config files in Lab] ***************************************************************************************** +2023-06-07 10:04:18,422 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:18,422 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:18,427 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:18,653 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 10:04:18,657 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: copy all *.config files] ************************************************************************************************ +2023-06-07 10:04:18,675 p=78339 u=rob n=ansible | skipping: [eveng] +2023-06-07 10:04:18,684 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:18,686 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:18,691 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:18,696 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: find all *.png files in Lab] ******************************************************************************************** +2023-06-07 10:04:18,721 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:18,721 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:18,727 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:18,955 p=78339 u=rob n=ansible | ok: [eveng -> localhost] +2023-06-07 10:04:18,961 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: copy all *.png files] *************************************************************************************************** +2023-06-07 10:04:18,986 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:18,988 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:18,994 p=78339 u=rob n=ansible | skipping: [client] +2023-06-07 10:04:19,451 p=78339 u=rob n=ansible | ok: [eveng -> localhost] => (item={'path': 'labs/tunnelbroker/topology.png', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 501, 'gid': 20, 'size': 34614, 'inode': 362567, 'dev': 16777229, 'nlink': 1, 'atime': 1676403697.1329076, 'mtime': 1648155110.0, 'ctime': 1675368464.8109767, 'gr_name': 'staff', 'pw_name': 'rob', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False}) +2023-06-07 10:04:19,457 p=78339 u=rob n=ansible | TASK [eve-ng-lab-test : tunnelbroker: copy ansible log files] ************************************************************************************************* +2023-06-07 10:04:19,480 p=78339 u=rob n=ansible | skipping: [vyos-oobm] +2023-06-07 10:04:19,482 p=78339 u=rob n=ansible | skipping: [vyos-wan] +2023-06-07 10:04:19,488 p=78339 u=rob n=ansible | skipping: [client] diff --git a/docs/configexamples/autotest/tunnelbroker/tunnelbroker.rst b/docs/configexamples/autotest/tunnelbroker/tunnelbroker.rst index 229c8e4c..7dd435b5 100644 --- a/docs/configexamples/autotest/tunnelbroker/tunnelbroker.rst +++ b/docs/configexamples/autotest/tunnelbroker/tunnelbroker.rst @@ -4,9 +4,9 @@ Tunnelbroker.net (IPv6) ####################### -| Testdate: 2022-10-09 -| Version: 1.3.2 -| Upgrade Version: 1.4-rolling-202210050218 +| Testdate: 2023-06-07 +| Version: 1.3.3 +| Upgrade Version: 1.4-rolling-202306070317 This guide walks through the setup of https://www.tunnelbroker.net/ for an IPv6 Tunnel. @@ -62,14 +62,14 @@ Now you should be able to ping a public IPv6 Address vyos@vyos-wan:~$ ping 2001:470:20::2 count 4 PING 2001:470:20::2(2001:470:20::2) 56 data bytes - 64 bytes from 2001:470:20::2: icmp_seq=1 ttl=64 time=29.6 ms - 64 bytes from 2001:470:20::2: icmp_seq=2 ttl=64 time=44.2 ms - 64 bytes from 2001:470:20::2: icmp_seq=3 ttl=64 time=29.6 ms - 64 bytes from 2001:470:20::2: icmp_seq=4 ttl=64 time=29.6 ms + 64 bytes from 2001:470:20::2: icmp_seq=1 ttl=64 time=30.2 ms + 64 bytes from 2001:470:20::2: icmp_seq=2 ttl=64 time=29.5 ms + 64 bytes from 2001:470:20::2: icmp_seq=3 ttl=64 time=29.5 ms + 64 bytes from 2001:470:20::2: icmp_seq=4 ttl=64 time=89.3 ms --- 2001:470:20::2 ping statistics --- 4 packets transmitted, 4 received, 0% packet loss, time 8ms - rtt min/avg/max/mdev = 29.569/33.245/44.180/6.314 ms + rtt min/avg/max/mdev = 29.477/44.626/89.337/25.815 ms Assuming the pings are successful, you need to add some DNS servers. @@ -86,13 +86,14 @@ You should now be able to ping something by IPv6 DNS name: vyos@vyos-wan:~$ ping tunnelbroker.net count 4 PING tunnelbroker.net(tunnelbroker.net (2001:470:0:63::2)) 56 data bytes - 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=1 ttl=48 time=182 ms - 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=2 ttl=48 time=181 ms - 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=3 ttl=48 time=181 ms + 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=1 ttl=46 time=173 ms + 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=2 ttl=46 time=260 ms + 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=3 ttl=46 time=172 ms + 64 bytes from tunnelbroker.net (2001:470:0:63::2): icmp_seq=4 ttl=46 time=211 ms --- tunnelbroker.net ping statistics --- - 4 packets transmitted, 3 received, 25% packet loss, time 7ms - rtt min/avg/max/mdev = 180.947/181.168/181.609/0.311 ms + 4 packets transmitted, 4 received, 0% packet loss, time 7ms + rtt min/avg/max/mdev = 172.288/203.911/260.112/36.010 ms ***************** @@ -148,14 +149,14 @@ Now the Client is able to ping a public IPv6 address vyos@client:~$ ping 2001:470:20::2 count 4 PING 2001:470:20::2(2001:470:20::2) 56 data bytes - 64 bytes from 2001:470:20::2: icmp_seq=1 ttl=63 time=30.0 ms - 64 bytes from 2001:470:20::2: icmp_seq=2 ttl=63 time=29.4 ms - 64 bytes from 2001:470:20::2: icmp_seq=3 ttl=63 time=29.5 ms - 64 bytes from 2001:470:20::2: icmp_seq=4 ttl=63 time=29.7 ms + 64 bytes from 2001:470:20::2: icmp_seq=1 ttl=63 time=30.9 ms + 64 bytes from 2001:470:20::2: icmp_seq=2 ttl=63 time=29.8 ms + 64 bytes from 2001:470:20::2: icmp_seq=3 ttl=63 time=29.7 ms + 64 bytes from 2001:470:20::2: icmp_seq=4 ttl=63 time=101 ms --- 2001:470:20::2 ping statistics --- 4 packets transmitted, 4 received, 0% packet loss, time 8ms - rtt min/avg/max/mdev = 29.439/29.651/30.013/0.309 ms + rtt min/avg/max/mdev = 29.712/47.770/100.760/30.597 ms Multiple LAN/DMZ Setup |