============================== lbuild-nightly.sh: failure at Thu Dec 27 01:46:54 CET 2018 false w q -> 01:39:57 -- + cat plctest1_vplc24.2018.12.27--py2-f29-1-vplc24.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 vplc24 plc-config-tty Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (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) -> 01:39:58 -- + rm plctest1_vplc24.2018.12.27--py2-f29-1-vplc24.plc-config-tty * 01:39:58 -- ********** 1 SUCCESSFUL step plc_configure on plctest1_vplc24 TRACE: 1 01-39-57->0s=0:00:00.731687s status=OK step=plc_configure plc=plctest1_vplc24 force=False * 01:39:58 -- ********** 1 RUNNING step plc_start on plc plctest1_vplc24 -> 01:39:58 -- + 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 vplc24 systemctl start plc Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (ECDSA) to the list of known hosts. * 01:40:19 -- ********** 1 SUCCESSFUL step plc_start on plctest1_vplc24 TRACE: 1 01-39-58->21s=0:00:21.157407s status=OK step=plc_start plc=plctest1_vplc24 force=False * 01:40:19 -- ********** 1 RUNNING step keys_fetch on plc plctest1_vplc24 -> 01:40:19 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 buzzcocks:/vservers/2018.12.27--py2-f29-1-vplc24//etc/planetlab/debug_ssh_key.pub keys/2018.12.27--py2-f29-1-vplc24-debug.pub -> 01:40:20 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 buzzcocks:/vservers/2018.12.27--py2-f29-1-vplc24//etc/planetlab/debug_ssh_key.rsa keys/2018.12.27--py2-f29-1-vplc24-debug.rsa * 01:40:20 -- ********** 1 SUCCESSFUL step keys_fetch on plctest1_vplc24 TRACE: 1 01-40-19->0s=0:00:00.741254s status=OK step=keys_fetch plc=plctest1_vplc24 force=False * 01:40:20 -- ********** 1 RUNNING step keys_store on plc plctest1_vplc24 * 01:40:20 -- Storing key key_admin in keys/key_admin.pub * 01:40:20 -- Storing key key_pi in keys/key_pi.pub * 01:40:20 -- Storing key key_user in keys/key_user.pub * 01:40:20 -- Storing key key_sfapi in keys/key_sfapi.pub * 01:40:20 -- Storing key key_sfauser in keys/key_sfauser.pub * 01:40:20 -- ********** 1 SUCCESSFUL step keys_store on plctest1_vplc24 TRACE: 1 01-40-20->0s=0:00:00.004446s status=OK step=keys_store plc=plctest1_vplc24 force=False * 01:40:20 -- ********** 1 RUNNING step keys_clear_known_hosts on plc plctest1_vplc24 * 01:40:20 -- Clearing entry for vnode02.pl.sophia.inria.fr in /root/.ssh/known_hosts -> 01:40:20 -- + sed -i -e '/^vnode02.pl.sophia.inria.fr/d' /root/.ssh/known_hosts * 01:40:20 -- ********** 1 SUCCESSFUL step keys_clear_known_hosts on plctest1_vplc24 TRACE: 1 01-40-20->0s=0:00:00.010890s status=OK step=keys_clear_known_hosts plc=plctest1_vplc24 force=False * 01:40:20 -- ********** 1 RUNNING step plcapi_urls on plc plctest1_vplc24 ... (http error 302) http://vplc24.pl.sophia.inria.fr/PLCAPI ... (http error 302) http://vplc24.pl.sophia.inria.fr/PLCAPI/ ... (http error 302) http://vplc24.pl.sophia.inria.fr:80/PLCAPI ... (http error 302) http://vplc24.pl.sophia.inria.fr:80/PLCAPI/ ... (http error 302) http://138.96.112.94/PLCAPI ... (http error 302) http://138.96.112.94/PLCAPI/ ... (http error 302) http://138.96.112.94:80/PLCAPI ... (http error 302) http://138.96.112.94:80/PLCAPI/ YES https://vplc24.pl.sophia.inria.fr/PLCAPI YES https://vplc24.pl.sophia.inria.fr/PLCAPI/ YES https://vplc24.pl.sophia.inria.fr:443/PLCAPI YES https://vplc24.pl.sophia.inria.fr:443/PLCAPI/ YES https://138.96.112.94/PLCAPI YES https://138.96.112.94/PLCAPI/ YES https://138.96.112.94:443/PLCAPI YES https://138.96.112.94:443/PLCAPI/ * 01:40:20 -- ********** 1 SUCCESSFUL step plcapi_urls on plctest1_vplc24 TRACE: 1 01-40-20->0s=0:00:00.313270s status=OK step=plcapi_urls plc=plctest1_vplc24 force=False * 01:40:20 -- ********** 1 RUNNING step speed_up_slices on plc plctest1_vplc24 -> 01:40:20 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 plctest1_vplc24.2018.12.27--py2-f29-1-vplc24.nodemanager buzzcocks:/vservers/2018.12.27--py2-f29-1-vplc24///var/www/html/PlanetLabConf/nodemanager * 01:40:21 -- ********** 1 SUCCESSFUL step speed_up_slices on plctest1_vplc24 TRACE: 1 01-40-20->0s=0:00:00.414472s status=OK step=speed_up_slices plc=plctest1_vplc24 force=False * 01:40:21 -- ********** 1 RUNNING step initscripts on plc plctest1_vplc24 > 01:40:21 -- Adding Initscript in plc plctest1_vplc24 { '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'}} * 01:40:21 -- ********** 1 SUCCESSFUL step initscripts on plctest1_vplc24 TRACE: 1 01-40-21->0s=0:00:00.023995s status=OK step=initscripts plc=plctest1_vplc24 force=False * 01:40:21 -- ********** 1 RUNNING step sites on plc plctest1_vplc24 * 01:40:21 -- Creating site inri & users in plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 {'Username': 'root@test.onelab.eu', 'AuthMethod': 'password', 'AuthString': 'test++', 'Role': 'root'} * 01:40:21 -- Adding user admin@onelab.eu - roles ['admin'] * 01:40:21 -- Adding user fake-pi1@onelab.eu - roles ['pi'] * 01:40:21 -- Adding user fake-pi2@onelab.eu - roles ['pi', 'tech'] * 01:40:21 -- Adding user fake-tech1@onelab.eu - roles ['tech'] * 01:40:21 -- Adding user fake-user1@onelab.eu - roles ['user'] * 01:40:21 -- Adding user fake-tech2@onelab.eu - roles ['tech', 'user'] * 01:40:22 -- ********** 1 SUCCESSFUL step sites on plctest1_vplc24 TRACE: 1 01-40-21->0s=0:00:00.835860s status=OK step=sites plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step nodes on plc plctest1_vplc24 * 01:40:22 -- Creating nodes for site inri in plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 > 01:40:22 -- Creating node {'name': 'node1', 'node_fields': {'hostname': 'vnode02.pl.sophia.inria.fr', 'model': 'qemu/minhw'}, 'host_box': 'boxtops', 'owner': 'pi', 'nodegroups': 'mynodegroup', 'interface_fields': {'method': 'static', 'type': 'ipv4', 'ip': '138.96.112.102', '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:02'}, '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.102', '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-debug'], 'tags': {}} { 'bootmedium_options': ['serial', 'no-hangcheck', 'systemd-debug'], '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.102', 'mac': '02:34:56:00:00:02', '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.102', 'netmask': '255.255.248.0', 'type': 'ipv4'}, 'name': 'node1', 'node_fields': { 'hostname': 'vnode02.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': {}} * 01:40:22 -- node vnode02.pl.sophia.inria.fr created by user fake-pi1@onelab.eu * 01:40:22 -- ********** 1 SUCCESSFUL step nodes on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.117468s status=OK step=nodes plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step slices on plc plctest1_vplc24 * 01:40:22 -- Creating slice inri_sl1 * 01:40:22 -- 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 * 01:40:22 -- Adding ['vnode02.pl.sophia.inria.fr'] in inri_sl1 * 01:40:22 -- Creating slice inri_sl2 * 01:40:22 -- Adding initscript name initscript_by_name in inri_sl2 * 01:40:22 -- Adding ['vnode02.pl.sophia.inria.fr'] in inri_sl2 * 01:40:22 -- Creating slice inri_sl3 * 01:40:22 -- Adding ['vnode02.pl.sophia.inria.fr'] in inri_sl3 * 01:40:22 -- ********** 1 SUCCESSFUL step slices on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.466276s status=OK step=slices plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step nodegroups on plc plctest1_vplc24 nodegroups: dealing with nodegroup mynodegroup on nodes ['vnode02.pl.sophia.inria.fr'] located tag (type) mynodegroup as 118 created nodegroup mynodegroup from tagname mynodegroup and value yes * 01:40:22 -- ********** 1 SUCCESSFUL step nodegroups on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.111520s status=OK step=nodegroups plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step leases on plc plctest1_vplc24 API answered grain= 1800 * 01:40:22 -- No reservable node found - proceeding without leases * 01:40:22 -- ********** 1 SUCCESSFUL step leases on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.015432s status=OK step=leases plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step check_vsys_defaults_ignore on plc plctest1_vplc24 Actual (but ignored) result for check_vsys_defaults is True * 01:40:22 -- ********** 1 IGNORED (OK) step check_vsys_defaults_ignore on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.070702s status=OK[I] step=check_vsys_defaults_ignore plc=plctest1_vplc24 force=False * 01:40:22 -- ********** 1 RUNNING step qemu_kill_mine on plc plctest1_vplc24 * 01:40:22 -- Stopping qemu for node vnode02.pl.sophia.inria.fr on box boxtops * 01:40:22 -- On boxtops: running qemu-vnode02.pl.sophia.inria.fr/qemu-kill-node vnode02.pl.sophia.inria.fr -> 01:40:22 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:23 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' qemu-vnode02.pl.sophia.inria.fr/qemu-kill-node vnode02.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. bash: qemu-vnode02.pl.sophia.inria.fr/qemu-kill-node: No such file or directory * 01:40:23 -- ********** 1 SUCCESSFUL step qemu_kill_mine on plctest1_vplc24 TRACE: 1 01-40-22->0s=0:00:00.594266s status=OK step=qemu_kill_mine plc=plctest1_vplc24 force=False * 01:40:23 -- ********** 1 RUNNING step nodestate_reinstall on plc plctest1_vplc24 * 01:40:23 -- ********** 1 SUCCESSFUL step nodestate_reinstall on plctest1_vplc24 TRACE: 1 01-40-23->0s=0:00:00.035298s status=OK step=nodestate_reinstall plc=plctest1_vplc24 force=False * 01:40:23 -- ********** 1 RUNNING step qemu_local_init on plc plctest1_vplc24 -> 01:40:23 -- + rm -rf qemu-vnode02.pl.sophia.inria.fr -> 01:40:23 -- + mkdir qemu-vnode02.pl.sophia.inria.fr -> 01:40:23 -- + rsync -v -a --exclude .svn template-qemu/ qemu-vnode02.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 * 01:40:23 -- ********** 1 SUCCESSFUL step qemu_local_init on plctest1_vplc24 TRACE: 1 01-40-23->0s=0:00:00.039130s status=OK step=qemu_local_init plc=plctest1_vplc24 force=False * 01:40:23 -- ********** 1 RUNNING step bootcd on plc plctest1_vplc24 * 01:40:23 -- Calling GetBootMedium for vnode02.pl.sophia.inria.fr * 01:40:41 -- Storing boot medium into qemu-vnode02.pl.sophia.inria.fr/vnode02.pl.sophia.inria.fr.iso * 01:40:45 -- ********** 1 SUCCESSFUL step bootcd on plctest1_vplc24 TRACE: 1 01-40-23->21s=0:00:21.904266s status=OK step=bootcd plc=plctest1_vplc24 force=False * 01:40:45 -- ********** 1 RUNNING step qemu_local_config on plc plctest1_vplc24 * 01:40:45 -- Storing qemu config for vnode02.pl.sophia.inria.fr in qemu-vnode02.pl.sophia.inria.fr/qemu.conf * 01:40:45 -- ********** 1 SUCCESSFUL step qemu_local_config on plctest1_vplc24 TRACE: 1 01-40-45->0s=0:00:00.034259s status=OK step=qemu_local_config plc=plctest1_vplc24 force=False * 01:40:45 -- ********** 1 RUNNING step qemu_clean_mine on plc plctest1_vplc24 * 01:40:45 -- Cleaning up qemu for host vnode02.pl.sophia.inria.fr on box boxtops -> 01:40:45 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops rm -rf /vservers/2018.12.27--py2-f29/qemu-vnode02.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 01:40:45 -- ********** 1 SUCCESSFUL step qemu_clean_mine on plctest1_vplc24 TRACE: 1 01-40-45->0s=0:00:00.270364s status=OK step=qemu_clean_mine plc=plctest1_vplc24 force=False * 01:40:45 -- ********** 1 RUNNING step qemu_export on plc plctest1_vplc24 * 01:40:45 -- Cleaning any former sequel of vnode02.pl.sophia.inria.fr on boxtops * 01:40:45 -- Transferring configuration files for node vnode02.pl.sophia.inria.fr onto boxtops -> 01:40:45 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:45 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -r qemu-vnode02.pl.sophia.inria.fr boxtops:/vservers/2018.12.27--py2-f29/qemu-vnode02.pl.sophia.inria.fr * 01:40:50 -- ********** 1 SUCCESSFUL step qemu_export on plctest1_vplc24 TRACE: 1 01-40-45->5s=0:00:05.113763s status=OK step=qemu_export plc=plctest1_vplc24 force=False * 01:40:50 -- ********** 1 RUNNING step qemu_cleanlog on plc plctest1_vplc24 * 01:40:50 -- On boxtops: running cd qemu-vnode02.pl.sophia.inria.fr; mv -f log.txt log.txt.bak -> 01:40:50 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:51 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' cd 'qemu-vnode02.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 * 01:40:51 -- ********** 1 SUCCESSFUL step qemu_cleanlog on plctest1_vplc24 TRACE: 1 01-40-50->0s=0:00:00.541848s status=OK step=qemu_cleanlog plc=plctest1_vplc24 force=False * 01:40:51 -- ********** 1 RUNNING step qemu_start on plc plctest1_vplc24 * 01:40:51 -- Starting qemu node vnode02.pl.sophia.inria.fr on boxtops * 01:40:51 -- On boxtops: running qemu-vnode02.pl.sophia.inria.fr/qemu-bridge-init start >> qemu-vnode02.pl.sophia.inria.fr/log.txt -> 01:40:51 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:51 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' qemu-vnode02.pl.sophia.inria.fr/qemu-bridge-init start '>>' qemu-vnode02.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 * 01:40:51 -- On boxtops: running qemu-vnode02.pl.sophia.inria.fr/qemu-start-node 2>&1 >> qemu-vnode02.pl.sophia.inria.fr/log.txt -> 01:40:51 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' qemu-vnode02.pl.sophia.inria.fr/qemu-start-node '2>&1' '>>' qemu-vnode02.pl.sophia.inria.fr/log.txt Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 01:40:52 -- ********** 1 SUCCESSFUL step qemu_start on plctest1_vplc24 TRACE: 1 01-40-51->0s=0:00:00.966675s status=OK step=qemu_start plc=plctest1_vplc24 force=False * 01:40:52 -- ********** 1 RUNNING step qemu_timestamp on plc plctest1_vplc24 * 01:40:52 -- On boxtops: running mkdir -p qemu-vnode02.pl.sophia.inria.fr -> 01:40:52 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:52 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' mkdir -p qemu-vnode02.pl.sophia.inria.fr Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 01:40:52 -- On boxtops: running echo 1545871252 > qemu-vnode02.pl.sophia.inria.fr/timestamp -> 01:40:52 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' echo 1545871252 '>' qemu-vnode02.pl.sophia.inria.fr/timestamp Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 01:40:53 -- ********** 1 SUCCESSFUL step qemu_timestamp on plctest1_vplc24 TRACE: 1 01-40-52->0s=0:00:00.816607s status=OK step=qemu_timestamp plc=plctest1_vplc24 force=False * 01:40:53 -- ********** 1 RUNNING step qemu_nodefamily on plc plctest1_vplc24 * 01:40:53 -- On boxtops: running echo lxc-f29-x86_64 > qemu-vnode02.pl.sophia.inria.fr/nodefamily -> 01:40:53 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops mkdir -p /vservers/2018.12.27--py2-f29 Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. -> 01:40:53 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null boxtops cd /vservers/2018.12.27--py2-f29 ';' echo lxc-f29-x86_64 '>' qemu-vnode02.pl.sophia.inria.fr/nodefamily Warning: Permanently added 'boxtops' (ECDSA) to the list of known hosts. * 01:40:53 -- ********** 1 SUCCESSFUL step qemu_nodefamily on plctest1_vplc24 TRACE: 1 01-40-53->0s=0:00:00.654338s status=OK step=qemu_nodefamily plc=plctest1_vplc24 force=False * 01:40:53 -- ********** 1 RUNNING step probe_kvm_iptables on plc plctest1_vplc24 -> 01:40:53 -- + 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 Thu Dec 27 01:40:54 2018 *filter :INPUT ACCEPT [0:0] :FORWARD ACCEPT [0:0] :OUTPUT ACCEPT [40:8202] -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.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.103/32 -j ACCEPT -A INPUT -d 138.96.112.103/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 -s 138.96.112.107/32 -j ACCEPT -A INPUT -d 138.96.112.107/32 -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.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.109/32 -j ACCEPT -A INPUT -d 138.96.112.109/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.104/32 -j ACCEPT -A INPUT -d 138.96.112.104/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 Thu Dec 27 01:40:54 2018 * 01:40:54 -- ********** 1 SUCCESSFUL step probe_kvm_iptables on plctest1_vplc24 TRACE: 1 01-40-53->0s=0:00:00.319952s status=OK step=probe_kvm_iptables plc=plctest1_vplc24 force=False * 01:40:54 -- ********** 1 RUNNING step ping_node on plc plctest1_vplc24 * 01:40:54 -- Completer [1 tasks]: max timeout is 60.0 seconds, silent for 60.0 seconds (period is 10 s) . 10s.. . 10s.. . 10s.. . 10s.. . 10s.. . total completer (ping_node) 55s * 01:41:49 -- ********** 1 SUCCESSFUL step ping_node on plctest1_vplc24 TRACE: 1 01-40-54->55s=0:00:55.184918s status=OK step=ping_node plc=plctest1_vplc24 force=False * 01:41:49 -- ********** 1 RUNNING step ssh_node_debug on plc plctest1_vplc24 * 01:41:49 -- checking ssh access to nodes (expected in debug mode) * 01:41:49 -- Completer [1 tasks]: max timeout is 10.0 minutes, silent for 8.0 minutes (period is 15 s) . 15s.. . Linux vnode02.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) 16s * 01:42:06 -- ********** 1 SUCCESSFUL step ssh_node_debug on plctest1_vplc24 TRACE: 1 01-41-49->16s=0:00:16.763176s status=OK step=ssh_node_debug plc=plctest1_vplc24 force=False * 01:42:06 -- ********** 1 RUNNING step plcsh_stress_test on plc plctest1_vplc24 -> 01:42:06 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 plcsh_stress_test.py buzzcocks:/vservers/2018.12.27--py2-f29-1-vplc24///usr/share/plc_api/plcsh_stress_test.py -> 01:42:06 -- + 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 vplc24 /usr/share/plc_api/plcsh_stress_test.py -- --check --tiny Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (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 119 Created tag type 120 Created tag type 121 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 users [9] to slice 8 Added slice attribute 54 of type 119 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 119 Updated tag type 120 Updated tag type 121 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 [] to slice 8 Added persons [9L] 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 119 Deleted tag type 120 Deleted tag type 121 Deleted address 2 Deleted address type 4 Deleted site 3 Cardinals after test (n,s,p,sl) [1, 2, 8, 7] * 01:42:08 -- ********** 1 SUCCESSFUL step plcsh_stress_test on plctest1_vplc24 TRACE: 1 01-42-06->1s=0:00:01.999529s status=OK step=plcsh_stress_test plc=plctest1_vplc24 force=False * 01:42:08 -- ********** 1 RUNNING step ssh_node_boot on plc plctest1_vplc24 * 01:42:08 -- checking ssh access to nodes (expected in boot mode) * 01:42:08 -- 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.. . Linux vnode02.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_boot) 233s * 01:46:01 -- ********** 1 SUCCESSFUL step ssh_node_boot on plctest1_vplc24 TRACE: 1 01-42-08->233s=0:03:53.206626s status=OK step=ssh_node_boot plc=plctest1_vplc24 force=False * 01:46:01 -- ********** 1 RUNNING step node_bmlogs on plc plctest1_vplc24 -> 01:46:01 -- + 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 vplc24 ls /var/log/bm/raw Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (ECDSA) to the list of known hosts. 2018-12-27-00-45-vnode02.pl.sophia.inria.fr-138.96.112.102.txt * 01:46:01 -- ********** 1 SUCCESSFUL step node_bmlogs on plctest1_vplc24 TRACE: 1 01-46-01->0s=0:00:00.623155s status=OK step=node_bmlogs plc=plctest1_vplc24 force=False * 01:46:01 -- ********** 1 RUNNING step ssh_slice on plc plctest1_vplc24 * 01:46:01 -- checking for ssh slice access enabled -- slice inri_sl1 * 01:46:01 -- checking for ssh slice access enabled -- slice inri_sl2 * 01:46:01 -- checking for ssh slice access enabled -- slice inri_sl3 * 01:46:01 -- Completer [3 tasks]: max timeout is 20.0 minutes, silent for 10.0 minutes (period is 15 s) . hostname id uid=1000(inri_sl1) gid=1001(slices) groups=1001(slices) uname -a Linux inrisl1 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 . hostname id uid=1001(inri_sl2) gid=1001(slices) groups=1001(slices) uname -a Linux inrisl2 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 . hostname id uid=1002(inri_sl3) gid=1001(slices) groups=1001(slices) uname -a Linux inrisl3 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_slice) 4s * 01:46:06 -- ********** 1 SUCCESSFUL step ssh_slice on plctest1_vplc24 TRACE: 1 01-46-01->4s=0:00:04.934047s status=OK step=ssh_slice plc=plctest1_vplc24 force=False * 01:46:06 -- ********** 1 RUNNING step ssh_slice_basics on plc plctest1_vplc24 * 01:46:06 -- checking true to return TRUE from ssh -- slice inri_sl1 on node vnode02.pl.sophia.inria.fr . * 01:46:07 -- checking false to return FALSE from ssh -- slice inri_sl1 on node vnode02.pl.sophia.inria.fr . * 01:46:08 -- checking someimprobablecommandname to return FALSE from ssh -- slice inri_sl1 on node vnode02.pl.sophia.inria.fr . * 01:46:08 -- checking ps to return TRUE from ssh -- slice inri_sl1 on node vnode02.pl.sophia.inria.fr . PID TTY TIME CMD 221 ? 00:00:00 ps * 01:46:09 -- checking ls /vservers to return FALSE from ssh -- slice inri_sl1 on node vnode02.pl.sophia.inria.fr . * 01:46:09 -- checking true to return TRUE from ssh -- slice inri_sl2 on node vnode02.pl.sophia.inria.fr . * 01:46:09 -- checking false to return FALSE from ssh -- slice inri_sl2 on node vnode02.pl.sophia.inria.fr . * 01:46:10 -- checking someimprobablecommandname to return FALSE from ssh -- slice inri_sl2 on node vnode02.pl.sophia.inria.fr . * 01:46:10 -- checking ps to return TRUE from ssh -- slice inri_sl2 on node vnode02.pl.sophia.inria.fr . PID TTY TIME CMD 221 ? 00:00:00 ps * 01:46:11 -- checking ls /vservers to return FALSE from ssh -- slice inri_sl2 on node vnode02.pl.sophia.inria.fr . * 01:46:11 -- checking true to return TRUE from ssh -- slice inri_sl3 on node vnode02.pl.sophia.inria.fr . * 01:46:12 -- checking false to return FALSE from ssh -- slice inri_sl3 on node vnode02.pl.sophia.inria.fr . * 01:46:12 -- checking someimprobablecommandname to return FALSE from ssh -- slice inri_sl3 on node vnode02.pl.sophia.inria.fr . * 01:46:12 -- checking ps to return TRUE from ssh -- slice inri_sl3 on node vnode02.pl.sophia.inria.fr . PID TTY TIME CMD 221 ? 00:00:00 ps * 01:46:13 -- checking ls /vservers to return FALSE from ssh -- slice inri_sl3 on node vnode02.pl.sophia.inria.fr . * 01:46:13 -- ********** 1 SUCCESSFUL step ssh_slice_basics on plctest1_vplc24 TRACE: 1 01-46-06->6s=0:00:06.667904s status=OK step=ssh_slice_basics plc=plctest1_vplc24 force=False * 01:46:13 -- ********** 1 RUNNING step cross_check_tcp on plc plctest1_vplc24 * 01:46:13 -- Will check TCP between s=inri_sl1@vnode02.pl.sophia.inria.fr and c=inri_sl1@vnode02.pl.sophia.inria.fr * 01:46:13 -- Will check TCP between s=inri_sl1@vnode02.pl.sophia.inria.fr and c=inri_sl2@vnode02.pl.sophia.inria.fr * 01:46:13 -- Completer [2 tasks]: max timeout is 30.0 seconds, silent for 24.0 seconds (period is 5 s) -> 01:46:13 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl1@vnode02.pl.sophia.inria.fr:./tcptest.py Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. -> 01:46:13 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl1@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:14 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . -> 01:46:14 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl2@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:15 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl2@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:15 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . 5s.. -> 01:46:21 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl1@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:21 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl1@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:21 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . -> 01:46:22 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl2@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:22 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl2@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:23 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . 5s.. -> 01:46:28 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl1@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:29 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl1@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:29 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . -> 01:46:29 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl2@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:30 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl2@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:30 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . 5s.. -> 01:46:35 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl1@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:36 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl1@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:36 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . -> 01:46:36 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl2@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:37 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl2@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:37 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. . 5s.. -> 01:46:42 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl1@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:43 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl1@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:43 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. network ready checker for inri_sl1@vnode02.pl.sophia.inria.fr -> KO -> 01:46:43 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -i keys/key_user.rsa tcptest.py inri_sl2@vnode02.pl.sophia.inria.fr:./tcptest.py -> 01:46:44 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_user.rsa inri_sl2@vnode02.pl.sophia.inria.fr ./tcptest.py ready -p 9999 Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 00:46:44 (ready) -- ==================== tcptest.py ready bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 bind_public: is_addr_any: unsupported socket address family 16 Traceback (most recent call last): File "./tcptest.py", line 184, in Ready().main() File "./tcptest.py", line 119, in main sys.exit(0 if can_bind() and eth0_has_ipv4() else 1) File "./tcptest.py", line 117, in eth0_has_ipv4 return subprocess.check_call(command, shell=True) == 0 File "/usr/lib64/python3.7/subprocess.py", line 341, in check_call raise CalledProcessError(retcode, cmd) subprocess.CalledProcessError: Command 'ip address show eth0 | grep -q ' inet '' returned non-zero exit status 1. network ready checker for inri_sl2@vnode02.pl.sophia.inria.fr -> KO could not bind port from sliver inri_sl1@vnode02.pl.sophia.inria.fr could not bind port from sliver inri_sl2@vnode02.pl.sophia.inria.fr * 01:46:44 -- ********** 1 FAILED step cross_check_tcp on plctest1_vplc24 (discarded from further steps) TRACE: 1 01-46-13->31s=0:00:31.464378s status=KO step=cross_check_tcp plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step check_system_slice on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000217s status=UNDEF step=check_system_slice plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step debug_nodemanager on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000151s status=UNDEF step=debug_nodemanager plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step empty_slices on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000179s status=UNDEF step=empty_slices plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step ssh_slice_off on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000149s status=UNDEF step=ssh_slice_off plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step slice_fs_deleted_ignore on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000148s status=UNDEF step=slice_fs_deleted_ignore plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step fill_slices on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000146s status=UNDEF step=fill_slices plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 SKIPPED Step ssh_slice_again on plctest1_vplc24 (has failed cross_check_tcp) TRACE: 1 01-46-44->0s=0:00:00.000149s status=UNDEF step=ssh_slice_again plc=plctest1_vplc24 force=False * 01:46:44 -- ********** 1 RUNNING step gather_logs (forced after cross_check_tcp has failed) on plc plctest1_vplc24 -------------------- TestPlc.gather_logs : PLC's /var/log -> 01:46:44 -- + mkdir -p logs/myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -> 01:46:44 -- + 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 vplc24 tar -C /var/log/ -cf - . + tar -C logs/myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (ECDSA) to the list of known hosts. tar: ./journal/38e8246917724c40a37d24855fdd1d26/system.journal: file changed as we read it -> 01:46:45 -- + chmod a+r,a+x logs/myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd -------------------- TestPlc.gather_logs : PLC's /var/lib/psql/data/pg_log/ -> 01:46:45 -- + mkdir -p logs/myplc.pgsql-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -> 01:46: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 vplc24 tar -C /var/lib/pgsql/data/pg_log/ -cf - . + tar -C logs/myplc.pgsql-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (ECDSA) to the list of known hosts. -------------------- TestPlc.gather_logs : PLC's /root/sfi/ -> 01:46:46 -- + mkdir -p logs/sfi.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -> 01:46: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 vplc24 tar -C /root/sfi/ -cf - . + tar -C logs/sfi.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24 -xf - Warning: Permanently added 'buzzcocks' (ECDSA) to the list of known hosts. Warning: Permanently added 'vplc24' (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 -> 01:46:47 -- + scp -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 boxtops:/vservers/2018.12.27--py2-f29/qemu-vnode02.pl.sophia.inria.fr/log.txt logs/node.qemu.vnode02.pl.sophia.inria.fr.txt -------------------- TestPlc.gather_logs : nodes's /var/log -> 01:46:47 -- + mkdir -p logs/node.var-log.vnode02.pl.sophia.inria.fr -> 01:46:47 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode02.pl.sophia.inria.fr tar -C /var/log -cf - . + tar -C logs/node.var-log.vnode02.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. -------------------- TestPlc.gather_logs : sample sliver's /var/log -> 01:46:49 -- + mkdir -p logs/sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr -> 01:46:49 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode02.pl.sophia.inria.fr tar -C /vservers/inri_sl1/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. -> 01:46:49 -- + mkdir -p logs/sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr -> 01:46:49 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode02.pl.sophia.inria.fr tar -C /vservers/inri_sl2/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. -> 01:46:49 -- + mkdir -p logs/sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr -> 01:46:49 -- + ssh -n -o BatchMode=yes -o StrictHostKeyChecking=no -o CheckHostIP=no -o ConnectTimeout=5 -o UserKnownHostsFile=/dev/null -i keys/key_admin.rsa vnode02.pl.sophia.inria.fr tar -C /vservers/inri_sl3/var/log -cf - . + tar -C logs/sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr -xf - Warning: Permanently added 'vnode02.pl.sophia.inria.fr' (ECDSA) to the list of known hosts. * 01:46:50 -- ********** 1 SUCCESSFUL step gather_logs on plctest1_vplc24 TRACE: 1 01-46-44->5s=0:00:05.221164s status=OK step=gather_logs plc=plctest1_vplc24 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.27--py2-f29 ++ slice=2018.12.27--py2-f29 ++ shift ++ echo /vservers/2018.12.27--py2-f29 + mkdir -p /vservers/2018.12.27--py2-f29/build/testlogs ++ rootdir 2018.12.27--py2-f29 ++ slice=2018.12.27--py2-f29 ++ shift ++ echo /vservers/2018.12.27--py2-f29 + rsync --verbose --archive root@testmaster.onelab.eu:2018.12.27--py2-f29/logs/ /vservers/2018.12.27--py2-f29/build/testlogs receiving incremental file list ./ node.qemu.vnode02.pl.sophia.inria.fr.txt run -> run-01-37.txt run-01-37.txt trace -> trace-01-37.txt trace-01-37.txt myplc.pgsql-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/README myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/boot.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/btmp myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/dnf.librepo.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/dnf.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/dnf.rpm.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/hawkey.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/lastlog myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/maillog myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/messages myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/myslice.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/php.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/plc_api_ratelimit.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/plcapi.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/secure myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/spooler myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/tallylog myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/wtmp myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-hostname/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-hostname/vnode02.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-hostname/vnode02.pl.sophia.inria.fr/2018-12-27-00-45 -> ../../raw/2018-12-27-00-45-vnode02.pl.sophia.inria.fr-138.96.112.102.txt myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-ip/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-ip/138.96.112.102/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-ip/138.96.112.102/2018-12-27-00-45 -> ../../raw/2018-12-27-00-45-vnode02.pl.sophia.inria.fr-138.96.112.102.txt myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-month/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-month/2018-12/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-month/2018-12/vnode02.pl.sophia.inria.fr/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/per-month/2018-12/vnode02.pl.sophia.inria.fr/27-00-45 -> ../../../raw/2018-12-27-00-45-vnode02.pl.sophia.inria.fr-138.96.112.102.txt myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/raw/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/bm/raw/2018-12-27-00-45-vnode02.pl.sophia.inria.fr-138.96.112.102.txt myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/cups/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/cups/access_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/cups/error_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/cups/page_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/access_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/error_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/ssl_access_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/ssl_error_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/httpd/ssl_request_log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/journal/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/journal/38e8246917724c40a37d24855fdd1d26/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/journal/38e8246917724c40a37d24855fdd1d26/system.journal myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/mail/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/mail/statistics myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/php-fpm/ myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/php-fpm/error.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/php-fpm/www-error.log myplc.var-log.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/private/ node.var-log.vnode02.pl.sophia.inria.fr/ node.var-log.vnode02.pl.sophia.inria.fr/README node.var-log.vnode02.pl.sophia.inria.fr/btmp node.var-log.vnode02.pl.sophia.inria.fr/codemux.log node.var-log.vnode02.pl.sophia.inria.fr/cron node.var-log.vnode02.pl.sophia.inria.fr/dnf.librepo.log node.var-log.vnode02.pl.sophia.inria.fr/dnf.log node.var-log.vnode02.pl.sophia.inria.fr/dnf.rpm.log node.var-log.vnode02.pl.sophia.inria.fr/hawkey.log node.var-log.vnode02.pl.sophia.inria.fr/lastlog node.var-log.vnode02.pl.sophia.inria.fr/lxc-sliceimage node.var-log.vnode02.pl.sophia.inria.fr/maillog node.var-log.vnode02.pl.sophia.inria.fr/messages node.var-log.vnode02.pl.sophia.inria.fr/nodemanager node.var-log.vnode02.pl.sophia.inria.fr/nodemanager.daemon node.var-log.vnode02.pl.sophia.inria.fr/secure node.var-log.vnode02.pl.sophia.inria.fr/spooler node.var-log.vnode02.pl.sophia.inria.fr/tallylog node.var-log.vnode02.pl.sophia.inria.fr/vsys node.var-log.vnode02.pl.sophia.inria.fr/wtmp node.var-log.vnode02.pl.sophia.inria.fr/glusterfs/ node.var-log.vnode02.pl.sophia.inria.fr/journal/ node.var-log.vnode02.pl.sophia.inria.fr/journal/31b81a8f05b64a16bb9c563efe1cc47b/ node.var-log.vnode02.pl.sophia.inria.fr/journal/31b81a8f05b64a16bb9c563efe1cc47b/system.journal node.var-log.vnode02.pl.sophia.inria.fr/journal/31b81a8f05b64a16bb9c563efe1cc47b/user-1000.journal node.var-log.vnode02.pl.sophia.inria.fr/journal/31b81a8f05b64a16bb9c563efe1cc47b/user-1001.journal node.var-log.vnode02.pl.sophia.inria.fr/journal/31b81a8f05b64a16bb9c563efe1cc47b/user-1002.journal node.var-log.vnode02.pl.sophia.inria.fr/libvirt/ node.var-log.vnode02.pl.sophia.inria.fr/libvirt/libxl/ node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/ node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/auto_drl.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/auto_netflow.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/auto_sfacm.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/auto_sirius.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/inri_sl1.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/inri_sl2.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/lxc/inri_sl3.log node.var-log.vnode02.pl.sophia.inria.fr/libvirt/qemu/ node.var-log.vnode02.pl.sophia.inria.fr/libvirt/uml/ node.var-log.vnode02.pl.sophia.inria.fr/mail/ node.var-log.vnode02.pl.sophia.inria.fr/mail/statistics node.var-log.vnode02.pl.sophia.inria.fr/ntpstats/ node.var-log.vnode02.pl.sophia.inria.fr/openvswitch/ node.var-log.vnode02.pl.sophia.inria.fr/openvswitch/ovs-vswitchd.log node.var-log.vnode02.pl.sophia.inria.fr/openvswitch/ovsdb-server.log node.var-log.vnode02.pl.sophia.inria.fr/ppp/ node.var-log.vnode02.pl.sophia.inria.fr/private/ node.var-log.vnode02.pl.sophia.inria.fr/swtpm/ node.var-log.vnode02.pl.sophia.inria.fr/swtpm/libvirt/ node.var-log.vnode02.pl.sophia.inria.fr/swtpm/libvirt/qemu/ sfi.plctest1_vplc24.2018.12.27--py2-f29-1-vplc24/ sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/ sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/README sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/btmp sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/dnf.librepo.log sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/dnf.log sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/dnf.rpm.log sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/hawkey.log sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/lastlog sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/sudo sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/tallylog sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/wtmp sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/journal/ sliver.var-log.inri_sl1@vnode02.pl.sophia.inria.fr/private/ sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/ sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/README sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/btmp sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/dnf.librepo.log sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/dnf.log sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/dnf.rpm.log sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/hawkey.log sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/lastlog sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/sudo sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/tallylog sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/wtmp sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/journal/ sliver.var-log.inri_sl2@vnode02.pl.sophia.inria.fr/private/ sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/ sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/README sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/btmp sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/dnf.librepo.log sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/dnf.log sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/dnf.rpm.log sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/hawkey.log sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/lastlog sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/sudo sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/tallylog sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/wtmp sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/journal/ sliver.var-log.inri_sl3@vnode02.pl.sophia.inria.fr/private/ sent 2,105 bytes received 69,939,821 bytes 46,627,950.67 bytes/sec total size is 69,914,534 speedup is 1.00 ++ rootdir 2018.12.27--py2-f29 ++ slice=2018.12.27--py2-f29 ++ shift ++ echo /vservers/2018.12.27--py2-f29 + chmod -R a+r /vservers/2018.12.27--py2-f29/build/testlogs/ ++ rootdir 2018.12.27--py2-f29 ++ slice=2018.12.27--py2-f29 ++ shift ++ echo /vservers/2018.12.27--py2-f29 + webpublish_rsync_dir /build/lxc//2018.12.27--py2-f29/testlogs/ /vservers/2018.12.27--py2-f29/build/testlogs/ + rsync --archive --delete /vservers/2018.12.27--py2-f29/build/testlogs/ root@build.onelab.eu:/build/lxc//2018.12.27--py2-f29/testlogs/ ++ date + echo '============================== END lbuild-nightly.sh:run_log on Thu Dec 27 01:46:53 CET 2018' ============================== END lbuild-nightly.sh:run_log on Thu Dec 27 01:46:53 CET 2018 + '[' -z '' ']' + echo 'Tests have failed - bailing out' Tests have failed - bailing out + failure + set -x + '[' -z /build/lxc//2018.12.27--py2-f29/log.txt ']' + webpublish mkdir -p /build/lxc//2018.12.27--py2-f29 + ssh root@build.onelab.eu mkdir -p /build/lxc//2018.12.27--py2-f29 + webpublish_cp_local_to_remote /vservers/2018.12.27--py2-f29/log.txt /build/lxc//2018.12.27--py2-f29/log.txt + scp /vservers/2018.12.27--py2-f29/log.txt root@build.onelab.eu:/build/lxc//2018.12.27--py2-f29/log.txt ******************** BEG SUMMARY Failed to analyze /vservers/2018.12.27--py2-f29/log.txt ******************** END SUMMARY