============================== lbuild-nightly.sh: failure at Sun Dec 30 15:21:52 CET 2018 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. myplc-core-5.3-4.lxc.2018.12.30.noarch -> 14:38:43 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 dnf -y install myplc slicerepo-lxc-f29-x86_64 noderepo-lxc-f29-x86_64 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. Last metadata expiration check: 0:01:01 ago on Sun 30 Dec 2018 02:37:43 PM CET. Dependencies resolved. ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: myplc noarch 5.3-4.lxc.2018.12.30 myplc 9.7 k noderepo-lxc-f29-x86_64 noarch 5.2-8.2018.12.30 myplc 242 M slicerepo-lxc-f29-x86_64 noarch 5.2-8.2018.12.30 myplc 52 k Installing dependencies: dosfstools x86_64 4.1-6.fc29 fedora 111 k drpm x86_64 0.3.0-14.fc29 fedora 69 k genisoimage x86_64 1.1.11-40.fc29 fedora 310 k gnupg x86_64 1.4.23-2.fc29 fedora 1.3 M libusal x86_64 1.1.11-40.fc29 fedora 137 k libusb x86_64 1:0.1.5-13.fc29 fedora 33 k mtools x86_64 4.0.18-15.fc29 fedora 204 k pciutils x86_64 3.6.2-1.fc29 fedora 91 k pciutils-libs x86_64 3.6.2-1.fc29 fedora 38 k perl-Crypt-PasswdMD5 noarch 1.4.0-13.fc29 fedora 14 k perl-Digest-SHA1 x86_64 2.13-25.fc29 fedora 52 k python3-distro noarch 1.3.0-4.fc29 fedora 35 k sharutils x86_64 4.15.2-13.fc29 fedora 307 k syslinux x86_64 6.04-0.8.fc28 fedora 575 k syslinux-nonlinux noarch 6.04-0.8.fc28 fedora 549 k syslinux-perl x86_64 6.04-0.8.fc28 fedora 42 k createrepo_c x86_64 0.11.1-2.fc29 updates 60 k createrepo_c-libs x86_64 0.11.1-2.fc29 updates 87 k dnf-plugins-core noarch 4.0.2-1.fc29 updates 29 k dnf-utils noarch 4.0.2-1.fc29 updates 35 k python3-dnf-plugins-core noarch 4.0.2-1.fc29 updates 141 k rpm-sign x86_64 4.14.2.1-2.fc29 updates 27 k bootcd-initscripts noarch 5.4-1.2018.12.30 myplc 7.9 k bootcd-lxc-f29-x86_64 noarch 5.4-1.2018.12.30 myplc 336 M bootmanager x86_64 5.3-4.lxc.2018.12.30 myplc 80 k myplc-docs noarch 5.3-4.lxc.2018.12.30 myplc 893 k myplc-release noarch 5.3-4.lxc.2018.12.30 myplc 7.7 k nodeconfig x86_64 5.2-5.lxc myplc 41 k nodeimage-lxc-f29-x86_64 noarch 5.2-8.2018.12.30 myplc 723 M nodeyum noarch 5.2-8.2018.12.30 myplc 23 k pypcilib x86_64 0.2-11.lxc myplc 9.5 k pyplnet noarch 4.3-19.lxc.2018.12.30 myplc 24 k www-register-wizard noarch 4.3-5.lxc myplc 329 k Transaction Summary ================================================================================ Install 36 Packages Total download size: 1.3 G Installed size: 1.3 G Downloading Packages: (1/36): drpm-0.3.0-14.fc29.x86_64.rpm 13 MB/s | 69 kB 00:00 (2/36): dosfstools-4.1-6.fc29.x86_64.rpm 14 MB/s | 111 kB 00:00 (3/36): genisoimage-1.1.11-40.fc29.x86_64.rpm 22 MB/s | 310 kB 00:00 (4/36): libusal-1.1.11-40.fc29.x86_64.rpm 21 MB/s | 137 kB 00:00 (5/36): libusb-0.1.5-13.fc29.x86_64.rpm 20 MB/s | 33 kB 00:00 (6/36): pciutils-3.6.2-1.fc29.x86_64.rpm 23 MB/s | 91 kB 00:00 (7/36): mtools-4.0.18-15.fc29.x86_64.rpm 26 MB/s | 204 kB 00:00 (8/36): pciutils-libs-3.6.2-1.fc29.x86_64.rpm 16 MB/s | 38 kB 00:00 (9/36): perl-Crypt-PasswdMD5-1.4.0-13.fc29.noar 8.2 MB/s | 14 kB 00:00 (10/36): perl-Digest-SHA1-2.13-25.fc29.x86_64.r 22 MB/s | 52 kB 00:00 (11/36): python3-distro-1.3.0-4.fc29.noarch.rpm 15 MB/s | 35 kB 00:00 (12/36): gnupg-1.4.23-2.fc29.x86_64.rpm 46 MB/s | 1.3 MB 00:00 (13/36): sharutils-4.15.2-13.fc29.x86_64.rpm 27 MB/s | 307 kB 00:00 (14/36): syslinux-perl-6.04-0.8.fc28.x86_64.rpm 16 MB/s | 42 kB 00:00 (15/36): createrepo_c-0.11.1-2.fc29.x86_64.rpm 20 MB/s | 60 kB 00:00 (16/36): syslinux-nonlinux-6.04-0.8.fc28.noarch 45 MB/s | 549 kB 00:00 (17/36): syslinux-6.04-0.8.fc28.x86_64.rpm 25 MB/s | 575 kB 00:00 (18/36): dnf-plugins-core-4.0.2-1.fc29.noarch.r 9.5 MB/s | 29 kB 00:00 (19/36): createrepo_c-libs-0.11.1-2.fc29.x86_64 11 MB/s | 87 kB 00:00 (20/36): dnf-utils-4.0.2-1.fc29.noarch.rpm 16 MB/s | 35 kB 00:00 (21/36): rpm-sign-4.14.2.1-2.fc29.x86_64.rpm 22 MB/s | 27 kB 00:00 (22/36): python3-dnf-plugins-core-4.0.2-1.fc29. 32 MB/s | 141 kB 00:00 (23/36): bootcd-initscripts-5.4-1.2018.12.30.no 2.6 MB/s | 7.9 kB 00:00 (24/36): myplc-5.3-4.lxc.2018.12.30.noarch.rpm 4.4 MB/s | 9.7 kB 00:00 (25/36): bootmanager-5.3-4.lxc.2018.12.30.x86_6 23 MB/s | 80 kB 00:00 (26/36): myplc-release-5.3-4.lxc.2018.12.30.noa 3.6 MB/s | 7.7 kB 00:00 (27/36): nodeconfig-5.2-5.lxc.x86_64.rpm 9.9 MB/s | 41 kB 00:00 (28/36): myplc-docs-5.3-4.lxc.2018.12.30.noarch 37 MB/s | 893 kB 00:00 (29/36): noderepo-lxc-f29-x86_64-5.2-8.2018.12. 41 MB/s | 242 MB 00:05 (30/36): nodeyum-5.2-8.2018.12.30.noarch.rpm 13 MB/s | 23 kB 00:00 (31/36): pypcilib-0.2-11.lxc.x86_64.rpm 6.1 MB/s | 9.5 kB 00:00 (32/36): pyplnet-4.3-19.lxc.2018.12.30.noarch.r 10 MB/s | 24 kB 00:00 (33/36): slicerepo-lxc-f29-x86_64-5.2-8.2018.12 18 MB/s | 52 kB 00:00 (34/36): www-register-wizard-4.3-5.lxc.noarch.r 1.9 MB/s | 329 kB 00:00 (35/36): bootcd-lxc-f29-x86_64-5.4-1.2018.12.30 35 MB/s | 336 MB 00:09 (36/36): nodeimage-lxc-f29-x86_64-5.2-8.2018.12 43 MB/s | 723 MB 00:16 -------------------------------------------------------------------------------- Total 77 MB/s | 1.3 GB 00:17 Running transaction check Transaction check succeeded. Running transaction test Transaction test succeeded. Running transaction Preparing : 1/1 Installing : pyplnet-4.3-19.lxc.2018.12.30.noarch 1/36 Installing : sharutils-4.15.2-13.fc29.x86_64 2/36 Installing : mtools-4.0.18-15.fc29.x86_64 3/36 Installing : syslinux-nonlinux-6.04-0.8.fc28.noarch 4/36 Installing : syslinux-6.04-0.8.fc28.x86_64 5/36 Installing : drpm-0.3.0-14.fc29.x86_64 6/36 Installing : createrepo_c-libs-0.11.1-2.fc29.x86_64 7/36 Installing : createrepo_c-0.11.1-2.fc29.x86_64 8/36 Installing : www-register-wizard-4.3-5.lxc.noarch 9/36 Installing : nodeyum-5.2-8.2018.12.30.noarch 10/36 Installing : nodeconfig-5.2-5.lxc.x86_64 11/36 Running scriptlet: nodeconfig-5.2-5.lxc.x86_64 11/36 Installing : myplc-release-5.3-4.lxc.2018.12.30.noarch 12/36 Installing : myplc-docs-5.3-4.lxc.2018.12.30.noarch 13/36 Installing : bootcd-initscripts-5.4-1.2018.12.30.noarch 14/36 Installing : rpm-sign-4.14.2.1-2.fc29.x86_64 15/36 Installing : python3-distro-1.3.0-4.fc29.noarch 16/36 Installing : python3-dnf-plugins-core-4.0.2-1.fc29.noarch 17/36 Installing : dnf-plugins-core-4.0.2-1.fc29.noarch 18/36 Installing : dnf-utils-4.0.2-1.fc29.noarch 19/36 Installing : perl-Digest-SHA1-2.13-25.fc29.x86_64 20/36 Installing : perl-Crypt-PasswdMD5-1.4.0-13.fc29.noarch 21/36 Installing : syslinux-perl-6.04-0.8.fc28.x86_64 22/36 Installing : pciutils-libs-3.6.2-1.fc29.x86_64 23/36 Running scriptlet: pciutils-libs-3.6.2-1.fc29.x86_64 23/36 Installing : pciutils-3.6.2-1.fc29.x86_64 24/36 Installing : pypcilib-0.2-11.lxc.x86_64 25/36 Installing : libusb-1:0.1.5-13.fc29.x86_64 26/36 Installing : gnupg-1.4.23-2.fc29.x86_64 27/36 Running scriptlet: gnupg-1.4.23-2.fc29.x86_64 27/36 Installing : bootmanager-5.3-4.lxc.2018.12.30.x86_64 28/36 Running scriptlet: bootmanager-5.3-4.lxc.2018.12.30.x86_64 28/36 Installing : nodeimage-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 29/36 Installing : libusal-1.1.11-40.fc29.x86_64 30/36 Running scriptlet: libusal-1.1.11-40.fc29.x86_64 30/36 Installing : genisoimage-1.1.11-40.fc29.x86_64 31/36 Running scriptlet: genisoimage-1.1.11-40.fc29.x86_64 31/36 Installing : dosfstools-4.1-6.fc29.x86_64 32/36 Installing : bootcd-lxc-f29-x86_64-5.4-1.2018.12.30.noarch 33/36 Running scriptlet: myplc-5.3-4.lxc.2018.12.30.noarch 34/36 Preserving /var/lib/pgsql/data Preserving /etc/planetlab Installing : myplc-5.3-4.lxc.2018.12.30.noarch 34/36 Running scriptlet: myplc-5.3-4.lxc.2018.12.30.noarch 34/36 Created symlink /etc/systemd/system/multi-user.target.wants/plc.service → /usr/lib/systemd/system/plc.service. Created symlink /etc/systemd/system/multi-user.target.wants/php-fpm.service → /usr/lib/systemd/system/php-fpm.service. Installing : noderepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 35/36 Running scriptlet: noderepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 35/36 PLC is not running. Skipping a restart ... Installing : slicerepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 36/36 Running scriptlet: slicerepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 36/36 The PLC is not running. Skipping a restart ... Verifying : dosfstools-4.1-6.fc29.x86_64 1/36 Verifying : drpm-0.3.0-14.fc29.x86_64 2/36 Verifying : genisoimage-1.1.11-40.fc29.x86_64 3/36 Verifying : gnupg-1.4.23-2.fc29.x86_64 4/36 Verifying : libusal-1.1.11-40.fc29.x86_64 5/36 Verifying : libusb-1:0.1.5-13.fc29.x86_64 6/36 Verifying : mtools-4.0.18-15.fc29.x86_64 7/36 Verifying : pciutils-3.6.2-1.fc29.x86_64 8/36 Verifying : pciutils-libs-3.6.2-1.fc29.x86_64 9/36 Verifying : perl-Crypt-PasswdMD5-1.4.0-13.fc29.noarch 10/36 Verifying : perl-Digest-SHA1-2.13-25.fc29.x86_64 11/36 Verifying : python3-distro-1.3.0-4.fc29.noarch 12/36 Verifying : sharutils-4.15.2-13.fc29.x86_64 13/36 Verifying : syslinux-6.04-0.8.fc28.x86_64 14/36 Verifying : syslinux-nonlinux-6.04-0.8.fc28.noarch 15/36 Verifying : syslinux-perl-6.04-0.8.fc28.x86_64 16/36 Verifying : createrepo_c-0.11.1-2.fc29.x86_64 17/36 Verifying : createrepo_c-libs-0.11.1-2.fc29.x86_64 18/36 Verifying : dnf-plugins-core-4.0.2-1.fc29.noarch 19/36 Verifying : dnf-utils-4.0.2-1.fc29.noarch 20/36 Verifying : python3-dnf-plugins-core-4.0.2-1.fc29.noarch 21/36 Verifying : rpm-sign-4.14.2.1-2.fc29.x86_64 22/36 Verifying : bootcd-initscripts-5.4-1.2018.12.30.noarch 23/36 Verifying : bootcd-lxc-f29-x86_64-5.4-1.2018.12.30.noarch 24/36 Verifying : bootmanager-5.3-4.lxc.2018.12.30.x86_64 25/36 Verifying : myplc-5.3-4.lxc.2018.12.30.noarch 26/36 Verifying : myplc-docs-5.3-4.lxc.2018.12.30.noarch 27/36 Verifying : myplc-release-5.3-4.lxc.2018.12.30.noarch 28/36 Verifying : nodeconfig-5.2-5.lxc.x86_64 29/36 Verifying : nodeimage-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 30/36 Verifying : noderepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 31/36 Verifying : nodeyum-5.2-8.2018.12.30.noarch 32/36 Verifying : pypcilib-0.2-11.lxc.x86_64 33/36 Verifying : pyplnet-4.3-19.lxc.2018.12.30.noarch 34/36 Verifying : slicerepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch 35/36 Verifying : www-register-wizard-4.3-5.lxc.noarch 36/36 Installed: myplc-5.3-4.lxc.2018.12.30.noarch noderepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch slicerepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch dosfstools-4.1-6.fc29.x86_64 drpm-0.3.0-14.fc29.x86_64 genisoimage-1.1.11-40.fc29.x86_64 gnupg-1.4.23-2.fc29.x86_64 libusal-1.1.11-40.fc29.x86_64 libusb-1:0.1.5-13.fc29.x86_64 mtools-4.0.18-15.fc29.x86_64 pciutils-3.6.2-1.fc29.x86_64 pciutils-libs-3.6.2-1.fc29.x86_64 perl-Crypt-PasswdMD5-1.4.0-13.fc29.noarch perl-Digest-SHA1-2.13-25.fc29.x86_64 python3-distro-1.3.0-4.fc29.noarch sharutils-4.15.2-13.fc29.x86_64 syslinux-6.04-0.8.fc28.x86_64 syslinux-nonlinux-6.04-0.8.fc28.noarch syslinux-perl-6.04-0.8.fc28.x86_64 createrepo_c-0.11.1-2.fc29.x86_64 createrepo_c-libs-0.11.1-2.fc29.x86_64 dnf-plugins-core-4.0.2-1.fc29.noarch dnf-utils-4.0.2-1.fc29.noarch python3-dnf-plugins-core-4.0.2-1.fc29.noarch rpm-sign-4.14.2.1-2.fc29.x86_64 bootcd-initscripts-5.4-1.2018.12.30.noarch bootcd-lxc-f29-x86_64-5.4-1.2018.12.30.noarch bootmanager-5.3-4.lxc.2018.12.30.x86_64 myplc-docs-5.3-4.lxc.2018.12.30.noarch myplc-release-5.3-4.lxc.2018.12.30.noarch nodeconfig-5.2-5.lxc.x86_64 nodeimage-lxc-f29-x86_64-5.2-8.2018.12.30.noarch nodeyum-5.2-8.2018.12.30.noarch pypcilib-0.2-11.lxc.x86_64 pyplnet-4.3-19.lxc.2018.12.30.noarch www-register-wizard-4.3-5.lxc.noarch Complete! -> 14:39:21 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 rpm -q myplc slicerepo-lxc-f29-x86_64 noderepo-lxc-f29-x86_64 Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. myplc-5.3-4.lxc.2018.12.30.noarch slicerepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch noderepo-lxc-f29-x86_64-5.2-8.2018.12.30.noarch * 14:39:22 -- ********** 1 SUCCESSFUL step plc_install on plctest1_vplc10 TRACE: 1 14-38-01->81s=0:01:21.176390s status=OK step=plc_install plc=plctest1_vplc10 force=False * 14:39:22 -- ********** 1 RUNNING step plc_configure on plc plctest1_vplc10 -> 14:39:22 -- + cat plctest1_vplc10.2018.12.30--py3-f29-1-vplc10.plc-config-tty e PLC_NAME Regression TestLab e PLC_ROOT_USER root@test.onelab.eu e PLC_ROOT_PASSWORD test++ e PLC_SLICE_PREFIX auto e PLC_HRN_ROOT a e PLC_SHORTNAME Rlab e PLC_MAIL_ENABLED false e PLC_MAIL_SUPPORT_ADDRESS thierry.parmentelat@inria.fr e PLC_DB_HOST vplc10.pl.sophia.inria.fr e PLC_API_HOST vplc10.pl.sophia.inria.fr e PLC_WWW_HOST vplc10.pl.sophia.inria.fr e PLC_BOOT_HOST vplc10.pl.sophia.inria.fr e PLC_NET_DNS1 138.96.112.1 e PLC_NET_DNS2 138.96.112.2 e PLC_RESERVATION_GRANULARITY 1800 e PLC_VSYS_DEFAULTS , vif_up, vif_down, fd_tuntap, promisc, e PLC_OMF_ENABLED false w q -> 14:39:22 -- + cat plctest1_vplc10.2018.12.30--py3-f29-1-vplc10.plc-config-tty + ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 plc-config-tty Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. Created directory /etc/planetlab/configs Enter command (u for usual changes, w to save, ? for help) == PLC_NAME : [PlanetLab Test] Enter command (u for usual changes, w to save, ? for help) == PLC_ROOT_USER : [root@localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_ROOT_PASSWORD : [root] Enter command (u for usual changes, w to save, ? for help) == PLC_SLICE_PREFIX : [pl] Enter command (u for usual changes, w to save, ? for help) == PLC_HRN_ROOT : [planetlab.test] Enter command (u for usual changes, w to save, ? for help) == PLC_SHORTNAME : [Tlab] Enter command (u for usual changes, w to save, ? for help) == PLC_MAIL_ENABLED : [false] Enter command (u for usual changes, w to save, ? for help) == PLC_MAIL_SUPPORT_ADDRESS : [root+support@localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_DB_HOST : [localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_API_HOST : [localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_WWW_HOST : [localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_BOOT_HOST : [localhost.localdomain] Enter command (u for usual changes, w to save, ? for help) == PLC_NET_DNS1 : [127.0.0.1] Enter command (u for usual changes, w to save, ? for help) == PLC_NET_DNS2 : [None] Enter command (u for usual changes, w to save, ? for help) == PLC_RESERVATION_GRANULARITY : [3600] Enter command (u for usual changes, w to save, ? for help) == PLC_VSYS_DEFAULTS : [ ] Enter command (u for usual changes, w to save, ? for help) == PLC_OMF_ENABLED : [false] Enter command (u for usual changes, w to save, ? for help) Wrote /etc/planetlab/configs/site.xml Merged /etc/planetlab/default_config.xml and /etc/planetlab/configs/site.xml into /etc/planetlab/plc_config.xml You might want to type 'r' (restart plc), 'R' (reload plc) or 'q' (quit) Enter command (u for usual changes, w to save, ? for help) -> 14:39:23 -- + rm plctest1_vplc10.2018.12.30--py3-f29-1-vplc10.plc-config-tty * 14:39:23 -- ********** 1 SUCCESSFUL step plc_configure on plctest1_vplc10 TRACE: 1 14-39-22->0s=0:00:00.695225s status=OK step=plc_configure plc=plctest1_vplc10 force=False * 14:39:23 -- ********** 1 RUNNING step plc_start on plc plctest1_vplc10 -> 14:39:23 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 systemctl start plc Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. * 14:39:40 -- ********** 1 SUCCESSFUL step plc_start on plctest1_vplc10 TRACE: 1 14-39-23->17s=0:00:17.415449s status=OK step=plc_start plc=plctest1_vplc10 force=False * 14:39:40 -- ********** 1 RUNNING step keys_fetch on plc plctest1_vplc10 -> 14:39:40 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 buzzcocks:/vservers/2018.12.30--py3-f29-1-vplc10//etc/planetlab/debug_ssh_key.pub keys/2018.12.30--py3-f29-1-vplc10-debug.pub -> 14:39:40 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 buzzcocks:/vservers/2018.12.30--py3-f29-1-vplc10//etc/planetlab/debug_ssh_key.rsa keys/2018.12.30--py3-f29-1-vplc10-debug.rsa * 14:39:41 -- ********** 1 SUCCESSFUL step keys_fetch on plctest1_vplc10 TRACE: 1 14-39-40->0s=0:00:00.741933s status=OK step=keys_fetch plc=plctest1_vplc10 force=False * 14:39:41 -- ********** 1 RUNNING step keys_store on plc plctest1_vplc10 * 14:39:41 -- Storing key key_admin in keys/key_admin.pub * 14:39:41 -- Storing key key_pi in keys/key_pi.pub * 14:39:41 -- Storing key key_user in keys/key_user.pub * 14:39:41 -- Storing key key_sfapi in keys/key_sfapi.pub * 14:39:41 -- Storing key key_sfauser in keys/key_sfauser.pub * 14:39:41 -- ********** 1 SUCCESSFUL step keys_store on plctest1_vplc10 TRACE: 1 14-39-41->0s=0:00:00.004183s status=OK step=keys_store plc=plctest1_vplc10 force=False * 14:39:41 -- ********** 1 RUNNING step keys_clear_known_hosts on plc plctest1_vplc10 * 14:39:41 -- Clearing entry for vnode13.pl.sophia.inria.fr in /root/.ssh/known_hosts -> 14:39:41 -- + sed -i -e '/^vnode13.pl.sophia.inria.fr/d' /root/.ssh/known_hosts * 14:39:41 -- ********** 1 SUCCESSFUL step keys_clear_known_hosts on plctest1_vplc10 TRACE: 1 14-39-41->0s=0:00:00.011108s status=OK step=keys_clear_known_hosts plc=plctest1_vplc10 force=False * 14:39:41 -- ********** 1 RUNNING step plcapi_urls on plc plctest1_vplc10 ... (http error 302) http://vplc10.pl.sophia.inria.fr/PLCAPI ... (http error 302) http://vplc10.pl.sophia.inria.fr/PLCAPI/ ... (http error 302) http://vplc10.pl.sophia.inria.fr:80/PLCAPI ... (http error 302) http://vplc10.pl.sophia.inria.fr:80/PLCAPI/ ... (http error 302) http://138.96.112.80/PLCAPI ... (http error 302) http://138.96.112.80/PLCAPI/ ... (http error 302) http://138.96.112.80:80/PLCAPI ... (http error 302) http://138.96.112.80:80/PLCAPI/ YES https://vplc10.pl.sophia.inria.fr/PLCAPI YES https://vplc10.pl.sophia.inria.fr/PLCAPI/ YES https://vplc10.pl.sophia.inria.fr:443/PLCAPI YES https://vplc10.pl.sophia.inria.fr:443/PLCAPI/ YES https://138.96.112.80/PLCAPI YES https://138.96.112.80/PLCAPI/ YES https://138.96.112.80:443/PLCAPI YES https://138.96.112.80:443/PLCAPI/ * 14:39:41 -- ********** 1 SUCCESSFUL step plcapi_urls on plctest1_vplc10 TRACE: 1 14-39-41->0s=0:00:00.317797s status=OK step=plcapi_urls plc=plctest1_vplc10 force=False * 14:39:41 -- ********** 1 RUNNING step speed_up_slices on plc plctest1_vplc10 -> 14:39:41 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 plctest1_vplc10.2018.12.30--py3-f29-1-vplc10.nodemanager buzzcocks:/vservers/2018.12.30--py3-f29-1-vplc10///var/www/html/PlanetLabConf/nodemanager * 14:39:42 -- ********** 1 SUCCESSFUL step speed_up_slices on plctest1_vplc10 TRACE: 1 14-39-41->0s=0:00:00.410992s status=OK step=speed_up_slices plc=plctest1_vplc10 force=False * 14:39:42 -- ********** 1 RUNNING step initscripts on plc plctest1_vplc10 > 14:39:42 -- Adding Initscript in plc plctest1_vplc10 { 'initscript_fields': { 'enabled': True, 'name': 'initscript_by_name', 'script': '#!/bin/bash\n' 'command=$1; shift\n' 'slicename=$1; shift\n' 'stamp="initscript_by_name"\n' 'stampfile=/var/tmp/$stamp.stamp\n' 'date=$(date)\n' '\n' 'echo $date "Running initscript ' 'with command=$command and ' 'slicename=$slicename"\n' '\n' 'function start () {\n' ' (echo $date Starting test ' 'initscript: $stamp on ' 'slicename $slicename ; date) ' '>> $stampfile\n' ' echo $date "This is the ' 'stdout of the sliver ' '$slicename initscript $command ' '(exp. start) pid=$$"\n' ' echo $date "This is the ' 'stderr of the sliver ' '$slicename initscript $command ' '(exp. start) pid=$$" 1>&2\n' '}\n' 'function stop () {\n' ' echo $date "Removing stamp ' '$stampfile"\n' ' rm -f $stampfile\n' '}\n' 'function restart () {\n' ' stop\n' ' start\n' '}\n' 'case $command in\n' 'start) start ;;\n' 'stop) stop ;;\n' 'restart) restart ;;\n' '*) echo "Unknown command in ' 'initscript $command" ;;\n' 'esac\n'}} * 14:39:42 -- ********** 1 SUCCESSFUL step initscripts on plctest1_vplc10 TRACE: 1 14-39-42->0s=0:00:00.017676s status=OK step=initscripts plc=plctest1_vplc10 force=False * 14:39:42 -- ********** 1 RUNNING step sites on plc plctest1_vplc10 * 14:39:42 -- Creating site inri & users in plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 {'Username': 'root@test.onelab.eu', 'AuthMethod': 'password', 'AuthString': 'test++', 'Role': 'root'} * 14:39:42 -- Adding user admin@onelab.eu - roles ['admin'] * 14:39:42 -- Adding user fake-pi1@onelab.eu - roles ['pi'] * 14:39:42 -- Adding user fake-pi2@onelab.eu - roles ['pi', 'tech'] * 14:39:42 -- Adding user fake-tech1@onelab.eu - roles ['tech'] * 14:39:42 -- Adding user fake-user1@onelab.eu - roles ['user'] * 14:39:42 -- Adding user fake-tech2@onelab.eu - roles ['tech', 'user'] * 14:39:42 -- ********** 1 SUCCESSFUL step sites on plctest1_vplc10 TRACE: 1 14-39-42->0s=0:00:00.674397s status=OK step=sites plc=plctest1_vplc10 force=False * 14:39:42 -- ********** 1 RUNNING step nodes on plc plctest1_vplc10 * 14:39:42 -- Creating nodes for site inri in plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 > 14:39:42 -- Creating node {'name': 'node1', 'node_fields': {'hostname': 'vnode13.pl.sophia.inria.fr', 'model': 'qemu/minhw'}, 'host_box': 'boxtops', 'owner': 'pi', 'nodegroups': 'mynodegroup', 'interface_fields': {'method': 'static', 'type': 'ipv4', 'ip': '138.96.112.113', 'gateway': '138.96.112.250', 'network': '138.96.112.0', 'broadcast': '138.96.119.255', 'netmask': '255.255.248.0', 'dns1': '138.96.112.1', 'dns2': '138.96.112.2', 'mac': '02:34:56:00:00:13'}, 'node_fields_nint': {'dns': '138.96.112.1,138.96.112.2'}, 'interface_fields_nint': {'is_primary': True, 'method': 'static'}, 'ipaddress_fields': {'type': 'ipv4', 'ip_addr': '138.96.112.113', 'netmask': '255.255.248.0'}, 'route_fields': {'subnet': '0.0.0.0/0', 'next_hop': '138.96.112.250'}, 'bootmedium_options': ['serial', 'no-hangcheck', 'systemd-console'], 'tags': {}} { 'bootmedium_options': ['serial', 'no-hangcheck', 'systemd-console'], 'host_box': 'boxtops', 'interface_fields': { 'broadcast': '138.96.119.255', 'dns1': '138.96.112.1', 'dns2': '138.96.112.2', 'gateway': '138.96.112.250', 'ip': '138.96.112.113', 'mac': '02:34:56:00:00:13', 'method': 'static', 'netmask': '255.255.248.0', 'network': '138.96.112.0', 'type': 'ipv4'}, 'interface_fields_nint': {'is_primary': True, 'method': 'static'}, 'ipaddress_fields': { 'ip_addr': '138.96.112.113', 'netmask': '255.255.248.0', 'type': 'ipv4'}, 'name': 'node1', 'node_fields': { 'hostname': 'vnode13.pl.sophia.inria.fr', 'model': 'qemu/minhw'}, 'node_fields_nint': {'dns': '138.96.112.1,138.96.112.2'}, 'nodegroups': 'mynodegroup', 'owner': 'pi', 'route_fields': {'next_hop': '138.96.112.250', 'subnet': '0.0.0.0/0'}, 'tags': {}} * 14:39:42 -- node vnode13.pl.sophia.inria.fr created by user fake-pi1@onelab.eu * 14:39:42 -- ********** 1 SUCCESSFUL step nodes on plctest1_vplc10 TRACE: 1 14-39-42->0s=0:00:00.111118s status=OK step=nodes plc=plctest1_vplc10 force=False * 14:39:42 -- ********** 1 RUNNING step slices on plc plctest1_vplc10 * 14:39:42 -- Creating slice inri_sl1 * 14:39:43 -- Adding initscript code #!/bin/bash command=$1; shift slicename=$1; shift stamp="initscript_by_code" stampfile=/var/tmp/$stamp.stamp date=$(date) echo $date "Running initscript with command=$command and slicename=$slicename" function start () { (echo $date Starting test initscript: $stamp on slicename $slicename ; date) >> $stampfile echo $date "This is the stdout of the sliver $slicename initscript $command (exp. start) pid=$$" echo $date "This is the stderr of the sliver $slicename initscript $command (exp. start) pid=$$" 1>&2 } function stop () { echo $date "Removing stamp $stampfile" rm -f $stampfile } function restart () { stop start } case $command in start) start ;; stop) stop ;; restart) restart ;; *) echo "Unknown command in initscript $command" ;; esac in inri_sl1 * 14:39:43 -- Adding ['vnode13.pl.sophia.inria.fr'] in inri_sl1 * 14:39:43 -- Creating slice inri_sl2 * 14:39:43 -- Adding initscript name initscript_by_name in inri_sl2 * 14:39:43 -- Adding ['vnode13.pl.sophia.inria.fr'] in inri_sl2 * 14:39:43 -- Creating slice inri_sl3 * 14:39:43 -- Adding ['vnode13.pl.sophia.inria.fr'] in inri_sl3 * 14:39:43 -- ********** 1 SUCCESSFUL step slices on plctest1_vplc10 TRACE: 1 14-39-42->0s=0:00:00.389944s status=OK step=slices plc=plctest1_vplc10 force=False * 14:39:43 -- ********** 1 RUNNING step nodegroups on plc plctest1_vplc10 nodegroups: dealing with nodegroup mynodegroup on nodes ['vnode13.pl.sophia.inria.fr'] located tag (type) mynodegroup as 133 created nodegroup mynodegroup from tagname mynodegroup and value yes * 14:39:43 -- ********** 1 SUCCESSFUL step nodegroups on plctest1_vplc10 TRACE: 1 14-39-43->0s=0:00:00.086327s status=OK step=nodegroups plc=plctest1_vplc10 force=False * 14:39:43 -- ********** 1 RUNNING step leases on plc plctest1_vplc10 API answered grain= 1800 * 14:39:43 -- No reservable node found - proceeding without leases * 14:39:43 -- ********** 1 SUCCESSFUL step leases on plctest1_vplc10 TRACE: 1 14-39-43->0s=0:00:00.010848s status=OK step=leases plc=plctest1_vplc10 force=False * 14:39:43 -- ********** 1 RUNNING step check_vsys_defaults_ignore on plc plctest1_vplc10 Actual (but ignored) result for check_vsys_defaults is True * 14:39:43 -- ********** 1 IGNORED (OK) step check_vsys_defaults_ignore on plctest1_vplc10 TRACE: 1 14-39-43->0s=0:00:00.059702s status=OK[I] step=check_vsys_defaults_ignore plc=plctest1_vplc10 force=False * 14:39:43 -- ********** 1 RUNNING step qemu_kill_mine on plc plctest1_vplc10 * 14:39:43 -- Stopping qemu for node vnode13.pl.sophia.inria.fr on box boxtops * 14:39:43 -- On boxtops: running qemu-vnode13.pl.sophia.inria.fr/qemu-kill-node vnode13.pl.sophia.inria.fr -> 14:39:43 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:39:43 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' qemu-vnode13.pl.sophia.inria.fr/qemu-kill-node vnode13.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. bash: qemu-vnode13.pl.sophia.inria.fr/qemu-kill-node: No such file or directory * 14:39:44 -- ********** 1 SUCCESSFUL step qemu_kill_mine on plctest1_vplc10 TRACE: 1 14-39-43->0s=0:00:00.644504s status=OK step=qemu_kill_mine plc=plctest1_vplc10 force=False * 14:39:44 -- ********** 1 RUNNING step nodestate_reinstall on plc plctest1_vplc10 * 14:39:44 -- ********** 1 SUCCESSFUL step nodestate_reinstall on plctest1_vplc10 TRACE: 1 14-39-44->0s=0:00:00.031433s status=OK step=nodestate_reinstall plc=plctest1_vplc10 force=False * 14:39:44 -- ********** 1 RUNNING step qemu_local_init on plc plctest1_vplc10 -> 14:39:44 -- + rm -rf qemu-vnode13.pl.sophia.inria.fr -> 14:39:44 -- + mkdir qemu-vnode13.pl.sophia.inria.fr -> 14:39:44 -- + rsync -v -a --exclude .svn template-qemu/ qemu-vnode13.pl.sophia.inria.fr/ sending incremental file list ./ iptables.py qemu-bridge-init qemu-ifdown qemu-ifup qemu-kill-node qemu-start-node sent 13,645 bytes received 133 bytes 27,556.00 bytes/sec total size is 13,252 speedup is 0.96 * 14:39:44 -- ********** 1 SUCCESSFUL step qemu_local_init on plctest1_vplc10 TRACE: 1 14-39-44->0s=0:00:00.040020s status=OK step=qemu_local_init plc=plctest1_vplc10 force=False * 14:39:44 -- ********** 1 RUNNING step bootcd on plc plctest1_vplc10 * 14:39:44 -- Calling GetBootMedium for vnode13.pl.sophia.inria.fr * 14:40:02 -- Storing boot medium into qemu-vnode13.pl.sophia.inria.fr/vnode13.pl.sophia.inria.fr.iso * 14:40:05 -- ********** 1 SUCCESSFUL step bootcd on plctest1_vplc10 TRACE: 1 14-39-44->21s=0:00:21.567433s status=OK step=bootcd plc=plctest1_vplc10 force=False * 14:40:05 -- ********** 1 RUNNING step qemu_local_config on plc plctest1_vplc10 * 14:40:05 -- Storing qemu config for vnode13.pl.sophia.inria.fr in qemu-vnode13.pl.sophia.inria.fr/qemu.conf * 14:40:05 -- ********** 1 SUCCESSFUL step qemu_local_config on plctest1_vplc10 TRACE: 1 14-40-05->0s=0:00:00.030785s status=OK step=qemu_local_config plc=plctest1_vplc10 force=False * 14:40:05 -- ********** 1 RUNNING step qemu_clean_mine on plc plctest1_vplc10 * 14:40:05 -- Cleaning up qemu for host vnode13.pl.sophia.inria.fr on box boxtops -> 14:40:05 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops rm -rf /vservers/2018.12.30--py3-f29/qemu-vnode13.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 14:40:06 -- ********** 1 SUCCESSFUL step qemu_clean_mine on plctest1_vplc10 TRACE: 1 14-40-05->0s=0:00:00.270634s status=OK step=qemu_clean_mine plc=plctest1_vplc10 force=False * 14:40:06 -- ********** 1 RUNNING step qemu_export on plc plctest1_vplc10 * 14:40:06 -- Cleaning any former sequel of vnode13.pl.sophia.inria.fr on boxtops * 14:40:06 -- Transferring configuration files for node vnode13.pl.sophia.inria.fr onto boxtops -> 14:40:06 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:40:06 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -r qemu-vnode13.pl.sophia.inria.fr boxtops:/vservers/2018.12.30--py3-f29/qemu-vnode13.pl.sophia.inria.fr * 14:40:10 -- ********** 1 SUCCESSFUL step qemu_export on plctest1_vplc10 TRACE: 1 14-40-06->4s=0:00:04.763762s status=OK step=qemu_export plc=plctest1_vplc10 force=False * 14:40:10 -- ********** 1 RUNNING step qemu_cleanlog on plc plctest1_vplc10 * 14:40:10 -- On boxtops: running cd qemu-vnode13.pl.sophia.inria.fr; mv -f log.txt log.txt.bak -> 14:40:10 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:40:11 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' cd 'qemu-vnode13.pl.sophia.inria.fr;' mv -f log.txt log.txt.bak Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. mv: cannot stat 'log.txt': No such file or directory * 14:40:11 -- ********** 1 SUCCESSFUL step qemu_cleanlog on plctest1_vplc10 TRACE: 1 14-40-10->0s=0:00:00.541846s status=OK step=qemu_cleanlog plc=plctest1_vplc10 force=False * 14:40:11 -- ********** 1 RUNNING step qemu_start on plc plctest1_vplc10 * 14:40:11 -- Starting qemu node vnode13.pl.sophia.inria.fr on boxtops * 14:40:11 -- On boxtops: running qemu-vnode13.pl.sophia.inria.fr/qemu-bridge-init start >> qemu-vnode13.pl.sophia.inria.fr/log.txt -> 14:40:11 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:40:11 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' qemu-vnode13.pl.sophia.inria.fr/qemu-bridge-init start '>>' qemu-vnode13.pl.sophia.inria.fr/log.txt Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. + INTERFACE_BRIDGE=br0 ++ discover_interface +++ gather_interfaces +++ python ++ for ifname in $(gather_interfaces) ++ ip link show eno3 ++ grep -qi 'state UP' ++ for ifname in $(gather_interfaces) ++ ip link show idrac ++ grep -qi 'state UP' ++ for ifname in $(gather_interfaces) ++ ip link show eno1 ++ grep -qi 'state UP' ++ echo eno1 ++ return + INTERFACE_LAN=eno1 + echo Using physical interface eno1 + main start * 14:40:12 -- On boxtops: running qemu-vnode13.pl.sophia.inria.fr/qemu-start-node 2>&1 >> qemu-vnode13.pl.sophia.inria.fr/log.txt -> 14:40:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' qemu-vnode13.pl.sophia.inria.fr/qemu-start-node '2>&1' '>>' qemu-vnode13.pl.sophia.inria.fr/log.txt Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 14:40:12 -- ********** 1 SUCCESSFUL step qemu_start on plctest1_vplc10 TRACE: 1 14-40-11->0s=0:00:00.967837s status=OK step=qemu_start plc=plctest1_vplc10 force=False * 14:40:12 -- ********** 1 RUNNING step qemu_timestamp on plc plctest1_vplc10 * 14:40:12 -- On boxtops: running mkdir -p qemu-vnode13.pl.sophia.inria.fr -> 14:40:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:40:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' mkdir -p qemu-vnode13.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 14:40:12 -- On boxtops: running echo 1546177212 > qemu-vnode13.pl.sophia.inria.fr/timestamp -> 14:40:12 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' echo 1546177212 '>' qemu-vnode13.pl.sophia.inria.fr/timestamp Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 14:40:13 -- ********** 1 SUCCESSFUL step qemu_timestamp on plctest1_vplc10 TRACE: 1 14-40-12->0s=0:00:00.816415s status=OK step=qemu_timestamp plc=plctest1_vplc10 force=False * 14:40:13 -- ********** 1 RUNNING step qemu_nodefamily on plc plctest1_vplc10 * 14:40:13 -- On boxtops: running echo lxc-f29-x86_64 > qemu-vnode13.pl.sophia.inria.fr/nodefamily -> 14:40:13 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.30--py3-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 14:40:13 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.30--py3-f29 ';' echo lxc-f29-x86_64 '>' qemu-vnode13.pl.sophia.inria.fr/nodefamily Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 14:40:13 -- ********** 1 SUCCESSFUL step qemu_nodefamily on plctest1_vplc10 TRACE: 1 14-40-13->0s=0:00:00.750544s status=OK step=qemu_nodefamily plc=plctest1_vplc10 force=False * 14:40:13 -- ********** 1 RUNNING step probe_kvm_iptables on plc plctest1_vplc10 -> 14:40:13 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops iptables-save Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. # Generated by iptables-save v1.8.0 on Sun Dec 30 14:40:14 2018 *filter :INPUT ACCEPT [0:0] :FORWARD ACCEPT [0:0] :OUTPUT ACCEPT [43:9650] -A INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT -A INPUT -p icmp -j ACCEPT -A INPUT -i lo -j ACCEPT -A INPUT -s 138.96.112.113/32 -j ACCEPT -A INPUT -d 138.96.112.113/32 -j ACCEPT -A INPUT -s 138.96.112.109/32 -j ACCEPT -A INPUT -d 138.96.112.109/32 -j ACCEPT -A INPUT -s 138.96.112.107/32 -j ACCEPT -A INPUT -d 138.96.112.107/32 -j ACCEPT -A INPUT -s 138.96.112.112/32 -j ACCEPT -A INPUT -d 138.96.112.112/32 -j ACCEPT -A INPUT -s 138.96.112.111/32 -j ACCEPT -A INPUT -d 138.96.112.111/32 -j ACCEPT -A INPUT -s 138.96.112.110/32 -j ACCEPT -A INPUT -d 138.96.112.110/32 -j ACCEPT -A INPUT -s 138.96.112.108/32 -j ACCEPT -A INPUT -d 138.96.112.108/32 -j ACCEPT -A INPUT -s 138.96.112.106/32 -j ACCEPT -A INPUT -d 138.96.112.106/32 -j ACCEPT -A INPUT -s 138.96.112.105/32 -j ACCEPT -A INPUT -d 138.96.112.105/32 -j ACCEPT -A INPUT -s 138.96.112.103/32 -j ACCEPT -A INPUT -d 138.96.112.103/32 -j ACCEPT -A INPUT -s 138.96.112.104/32 -j ACCEPT -A INPUT -d 138.96.112.104/32 -j ACCEPT -A INPUT -s 138.96.112.102/32 -j ACCEPT -A INPUT -d 138.96.112.102/32 -j ACCEPT -A INPUT -s 138.96.112.101/32 -j ACCEPT -A INPUT -d 138.96.112.101/32 -j ACCEPT -A INPUT -s 138.96.112.120/32 -j ACCEPT -A INPUT -d 138.96.112.120/32 -j ACCEPT -A INPUT -s 138.96.112.119/32 -j ACCEPT -A INPUT -d 138.96.112.119/32 -j ACCEPT -A INPUT -s 138.96.112.118/32 -j ACCEPT -A INPUT -d 138.96.112.118/32 -j ACCEPT -A INPUT -s 138.96.112.117/32 -j ACCEPT -A INPUT -d 138.96.112.117/32 -j ACCEPT -A INPUT -s 138.96.112.116/32 -j ACCEPT -A INPUT -d 138.96.112.116/32 -j ACCEPT -A INPUT -s 138.96.112.115/32 -j ACCEPT -A INPUT -d 138.96.112.115/32 -j ACCEPT -A INPUT -s 138.96.112.114/32 -j ACCEPT -A INPUT -d 138.96.112.114/32 -j ACCEPT -A INPUT -p tcp -m state --state NEW -m tcp --dport 22 -j ACCEPT -A INPUT -j REJECT --reject-with icmp-host-prohibited -A FORWARD -j REJECT --reject-with icmp-host-prohibited COMMIT # Completed on Sun Dec 30 14:40:14 2018 * 14:40:14 -- ********** 1 SUCCESSFUL step probe_kvm_iptables on plctest1_vplc10 TRACE: 1 14-40-13->0s=0:00:00.320577s status=OK step=probe_kvm_iptables plc=plctest1_vplc10 force=False * 14:40:14 -- ********** 1 RUNNING step ping_node on plc plctest1_vplc10 * 14:40:14 -- Completer [1 tasks]: max timeout is 60.0 seconds, silent for 60.0 seconds (period is 10 s) . 10s.. . 10s.. . 10s.. . 10s.. . total completer (ping_node) 44s * 14:40:58 -- ********** 1 SUCCESSFUL step ping_node on plctest1_vplc10 TRACE: 1 14-40-14->44s=0:00:44.154679s status=OK step=ping_node plc=plctest1_vplc10 force=False * 14:40:58 -- ********** 1 RUNNING step ssh_node_debug on plc plctest1_vplc10 * 14:40:58 -- checking ssh access to nodes (expected in debug mode) * 14:40:58 -- Completer [1 tasks]: max timeout is 10.0 minutes, silent for 8.0 minutes (period is 15 s) . 15s.. . 15s.. . Linux vnode13.pl.sophia.inria.fr 4.19.10-300.fc29.x86_64 #1 SMP Mon Dec 17 15:34:44 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux total completer (ssh_node_debug) 30s * 14:41:29 -- ********** 1 SUCCESSFUL step ssh_node_debug on plctest1_vplc10 TRACE: 1 14-40-58->30s=0:00:30.645362s status=OK step=ssh_node_debug plc=plctest1_vplc10 force=False * 14:41:29 -- ********** 1 RUNNING step plcsh_stress_test on plc plctest1_vplc10 -> 14:41:29 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 plcsh_stress_test.py buzzcocks:/vservers/2018.12.30--py3-f29-1-vplc10///usr/share/plc_api/plcsh_stress_test.py -> 14:41:29 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 /usr/share/plc_api/plcsh_stress_test.py -- --check --tiny Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. Cardinals before test (n,s,p,sl) [1, 2, 8, 7] nl[a] in random_site 50 actual 50 Added site 3 Added address type 4 Added address 2 to site 3 Added user 9 to site 3 Added key 9 to user 9 Created tag type 134 Created tag type 135 Created tag type 136 Added node group 2 Added node 2 Added interface 2 to node 2 Added Ilink 1 - attached interface 2 to 2 Added PCU 1 to site 3 Added configuration file 27 Added configuration file 28 to node group 2 Added configuration file 29 to node 2 Added slice 8 to site 3 Added slice attribute 54 of type 134 nl[a] in random_site 50 actual 50 Updated site 3 Updated address_type 4 Updated address 2 Updated person 9 Updated person 9 to sites [] Updated key 8 Updated tag type 134 Updated tag type 135 Updated tag type 136 Updated node group 2 Updated node 2 Updated interface 2 Updated Ilink 1 Updated PCU 1 Updated configuration file 27 Updated configuration file 28 Updated configuration file 29 Updated slice 8 Added nodes [2] to slice 8 Added persons [] to slice 8 Updated slice attribute 54 Deleted slice attribute 54 Deleted slice 8 Deleted key 8 Deleted configuration file 27 Deleted configuration file 28 Deleted configuration file 29 Deleted PCU 1 Deleted Ilink 1 Deleted interface 2 Deleted node 2 Deleted user 9 Deleted node group 2 Deleted tag type 134 Deleted tag type 135 Deleted tag type 136 Deleted address 2 Deleted address type 4 Deleted site 3 Cardinals after test (n,s,p,sl) [1, 2, 8, 7] * 14:41:31 -- ********** 1 SUCCESSFUL step plcsh_stress_test on plctest1_vplc10 TRACE: 1 14-41-29->2s=0:00:02.101220s status=OK step=plcsh_stress_test plc=plctest1_vplc10 force=False * 14:41:31 -- ********** 1 RUNNING step ssh_node_boot on plc plctest1_vplc10 * 14:41:31 -- checking ssh access to nodes (expected in boot mode) * 14:41:31 -- Completer [1 tasks]: max timeout is 40.0 minutes, silent for 38.0 minutes (period is 15 s) . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. . 15s.. -> 15:19:44 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:19:59 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:20:14 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:20:29 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:20:45 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:21:00 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:21:15 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:21:30 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). 15s.. -> 15:21:45 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr 'hostname;uname' -a Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). Cannot reach vnode13.pl.sophia.inria.fr in boot mode * 15:21:46 -- ********** 1 FAILED step ssh_node_boot on plctest1_vplc10 (discarded from further steps) TRACE: 1 14-41-31->2414s=0:40:14.965746s status=KO step=ssh_node_boot plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step node_bmlogs on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000249s status=UNDEF step=node_bmlogs plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step ssh_slice on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000156s status=UNDEF step=ssh_slice plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step ssh_slice_basics on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000152s status=UNDEF step=ssh_slice_basics plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step check_system_slice on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000172s status=UNDEF step=check_system_slice plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step debug_nodemanager on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000145s status=UNDEF step=debug_nodemanager plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step empty_slices on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000143s status=UNDEF step=empty_slices plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step ssh_slice_off on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000147s status=UNDEF step=ssh_slice_off plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step slice_fs_deleted_ignore on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000172s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step fill_slices on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000144s status=UNDEF step=fill_slices plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 SKIPPED Step ssh_slice_again on plctest1_vplc10 (has failed ssh_node_boot) TRACE: 1 15-21-46->0s=0:00:00.000142s status=UNDEF step=ssh_slice_again plc=plctest1_vplc10 force=False * 15:21:46 -- ********** 1 RUNNING step gather_logs (forced after ssh_node_boot has failed) on plc plctest1_vplc10 -------------------- TestPlc.gather_logs : PLC's /var/log -> 15:21:46 -- + mkdir -p logs/myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -> 15:21:46 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 tar -C /var/log/ -cf - . + tar -C logs/myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. tar: ./journal/ca8eea526f794662981c54ece198f2ee/system.journal: file changed as we read it -> 15:21:47 -- + chmod a+r,a+x logs/myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd -------------------- TestPlc.gather_logs : PLC's /var/lib/psql/data/pg_log/ -> 15:21:47 -- + mkdir -p logs/myplc.pgsql-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -> 15:21:47 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 tar -C /var/lib/pgsql/data/pg_log/ -cf - . + tar -C logs/myplc.pgsql-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. -------------------- TestPlc.gather_logs : PLC's /root/sfi/ -> 15:21:47 -- + mkdir -p logs/sfi.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -> 15:21:47 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null buzzcocks ssh -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null vplc10 tar -C /root/sfi/ -cf - . + tar -C logs/sfi.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc10' (ECDSA) to the list of known hosts. tar: /root/sfi/: Cannot open: No such file or directory tar: Error is not recoverable: exiting now tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -------------------- TestPlc.gather_logs : nodes's QEMU logs -> 15:21:48 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 boxtops:/vservers/2018.12.30--py3-f29/qemu-vnode13.pl.sophia.inria.fr/log.txt logs/node.qemu.vnode13.pl.sophia.inria.fr.txt -------------------- TestPlc.gather_logs : nodes's /var/log -> 15:21:48 -- + mkdir -p logs/node.var-log.vnode13.pl.sophia.inria.fr -> 15:21:48 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr tar -C /var/log -cf - . + tar -C logs/node.var-log.vnode13.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -------------------- TestPlc.gather_logs : sample sliver's /var/log -> 15:21:48 -- + mkdir -p logs/sliver.var-log.inri_sl1@vnode13.pl.sophia.inria.fr -> 15:21:48 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr tar -C /vservers/inri_sl1/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl1@vnode13.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -> 15:21:48 -- + mkdir -p logs/sliver.var-log.inri_sl2@vnode13.pl.sophia.inria.fr -> 15:21:48 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr tar -C /vservers/inri_sl2/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl2@vnode13.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors -> 15:21:49 -- + mkdir -p logs/sliver.var-log.inri_sl3@vnode13.pl.sophia.inria.fr -> 15:21:49 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode13.pl.sophia.inria.fr tar -C /vservers/inri_sl3/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl3@vnode13.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode13.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. root@vnode13.pl.sophia.inria.fr: Permission denied (publickey,gssapi-keyex,gssapi-with-mic,password). tar: This does not look like a tar archive tar: Exiting with failure status due to previous errors * 15:21:49 -- ********** 1 SUCCESSFUL step gather_logs on plctest1_vplc10 TRACE: 1 15-21-46->3s=0:00:03.105436s status=OK step=gather_logs plc=plctest1_vplc10 force=True TestMain exit code 1 run_log exit_code 1 + retcod=1 + set -e + trap failure ERR INT + echo 'retcod from run_log' 1 retcod from run_log 1 + case $retcod in + success= + IGNORED= ++ rootdir 2018.12.30--py3-f29 ++ slice=2018.12.30--py3-f29 ++ shift ++ echo /vservers/2018.12.30--py3-f29 + mkdir -p /vservers/2018.12.30--py3-f29/build/testlogs ++ rootdir 2018.12.30--py3-f29 ++ slice=2018.12.30--py3-f29 ++ shift ++ echo /vservers/2018.12.30--py3-f29 + rsync --verbose --archive root@testmaster.onelab.eu:2018.12.30--py3-f29/logs/ /vservers/2018.12.30--py3-f29/build/testlogs receiving incremental file list ./ node.qemu.vnode13.pl.sophia.inria.fr.txt run -> run-14-36.txt run-14-36.txt trace -> trace-14-36.txt trace-14-36.txt myplc.pgsql-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/README myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/boot.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/btmp myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/dnf.librepo.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/dnf.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/dnf.rpm.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/hawkey.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/lastlog myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/maillog myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/messages myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/myslice.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/php.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/plc_api_ratelimit.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/plcapi.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/secure myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/spooler myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/tallylog myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/wtmp myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-hostname/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-hostname/vnode13.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-hostname/vnode13.pl.sophia.inria.fr/2018-12-30-13-43 -> ../../raw/2018-12-30-13-43-vnode13.pl.sophia.inria.fr-138.96.112.113.txt myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-ip/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-ip/138.96.112.113/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-ip/138.96.112.113/2018-12-30-13-43 -> ../../raw/2018-12-30-13-43-vnode13.pl.sophia.inria.fr-138.96.112.113.txt myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-month/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-month/2018-12/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-month/2018-12/vnode13.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/per-month/2018-12/vnode13.pl.sophia.inria.fr/30-13-43 -> ../../../raw/2018-12-30-13-43-vnode13.pl.sophia.inria.fr-138.96.112.113.txt myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/raw/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/bm/raw/2018-12-30-13-43-vnode13.pl.sophia.inria.fr-138.96.112.113.txt myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/cups/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/cups/access_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/cups/error_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/cups/page_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/access_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/error_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/ssl_access_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/ssl_error_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/httpd/ssl_request_log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/journal/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/journal/ca8eea526f794662981c54ece198f2ee/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/journal/ca8eea526f794662981c54ece198f2ee/system.journal myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/mail/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/mail/statistics myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/php-fpm/ myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/php-fpm/error.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/php-fpm/www-error.log myplc.var-log.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/private/ node.var-log.vnode13.pl.sophia.inria.fr/ sfi.plctest1_vplc10.2018.12.30--py3-f29-1-vplc10/ sliver.var-log.inri_sl1@vnode13.pl.sophia.inria.fr/ sliver.var-log.inri_sl2@vnode13.pl.sophia.inria.fr/ sliver.var-log.inri_sl3@vnode13.pl.sophia.inria.fr/ sent 800 bytes received 13,769,726 bytes 27,541,052.00 bytes/sec total size is 13,762,874 speedup is 1.00 ++ rootdir 2018.12.30--py3-f29 ++ slice=2018.12.30--py3-f29 ++ shift ++ echo /vservers/2018.12.30--py3-f29 + chmod -R a+r /vservers/2018.12.30--py3-f29/build/testlogs/ ++ rootdir 2018.12.30--py3-f29 ++ slice=2018.12.30--py3-f29 ++ shift ++ echo /vservers/2018.12.30--py3-f29 + webpublish_rsync_dir /build/lxc//2018.12.30--py3-f29/testlogs/ /vservers/2018.12.30--py3-f29/build/testlogs/ + rsync --archive --delete /vservers/2018.12.30--py3-f29/build/testlogs/ root@build.onelab.eu:/build/lxc//2018.12.30--py3-f29/testlogs/ ++ date + echo '============================== END lbuild-nightly.sh:run_log on Sun Dec 30 15:21:51 CET 2018' ============================== END lbuild-nightly.sh:run_log on Sun Dec 30 15:21:51 CET 2018 + '[' -z '' ']' + echo 'Tests have failed - bailing out' Tests have failed - bailing out + failure + set -x + '[' -z /build/lxc//2018.12.30--py3-f29/log.txt ']' + webpublish mkdir -p /build/lxc//2018.12.30--py3-f29 + ssh root@build.onelab.eu mkdir -p /build/lxc//2018.12.30--py3-f29 + webpublish_cp_local_to_remote /vservers/2018.12.30--py3-f29/log.txt /build/lxc//2018.12.30--py3-f29/log.txt + scp /vservers/2018.12.30--py3-f29/log.txt root@build.onelab.eu:/build/lxc//2018.12.30--py3-f29/log.txt ******************** BEG SUMMARY Failed to analyze /vservers/2018.12.30--py3-f29/log.txt ******************** END SUMMARY