BootManager log created on: 2017-07-16-20-29 From IP: 138.96.112.120 hostname: vnode20.pl.sophia.inria.fr uploaded file: /tmp/phpqKXLhK (49687 bytes) ----------------- 20:25:54(UTC) BootManager started at: Sun, 16 Jul 2017 20:25:54 +0000 20:25:54(UTC) Running version 5.3 of BootManager. 20:25:54(UTC) Step: Initializing the BootManager. 20:25:54(UTC) Opening connection to API server 20:25:54(UTC) Running on boot cd version: (5, 4) 20:25:54(UTC) Step: Reading node configuration file. 20:25:55(UTC) Checking standard floppy disk for plnode.txt file. 20:25:55(UTC) Mounting /dev/fd0 on /tmp/conffilemount 20:25:55(UTC) sysexec >>> mount -o ro -t ext2,msdos /dev/fd0 /tmp/conffilemount 20:25:55(UTC) ==========stderr mount: special device /dev/fd0 does not exist 20:25:55(UTC) sysexec >>> umount /tmp/conffilemount 20:25:55(UTC) ==========stderr umount: /tmp/conffilemount: not mounted 20:25:55(UTC) Checking flash devices for plnode.txt file. 20:25:55(UTC) Skipping non-scsi device sr0 20:25:55(UTC) Skipping non-removable device sda 20:25:55(UTC) Checking standard floppy disk for planet.cnf file (for legacy nodes). 20:25:55(UTC) Checking / (ramdisk) for plnode.txt file. 20:25:55(UTC) Checking for existence of /plnode.txt 20:25:55(UTC) Checking /usr/boot (cd) for plnode.txt file. 20:25:55(UTC) Checking for existence of /usr/boot/plnode.txt 20:25:55(UTC) Read in contents of file /usr/boot/plnode.txt 20:25:55(UTC) Successfully read and parsed node configuration file. 20:25:55(UTC) Checking that hostname vnode20.pl.sophia.inria.fr resolves 20:25:55(UTC) Hostname vnode20.pl.sophia.inria.fr correctly resolves to 138.96.112.120: 20:25:55(UTC) Using NODE_ID 1 20:25:55(UTC) ReadNodeConfiguration: [5] using plnode.txt from CD in /usr/boot 20:25:55(UTC) Step: Authenticating node with PLC. 20:25:55(UTC) Authentication successful. 20:25:55(UTC) Step: Updating node last boot times at PLC. 20:25:55(UTC) Successfully updated boot state for this node at PLC 20:25:55(UTC) Step: Starting RunlevelAgent.py 20:25:55(UTC) Step: Retrieving details of node from PLC. 20:25:55(UTC) Successfully retrieved node record. 20:25:55(UTC) Current boot state: reinstall 20:25:55(UTC) Node make/model: qemu/minhw 20:25:55(UTC) node model indicates override to hardware requirements. 20:25:55(UTC) Primary network as returned from PLC: {'last_updated': None, 'network': '138.96.112.0', 'ip': '138.96.112.120', 'dns1': '138.96.112.1', 'hostname': None, 'netmask': '255.255.248.0', 'interface_tag_ids': [], 'interface_id': 1, 'broadcast': '138.96.119.255', 'method': 'static', 'mac': '02:34:56:00:00:20', 'node_id': 1, 'gateway': '138.96.112.250', 'dns2': '138.96.112.2', 'is_primary': True, 'type': 'ipv4', 'bwlimit': None} 20:25:55(UTC) NodeFlavour as returned from PLC: {'fcdistro': 'f24', 'nodefamily': 'lxc-f24-x86_64', 'virt': 'lxc', 'extensions': [], 'pldistro': 'lxc', 'plain': False, 'arch': 'x86_64'} 20:25:55(UTC) Step: Starting fallback sshd. 20:25:55(UTC) Creating SSH1 RSA host key /etc/ssh/ssh_host_key 20:25:55(UTC) sysexec >>> ssh-keygen -q -t rsa1 -f /etc/ssh/ssh_host_key -C '' -N '' 20:25:55(UTC) ==========stderr Saving key "/etc/ssh/ssh_host_key" failed: unknown or unsupported key type 20:25:55(UTC) sysexec >>> chmod 600 /etc/ssh/ssh_host_key 20:25:55(UTC) ==========stderr chmod: cannot access '/etc/ssh/ssh_host_key': No such file or directory 20:25:55(UTC) sysexec >>> chmod 644 /etc/ssh/ssh_host_key.pub 20:25:55(UTC) ==========stderr chmod: cannot access '/etc/ssh/ssh_host_key.pub': No such file or directory 20:25:55(UTC) Creating SSH2 DSA host key /etc/ssh/ssh_host_dsa_key 20:25:55(UTC) sysexec >>> ssh-keygen -q -t dsa -f /etc/ssh/ssh_host_dsa_key -C '' -N '' 20:25:55(UTC) sysexec >>> chmod 600 /etc/ssh/ssh_host_dsa_key 20:25:55(UTC) sysexec >>> chmod 644 /etc/ssh/ssh_host_dsa_key.pub 20:25:55(UTC) sysexec >>> cp -f /tmp/source/debug_files//sshd_config /etc/ssh//sshd_config 20:25:55(UTC) Installing debug ssh key for root user 20:25:55(UTC) sysexec >>> cp -f /tmp/source/debug_files//debug_root_ssh_key /root/.ssh/authorized_keys 20:25:55(UTC) sysexec >>> chmod 700 /root/.ssh 20:25:55(UTC) sysexec >>> chmod 600 /root/.ssh/authorized_keys 20:25:55(UTC) Starting sshd 20:25:55(UTC) sysexec >>> service sshd start 20:25:55(UTC) ==========stderr Redirecting to /bin/systemctl start sshd.service Calling manager for StartUnit on sshd.service, replace Sent message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=StartUnit cookie=1 reply_cookie=0 error=n/a Got message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a Sent message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=GetUnit cookie=2 reply_cookie=0 error=n/a Got message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=4 reply_cookie=2 error=n/a Sent message type=method_call sender=n/a destination=org.freedesktop.systemd1 object=/org/freedesktop/systemd1/unit/sshd_2eservice interface=org.freedesktop.DBus.Properties member=Get cookie=3 reply_cookie=0 error=n/a Got message type=method_return sender=n/a destination=n/a object=n/a interface=n/a member=n/a cookie=5 reply_cookie=3 error=n/a Adding /org/freedesktop/systemd1/job/159 to the set Got message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobNew cookie=2 reply_cookie=0 error=n/a Got message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1 interface=org.freedesktop.systemd1.Manager member=JobRemoved cookie=3 reply_cookie=0 error=n/a Got result done/No such file or directory for job sshd.service 20:25:55(UTC) sysexec >>> touch /tmp/SSHD_RUNNING 20:25:55(UTC) Step: Checking if hardware requirements met. 20:25:55(UTC) Checking for available memory. 20:25:55(UTC) Looks like we have enough memory: 2047028 kb 20:26:00(UTC) Detected block devices: 20:26:00(UTC) {'/dev/sda': (8, 0, 104857600, 107.3741824, False)} 20:26:00(UTC) Usable block devices: 20:26:00(UTC) ['/dev/sda'] 20:26:00(UTC) The total usable disk size of all disks is insufficient, but running anyway. 20:26:00(UTC) Total size for all usable block devices: 107.37 Gb 20:26:00(UTC) Step: Install: Initializing. 20:26:00(UTC) Unmounting any previous mounts 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg/proc 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg/proc: mountpoint not found 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg/mnt/cdrom 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg/mnt/cdrom: mountpoint not found 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg/vservers 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg/vservers: mountpoint not found 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg/sys 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg/sys: mountpoint not found 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg/dev 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg/dev: mountpoint not found 20:26:00(UTC) sysexec >>> umount /tmp/mnt/sysimg 20:26:00(UTC) ==========stderr umount: /tmp/mnt/sysimg: mountpoint not found 20:26:00(UTC) Cleaning up any existing PlanetLab config files 20:26:00(UTC) Creating system image path 20:26:00(UTC) Step: Install: partitioning disks. 20:26:00(UTC) sysexec >>> swapoff /dev/mapper/planetlab-swap 20:26:00(UTC) ==========stderr swapoff: /dev/mapper/planetlab-swap: swapoff failed: No such file or directory 20:26:00(UTC) sysexec >>> vgscan 20:26:00(UTC) ==========stdout Reading all physical volumes. This may take a while... 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgscan invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on vgscan invocation. Parent PID 653: /usr/bin/python 20:26:00(UTC) sysexec >>> vgchange -ay 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgchange invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on vgchange invocation. Parent PID 653: /usr/bin/python 20:26:00(UTC) sysexec >>> lvremove -f /dev/mapper/planetlab-root 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvremove invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on lvremove invocation. Parent PID 653: /usr/bin/python Volume group "planetlab" not found Cannot process volume group planetlab 20:26:00(UTC) sysexec >>> lvremove -f /dev/mapper/planetlab-swap 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvremove invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on lvremove invocation. Parent PID 653: /usr/bin/python Volume group "planetlab" not found Cannot process volume group planetlab 20:26:00(UTC) sysexec >>> lvremove -f /dev/mapper/planetlab-vservers 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvremove invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on lvremove invocation. Parent PID 653: /usr/bin/python Volume group "planetlab" not found Cannot process volume group planetlab 20:26:00(UTC) sysexec >>> vgchange -an 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgchange invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on vgchange invocation. Parent PID 653: /usr/bin/python 20:26:00(UTC) sysexec >>> vgremove -f planetlab 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgremove invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on vgremove invocation. Parent PID 653: /usr/bin/python Volume group "planetlab" not found Cannot process volume group planetlab 20:26:00(UTC) Running vgscan for devices 20:26:00(UTC) sysexec >>> vgscan 20:26:00(UTC) ==========stdout Reading all physical volumes. This may take a while... 20:26:00(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgscan invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[35220]) leaked on vgscan invocation. Parent PID 653: /usr/bin/python 20:26:00(UTC) Using pyparted 2.x 20:26:00(UTC) sysexec >>> dd if=/dev/zero of=/dev/sda bs=512 count=1 20:26:00(UTC) ==========stderr 1+0 records in 1+0 records out 512 bytes copied, 0.0254426 s, 20.1 kB/s 20:26:00(UTC) Current disk for /dev/sda - partition type msdos parted.Disk instance -- type: msdos primaryPartitionCount: 1 lastPartitionNumber: 1 maxPrimaryPartitionCount: 4 partitions: [] device: PedDisk: <_ped.Disk object at 0x7f071a6a47e8> 20:26:00(UTC) Current dev for /dev/sda parted.Device instance -- model: ATA QEMU HARDDISK path: /dev/sda type: 1 sectorSize: 512 physicalSectorSize: 512 length: 209715200 openCount: 0 readOnly: False externalMode: False dirty: False bootDirty: False host: 1 did: 0 busy: False hardwareGeometry: (411206, 255, 2) biosGeometry: (411206, 255, 2) PedDevice: <_ped.Device object at 0x7f071bb94950> 20:26:00(UTC) Successfully initialized /dev/sda 20:26:00(UTC) partitions=['/dev/sda1'] 20:26:00(UTC) sysexec >>> dd if=/dev/zero of=/dev/sda1 bs=512 count=1 20:26:00(UTC) ==========stderr 1+0 records in 1+0 records out 512 bytes copied, 0.00167264 s, 306 kB/s 20:26:01(UTC) sysexec >>> pvcreate -ffy /dev/sda1 20:26:01(UTC) ==========stdout Physical volume "/dev/sda1" successfully created. 20:26:01(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on pvcreate invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on pvcreate invocation. Parent PID 653: /usr/bin/python 20:26:01(UTC) sysexec >>> vgcreate -s32M planetlab /dev/sda1 20:26:01(UTC) ==========stdout Volume group "planetlab" successfully created 20:26:01(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgcreate invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on vgcreate invocation. Parent PID 653: /usr/bin/python 20:26:01(UTC) sysexec >>> lvcreate -L1G -nswap planetlab 20:26:01(UTC) ==========stdout Logical volume "swap" created. 20:26:01(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python 20:26:01(UTC) sysexec >>> lvcreate -L70G -nroot planetlab 20:26:01(UTC) ==========stdout Logical volume "root" created. 20:26:01(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python 20:26:01(UTC) sysexec >>> lvcreate -l927 -nvservers planetlab 20:26:01(UTC) ==========stdout Logical volume "vservers" created. 20:26:01(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on lvcreate invocation. Parent PID 653: /usr/bin/python 20:26:01(UTC) sysexec >>> mkswap -f /dev/mapper/planetlab-swap 20:26:01(UTC) ==========stdout Setting up swapspace version 1, size = 1024 MiB (1073737728 bytes) no label, UUID=66d59e23-99a1-4d24-a506-5a2aa2abb068 20:26:01(UTC) formatting root partition (/dev/mapper/planetlab-root). 20:26:02(UTC) sysexec >>> mkfs.ext2 -q -m 5 -j /dev/mapper/planetlab-root 20:26:07(UTC) sysexec >>> tune2fs -c -1 -i 0 /dev/mapper/planetlab-root 20:26:07(UTC) ==========stdout tune2fs 1.42.13 (17-May-2015) Setting maximal mount count to -1 Setting interval between checks to 0 seconds 20:26:07(UTC) formatting vservers btrfs partition (/dev/mapper/planetlab-vservers). 20:26:07(UTC) sysexec >>> mkfs.btrfs -f /dev/mapper/planetlab-vservers 20:26:08(UTC) ==========stdout btrfs-progs v4.5.2 See http://btrfs.wiki.kernel.org for more information. Performing full device TRIM (28.97GiB) ... Label: (null) UUID: 3e862368-33c5-476f-ae22-245e9b020407 Node size: 16384 Sector size: 4096 Filesystem size: 28.97GiB Block group profiles: Data: single 8.00MiB Metadata: DUP 1.01GiB System: DUP 12.00MiB SSD detected: no Incompat features: extref, skinny-metadata Number of devices: 1 Devices: ID SIZE PATH 1 28.97GiB /dev/mapper/planetlab-vservers 20:26:08(UTC) Block devices used (in lvm): ['/dev/sda'] 20:26:08(UTC) End of InstallPartitionDisks - PARTITIONS status - BEG 20:26:08(UTC) sysexec >>> vgdisplay 20:26:08(UTC) ==========stdout --- Volume group --- VG Name planetlab System ID Format lvm2 Metadata Areas 1 Metadata Sequence No 4 VG Access read/write VG Status resizable MAX LV 0 Cur LV 3 Open LV 0 Max PV 0 Cur PV 1 Act PV 1 VG Size 99.97 GiB PE Size 32.00 MiB Total PE 3199 Alloc PE / Size 3199 / 99.97 GiB Free PE / Size 0 / 0 VG UUID GMTctw-WbSS-Jlac-YNfO-bhxZ-HV1b-kucQ02 20:26:08(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python 20:26:08(UTC) sysexec >>> pvdisplay 20:26:08(UTC) ==========stdout --- Physical volume --- PV Name /dev/sda1 VG Name planetlab PV Size 100.00 GiB / not usable 32.00 MiB Allocatable yes (but full) PE Size 32.00 MiB Total PE 3199 Free PE 0 Allocated PE 3199 PV UUID W8oftb-sDin-fMe4-aDv7-Bw96-0rKF-qZ43EW 20:26:08(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python 20:26:08(UTC) PARTITIONS[root]=/dev/mapper/planetlab-root 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-root 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-root -> ../dm-1 20:26:08(UTC) PARTITIONS[swap]=/dev/mapper/planetlab-swap 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-swap 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-swap -> ../dm-0 20:26:08(UTC) PARTITIONS[vservers]=/dev/mapper/planetlab-vservers 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-vservers 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-vservers -> ../dm-2 20:26:08(UTC) End of InstallPartitionDisks - PARTITIONS status - END 20:26:08(UTC) Step: Install: bootstrapfs tarball (upgrade=False). 20:26:08(UTC) In InstallBootstrapFS - PARTITIONS status - BEG 20:26:08(UTC) sysexec >>> vgdisplay 20:26:08(UTC) ==========stdout --- Volume group --- VG Name planetlab System ID Format lvm2 Metadata Areas 1 Metadata Sequence No 4 VG Access read/write VG Status resizable MAX LV 0 Cur LV 3 Open LV 0 Max PV 0 Cur PV 1 Act PV 1 VG Size 99.97 GiB PE Size 32.00 MiB Total PE 3199 Alloc PE / Size 3199 / 99.97 GiB Free PE / Size 0 / 0 VG UUID GMTctw-WbSS-Jlac-YNfO-bhxZ-HV1b-kucQ02 20:26:08(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python 20:26:08(UTC) sysexec >>> pvdisplay 20:26:08(UTC) ==========stdout --- Physical volume --- PV Name /dev/sda1 VG Name planetlab PV Size 100.00 GiB / not usable 32.00 MiB Allocatable yes (but full) PE Size 32.00 MiB Total PE 3199 Free PE 0 Allocated PE 3199 PV UUID W8oftb-sDin-fMe4-aDv7-Bw96-0rKF-qZ43EW 20:26:08(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[37818]) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python 20:26:08(UTC) PARTITIONS[root]=/dev/mapper/planetlab-root 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-root 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-root -> ../dm-1 20:26:08(UTC) PARTITIONS[swap]=/dev/mapper/planetlab-swap 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-swap 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-swap -> ../dm-0 20:26:08(UTC) PARTITIONS[vservers]=/dev/mapper/planetlab-vservers 20:26:08(UTC) sysexec >>> ls -l /dev/mapper/planetlab-vservers 20:26:08(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-vservers -> ../dm-2 20:26:08(UTC) In InstallBootstrapFS - PARTITIONS status - END 20:26:08(UTC) turning on swap space 20:26:08(UTC) sysexec >>> swapon /dev/mapper/planetlab-swap 20:26:08(UTC) mounting root file system 20:26:08(UTC) sysexec >>> mount -t ext3 /dev/mapper/planetlab-root /tmp/mnt/sysimg 20:26:08(UTC) mounting vserver partition in root file system (type btrfs) 20:26:08(UTC) sysexec >>> mount -t btrfs /dev/mapper/planetlab-vservers /tmp/mnt/sysimg/vservers 20:26:08(UTC) Enabling btrfs quota on /tmp/mnt/sysimg/vservers 20:26:08(UTC) Using compressed bootstrapfs images 20:26:08(UTC) Using nodefamily=lxc-f24-x86_64 20:26:08(UTC) Installing only core software 20:26:08(UTC) downloading /boot/bootstrapfs-lxc-f24-x86_64.tar.bz2 20:26:30(UTC) Done downloading (22 seconds) 20:26:30(UTC) downloading sha1sum for /boot/bootstrapfs-lxc-f24-x86_64.tar.bz2 20:26:30(UTC) verifying sha1sum for /boot/bootstrapfs-lxc-f24-x86_64.tar.bz2 20:26:31(UTC) extracting /tmp/mnt/sysimg/bootstrapfs-lxc-f24-x86_64.tar.bz2 in /tmp/mnt/sysimg 20:26:31(UTC) sysexec >>> tar -C /tmp/mnt/sysimg -xpf /tmp/mnt/sysimg/bootstrapfs-lxc-f24-x86_64.tar.bz2 -j 20:27:55(UTC) Done extracting (83 seconds) 20:27:55(UTC) Copying resolv.conf to temp dir 20:27:55(UTC) sysexec >>> cp /etc/resolv.conf /tmp/mnt/sysimg/etc/ 20:27:55(UTC) Copying boot server certificates and public key 20:27:55(UTC) sysexec (shell mode) >>> gpg --homedir=/root --export --armor --no-default-keyring --keyring /tmp/mnt/sysimg/usr/boot/pubring.gpg > /tmp/mnt/sysimg/etc/pki/rpm-gpg/RPM-GPG-KEY-planetlab20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg rpm --import /etc/pki/rpm-gpg/RPM-GPG-KEY-planetlab 20:27:55(UTC) Step: Install: Writing configuration files. 20:27:55(UTC) Setting local time to UTC 20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg ln -sf /usr/share/zoneinfo/UTC /etc/localtime 20:27:55(UTC) Creating system directory /etc/planetlab 20:27:55(UTC) Writing system /etc/fstab 20:27:55(UTC) Writing system /etc/issue 20:27:55(UTC) Setting up authentication (non-ssh) 20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg authconfig --nostart --kickstart --enablemd5 --enableshadow 20:27:55(UTC) ==========stderr getsebool: SELinux is disabled 20:27:55(UTC) sysexec (shell mode) >>> sed -e 's/^root\:\:/root\:*\:/g' /tmp/mnt/sysimg/etc/shadow > /tmp/mnt/sysimg/etc/shadow.new20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg mv /etc/shadow.new /etc/shadow 20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg chmod 400 /etc/shadow 20:27:55(UTC) Writing node install_version 20:27:55(UTC) Creating ssh host keys 20:27:55(UTC) Generating SSH1 RSA host key /etc/ssh/ssh_host_key (mandatory success=False) 20:27:55(UTC) sysexec >>> chmod 600 /tmp/mnt/sysimg//etc/ssh/ssh_host_key 20:27:55(UTC) ==========stderr chmod: cannot access '/tmp/mnt/sysimg//etc/ssh/ssh_host_key': No such file or directory 20:27:55(UTC) sysexec >>> chmod 644 /tmp/mnt/sysimg//etc/ssh/ssh_host_key.pub 20:27:55(UTC) ==========stderr chmod: cannot access '/tmp/mnt/sysimg//etc/ssh/ssh_host_key.pub': No such file or directory 20:27:55(UTC) Generating SSH2 RSA host key /etc/ssh/ssh_host_rsa_key (mandatory success=True) 20:27:55(UTC) sysexec >>> chroot /tmp/mnt/sysimg /usr/bin/ssh-keygen -q -t rsa -f /etc/ssh/ssh_host_rsa_key -C '' -N '' 20:27:56(UTC) sysexec >>> chmod 600 /tmp/mnt/sysimg//etc/ssh/ssh_host_rsa_key 20:27:56(UTC) sysexec >>> chmod 644 /tmp/mnt/sysimg//etc/ssh/ssh_host_rsa_key.pub 20:27:56(UTC) Generating SSH2 DSA host key /etc/ssh/ssh_host_dsa_key (mandatory success=True) 20:27:56(UTC) sysexec >>> chroot /tmp/mnt/sysimg /usr/bin/ssh-keygen -q -t dsa -f /etc/ssh/ssh_host_dsa_key -C '' -N '' 20:27:56(UTC) sysexec >>> chmod 600 /tmp/mnt/sysimg//etc/ssh/ssh_host_dsa_key 20:27:56(UTC) sysexec >>> chmod 644 /tmp/mnt/sysimg//etc/ssh/ssh_host_dsa_key.pub 20:27:56(UTC) Step: Install: Shutting down installer. 20:27:56(UTC) Shutting down swap 20:27:56(UTC) sysexec >>> swapoff /dev/mapper/planetlab-swap 20:27:56(UTC) Step: Running Ansible Hook 20:27:56(UTC) No Ansible directive. Skipping. 20:27:56(UTC) Step: Updating node boot state at PLC. 20:27:56(UTC) Successfully updated boot state for this node at PLC 20:27:56(UTC) Step: Starting fallback sshd. 20:27:56(UTC) Creating SSH1 RSA host key /etc/ssh/ssh_host_key 20:27:56(UTC) sysexec >>> ssh-keygen -q -t rsa1 -f /etc/ssh/ssh_host_key -C '' -N '' 20:27:56(UTC) ==========stderr Saving key "/etc/ssh/ssh_host_key" failed: unknown or unsupported key type 20:27:56(UTC) sysexec >>> chmod 600 /etc/ssh/ssh_host_key 20:27:56(UTC) ==========stderr chmod: cannot access '/etc/ssh/ssh_host_key': No such file or directory 20:27:56(UTC) sysexec >>> chmod 644 /etc/ssh/ssh_host_key.pub 20:27:56(UTC) ==========stderr chmod: cannot access '/etc/ssh/ssh_host_key.pub': No such file or directory 20:27:56(UTC) sysexec >>> cp -f /tmp/source/debug_files//sshd_config /etc/ssh//sshd_config 20:27:56(UTC) Installing debug ssh key for root user 20:27:56(UTC) sysexec >>> cp -f /tmp/source/debug_files//debug_root_ssh_key /root/.ssh/authorized_keys 20:27:56(UTC) sysexec >>> chmod 700 /root/.ssh 20:27:56(UTC) sysexec >>> chmod 600 /root/.ssh/authorized_keys 20:27:56(UTC) sshd is already running 20:27:56(UTC) Step: Install: Initializing. 20:27:56(UTC) Unmounting any previous mounts 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg/proc 20:27:56(UTC) ==========stderr umount: /tmp/mnt/sysimg/proc: not mounted 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg/mnt/cdrom 20:27:56(UTC) ==========stderr umount: /tmp/mnt/sysimg/mnt/cdrom: not mounted 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg/vservers 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg/sys 20:27:56(UTC) ==========stderr umount: /tmp/mnt/sysimg/sys: not mounted 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg/dev 20:27:56(UTC) ==========stderr umount: /tmp/mnt/sysimg/dev: not mounted 20:27:56(UTC) sysexec >>> umount /tmp/mnt/sysimg 20:27:57(UTC) Cleaning up any existing PlanetLab config files 20:27:57(UTC) Creating system image path 20:27:57(UTC) Step: Validating node installation. 20:27:57(UTC) sysexec >>> vgscan 20:27:57(UTC) ==========stdout Reading all physical volumes. This may take a while... Found volume group "planetlab" using metadata type lvm2 20:27:57(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgscan invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on vgscan invocation. Parent PID 653: /usr/bin/python 20:27:57(UTC) sysexec >>> vgchange -ay planetlab 20:27:57(UTC) ==========stdout 3 logical volume(s) in volume group "planetlab" now active 20:27:57(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgchange invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on vgchange invocation. Parent PID 653: /usr/bin/python 20:27:57(UTC) fsck root file system 20:27:57(UTC) sysexec >>> e2fsck -v -p /dev/mapper/planetlab-root 20:27:57(UTC) ==========stdout /dev/mapper/planetlab-root: clean, 31823/4587520 files, 560794/18350080 blocks 20:27:57(UTC) sysexec >>> tune2fs -c -1 -i 0 /dev/mapper/planetlab-root 20:27:57(UTC) ==========stdout tune2fs 1.42.13 (17-May-2015) Setting maximal mount count to -1 Setting interval between checks to 0 seconds 20:27:57(UTC) mounting root file system 20:27:57(UTC) sysexec >>> mount -t ext3 /dev/mapper/planetlab-root /tmp/mnt/sysimg 20:27:57(UTC) mounting /proc 20:27:57(UTC) sysexec >>> mount -t proc none /tmp/mnt/sysimg/proc 20:27:57(UTC) mounting vservers partition in root file system 20:27:57(UTC) sysexec >>> mount -t btrfs /dev/mapper/planetlab-vservers /tmp/mnt/sysimg/vservers 20:27:57(UTC) Checking for a custom kernel 20:27:57(UTC) Updated /etc/planetlab/node_id 20:27:57(UTC) Node installation appears to be ok 20:27:57(UTC) Step: Install: Writing Network Configuration files. 20:27:57(UTC) Writing /etc/planetlab/plc_config 20:27:57(UTC) getting via https://vplc20.pl.sophia.inria.fr/PlanetLabConf/get_plc_config.php 20:27:57(UTC) .. Failed. Using old method. -- stack trace follows Traceback (most recent call last): File "/tmp/source/steps/WriteNetworkConfig.py", line 126, in Run bootserver.connect() File "/usr/lib64/python2.7/httplib.py", line 1263, in connect server_hostname=server_hostname) File "/usr/lib64/python2.7/ssl.py", line 363, in wrap_socket _context=self) File "/usr/lib64/python2.7/ssl.py", line 611, in __init__ self.do_handshake() File "/usr/lib64/python2.7/ssl.py", line 840, in do_handshake self._sslobj.do_handshake() SSLError: [SSL: CERTIFICATE_VERIFY_FAILED] certificate verify failed (_ssl.c:661) 20:27:57(UTC) Writing /etc/hosts 20:27:57(UTC) net:InitInterfaces interface 1: {'last_updated': None, 'network': '138.96.112.0', 'ip': '138.96.112.120', 'dns1': '138.96.112.1', 'hostname': None, 'netmask': '255.255.248.0', 'interface_tag_ids': [], 'interface_id': 1, 'broadcast': '138.96.119.255', 'method': 'static', 'mac': '02:34:56:00:00:20', 'node_id': 1, 'gateway': '138.96.112.250', 'dns2': '138.96.112.2', 'is_primary': True, 'type': 'ipv4', 'bwlimit': None} 20:27:57(UTC) net:InitInterfaces macs = {'00:00:00:00:00:00': 'lo', '02:34:56:00:00:20': 'eth0'} 20:27:57(UTC) net:InitInterfaces ips = {'138.96.112.120': 'eth0', '127.0.0.1': 'lo'} 20:27:57(UTC) net:InitInterfaces orig_ifname = eth0 20:27:57(UTC) net:InitInterfaces: Device map: {'eth0': {'USERCTL': 'no', 'DNS2': '138.96.112.2', 'DNS1': '138.96.112.1', 'IPADDR': '138.96.112.120', 'PRIMARY': 'yes', 'GATEWAY': '138.96.112.250', 'NM_CONTROLLED': 'no', 'NETMASK': '255.255.248.0', 'BOOTPROTO': 'static', 'HWADDR': '02:34:56:00:00:20', 'ONBOOT': 'yes'}} 20:27:57(UTC) net:InitInterfaces adding configuration for eth0 20:27:57(UTC) Step: Checking for unused disks to add to LVM. 20:27:57(UTC) In CheckForNewDisks - PARTITIONS status - BEG 20:27:57(UTC) sysexec >>> vgdisplay 20:27:57(UTC) ==========stdout --- Volume group --- VG Name planetlab System ID Format lvm2 Metadata Areas 1 Metadata Sequence No 4 VG Access read/write VG Status resizable MAX LV 0 Cur LV 3 Open LV 2 Max PV 0 Cur PV 1 Act PV 1 VG Size 99.97 GiB PE Size 32.00 MiB Total PE 3199 Alloc PE / Size 3199 / 99.97 GiB Free PE / Size 0 / 0 VG UUID GMTctw-WbSS-Jlac-YNfO-bhxZ-HV1b-kucQ02 20:27:57(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python 20:27:57(UTC) sysexec >>> pvdisplay 20:27:57(UTC) ==========stdout --- Physical volume --- PV Name /dev/sda1 VG Name planetlab PV Size 100.00 GiB / not usable 32.00 MiB Allocatable yes (but full) PE Size 32.00 MiB Total PE 3199 Free PE 0 Allocated PE 3199 PV UUID W8oftb-sDin-fMe4-aDv7-Bw96-0rKF-qZ43EW 20:27:57(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python 20:27:57(UTC) PARTITIONS[root]=/dev/mapper/planetlab-root 20:27:57(UTC) sysexec >>> ls -l /dev/mapper/planetlab-root 20:27:57(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:27 /dev/mapper/planetlab-root -> ../dm-1 20:27:57(UTC) PARTITIONS[swap]=/dev/mapper/planetlab-swap 20:27:57(UTC) sysexec >>> ls -l /dev/mapper/planetlab-swap 20:27:57(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:27 /dev/mapper/planetlab-swap -> ../dm-0 20:27:57(UTC) PARTITIONS[vservers]=/dev/mapper/planetlab-vservers 20:27:57(UTC) sysexec >>> ls -l /dev/mapper/planetlab-vservers 20:27:57(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-vservers -> ../dm-2 20:27:57(UTC) In CheckForNewDisks - PARTITIONS status - END 20:27:57(UTC) Checking device /dev/sda to see if it is part of the volume group. 20:27:57(UTC) sysexec (shell mode) >>> pvdisplay /dev/sda1 | grep -q planetlab20:27:57(UTC) It appears /dev/sda is part of the volume group, continuing. 20:27:57(UTC) No new disk devices to add to volume group. 20:27:57(UTC) Step: Sending hardware configuration to PLC. 20:27:57(UTC) step SendHardwareConfigToPLC Not implemented, continuing. 20:27:57(UTC) Step: Chain booting node. 20:27:57(UTC) In ChainBootNode - PARTITIONS status - BEG 20:27:57(UTC) sysexec >>> vgdisplay 20:27:58(UTC) ==========stdout --- Volume group --- VG Name planetlab System ID Format lvm2 Metadata Areas 1 Metadata Sequence No 4 VG Access read/write VG Status resizable MAX LV 0 Cur LV 3 Open LV 2 Max PV 0 Cur PV 1 Act PV 1 VG Size 99.97 GiB PE Size 32.00 MiB Total PE 3199 Alloc PE / Size 3199 / 99.97 GiB Free PE / Size 0 / 0 VG UUID GMTctw-WbSS-Jlac-YNfO-bhxZ-HV1b-kucQ02 20:27:58(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on vgdisplay invocation. Parent PID 653: /usr/bin/python 20:27:58(UTC) sysexec >>> pvdisplay 20:27:58(UTC) ==========stdout --- Physical volume --- PV Name /dev/sda1 VG Name planetlab PV Size 100.00 GiB / not usable 32.00 MiB Allocatable yes (but full) PE Size 32.00 MiB Total PE 3199 Free PE 0 Allocated PE 3199 PV UUID W8oftb-sDin-fMe4-aDv7-Bw96-0rKF-qZ43EW 20:27:58(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[39856]) leaked on pvdisplay invocation. Parent PID 653: /usr/bin/python 20:27:58(UTC) PARTITIONS[root]=/dev/mapper/planetlab-root 20:27:58(UTC) sysexec >>> ls -l /dev/mapper/planetlab-root 20:27:58(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:27 /dev/mapper/planetlab-root -> ../dm-1 20:27:58(UTC) PARTITIONS[swap]=/dev/mapper/planetlab-swap 20:27:58(UTC) sysexec >>> ls -l /dev/mapper/planetlab-swap 20:27:58(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:27 /dev/mapper/planetlab-swap -> ../dm-0 20:27:58(UTC) PARTITIONS[vservers]=/dev/mapper/planetlab-vservers 20:27:58(UTC) sysexec >>> ls -l /dev/mapper/planetlab-vservers 20:27:58(UTC) ==========stdout lrwxrwxrwx 1 root root 7 Jul 16 20:26 /dev/mapper/planetlab-vservers -> ../dm-2 20:27:58(UTC) In ChainBootNode - PARTITIONS status - END 20:27:58(UTC) Updated /etc/planetlab/session 20:27:58(UTC) Updating configuration files. 20:27:58(UTC) sysexec >>> chroot /tmp/mnt/sysimg /usr/bin/env python /usr/share/NodeManager/conf_files.py --noscripts 20:28:01(UTC) Running node update. 20:28:01(UTC) sysexec >>> chroot /tmp/mnt/sysimg /usr/bin/NodeUpdate.py start noreboot 20:29:02(UTC) ==========stdout 0 files removed cachedir: /var/cache/dnf DNF version: 1.1.10 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 updates: using metadata from Sat Jul 15 18:08:46 2017. lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. fedora: using metadata from Tue Jun 14 16:07:53 2016. Last metadata expiration check: 0:00:26 ago on Sun Jul 16 20:28:23 2017. --> Starting dependency resolution --> Finished dependency resolution Dependencies resolved. Nothing to do. Complete! cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 repo: using cache for: updates repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. updates: using metadata from Sat Jul 15 18:08:46 2017. fedora: using metadata from Tue Jun 14 16:07:53 2016. Last metadata expiration check: 0:00:31 ago on Sun Jul 16 20:28:23 2017. --> Starting dependency resolution --> Finished dependency resolution Dependencies resolved. Nothing to do. Complete! cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 repo: using cache for: updates fedora: using metadata from Tue Jun 14 16:07:53 2016. lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. updates: using metadata from Sat Jul 15 18:08:46 2017. Last metadata expiration check: 0:00:32 ago on Sun Jul 16 20:28:23 2017. --> Starting dependency resolution --> Finished dependency resolution Dependencies resolved. Nothing to do. Complete! cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 repo: using cache for: updates repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 fedora: using metadata from Tue Jun 14 16:07:53 2016. updates: using metadata from Sat Jul 15 18:08:46 2017. lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. Last metadata expiration check: 0:00:32 ago on Sun Jul 16 20:28:23 2017. No package nodemanager-vs available. cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: updates repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 updates: using metadata from Sat Jul 15 18:08:46 2017. lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. fedora: using metadata from Tue Jun 14 16:07:53 2016. Last metadata expiration check: 0:00:33 ago on Sun Jul 16 20:28:23 2017. Adding packages from group 'planetlab': {'openssh-clients', 'kbd', 'hdparm', 'fprobe-ulog', 'transforward', 'vconfig', 'comgt', 'nano', 'less', 'readline', 'dhcp-client', 'coreutils', 'ed', 'iproute', 'sliceimage-system-lxc-f24-x86_64', 'filesystem', 'lxc-userspace', 'emacs-nox', 'sliceimage-lxc-f24-x86_64', 'policycoreutils', 'rpm', 'net-tools', 'traceroute', 'parted', 'ethtool', 'linux-firmware', 'expect', 'e2fsprogs', 'systemd', 'nfs-utils', 'diffutils', 'rsh', 'setup', 'wireless-tools', 'lxc-sliceimage', 'python3', 'authconfig', 'telnet', 'cronie-anacron', 'NodeUpdate', 'bash', 'passwd', 'kernel-modules', 'tcpdump', 'nmap-ncat', 'vim-enhanced', 'crontabs', 'ppp', 'codemux', 'perl', 'at', 'setserial', 'wget', 'libnl3', 'nodemanager-lxc', 'vsys-scripts', 'tmpwatch', 'pciutils', 'cpio', 'logrotate', 'iptables', 'sendmail', 'tar', 'systemd-container', 'lvm2', 'basesystem', 'sudo', 'pl_sshd', 'file', 'mailcap', 'btrfs-progs', 'quota', 'glibc', 'kernel-modules-extra', 'planetlab-umts-tools-backend', 'shadow-utils', 'curl', 'rootfiles', 'bridge-utils', 'openssh-server', 'kernel', 'bind-utils', 'initscripts', 'dracut', 'cronie', 'util-linux', 'ipod', 'libvirt', 'rsyslog', 'dnsmasq', 'vsys', 'rsync', 'psacct', 'yum', 'lsof', 'gzip', 'bc', 'openvpn', 'bzip2', 'iputils', 'ntp', 'procps-ng', 'python', 'time', 'bash-completion'} --> Starting dependency resolution ---> Package kernel.x86_64 4.11.9-100.fc24 will be installed --> Finished dependency resolution Dependencies resolved. ================================================================================ Group Packages ================================================================================ Marking packages as installed by the group: @PlanetLab openssh-clients kbd hdparm fprobe-ulog transforward vconfig comgt nano less readline dhcp-client coreutils ed iproute sliceimage-system-lxc-f24-x86_64 filesystem lxc-userspace emacs-nox sliceimage-lxc-f24-x86_64 policycoreutils rpm net-tools traceroute parted ethtool linux-firmware expect e2fsprogs systemd nfs-utils diffutils rsh setup wireless-tools lxc-sliceimage python3 authconfig telnet cronie-anacron NodeUpdate bash passwd kernel-modules tcpdump nmap-ncat vim-enhanced crontabs ppp codemux perl at setserial wget libnl3 nodemanager-lxc vsys-scripts tmpwatch pciutils cpio logrotate iptables sendmail tar systemd-container lvm2 basesystem sudo pl_sshd file mailcap btrfs-progs quota glibc kernel-modules-extra planetlab-umts-tools-backend shadow-utils curl rootfiles bridge-utils openssh-server bind-utils kernel initscripts dracut cronie util-linux ipod libvirt rsyslog dnsmasq vsys rsync psacct yum lsof gzip bc openvpn bzip2 iputils ntp procps-ng python time bash-completion ================================================================================ Package Arch Version Repository Size ================================================================================ Installing: kernel x86_64 4.11.9-100.fc24 updates 84 k Transaction Summary ================================================================================ Install 1 Package Total download size: 84 k Installed size: 0 Downloading Packages: -------------------------------------------------------------------------------- Total 179 kB/s | 84 kB 00:00 Running transaction check Transaction check succeeded. Running transaction test Transaction test succeeded. Running transaction Installing : kernel-4.11.9-100.fc24.x86_64 1/1 Verifying : kernel-4.11.9-100.fc24.x86_64 1/1 Installed: kernel.x86_64 4.11.9-100.fc24 Complete! group persistor: saving. cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 repo: using cache for: updates repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. updates: using metadata from Sat Jul 15 18:08:46 2017. fedora: using metadata from Tue Jun 14 16:07:53 2016. Last metadata expiration check: 0:00:36 ago on Sun Jul 16 20:28:23 2017. group persistor md version: 0.6.0 --> Starting dependency resolution --> Finished dependency resolution Dependencies resolved. Nothing to do. Complete! group persistor: saving. cachedir: /var/cache/dnf DNF version: 1.1.10 repo: using cache for: updates repo: using cache for: fedora not found deltainfo for: Fedora 24 - x86_64 not found updateinfo for: Fedora 24 - x86_64 repo: using cache for: lxc-f24-x86_64 not found deltainfo for: Regression TestLab lxc-f24-x86_64 not found updateinfo for: Regression TestLab lxc-f24-x86_64 updates: using metadata from Sat Jul 15 18:08:46 2017. fedora: using metadata from Tue Jun 14 16:07:53 2016. lxc-f24-x86_64: using metadata from Sun Jul 16 20:24:31 2017. Last metadata expiration check: 0:00:38 ago on Sun Jul 16 20:28:23 2017. --> Starting dependency resolution --> Finished dependency resolution Dependencies resolved. Nothing to do. Complete! ***** 2017-07-16 20:28:01 Turning on messages ***** 2017-07-16 20:28:01 Checking existence of proxy config file... ***** 2017-07-16 20:28:01 Not using any proxy. ***** 2017-07-16 20:28:01 Rebuilding RPM Database. ***** 2017-07-16 20:28:02 Looking for RPMs to be deleted. ***** 2017-07-16 20:28:02 No RPMs listed in file to delete. ***** 2017-07-16 20:28:02 Removing any existing GPG signing keys from the RPM database ***** 2017-07-16 20:28:02 Installing all GPG signing keys in /etc/pki/rpm-gpg ***** 2017-07-16 20:28:02 Removing any existing reboot flags ***** 2017-07-16 20:28:02 Ignoring any reboot flags set by RPMs ***** 2017-07-16 20:28:02 Checking if /usr/bin/dnf supports --verbose ***** 2017-07-16 20:28:03 It does, using --verbose option ***** 2017-07-16 20:28:03 Checking if /usr/bin/dnf supports SSL certificate checks ***** 2017-07-16 20:28:03 Unsupported, not using --sslcertdir option ***** 2017-07-16 20:28:03 Invoking /usr/bin/dnf clean all ***** 2017-07-16 20:28:03 List of crucial packages: ['NodeUpdate', 'nodemanager-lib', 'nodemanager-lxc', 'nodemanager-vs'] ***** 2017-07-16 20:28:03 Invoking /usr/bin/dnf --verbose -y update NodeUpdate ***** 2017-07-16 20:28:54 Invoking /usr/bin/dnf --verbose -y update nodemanager-lib ***** 2017-07-16 20:28:55 Invoking /usr/bin/dnf --verbose -y update nodemanager-lxc ***** 2017-07-16 20:28:56 Invoking /usr/bin/dnf --verbose -y install nodemanager-vs ***** 2017-07-16 20:28:56 Updating PlanetLab group ***** 2017-07-16 20:28:56 Invoking /usr/bin/dnf --verbose -y group install PlanetLab ***** 2017-07-16 20:29:00 Invoking /usr/bin/dnf --verbose -y group upgrade PlanetLab ***** 2017-07-16 20:29:02 Updating rest of system ***** 2017-07-16 20:29:02 Invoking /usr/bin/dnf --verbose -y update ***** 2017-07-16 20:29:02 Checking for extra groups (extensions) to update ***** 2017-07-16 20:29:02 No extra groups found in file. ***** 2017-07-16 20:29:02 Update complete. 20:29:03(UTC) ==========stderr Error: Unable to find a match. 20:29:03(UTC) Step: Updating node configuration. 20:29:03(UTC) Updating vserver's /etc/hosts and /etc/resolv.conf files 20:29:03(UTC) Updating network configuration in: 20:29:03(UTC) /tmp/mnt/sysimg//vservers/.vstub 20:29:03(UTC) /tmp/mnt/sysimg//vservers/.vref 20:29:03(UTC) /tmp/mnt/sysimg//vservers/.lvref 20:29:03(UTC) /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64 20:29:03(UTC) Forcing update on vserver reference directory: /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64 20:29:03(UTC) sysexec (shell mode) >>> echo 'AUTO_UPDATE_NET_FILES' > /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64/etc/AUTO_UPDATE_NET_FILES20:29:03(UTC) Updating network files in /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64. 20:29:03(UTC) sysexec >>> chattr -i /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64/etc/hosts 20:29:03(UTC) sysexec >>> chattr -i /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64/etc/resolv.conf 20:29:03(UTC) ==========stderr chattr: No such file or directory while trying to stat /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64/etc/resolv.conf 20:29:03(UTC) sysexec >>> cp /tmp/mnt/sysimg/etc/resolv.conf /tmp/mnt/sysimg//vservers/.vref/lxc-f24-x86_64/etc 20:29:03(UTC) Updating ssh public host key with PLC. 20:29:03(UTC) Copying kernel and initrd for booting. 20:29:03(UTC) locating kernel: found 1 matches in /boot/vmlinuz-4.11.9-100.fc24.x86_64* 20:29:03(UTC) locating initrd: found 1 matches in /boot/initramfs-4.11.9-100.fc24.x86_64.img 20:29:03(UTC) Unmounting disks. 20:29:03(UTC) sysexec >>> umount /tmp/mnt/sysimg/vservers 20:29:03(UTC) sysexec >>> umount /tmp/mnt/sysimg/proc 20:29:03(UTC) sysexec >>> umount /tmp/mnt/sysimg/dev 20:29:03(UTC) ==========stderr umount: /tmp/mnt/sysimg/dev: not mounted 20:29:03(UTC) sysexec >>> umount /tmp/mnt/sysimg/sys 20:29:03(UTC) ==========stderr umount: /tmp/mnt/sysimg/sys: not mounted 20:29:03(UTC) sysexec >>> umount /tmp/mnt/sysimg 20:29:03(UTC) sysexec >>> vgchange -an 20:29:03(UTC) ==========stdout 0 logical volume(s) in volume group "planetlab" now active 20:29:03(UTC) ==========stderr File descriptor 3 (/tmp/bm.log) leaked on vgchange invocation. Parent PID 653: /usr/bin/python File descriptor 4 (socket:[41085]) leaked on vgchange invocation. Parent PID 653: /usr/bin/python 20:29:03(UTC) Step: Stopping RunlevelAgent.py 20:29:03(UTC) Unloading modules and chain booting to new kernel. 20:29:03(UTC) Uploading logs to /boot/upload-bmlog.php