Installer.log
2023-10-13 21:30:21,125 INFO Scanning devices to assemble RAID. This may take few minutes
2023-10-13 21:30:21,125 INFO Scanning devices: ['nvme2n1', 'nvme0n1', 'nvme1n1', 'sda', 'sdb']
2023-10-13 21:30:22,106 DEBUG ret: 0, out: , error:
2023-10-13 21:30:22,534 INFO Model detected: CommunityEdition
2023-10-13 21:30:23,018 INFO vpd_info {'rackable_unit_serial': u'To Be Filled By O.E.M.', 'vpd_method': None, 'node_serial': u'System Serial Number'}
2023-10-13 21:30:23,018 INFO Using block_id from FRU
2023-10-13 21:30:23,018 INFO Generating cluster_id
2023-10-13 21:30:23,019 INFO node_serial = 8c5390b9-e74f-43ee-b3d9-7b61bde1da7d, node_uuid = 0e34a790-d00f-43cd-b31f-866394dce746, block_id = To Be Filled By O.E.M., cluster_id = 4784288006423010099, model = USE_LAYOUT, model_string = CommunityEdition, node_position =
2023-10-13 21:30:23,568 INFO Re-reading partition table for sdc
2023-10-13 21:30:24,228 INFO LVM volume group ahv was not detected; assuming that AHV is installed on normal partitions
2023-10-13 21:30:24,368 INFO LVM volume group ahv was not detected; assuming that AHV is installed on normal partitions
2023-10-13 21:30:35,395 INFO Getting NOS version from the CVM
2023-10-13 21:30:35,661 DEBUG Marker file found, skipping assembling RAID again
2023-10-13 21:30:36,690 DEBUG Couldn't find AOS release version on the disks
2023-10-13 21:30:36,904 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:32:01,065 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:32:03,581 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:32:06,217 INFO Phoenix ISO is already mounted
2023-10-13 21:32:40,907 INFO Picking the bundled hypervisor:/mnt/svm_installer/./install/ahv/kvm_host_bundle_20220304.342.iso
2023-10-13 21:32:41,372 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:36:18,426 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:36:21,023 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:36:23,565 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:36:26,121 INFO Getting AOS version from /mnt/iso/images/svm/nutanix_installer_package.tar.p00, This may take a few minutes if phoenix is running from BMC
2023-10-13 21:36:59,022 INFO Phoenix ISO is already mounted
2023-10-13 21:37:33,473 INFO Picking the bundled hypervisor:/mnt/svm_installer/./install/ahv/kvm_host_bundle_20220304.342.iso
2023-10-13 21:37:33,674 INFO Phoenix ISO is already mounted
2023-10-13 21:38:08,014 INFO Picking the bundled hypervisor:/mnt/svm_installer/./install/ahv/kvm_host_bundle_20220304.342.iso
2023-10-13 21:38:11,319 DEBUG Available memory 251.808998108, Max allowed memory for CVM: 245.808998108
2023-10-13 21:38:11,320 DEBUG Using new CVM sizing policy: False
2023-10-13 21:38:11,320 DEBUG Determining numa node for allocating vcpu
2023-10-13 21:38:11,320 DEBUG Determining numa node for storage controller:
2023-10-13 21:38:11,323 ERROR Couldn't determine numa_node for device, exception: StandardError(u"Failed command: [lspci -d -nm] with error: [lspci: -d: ':' expected]\nout: []",)
2023-10-13 21:38:11,323 DEBUG Couldn't determine numa node for storage controller
2023-10-13 21:38:11,323 DEBUG Couldn't identify a numa node, falling back to 0
2023-10-13 21:38:11,375 DEBUG Computed CVM size to be vcpu 8, mem 20
2023-10-13 21:38:11,376 DEBUG Enabling numa because sufficient memory and CPUs available on a numa node 0
2023-10-13 21:38:11,400 INFO Running CVM Installer
2023-10-13 21:38:11,400 INFO Generating unique SSH identity for this Hypervisor-CVM pair.
2023-10-13 21:38:11,447 INFO Generating SSL certificate for this Hypervisor-CVM pair.
2023-10-13 21:38:11,454 INFO Extracting the SVM installer into memory. This will take some time...
2023-10-13 21:38:35,939 INFO Injecting SSH keys into SVM installer.
2023-10-13 21:38:35,941 INFO Checking if a newer HCL exists on CVM
2023-10-13 21:38:35,941 INFO Getting NOS version from the CVM
2023-10-13 21:38:36,231 DEBUG Marker file found, skipping assembling RAID again
2023-10-13 21:38:37,024 INFO Using hcl from /phoenix/hcl.json with last_edit 1675751144
2023-10-13 21:38:37,271 INFO Drive 'INTEL SSDPEDMD800G4' added to hcl /phoenix/hcl.json
2023-10-13 21:38:37,337 INFO Drive 'INTEL SSDPEDMD800G4' added to hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,401 INFO Drive 'INTEL SSDPEDMD80' added to hcl /phoenix/hcl.json
2023-10-13 21:38:37,465 INFO Drive 'INTEL SSDPEDMD80' added to hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,529 INFO Drive 'INTEL SSDPEDMD800G4' is already present in hcl /phoenix/hcl.json
2023-10-13 21:38:37,536 INFO Drive 'INTEL SSDPEDMD800G4' is already present in hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,543 INFO Drive 'INTEL SSDPEDMD80' is already present in hcl /phoenix/hcl.json
2023-10-13 21:38:37,551 INFO Drive 'INTEL SSDPEDMD80' is already present in hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,558 INFO Drive 'Samsung SSD 960 EVO 1TB' added to hcl /phoenix/hcl.json
2023-10-13 21:38:37,622 INFO Drive 'Samsung SSD 960 EVO 1TB' added to hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,685 INFO Drive 'Samsung SSD 960 ' added to hcl /phoenix/hcl.json
2023-10-13 21:38:37,749 INFO Drive 'Samsung SSD 960 ' added to hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,812 INFO Drive 'Samsung SSD 850' added to hcl /phoenix/hcl.json
2023-10-13 21:38:37,876 INFO Drive 'Samsung SSD 850' added to hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:37,939 INFO Drive 'Samsung SSD 850' is already present in hcl /phoenix/hcl.json
2023-10-13 21:38:37,947 INFO Drive 'Samsung SSD 850' is already present in hcl /mnt/svm_installer/install/config/hcl.json
2023-10-13 21:38:38,171 INFO Host boot_disk: sdc
2023-10-13 21:38:38,173 INFO Imaging the SVM
2023-10-13 21:38:38,173 INFO Deactivating LVM VG ahv
2023-10-13 21:38:38,173 INFO Formatting 5 data disks ['sdb', 'nvme1n1', 'nvme2n1', 'sda', 'nvme0n1']
2023-10-13 21:38:38,235 INFO Zeroing the superblock: ['/dev/sdb1', '/dev/nvme2n1p1']
2023-10-13 21:38:38,298 INFO Executing ['python2.7', '/mnt/svm_installer/install/bin/svm_rescue'] with arg_list ['-i', '/mnt/svm_installer/install', '--factory_deploy', '--node_name=447df05a-A', '--node_serial=8c5390b9-e74f-43ee-b3d9-7b61bde1da7d', '--node_model=USE_LAYOUT', '--cluster_id=4784288006423010099', '--node_uuid=0e34a790-d00f-43cd-b31f-866394dce746']
2023-10-13 21:38:38,369 INFO AHV LVM VG was not found
2023-10-13 21:38:38,369 INFO Calling wipefs on /dev/sdc
2023-10-13 21:38:38,564 DEBUG This AHV supports metadata-iso
2023-10-13 21:38:38,644 INFO Installer VM memory = 32G
2023-10-13 21:38:38,644 INFO Installation Device = /dev/sdc
2023-10-13 21:38:38,650 DEBUG Using AHV-metadata iso for AHV installation
2023-10-13 21:38:38,650 INFO Executing /usr/bin/qemu-system-x86_64 -m 32G -machine q35 -enable-kvm -drive file=/dev/sdc,cache=writethrough,format=raw -drive file=/phoenix/imaging_helper/installer.iso,media=cdrom -netdev user,id=net0,net=192.168.5.0/24 -device e1000,netdev=net0,id=net0,mac=34:97:f6:01:5b:34 -vnc :1 -boot order=d -pidfile installer_vm.pid -daemonize -smp 4 -serial file:/tmp/installer_vm.log -drive file=/phoenix/imaging_helper/installer.iso-meta.iso,media=cdrom -drive file=/usr/share/edk2.git/ovmf-x64/OVMF_CODE-pure-efi.fd,if=pflash,format=raw,unit=0,readonly=on -drive file=/usr/share/edk2.git/ovmf-x64/OVMF_VARS-pure-efi.fd,if=pflash,format=raw,unit=1
2023-10-13 21:38:38,734 INFO Installer VM is now running the installation
2023-10-13 21:38:38,734 INFO Installer VM running with PID = 4235
2023-10-13 21:39:08,758 INFO Loaded config parameters successfully
2023-10-13 21:39:08,758 INFO [30/2430] Hypervisor installation in progress
2023-10-13 21:39:38,788 INFO [60/2430] Hypervisor installation in progress
2023-10-13 21:40:08,818 INFO [90/2430] Hypervisor installation in progress
2023-10-13 21:40:38,848 INFO [120/2430] Hypervisor installation in progress
2023-10-13 21:41:08,879 INFO [150/2430] Hypervisor installation in progress
2023-10-13 21:41:38,909 INFO [180/2430] Hypervisor installation in progress
2023-10-13 21:41:38,912 DEBUG svm_rescue:
Unable to read /etc/nutanix/release_version: [Errno 2] No such file or directory: '/etc/nutanix/release_version'
2023-10-13 21:38:39,506Z INFO svm_rescue:928 Will image ['/dev/sda', '/dev/sdb'] from /mnt/svm_installer/install/images/svm.tar.xz.
2023-10-13 21:38:39,506Z INFO svm_rescue:114 exec_cmd: mdadm --stop --scan
2023-10-13 21:38:39,510Z INFO svm_rescue:713 Disks detected from Phoenix: ['/dev/sdb', '/dev/nvme1n1', '/dev/nvme2n1', '/dev/sda', '/dev/nvme0n1']
2023-10-13 21:38:39,510Z INFO svm_rescue:735 Available disk /dev/sdb
2023-10-13 21:38:39,510Z INFO svm_rescue:738 Repartitioning disk /dev/sdb
2023-10-13 21:38:39,510Z INFO svm_rescue:114 exec_cmd: parted -s /dev/sdb unit s print
2023-10-13 21:38:39,519Z INFO svm_rescue:187 No partition table present on disk /dev/sdb
2023-10-13 21:38:39,519Z INFO svm_rescue:675 Need to repartition and format blank boot drive /dev/sdb
2023-10-13 21:38:39,519Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/repartition_disks -b /dev/sdb
2023-10-13 21:38:40,694Z INFO svm_rescue:685 ret: 0
2023-10-13 21:38:40,695Z INFO svm_rescue:686 stdout:
2023-10-13 21:38:40,695Z INFO svm_rescue:687 stderr: Unable to read /etc/nutanix/release_version: [Errno 2] No such file or directory: '/etc/nutanix/release_version'
2023-10-13 21:38:40,557Z INFO repartition_disks:367 Cleaning existing partition tables on disks: set(['/dev/sdb'])
2023-10-13 21:38:40,571Z INFO repartition_disks:378 Creating boot partitions on disks: set(['/dev/sdb'])
2023-10-13 21:38:40,572Z INFO repartition_disks:291 Disk /dev/sdb: size 931 GB
2023-10-13 21:38:40,579Z INFO repartition_disks:215 Detected partition /dev/sdb1
2023-10-13 21:38:40,579Z INFO repartition_disks:322 Disk /dev/sdb size 931 GB
2023-10-13 21:38:40,590Z INFO repartition_disks:215 Detected partition /dev/sdb2
2023-10-13 21:38:40,590Z INFO repartition_disks:322 Disk /dev/sdb size 931 GB
2023-10-13 21:38:40,604Z INFO repartition_disks:215 Detected partition /dev/sdb3
2023-10-13 21:38:40,604Z INFO repartition_disks:322 Disk /dev/sdb size 931 GB
2023-10-13 21:38:40,629Z INFO repartition_disks:215 Detected partition /dev/sdb4
2023-10-13 21:38:40,695Z INFO svm_rescue:735 Available disk /dev/nvme1n1
2023-10-13 21:38:40,695Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/repartition_disks -d /dev/nvme1n1
2023-10-13 21:38:42,972Z INFO svm_rescue:735 Available disk /dev/nvme2n1
2023-10-13 21:38:42,972Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/repartition_disks -d /dev/nvme2n1
2023-10-13 21:38:45,672Z INFO svm_rescue:735 Available disk /dev/sda
2023-10-13 21:38:45,672Z INFO svm_rescue:738 Repartitioning disk /dev/sda
2023-10-13 21:38:45,672Z INFO svm_rescue:114 exec_cmd: parted -s /dev/sda unit s print
2023-10-13 21:38:45,685Z INFO svm_rescue:187 No partition table present on disk /dev/sda
2023-10-13 21:38:45,685Z INFO svm_rescue:675 Need to repartition and format blank boot drive /dev/sda
2023-10-13 21:38:45,685Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/repartition_disks -b /dev/sda
2023-10-13 21:38:46,812Z INFO svm_rescue:685 ret: 0
2023-10-13 21:38:46,812Z INFO svm_rescue:686 stdout:
2023-10-13 21:38:46,813Z INFO svm_rescue:687 stderr: Unable to read /etc/nutanix/release_version: [Errno 2] No such file or directory: '/etc/nutanix/release_version'
2023-10-13 21:38:46,680Z INFO repartition_disks:367 Cleaning existing partition tables on disks: set(['/dev/sda'])
2023-10-13 21:38:46,692Z INFO repartition_disks:378 Creating boot partitions on disks: set(['/dev/sda'])
2023-10-13 21:38:46,692Z INFO repartition_disks:291 Disk /dev/sda: size 931 GB
2023-10-13 21:38:46,702Z INFO repartition_disks:215 Detected partition /dev/sda1
2023-10-13 21:38:46,703Z INFO repartition_disks:322 Disk /dev/sda size 931 GB
2023-10-13 21:38:46,714Z INFO repartition_disks:215 Detected partition /dev/sda2
2023-10-13 21:38:46,714Z INFO repartition_disks:322 Disk /dev/sda size 931 GB
2023-10-13 21:38:46,728Z INFO repartition_disks:215 Detected partition /dev/sda3
2023-10-13 21:38:46,728Z INFO repartition_disks:322 Disk /dev/sda size 931 GB
2023-10-13 21:38:46,753Z INFO repartition_disks:215 Detected partition /dev/sda4
2023-10-13 21:38:46,813Z INFO svm_rescue:735 Available disk /dev/nvme0n1
2023-10-13 21:38:46,813Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/repartition_disks -d /dev/nvme0n1
2023-10-13 21:38:49,493Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sdb4
2023-10-13 21:38:49,493Z INFO svm_rescue:654 Running blkdiscard on disk /dev/nvme1n1p1
2023-10-13 21:38:49,493Z INFO svm_rescue:654 Running blkdiscard on disk /dev/nvme2n1p1
2023-10-13 21:38:49,493Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sda4
2023-10-13 21:38:49,493Z INFO svm_rescue:654 Running blkdiscard on disk /dev/nvme0n1p1
2023-10-13 21:38:49,493Z INFO svm_rescue:659 Formatting disks ['/dev/nvme0n1p1', '/dev/nvme1n1p1', '/dev/nvme2n1p1', '/dev/sda4', '/dev/sdb4']
2023-10-13 21:38:49,494Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/clean_disks -p /dev/nvme0n1p1,/dev/nvme1n1p1,/dev/nvme2n1p1,/dev/sda4,/dev/sdb4
2023-10-13 21:38:58,814Z INFO svm_rescue:848 All partitions in boot disk /dev/sda: ['/dev/sda4', '/dev/sda3', '/dev/sda2', '/dev/sda1']
2023-10-13 21:38:58,815Z INFO svm_rescue:837 Boot partitions: ['/dev/sda3', '/dev/sda2', '/dev/sda1']
2023-10-13 21:38:58,815Z INFO svm_rescue:850 Boot partitions for boot disk /dev/sda: [['/dev/sda1', '/dev/sda2', '/dev/sda3']]
2023-10-13 21:38:58,815Z INFO svm_rescue:848 All partitions in boot disk /dev/sdb: ['/dev/sdb4', '/dev/sdb3', '/dev/sdb2', '/dev/sdb1']
2023-10-13 21:38:58,815Z INFO svm_rescue:837 Boot partitions: ['/dev/sdb3', '/dev/sdb2', '/dev/sdb1']
2023-10-13 21:38:58,815Z INFO svm_rescue:850 Boot partitions for boot disk /dev/sdb: [['/dev/sda1', '/dev/sda2', '/dev/sda3'], ['/dev/sdb1', '/dev/sdb2', '/dev/sdb3']]
2023-10-13 21:38:58,815Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sda1
2023-10-13 21:38:58,816Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sda2
2023-10-13 21:38:58,816Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sda3
2023-10-13 21:38:58,816Z INFO svm_rescue:659 Formatting disks ['/dev/sda1', '/dev/sda2', '/dev/sda3']
2023-10-13 21:38:58,816Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/clean_disks -p /dev/sda1,/dev/sda2,/dev/sda3
2023-10-13 21:39:01,469Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sdb1
2023-10-13 21:39:01,470Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sdb2
2023-10-13 21:39:01,470Z INFO svm_rescue:654 Running blkdiscard on disk /dev/sdb3
2023-10-13 21:39:01,470Z INFO svm_rescue:659 Formatting disks ['/dev/sdb1', '/dev/sdb2', '/dev/sdb3']
2023-10-13 21:39:01,470Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/clean_disks -p /dev/sdb1,/dev/sdb2,/dev/sdb3
2023-10-13 21:39:04,155Z INFO disk_utils.py:80 Creating RAID1 volume /dev/md0 from ('/dev/sda1', '/dev/sdb1')
2023-10-13 21:39:04,300Z INFO disk_utils.py:80 Creating RAID1 volume /dev/md1 from ('/dev/sda2', '/dev/sdb2')
2023-10-13 21:39:04,524Z INFO disk_utils.py:80 Creating RAID1 volume /dev/md2 from ('/dev/sda3', '/dev/sdb3')
2023-10-13 21:39:04,722Z INFO svm_rescue:654 Running blkdiscard on disk /dev/md0
2023-10-13 21:39:04,722Z INFO svm_rescue:654 Running blkdiscard on disk /dev/md1
2023-10-13 21:39:04,723Z INFO svm_rescue:654 Running blkdiscard on disk /dev/md2
2023-10-13 21:39:04,723Z INFO svm_rescue:659 Formatting disks ['/dev/md0', '/dev/md1', '/dev/md2']
2023-10-13 21:39:04,723Z INFO svm_rescue:114 exec_cmd: /mnt/cdrom/bin/clean_disks -p /dev/md0,/dev/md1,/dev/md2
2023-10-13 21:39:08,521Z INFO svm_rescue:114 exec_cmd: mount /dev/md0 /mnt/disk
2023-10-13 21:39:08,697Z INFO svm_rescue:114 exec_cmd: cd /mnt/disk; tar -xJpf /mnt/svm_installer/install/images/svm.tar.xz
2023-10-13 21:39:38,305Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-bootstrap-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:39:40,281Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:39:40,281Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:39:40,291Z INFO svm_rescue:908 hardlink output:
Directories 15
Objects 98
IFREG 82
Comparisons 0
Linked 0
saved 0
2023-10-13 21:39:40,293Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-core-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:15,676Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:15,676Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:15,869Z INFO svm_rescue:908 hardlink output:
Directories 125
Objects 2717
IFREG 2514
Comparisons 639
Linked 634
saved 174661632
2023-10-13 21:40:15,871Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-clusters-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.gz'
2023-10-13 21:40:15,883Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:15,884Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:15,913Z INFO svm_rescue:908 hardlink output:
Directories 126
Objects 2721
IFREG 2517
Comparisons 5
Linked 0
saved 0
2023-10-13 21:40:15,915Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-pe-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:18,504Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:18,504Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:18,675Z INFO svm_rescue:908 hardlink output:
Directories 339
Objects 4659
IFREG 4240
Comparisons 483
Linked 469
saved 96567296
2023-10-13 21:40:18,678Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-diagnostics-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:19,892Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:19,893Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:19,999Z INFO svm_rescue:908 hardlink output:
Directories 345
Objects 4756
IFREG 4331
Comparisons 215
Linked 202
saved 75358208
2023-10-13 21:40:20,002Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-infrastructure-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:23,161Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:23,161Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:23,255Z INFO svm_rescue:908 hardlink output:
Directories 371
Objects 5264
IFREG 4802
Comparisons 126
Linked 113
saved 52527104
2023-10-13 21:40:23,257Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-lcm-2.5.0.2.32663.tar.xz'
2023-10-13 21:40:23,958Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:23,959Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:24,005Z INFO svm_rescue:908 hardlink output:
Directories 392
Objects 5390
IFREG 4907
Comparisons 54
Linked 41
saved 499712
2023-10-13 21:40:24,006Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-ikatproxy-master.tar.xz'
2023-10-13 21:40:25,030Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:25,030Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:25,074Z INFO svm_rescue:908 hardlink output:
Directories 395
Objects 5396
IFREG 4910
Comparisons 13
Linked 0
saved 0
2023-10-13 21:40:25,076Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-ikatcontrolplane-master.tar.xz'
2023-10-13 21:40:25,806Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:25,806Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:25,851Z INFO svm_rescue:908 hardlink output:
Directories 398
Objects 5405
IFREG 4916
Comparisons 13
Linked 0
saved 0
2023-10-13 21:40:25,853Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-networkservice-1.0.136.tar.xz'
2023-10-13 21:40:26,880Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:26,881Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:26,920Z INFO svm_rescue:908 hardlink output:
Directories 401
Objects 5410
IFREG 4918
Comparisons 13
Linked 0
saved 0
2023-10-13 21:40:26,923Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-serviceability-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:29,174Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:29,174Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:29,283Z INFO svm_rescue:908 hardlink output:
Directories 529
Objects 6347
IFREG 5723
Comparisons 664
Linked 649
saved 50388992
2023-10-13 21:40:29,286Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-ncc-el7.3-release-ncc-4.6.2.1-x86_64-latest.tar.gz'
2023-10-13 21:40:32,967Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:32,967Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:33,053Z INFO svm_rescue:908 hardlink output:
Directories 756
Objects 7572
IFREG 6717
Comparisons 54
Linked 40
saved 44707840
2023-10-13 21:40:33,056Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-minervacvm-el7.3-release-fsm-1.5.2-stable-5769c7bec5a02f5101db0a0a780d03a99b36e59b-x86_64.tar.gz'
2023-10-13 21:40:33,581Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:33,582Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:33,677Z INFO svm_rescue:908 hardlink output:
Directories 967
Objects 9006
IFREG 7940
Comparisons 620
Linked 589
saved 18214912
2023-10-13 21:40:33,680Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-perftools-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:34,963Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:34,964Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:35,041Z INFO svm_rescue:908 hardlink output:
Directories 971
Objects 9055
IFREG 7985
Comparisons 65
Linked 46
saved 27410432
2023-10-13 21:40:35,045Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/srv/; tar -xf /mnt/svm_installer/install/pkg/nutanix-salt-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:35,065Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/srv/;hardlink -c -v .
2023-10-13 21:40:35,066Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/srv/;hardlink -c -v .'
2023-10-13 21:40:35,073Z INFO svm_rescue:908 hardlink output:
Directories 25
Objects 140
IFREG 115
Comparisons 0
Linked 0
saved 0
2023-10-13 21:40:35,075Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-intentgateway-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:39,935Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:39,935Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:40,264Z INFO svm_rescue:908 hardlink output:
Directories 1576
Objects 14804
IFREG 13129
Comparisons 4178
Linked 4134
saved 182816768
2023-10-13 21:40:40,266Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-clusterconfig-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:40,279Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:40,279Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:40,388Z INFO svm_rescue:908 hardlink output:
Directories 1576
Objects 14805
IFREG 13130
Comparisons 24
Linked 0
saved 0
2023-10-13 21:40:40,390Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-zkcore-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:40,402Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:40,402Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:40,502Z INFO svm_rescue:908 hardlink output:
Directories 1576
Objects 14806
IFREG 13131
Comparisons 24
Linked 0
saved 0
2023-10-13 21:40:40,504Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-athena-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:46,419Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:46,419Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:46,500Z INFO svm_rescue:908 hardlink output:
Directories 1578
Objects 14815
IFREG 13138
Comparisons 24
Linked 0
saved 0
2023-10-13 21:40:46,503Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-xtrim-el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af.tar.xz'
2023-10-13 21:40:46,519Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:46,519Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:46,615Z INFO svm_rescue:908 hardlink output:
Directories 1578
Objects 14817
IFREG 13140
Comparisons 24
Linked 0
saved 0
2023-10-13 21:40:46,617Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix; tar -xf /mnt/svm_installer/install/pkg/nutanix-flow-1.0.1.tar.xz'
2023-10-13 21:40:46,691Z INFO svm_rescue:902 hardlink all duplicate files: cd /mnt/disk/usr/local/nutanix;hardlink -c -v .
2023-10-13 21:40:46,691Z INFO svm_rescue:114 exec_cmd: bash -c 'cd /mnt/disk/usr/local/nutanix;hardlink -c -v .'
2023-10-13 21:40:46,767Z INFO svm_rescue:908 hardlink output:
Directories 1582
Objects 14830
IFREG 13149
Comparisons 27
Linked 3
saved 159744
2023-10-13 21:40:46,768Z INFO svm_rescue:1018 Making post deployment modifications on /dev/md0
2023-10-13 21:40:48,618Z INFO svm_rescue:114 exec_cmd: blkid -c /dev/null /dev/md0
2023-10-13 21:40:48,625Z INFO svm_rescue:621 Root filesystem (on /dev/md0) UUID is 46d18e5a-6325-4882-9dc8-1e9ef57336ab instead of ad6af956-f77d-45dd-8d44-9c006cbaddd5.
2023-10-13 21:40:48,718Z INFO svm_rescue:875 Injecting mdadm.conf into boot partition.
2023-10-13 21:40:48,732Z INFO svm_rescue:880 Injecting mdadm.conf into initrd /mnt/disk/boot/initramfs-3.10.0-1160.76.1.el7.nutanix.20220817.cvm.x86_64.img
2023-10-13 21:40:48,733Z INFO svm_rescue:114 exec_cmd: cd /tmp/tmp1qR56u; gunzip -c /mnt/disk/boot/initramfs-3.10.0-1160.76.1.el7.nutanix.20220817.cvm.x86_64.img | cpio -id
2023-10-13 21:40:49,172Z INFO svm_rescue:114 exec_cmd: cd /tmp/tmp1qR56u; find . | cpio -o -H newc | gzip > /mnt/disk/boot/initramfs-3.10.0-1160.76.1.el7.nutanix.20220817.cvm.x86_64.img
2023-10-13 21:40:51,935Z INFO svm_rescue:213 Creating Nutanix boot marker file from grub.conf ...
2023-10-13 21:40:51,958Z INFO svm_rescue:224 Wrote marker file, contents:
KERNEL=/boot/vmlinuz-3.10.0-1160.76.1.el7.nutanix.20220817.cvm.x86_64
CMDLINE='ro root=UUID=46d18e5a-6325-4882-9dc8-1e9ef57336ab rd_NO_LUKS rd_NO_LVM rd_NO_DM LANG=en_US.UTF-8 SYSFONT=latarcyrheb-sun16 rhgb KEYBOARDTYPE=pc KEYTABLE=us audit=1 audit_backlog_limit=8192 nousb fips=1 nomodeset biosdevname=0 net.ifnames=0 scsi_mod.use_blk_mq=y clocksource=tsc kvm_nopvspin=1 xen_nopvspin=1 hv_netvsc.ring_size=512 mds=off crashkernel=no panic=30 mpt2sas.prot_mask=1 mpt3sas.prot_mask=1 mpt3sas.hbas_to_enumerate=0 iavmd.direct_assign=1 vmd.direct_assign=1 mitigations=off rd_MD_UUID=68ffe44d:da91e3e2:9cba775b:ab5d971d console=ttyS0,115200n8 console=tty0'
INITRD=/boot/initramfs-3.10.0-1160.76.1.el7.nutanix.20220817.cvm.x86_64.img
2023-10-13 21:40:51,959Z INFO svm_rescue:241 Will try to copy /sys/class/dmi/id/product_uuid to /mnt/disk/.cvm_uuid
2023-10-13 21:40:51,959Z ERROR svm_rescue:254 Unable to create CVM UUID Marker. Error: [Errno 2] No such file or directory: '/sys/class/dmi/id/product_uuid'
2023-10-13 21:40:51,959Z ERROR svm_rescue:1043 Unable to create CVM UUID marker.
2023-10-13 21:40:51,959Z INFO svm_rescue:440 Adding /dev/md2 to /etc/fstab to be mounted at /home...
2023-10-13 21:40:51,959Z INFO svm_rescue:114 exec_cmd: blkid -c /dev/null /dev/md2
2023-10-13 21:40:51,974Z INFO svm_rescue:478 Adding journal log bind entry in fstab, path: /mnt/disk/etc/fstab
2023-10-13 21:40:51,975Z INFO svm_rescue:114 exec_cmd: mount /dev/md2 /mnt/data
2023-10-13 21:40:52,036Z INFO svm_rescue:1113 Fixing permissions on /home.
2023-10-13 21:40:52,036Z INFO svm_rescue:1113 Fixing permissions on /home./nutanix
2023-10-13 21:40:52,036Z INFO svm_rescue:1138 Copying installer to /mnt/data/nutanix/data/installer/el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af
2023-10-13 21:40:52,036Z INFO svm_rescue:114 exec_cmd: tar -C '/mnt/svm_installer/install' -cf - . | tar -C '/mnt/data/nutanix/data/installer/el7.3-release-fraser-6.5.2-stable-f2ce4db7d67f495ebfd6208bef9ab0afec9c74af' -xf -
2023-10-13 21:40:55,971Z INFO svm_rescue:114 exec_cmd: tar -xf /mnt/svm_installer/install/pkg/nutanix-foundation-5.3.3-20221212-d90b5753.tar.xz -C /mnt/data/nutanix
2023-10-13 21:41:14,564Z INFO svm_rescue:114 exec_cmd: sudo cp -rf /mnt/svm_installer/install/ce_vibs /mnt/data/nutanix
2023-10-13 21:41:14,593Z INFO svm_rescue:114 exec_cmd: sudo cp -rf /mnt/svm_installer/install/ce_scripts /mnt/data/nutanix
2023-10-13 21:41:14,619Z INFO svm_rescue:1187 Setting up SSH configuration
2023-10-13 21:41:14,620Z INFO svm_rescue:1197 Writing out rc.nutanix
2023-10-13 21:41:17,371Z INFO svm_rescue:1288 Running setfiles.sh on the chroot: /mnt/disk
2023-10-13 21:41:37,309Z INFO svm_rescue:114 exec_cmd: sync; sync; sync
2023-10-13 21:41:37,477Z INFO svm_rescue:114 exec_cmd: umount -R /mnt/disk
2023-10-13 21:41:38,181Z INFO svm_rescue:114 exec_cmd: umount -R /mnt/data
2023-10-13 21:41:41,086 INFO touching .autorelabel
2023-10-13 21:41:41,088 INFO Creating layout file for CommunityEdition in position A
2023-10-13 21:41:41,096 INFO Could not generate firmware config. Proceeding with imaging without it
2023-10-13 21:41:41,096 INFO Injecting post-cluster create settings into CVM
2023-10-13 21:41:41,681 INFO Imaging of SVM has completed successfully!
2023-10-13 21:41:41,682 INFO 0/330 Waiting for md arrays to complete resync
2023-10-13 21:42:08,940 INFO [210/2430] Hypervisor installation in progress
2023-10-13 21:42:11,713 INFO 30/330 Waiting for md arrays to complete resync
2023-10-13 21:42:38,970 INFO [240/2430] Hypervisor installation in progress
2023-10-13 21:42:41,744 INFO Imaging thread 'svm' has completed successfully
2023-10-13 21:43:09,001 INFO [270/2430] Hypervisor installation in progress
2023-10-13 21:43:39,031 INFO [300/2430] Hypervisor installation in progress
2023-10-13 21:44:09,061 INFO [330/2430] Hypervisor installation in progress
2023-10-13 21:44:39,092 INFO [360/2430] Hypervisor installation in progress
2023-10-13 21:45:09,122 INFO [390/2430] Hypervisor installation in progress
2023-10-13 21:45:39,152 INFO [420/2430] Hypervisor installation in progress
2023-10-13 21:46:09,183 INFO [450/2430] Hypervisor installation in progress
2023-10-13 21:46:39,213 INFO [480/2430] Hypervisor installation in progress
2023-10-13 21:47:09,243 INFO [510/2430] Hypervisor installation in progress
2023-10-13 21:47:39,274 INFO [540/2430] Hypervisor installation in progress
2023-10-13 21:48:09,304 INFO [570/2430] Hypervisor installation in progress
2023-10-13 21:48:39,334 INFO [600/2430] Hypervisor installation in progress
2023-10-13 21:49:09,365 INFO [630/2430] Hypervisor installation in progress
2023-10-13 21:49:39,395 INFO [660/2430] Hypervisor installation in progress
2023-10-13 21:50:09,425 INFO [690/2430] Hypervisor installation in progress
2023-10-13 21:50:39,455 INFO [720/2430] Hypervisor installation in progress
2023-10-13 21:51:09,486 INFO [750/2430] Hypervisor installation in progress
2023-10-13 21:51:39,516 INFO [780/2430] Hypervisor installation in progress
2023-10-13 21:52:09,546 INFO [810/2430] Hypervisor installation in progress
2023-10-13 21:52:39,577 INFO [840/2430] Hypervisor installation in progress
2023-10-13 21:53:09,607 INFO [870/2430] Hypervisor installation in progress
2023-10-13 21:53:39,607 INFO [900/2430] Hypervisor installation in progress
2023-10-13 21:54:09,617 INFO [930/2430] Hypervisor installation in progress
2023-10-13 21:54:39,648 INFO [961/2430] Hypervisor installation in progress
2023-10-13 21:55:09,678 INFO [991/2430] Hypervisor installation in progress
2023-10-13 21:55:39,708 INFO [1021/2430] Hypervisor installation in progress
2023-10-13 21:56:09,739 INFO [1051/2430] Hypervisor installation in progress
2023-10-13 21:56:39,769 INFO [1081/2430] Hypervisor installation in progress
2023-10-13 21:57:09,799 INFO [1111/2430] Hypervisor installation in progress
2023-10-13 21:57:39,830 INFO [1141/2430] Hypervisor installation in progress
2023-10-13 21:58:09,860 INFO [1171/2430] Hypervisor installation in progress
2023-10-13 21:58:39,890 INFO [1201/2430] Hypervisor installation in progress
2023-10-13 21:59:09,921 INFO [1231/2430] Hypervisor installation in progress
2023-10-13 21:59:39,951 INFO [1261/2430] Hypervisor installation in progress
2023-10-13 22:00:09,981 INFO [1291/2430] Hypervisor installation in progress
2023-10-13 22:00:40,012 INFO [1321/2430] Hypervisor installation in progress
2023-10-13 22:01:10,042 INFO [1351/2430] Hypervisor installation in progress
2023-10-13 22:01:40,072 INFO [1381/2430] Hypervisor installation in progress
2023-10-13 22:02:10,103 INFO [1411/2430] Hypervisor installation in progress
2023-10-13 22:02:40,133 INFO [1441/2430] Hypervisor installation in progress
2023-10-13 22:03:10,163 INFO [1471/2430] Hypervisor installation in progress
2023-10-13 22:03:40,194 INFO [1501/2430] Hypervisor installation in progress
2023-10-13 22:04:10,224 INFO [1531/2430] Hypervisor installation in progress
2023-10-13 22:04:40,254 INFO [1561/2430] Hypervisor installation in progress
2023-10-13 22:05:10,285 INFO [1591/2430] Hypervisor installation in progress
2023-10-13 22:05:40,315 INFO [1621/2430] Hypervisor installation in progress
2023-10-13 22:06:10,345 INFO [1651/2430] Hypervisor installation in progress
2023-10-13 22:06:40,375 INFO [1681/2430] Hypervisor installation in progress
2023-10-13 22:07:10,406 INFO [1711/2430] Hypervisor installation in progress
2023-10-13 22:07:40,436 INFO [1741/2430] Hypervisor installation in progress
2023-10-13 22:08:10,466 INFO [1771/2430] Hypervisor installation in progress
2023-10-13 22:08:40,497 INFO [1801/2430] Hypervisor installation in progress
2023-10-13 22:09:10,527 INFO [1831/2430] Hypervisor installation in progress
2023-10-13 22:09:40,557 INFO [1861/2430] Hypervisor installation in progress
2023-10-13 22:10:10,588 INFO [1891/2430] Hypervisor installation in progress
2023-10-13 22:10:40,618 INFO [1921/2430] Hypervisor installation in progress
2023-10-13 22:11:10,648 INFO [1952/2430] Hypervisor installation in progress
2023-10-13 22:11:40,679 INFO [1982/2430] Hypervisor installation in progress
2023-10-13 22:12:10,709 INFO [2012/2430] Hypervisor installation in progress
2023-10-13 22:12:40,739 INFO [2042/2430] Hypervisor installation in progress
2023-10-13 22:13:10,770 INFO [2072/2430] Hypervisor installation in progress
2023-10-13 22:13:40,800 INFO [2102/2430] Hypervisor installation in progress
2023-10-13 22:14:10,830 INFO [2132/2430] Hypervisor installation in progress
2023-10-13 22:14:40,861 INFO [2162/2430] Hypervisor installation in progress
2023-10-13 22:15:10,891 INFO [2192/2430] Hypervisor installation in progress
2023-10-13 22:15:40,921 INFO [2222/2430] Hypervisor installation in progress
2023-10-13 22:16:10,951 INFO [2252/2430] Hypervisor installation in progress
2023-10-13 22:16:40,982 INFO [2282/2430] Hypervisor installation in progress
2023-10-13 22:17:11,012 INFO [2312/2430] Hypervisor installation in progress
2023-10-13 22:17:41,042 INFO [2342/2430] Hypervisor installation in progress
2023-10-13 22:18:11,073 INFO [2372/2430] Hypervisor installation in progress
2023-10-13 22:18:41,103 INFO [2402/2430] Hypervisor installation in progress
2023-10-13 22:18:41,103 INFO InstallerVM timeout occurred, current retry 0
2023-10-13 22:18:41,104 INFO Please take a look at installer_vm_*.log inside foundation logs to debug hypervisor installation issues
2023-10-13 22:18:41,104 INFO Terminating InstallerVM(4235)
2023-10-13 22:18:44,113 DEBUG Using AHV-metadata iso for AHV installation
2023-10-13 22:18:44,114 INFO Executing /usr/bin/qemu-system-x86_64 -m 32G -machine q35 -enable-kvm -drive file=/dev/sdc,cache=writethrough,format=raw -drive file=/phoenix/imaging_helper/installer.iso,media=cdrom -netdev user,id=net0,net=192.168.5.0/24 -device e1000,netdev=net0,id=net0,mac=34:97:f6:01:5b:34 -vnc :1 -boot order=d -pidfile installer_vm.pid -daemonize -smp 4 -serial file:/tmp/installer_vm.log -drive file=/phoenix/imaging_helper/installer.iso-meta.iso,media=cdrom -drive file=/usr/share/edk2.git/ovmf-x64/OVMF_CODE-pure-efi.fd,if=pflash,format=raw,unit=0,readonly=on -drive file=/usr/share/edk2.git/ovmf-x64/OVMF_VARS-pure-efi.fd,if=pflash,format=raw,unit=1
2023-10-13 22:18:44,224 INFO Installer VM is now running the installation
2023-10-13 22:18:44,225 INFO Installer VM running with PID = 5563
2023-10-13 22:19:14,248 INFO [30/2430] Hypervisor installation in progress
2023-10-13 22:19:44,279 INFO [60/2430] Hypervisor installation in progress