Skip to content

Instantly share code, notes, and snippets.

@M4rtinK

M4rtinK/anaconda.log

Created July 11, 2017 17:23
Show Gist options
  • Save M4rtinK/1bfa0a5913a404eb42cb78c5c79b57d8 to your computer and use it in GitHub Desktop.
Save M4rtinK/1bfa0a5913a404eb42cb78c5c79b57d8 to your computer and use it in GitHub Desktop.
Download ZIP
anaconda.log with structured logging
Raw
anaconda.log
17:12:03,702 INF main: /sbin/anaconda 27.18-1
17:12:03,764 INF isys: 2155164 kB (2104 MB) are available
17:12:03,777 INF startup_utils: check_memory(): total:2104, needed:320, graphical:410
17:12:03,784 INF main: anaconda called with cmdline = ['/sbin/anaconda']
17:12:03,785 INF main: Default encoding = utf-8
17:12:03,801 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
17:12:03,817 INF misc: Initializing docker addon
17:12:03,996 DBG localization: setting locale to: en_US.UTF-8
17:12:04,006 DBG network: devices found ['ens3']
17:12:04,014 DBG network: ensure single initramfs connections
17:12:04,037 DBG network: apply kickstart
17:12:04,037 DBG network: create missing ifcfg files
17:12:04,093 DBG network: set real ONBOOT value
17:12:04,093 DBG network: updating host name localhost.localdomain
17:12:04,106 INF threading: Running Thread: AnaWaitForConnectingNMThread (140230036047616)
17:12:04,107 DBG anaconda: setting display mode to GUI
17:12:04,113 INF display: Display mode = DisplayModes.GUI
17:12:04,115 INF isys: 2155164 kB (2104 MB) are available
17:12:04,129 INF startup_utils: check_memory(): total:2104, needed:320, graphical:410
17:12:04,134 INF network: got 2 NTP servers from DHCP
17:12:04,141 INF threading: Thread Done: AnaWaitForConnectingNMThread (140230036047616)
17:12:05,313 DBG iutil: X server has signalled a successful start.
17:12:05,686 INF display: Started spice-vdagent.
17:12:05,914 INF installclass: Fedora is the highest priority installclass, using it
17:12:06,200 DBG payload: getting release version from tree at None (rawhide)
17:12:06,201 DBG payload: using default release version of rawhide
17:12:06,344 INF bootloader: bootloader GRUB2 on X86 platform
17:12:06,345 INF blivet.autopart: Detected 2.06 GiB of memory
17:12:06,346 INF blivet.autopart: Suggested swap size (2.06 GiB) exceeds 10 % of disk space, using 10 % of disk space (0.0) instead.
17:12:06,346 INF blivet.autopart: Swap attempt of 0.0
17:12:06,347 INF bootloader: bootloader GRUB2 on X86 platform
17:12:06,408 INF threading: Running Thread: AnaStorageThread (140230036047616)
17:12:06,413 INF threading: Running Thread: AnaTimeInitThread (140229854775040)
17:12:06,456 DBG payload: Restarting payload thread
17:12:06,458 INF threading: Running Thread: AnaPayloadRestartThread (140229846382336)
17:12:06,458 ERR startup_utils: geoloc: wrong provider id specified: None
17:12:06,461 INF threading: Running Thread: AnaGeolocationRefreshThread (140229837989632)
17:12:06,461 INF geoloc: Starting geolocation lookup
17:12:06,462 INF geoloc: Geolocation provider: Fedora GeoIP
17:12:06,463 INF threading: Running Thread: AnaPayloadThread (140229829596928)
17:12:06,463 INF threading: Thread Done: AnaPayloadRestartThread (140229846382336)
17:12:06,478 DBG payload: Updating payload thread state: 0
17:12:06,479 DBG payload: Updating payload thread state: 1
17:12:06,903 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:07,177 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:07,230 DBG ui.gui: Detected primary monitor: 1024x768 95dpix 96dpiy
17:12:07,423 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:07,511 INF geoloc: Geolocation lookup finished in 1.0 seconds
17:12:07,512 INF geoloc: got results from geolocation
17:12:07,513 INF threading: Thread Done: AnaGeolocationRefreshThread (140229837989632)
17:12:07,764 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:07,874 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:07,955 WRN misc: /usr/lib/python3.6/site-packages/blivet/populator/populator.py:121: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev.device_is_realdisk(info)
17:12:08,200 DBG localization: setting locale to: en_US.UTF-8
17:12:08,204 DBG localization: setting locale to: en_US.UTF-8
17:12:08,228 DBG ui.common: Entered spoke: WelcomeLanguageSpoke
17:12:08,229 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/Gdk.py:334: DeprecationWarning: Gdk.Cursor.new is deprecated
return cls.new(*args, **kwds)
17:12:09,157 INF threading: Thread Done: AnaStorageThread (140230036047616)
17:12:09,157 DBG payload: Updating payload thread state: 2
17:12:09,158 DBG payload: Updating payload thread state: 3
17:12:09,189 DBG payload: Updating payload thread state: 4
17:12:10,005 INF threading: Thread Done: AnaTimeInitThread (140229854775040)
17:12:28,148 DBG localization: setting locale to: en_US.UTF-8
17:12:28,187 DBG ui.gui.spokes.network: network standalone spoke (init): completed: ['ens3']
17:12:28,199 DBG ui.common: Left spoke: WelcomeLanguageSpoke
17:12:28,208 INF lifecycle: Adding controller: SummaryHub
17:12:28,256 INF threading: Running Thread: AnaAddLayoutsInitThread (140229837989632)
17:12:28,269 DBG ui.gui.utils: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f89cb8ab668>> on <list object at 0x7f89cb8adac8>
17:12:28,286 INF threading: Running Thread: AnaGtkBatchPre1 (140230036047616)
17:12:28,287 INF threading: Thread Done: AnaGtkBatchPre1 (140230036047616)
17:12:28,311 INF threading: Running Thread: AnaKeyboardThread (140230036047616)
17:12:28,378 INF lifecycle: Module initialized: LangsupportSpoke
17:12:28,596 INF threading: Running Thread: AnaNTPserver1 (140229854775040)
17:12:28,602 INF threading: Running Thread: AnaNTPserver2 (140229846382336)
17:12:28,612 INF threading: Running Thread: AnaDateTimeThread (140229727954688)
17:12:28,771 INF threading: Thread Done: AnaNTPserver2 (140229846382336)
17:12:28,805 INF threading: Thread Done: AnaNTPserver1 (140229854775040)
17:12:29,653 INF threading: Running Thread: AnaSourceWatcher (140229854775040)
17:12:29,664 INF threading: Running Thread: AnaSoftwareWatcher (140229846382336)
17:12:29,679 INF threading: Running Thread: AnaStorageWatcher (140229670491904)
17:12:29,693 INF lifecycle: Module initialized: StorageSpoke
17:12:29,693 INF threading: Thread Done: AnaStorageWatcher (140229670491904)
17:12:29,705 INF threading: Running Thread: AnaCustomStorageInit (140229670491904)
17:12:29,736 INF lifecycle: Module initialized: BlivetGuiSpoke
17:12:29,771 INF lifecycle: Module initialized: FilterSpoke
17:12:29,802 DBG ui.gui.spokes.network: can't get remote connection of active connection of device ens3
17:12:29,802 DBG ui.gui.spokes.network: device configuration added: connection 7f94df71-32bd-4340-ab20-7b838adc6589 device ens3
17:12:29,804 DBG ui.gui.spokes.network: not adding connection 7f94df71-32bd-4340-ab20-7b838adc6589, already in list
17:12:29,906 DBG network: updating host name localhost.localdomain
17:12:29,926 INF lifecycle: Module initialized: NetworkSpoke
17:12:30,038 DBG payload: Source doesn't require network for installation
17:12:30,038 INF lifecycle: Initialization of all modules (10) has been started.
17:12:30,040 DBG ui.gui.hubs: Starting event loop for hub SummaryHub
17:12:30,043 INF lifecycle: Module initialized: CustomPartitioningSpoke
17:12:30,043 INF threading: Thread Done: AnaCustomStorageInit (140229670491904)
17:12:30,048 DBG ui.common: Entered hub: SummaryHub
19:12:30,000 INF isys: System time set to Tue Jul 11 17:12:30 2017 UTC
19:12:30,000 INF isys: System time set to Tue Jul 11 17:12:30 2017 UTC
19:12:30,000 INF isys: System time set to Tue Jul 11 17:12:30 2017 UTC
19:12:30,008 INF lifecycle: Module initialized: DatetimeSpoke
19:12:30,008 INF threading: Thread Done: AnaDateTimeThread (140229727954688)
19:12:30,011 DBG ui.gui.hubs: spoke is not ready: KeyboardSpoke
19:12:30,012 DBG ui.gui.hubs: setting KeyboardSpoke status to: Getting list of layouts...
19:12:30,013 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
19:12:30,014 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
19:12:30,015 DBG ui.gui.hubs: setting SourceSpoke status to: Setting up installation source...
19:12:30,015 DBG ui.gui.hubs: setting SourceSpoke status to: Probing storage...
19:12:30,015 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading package metadata...
19:12:30,016 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading group metadata...
19:12:30,016 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading package metadata...
19:12:30,016 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading group metadata...
19:12:30,017 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
19:12:30,019 DBG ui.gui.hubs: spoke is ready: StorageSpoke
19:12:30,020 DBG ui.gui.hubs: spoke is ready: DatetimeSpoke
19:12:31,316 DBG ui.gui.utils: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f89cb8ab668>> on <list object at 0x7f89cb8adac8>
19:12:31,316 INF threading: Thread Done: AnaAddLayoutsInitThread (140229837989632)
19:12:31,317 INF lifecycle: Module initialized: KeyboardSpoke
19:12:31,317 INF threading: Thread Done: AnaKeyboardThread (140230036047616)
19:12:31,419 DBG ui.gui.hubs: spoke is ready: KeyboardSpoke
19:12:55,217 DBG ui.common: Entered spoke: StorageSpoke
19:13:02,364 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/GObject.py:553: Warning: gsignal.c:2641: instance '0x5605d860fed0' has no handler with id '15977'
return func(*args, **kwargs)
19:13:02,364 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/GObject.py:553: Warning: gsignal.c:2641: instance '0x5605d860fed0' has no handler with id '15978'
return func(*args, **kwargs)
19:13:02,366 INF blivet.autopart: Detected 2.06 GiB of memory
19:13:02,366 INF blivet.autopart: Suggested swap size (2.06 GiB) exceeds 10 % of disk space, using 10 % of disk space (2 GiB) instead.
19:13:02,367 INF blivet.autopart: Swap attempt of 2 GiB
19:13:02,367 INF threading: Running Thread: AnaExecuteStorageThread (140230036047616)
19:13:02,367 DBG ui.common: Left spoke: StorageSpoke
19:13:02,421 DBG bootloader: new disk order: []
19:13:02,421 DBG kickstart.kickstart.bootloader: Bootloader: /boot partition is not present, dry run True
19:13:02,421 DBG kickstart.kickstart.bootloader: Bootloader: fallback use first disk return from Blivet 'vda' as boot drive, dry run True
19:13:02,424 INF blivet.autopart: Detected 2.06 GiB of memory
19:13:02,425 INF blivet.autopart: Suggested swap size (2.06 GiB) exceeds 10 % of disk space, using 10 % of disk space (2 GiB) instead.
19:13:02,425 INF blivet.autopart: Swap attempt of 2 GiB
19:13:02,425 DBG blivet.autopart: do_autopart: True
19:13:02,426 DBG blivet.autopart: encrypted_autopart: False
19:13:02,426 DBG blivet.autopart: autopart_type: 2
19:13:02,426 DBG blivet.autopart: clear_part_type: 2
19:13:02,426 DBG blivet.autopart: clear_part_disks: ['vda']
19:13:02,426 DBG blivet.autopart: autopart_requests:
PartSpec instance (0x7f89d0ff9978) --
mountpoint = / lv = True thin = True btrfs = True
weight = 0 fstype = ext4 encrypted = True
size = 1024 MiB max_size = 50 GiB grow = True
PartSpec instance (0x7f89d0ff9a20) --
mountpoint = /home lv = True thin = True btrfs = True
weight = 0 fstype = ext4 encrypted = True
size = 500 MiB max_size = None grow = True
PartSpec instance (0x7f89d0ff9a90) --
mountpoint = None lv = False thin = False btrfs = False
weight = 5000 fstype = biosboot encrypted = False
size = 1024 KiB max_size = None grow = False
PartSpec instance (0x7f89d0ff9b00) --
mountpoint = /boot lv = False thin = False btrfs = False
weight = 2000 fstype = ext4 encrypted = False
size = 1024 MiB max_size = None grow = False
PartSpec instance (0x7f89d0ff9e80) --
mountpoint = None lv = True thin = False btrfs = False
weight = 0 fstype = swap encrypted = True
size = 2 GiB max_size = None grow = False
19:13:02,426 DBG blivet.autopart: storage.disks: ['vda']
19:13:02,426 DBG blivet.autopart: storage.partitioned: ['vda']
19:13:02,426 DBG blivet.autopart: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url1/install.img (deleted)', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'vda']
19:13:02,429 DBG blivet.autopart: boot disk: vda
19:13:02,445 DBG blivet.autopart: candidate disks: [DiskDevice instance (0x7f89d0143668) --
name = vda status = True id = 2
children = []
parents = []
uuid = None size = 20 GiB
format = non-existent msdos disklabel
major = 252 minor = 0 exists = True protected = False
sysfs path = /sys/devices/pci0000:00/0000:00:07.0/virtio2/block/vda
target size = 20 GiB path = /dev/vda
format args = [] original_format = disklabel removable = False]
19:13:02,446 DBG blivet.autopart: devs: [PartitionDevice instance (0x7f89c8a2c7b8) --
name = req0 status = False id = 220
children = []
parents = []
uuid = None size = 500 MiB
format = non-existent lvmpv
major = 0 minor = 0 exists = False protected = False
sysfs path =
target size = 500 MiB path = /dev/req0
format args = [] original_format = lvmpv grow = True max size = 0 B bootable = None
part type = None primary = False start sector = None end sector = None
parted_partition = None
disk = None
]
19:13:02,447 DBG bootloader: _is_valid_disklabel(vda) returning True
19:13:02,448 DBG bootloader: _is_valid_size(vda) returning True
19:13:02,448 DBG bootloader: _is_valid_location(vda) returning True
19:13:02,448 DBG bootloader: _is_valid_format(vda) returning True
19:13:02,448 DBG bootloader: is_valid_stage1_device(vda) returning True
19:13:02,448 INF blivet.autopart: skipping unneeded stage1 biosboot request
19:13:02,448 DBG blivet.autopart: PartSpec instance (0x7f89d0ff9a90) --
mountpoint = None lv = False thin = False btrfs = False
weight = 5000 fstype = biosboot encrypted = False
size = 1024 KiB max_size = None grow = False
19:13:02,449 DBG blivet.autopart: existing 20 GiB disk vda (2) with non-existent msdos disklabel
19:13:02,461 DBG payload: Source doesn't require network for installation
19:13:02,463 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
19:13:02,463 DBG ui.gui.hubs: setting StorageSpoke status to: Saving storage configuration...
19:13:02,599 DBG bootloader: new disk order: []
19:13:02,599 DBG kickstart.kickstart.bootloader: Bootloader: use /boot partition's disk 'vda' as boot drive, dry run False
19:13:02,606 DBG bootloader: stage1 device cannot be of type lvmvg
19:13:02,606 DBG bootloader: stage1 device cannot be of type lvmlv
19:13:02,606 DBG bootloader: stage1 device cannot be of type lvmlv
19:13:02,607 DBG bootloader: _is_valid_disklabel(vda) returning True
19:13:02,607 DBG bootloader: _is_valid_size(vda) returning True
19:13:02,607 DBG bootloader: _is_valid_location(vda) returning True
19:13:02,608 DBG bootloader: _is_valid_format(vda) returning True
19:13:02,608 DBG bootloader: is_valid_stage1_device(vda) returning True
19:13:02,610 DBG bootloader: _is_valid_disklabel(vda) returning True
19:13:02,610 DBG bootloader: _is_valid_size(vda) returning True
19:13:02,610 DBG bootloader: _is_valid_location(vda) returning True
19:13:02,617 DBG bootloader: _is_valid_format(vda) returning True
19:13:02,617 DBG bootloader: is_valid_stage1_device(vda) returning True
19:13:02,618 DBG bootloader: _is_valid_disklabel(vda1) returning True
19:13:02,619 DBG bootloader: _is_valid_size(vda1) returning True
19:13:02,619 DBG bootloader: _is_valid_partition(vda1) returning True
19:13:02,619 DBG bootloader: _is_valid_format(vda1) returning True
19:13:02,620 DBG bootloader: is_valid_stage2_device(vda1) returning True
19:13:02,621 INF blivet.autopart: Suggested swap size (2.06 GiB) exceeds 10 % of disk space, using 10 % of disk space (1.8 GiB) instead.
19:13:02,621 DBG storage_utils: Total swap space: 2 GiB
19:13:02,622 DBG storage_utils: Used disk space: 18 GiB
19:13:02,622 DBG storage_utils: Recommended swaps space: 1.8 GiB
19:13:02,628 DBG kickstart.kickstart.autopart: Storage check started with constraints {'min_ram': 410, 'min_root': Size (250 MiB), 'min_partition_sizes': {'/usr': Size (250 MiB), '/tmp': Size (50 MiB), '/var': Size (384 MiB), '/home': Size (100 MiB), '/boot': Size (200 MiB)}, 'must_be_on_linuxfs': {'/var', '/usr/lib', '/usr', '/', '/usr/share', '/tmp', '/home'}, 'must_be_on_root': {'/sbin', '/lib', '/mnt', '/root', '/etc', '/bin', 'lost+found', '/dev', '/proc'}, 'swap_is_recommended': True}.
19:13:02,634 DBG kickstart.kickstart.autopart: Run sanity check verify_root.
19:13:02,635 DBG kickstart.kickstart.autopart: Run sanity check verify_s390_constraints.
19:13:02,635 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_sizes.
19:13:02,635 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_format_sizes.
19:13:02,636 DBG kickstart.kickstart.autopart: Run sanity check verify_bootloader.
19:13:02,636 DBG kickstart.kickstart.autopart: Run sanity check verify_gpt_biosboot.
19:13:02,636 DBG kickstart.kickstart.autopart: Run sanity check verify_swap.
19:13:02,636 DBG kickstart.kickstart.autopart: Run sanity check verify_swap_uuid.
19:13:02,637 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_linuxfs.
19:13:02,637 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_root.
19:13:02,637 DBG kickstart.kickstart.autopart: Run sanity check verify_luks_devices_have_key.
19:13:02,638 DBG kickstart.kickstart.autopart: Run sanity check verify_mounted_partitions.
19:13:02,638 DBG kickstart.kickstart.autopart: Storage check finished with success.
19:13:02,638 DBG bootloader: new disk order: []
19:13:02,715 WRN misc: /usr/lib64/python3.6/site-packages/pyanaconda/storage_utils.py:848: DeprecationWarning: device_is_realdisk will be removed in a future version. udev.device_is_disk provides same functionality in 3.0
return udev_device and udev.device_is_realdisk(udev_device)
19:13:02,733 DBG bootloader: stage1 device cannot be of type lvmvg
19:13:02,733 DBG bootloader: stage1 device cannot be of type lvmlv
19:13:02,734 DBG bootloader: stage1 device cannot be of type lvmlv
19:13:02,734 DBG bootloader: _is_valid_disklabel(vda) returning True
19:13:02,735 DBG bootloader: _is_valid_size(vda) returning True
19:13:02,735 DBG bootloader: _is_valid_location(vda) returning True
19:13:02,735 DBG bootloader: _is_valid_format(vda) returning True
19:13:02,735 DBG bootloader: is_valid_stage1_device(vda) returning True
19:13:02,736 INF threading: Running Thread: AnaCheckStorageThread (140229837989632)
19:13:02,747 INF threading: Thread Done: AnaExecuteStorageThread (140230036047616)
19:13:02,750 DBG bootloader: _is_valid_disklabel(vda) returning True
19:13:02,751 DBG bootloader: _is_valid_size(vda) returning True
19:13:02,751 DBG bootloader: _is_valid_location(vda) returning True
19:13:02,751 DBG bootloader: _is_valid_format(vda) returning True
19:13:02,752 DBG bootloader: is_valid_stage1_device(vda) returning True
19:13:02,752 DBG bootloader: _is_valid_disklabel(vda1) returning True
19:13:02,752 DBG bootloader: _is_valid_size(vda1) returning True
19:13:02,753 DBG bootloader: _is_valid_partition(vda1) returning True
19:13:02,753 DBG bootloader: _is_valid_format(vda1) returning True
19:13:02,753 DBG bootloader: is_valid_stage2_device(vda1) returning True
19:13:02,755 INF blivet.autopart: Suggested swap size (2.06 GiB) exceeds 10 % of disk space, using 10 % of disk space (1.8 GiB) instead.
19:13:02,755 DBG storage_utils: Total swap space: 2 GiB
19:13:02,756 DBG storage_utils: Used disk space: 18 GiB
19:13:02,756 DBG storage_utils: Recommended swaps space: 1.8 GiB
19:13:02,768 DBG ui.gui.hubs: spoke is ready: StorageSpoke
19:13:02,770 DBG ui.helpers: Storage check started with constraints {'min_ram': 410, 'min_root': Size (250 MiB), 'min_partition_sizes': {'/usr': Size (250 MiB), '/tmp': Size (50 MiB), '/var': Size (384 MiB), '/home': Size (100 MiB), '/boot': Size (200 MiB)}, 'must_be_on_linuxfs': {'/var', '/usr/lib', '/usr', '/', '/usr/share', '/tmp', '/home'}, 'must_be_on_root': {'/sbin', '/lib', '/mnt', '/root', '/etc', '/bin', 'lost+found', '/dev', '/proc'}, 'swap_is_recommended': True}.
19:13:02,771 DBG ui.helpers: Run sanity check verify_root.
19:13:02,771 DBG ui.helpers: Run sanity check verify_s390_constraints.
19:13:02,771 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
19:13:02,771 DBG ui.helpers: Run sanity check verify_partition_sizes.
19:13:02,772 DBG ui.gui.hubs: setting StorageSpoke status to: Checking storage configuration...
19:13:02,772 DBG ui.helpers: Run sanity check verify_partition_format_sizes.
19:13:02,772 DBG ui.helpers: Run sanity check verify_bootloader.
19:13:02,773 DBG ui.helpers: Run sanity check verify_gpt_biosboot.
19:13:02,773 DBG ui.helpers: Run sanity check verify_swap.
19:13:02,773 DBG ui.gui.hubs: spoke is ready: StorageSpoke
19:13:02,773 DBG ui.helpers: Run sanity check verify_swap_uuid.
19:13:02,774 DBG ui.helpers: Run sanity check verify_mountpoints_on_linuxfs.
19:13:02,774 DBG ui.helpers: Run sanity check verify_mountpoints_on_root.
19:13:02,774 DBG ui.helpers: Run sanity check verify_luks_devices_have_key.
19:13:02,774 DBG ui.helpers: Run sanity check verify_mounted_partitions.
19:13:02,775 DBG ui.helpers: Storage check finished with success.
19:13:02,775 INF threading: Thread Done: AnaCheckStorageThread (140229837989632)
19:13:03,967 INF payload: Refreshing environmentAddons
19:13:05,575 INF threading: Running Thread: AnaSyncTime_clock01.util.phx2.redhat.com (140229837989632)
19:13:05,750 DBG ui.common: Entered spoke: DatetimeSpoke
19:13:06,000 INF isys: System time set to Tue Jul 11 17:13:06 2017 UTC
19:13:06,000 INF threading: Thread Done: AnaSyncTime_clock01.util.phx2.redhat.com (140229837989632)
19:13:06,385 INF threading: Running Thread: AnaNTPserver3 (140229837989632)
19:13:06,406 INF threading: Running Thread: AnaNTPserver4 (140229670491904)
19:13:06,597 INF threading: Thread Done: AnaNTPserver3 (140229837989632)
19:13:06,613 INF threading: Thread Done: AnaNTPserver4 (140229670491904)
19:13:08,177 DBG payload: Updating payload thread state: 5
19:13:08,178 INF ui.gui.spokes.software_selection: using environment from kickstart: None
19:13:08,180 INF threading: Thread Done: AnaPayloadThread (140229829596928)
19:13:08,182 DBG ui.gui.spokes.installation_source: Clearing checks in source spoke
19:13:08,183 DBG ui.gui.spokes.installation_source: Setting up repos: []
19:13:08,249 INF threading: Running Thread: AnaCheckSoftwareThread (140229829596928)
19:13:08,249 INF lifecycle: Module initialized: SoftwareSelectionSpoke
19:13:08,250 INF threading: Thread Done: AnaSoftwareWatcher (140229846382336)
19:13:12,119 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
19:13:12,230 DBG ui.gui.hubs: spoke is ready: SourceSpoke
19:13:12,377 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
19:13:12,525 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
19:13:12,672 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
19:13:12,689 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Checking software dependencies...
19:13:13,104 INF threading: Thread Done: AnaCheckSoftwareThread (140229829596928)
19:13:13,195 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
19:13:13,197 DBG ui.gui.hubs: spoke is ready: SourceSpoke
19:13:14,067 INF lifecycle: Module initialized: SourceSpoke
19:13:14,068 INF lifecycle: All modules have been initialized.
19:13:14,069 INF threading: Thread Done: AnaSourceWatcher (140229854775040)
19:13:14,099 DBG ui.gui.hubs: spoke is ready: SourceSpoke
19:13:14,533 DBG ui.common: Left spoke: DatetimeSpoke
19:13:14,596 DBG payload: Source doesn't require network for installation
19:13:14,715 INF ui.lib.space: fs space: 14.45 GiB needed: 1.25 GiB
19:13:15,847 DBG ui.common: Entered spoke: LangsupportSpoke
19:13:19,892 DBG ui.common: Left spoke: LangsupportSpoke
19:13:19,956 DBG payload: Source doesn't require network for installation
19:13:20,073 INF ui.lib.space: fs space: 14.45 GiB needed: 1.25 GiB
19:13:22,400 DBG ui.common: Entered spoke: SoftwareSelectionSpoke
19:13:40,931 DBG ui.gui.spokes.software_selection: Environment selected for installation: custom-environment
19:13:40,931 DBG ui.gui.spokes.software_selection: Groups selected for installation: ['3d-printing']
19:13:40,932 INF threading: Running Thread: AnaCheckSoftwareThread (140229727954688)
19:13:40,932 DBG ui.common: Left spoke: SoftwareSelectionSpoke
19:13:42,343 INF threading: Thread Done: AnaCheckSoftwareThread (140229727954688)
19:13:42,352 DBG payload: Source doesn't require network for installation
19:13:42,357 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
19:13:42,617 INF ui.lib.space: fs space: 14.45 GiB needed: 3.33 GiB
19:13:42,618 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
19:13:42,619 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Checking software dependencies...
19:13:42,877 INF ui.lib.space: fs space: 14.45 GiB needed: 3.33 GiB
19:13:42,877 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
19:13:43,138 INF ui.lib.space: fs space: 14.45 GiB needed: 3.33 GiB
19:13:43,138 DBG ui.gui.hubs: spoke is ready: SourceSpoke
19:13:52,497 DBG ui.common: Entered spoke: KeyboardSpoke
19:14:00,412 DBG ui.common: Left spoke: KeyboardSpoke
19:14:00,485 DBG payload: Source doesn't require network for installation
19:14:00,731 INF ui.lib.space: fs space: 14.45 GiB needed: 3.33 GiB
19:14:02,257 DBG ui.common: Left hub: SummaryHub
19:14:02,267 INF lifecycle: Adding controller: ProgressHub
19:14:02,274 INF lifecycle: Module initialized: PasswordSpoke
19:14:02,289 INF lifecycle: Module initialized: UserSpoke
19:14:02,291 INF lifecycle: Initialization of all modules (2) has been started.
19:14:02,291 INF lifecycle: All modules have been initialized.
19:14:02,293 DBG ui.gui.hubs: Disabling event loop for hub SummaryHub
19:14:02,293 DBG ui.gui.hubs: Starting event loop for hub ProgressHub
19:14:02,293 INF threading: Running Thread: AnaInstallThread (140229854775040)
19:14:02,295 INF installation: Top-level task queue: Installation queue
Number of task queues: 8
Number of tasks: 18
Task & task group listing:
Task: Save system time to HW clock
Task queue: Installation environment setup
Task: Setup firstboot
Task: Setup addons
Task queue: Early storage configuration
Task: Insert custom storage to ksdata
Task: Run pre-storage tasks
Task: Activate filesystems
Task: Write early storage
Task queue: Pre-install scripts
Task: Run %pre-install scripts
Task queue: Pre install tasks
Task: Setup authconfig
Task: Setup firewall
Task: Setup network
Task: Setup timezone
Task: Copy /resolv.conf to sysroot
Task: Find additional packages & run preInstall()
Task queue: Payload installation
Task: Install the payload
Task queue: Late storage configuration
Task: Write late storage
Task queue: Bootloader installation
Task: Install bootloader
Task queue: Post-installation setup tasks
Task: Run post-installation setup tasks
19:14:02,295 INF installation: Task started: Save system time to HW clock (1/18)
19:14:02,310 DBG ui.common: Entered hub: ProgressHub
19:14:02,396 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
19:14:03,501 DBG installation: Task completed: Save system time to HW clock (1/18) (1.2 s)
19:14:03,501 INF progress: Setting up the installation environment
19:14:03,501 INF installation: Queue started: Installation environment setup (1/8)
19:14:03,501 INF installation: Task started: Setup firstboot (2/18)
19:14:03,502 DBG installation: Task completed: Setup firstboot (2/18) (0.0 s)
19:14:03,502 INF installation: Task started: Setup addons (3/18)
19:14:03,502 DBG installation: Task completed: Setup addons (3/18) (0.0 s)
19:14:03,502 INF progress: Setting up the installation environment -- DONE
19:14:03,502 INF progress: Configuring storage
19:14:03,502 INF installation: Queue started: Early storage configuration (2/8)
19:14:03,502 INF installation: Task started: Insert custom storage to ksdata (4/18)
19:14:03,503 DBG installation: Task completed: Insert custom storage to ksdata (4/18) (0.0 s)
19:14:03,504 INF installation: Task started: Run pre-storage tasks (5/18)
19:14:03,504 DBG installation: Task completed: Run pre-storage tasks (5/18) (0.0 s)
19:14:03,504 INF installation: Task started: Activate filesystems (6/18)
19:14:03,615 DBG ui.common: Entered spoke: PasswordSpoke
19:14:04,235 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/BlockDev.py:511: Warning: g_variant_builder_add_value: assertion '!GVSB(builder)->expected_type || g_variant_is_of_type (value, GVSB(builder)->expected_type)' failed
return _lvm_lvremove(vg_name, lv_name, force, extra)
19:14:04,514 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/BlockDev.py:511: Warning: g_variant_builder_add_value: assertion '!GVSB(builder)->expected_type || g_variant_is_of_type (value, GVSB(builder)->expected_type)' failed
return _lvm_lvremove(vg_name, lv_name, force, extra)
19:14:04,742 WRN misc: /usr/lib64/python3.6/site-packages/gi/overrides/BlockDev.py:434: Warning: g_variant_builder_add_value: assertion '!GVSB(builder)->expected_type || g_variant_is_of_type (value, GVSB(builder)->expected_type)' failed
return _lvm_pvremove(device, extra)
19:14:05,133 INF progress: Creating disklabel on /dev/vda
19:14:05,208 INF progress: Created disklabel on /dev/vda
19:14:05,382 INF progress: Creating ext4 on /dev/vda1
19:14:05,551 INF progress: Created ext4 on /dev/vda1
19:14:05,709 INF progress: Creating lvmpv on /dev/vda2
19:14:05,832 INF progress: Created lvmpv on /dev/vda2
19:14:06,033 INF progress: Creating swap on /dev/mapper/fedora-swap
19:14:06,082 INF progress: Created swap on /dev/mapper/fedora-swap
19:14:06,196 INF progress: Creating ext4 on /dev/mapper/fedora-root
19:14:06,436 INF progress: Created ext4 on /dev/mapper/fedora-root
19:14:08,138 DBG installation: Task completed: Activate filesystems (6/18) (4.6 s)
19:14:08,138 INF installation: Task started: Write early storage (7/18)
19:14:08,142 DBG installation: Task completed: Write early storage (7/18) (0.0 s)
19:14:08,142 INF progress: Configuring storage -- DONE
19:14:08,143 INF progress: Running pre-installation scripts
19:14:08,143 INF installation: Queue started: Pre-install scripts (3/8)
19:14:08,144 INF installation: Task started: Run %pre-install scripts (8/18)
19:14:08,144 DBG installation: Task completed: Run %pre-install scripts (8/18) (0.0 s)
19:14:08,144 INF progress: Running pre-installation scripts -- DONE
19:14:08,145 INF progress: Running pre-installation tasks
19:14:08,145 INF installation: Queue started: Pre install tasks (4/8)
19:14:08,145 INF installation: Task started: Setup authconfig (9/18)
19:14:08,145 DBG installation: Task completed: Setup authconfig (9/18) (0.0 s)
19:14:08,146 INF installation: Task started: Setup firewall (10/18)
19:14:08,146 DBG installation: Task completed: Setup firewall (10/18) (0.0 s)
19:14:08,146 INF installation: Task started: Setup network (11/18)
19:14:08,158 DBG installation: Task completed: Setup network (11/18) (0.0 s)
19:14:08,158 INF installation: Task started: Setup timezone (12/18)
19:14:08,185 DBG installation: Task completed: Setup timezone (12/18) (0.0 s)
19:14:08,185 INF installation: Task started: Copy /resolv.conf to sysroot (13/18)
19:14:08,185 ERR installation_tasks: Task Copy /resolv.conf to sysroot callable not set.
19:14:08,186 DBG installation: Task completed: Copy /resolv.conf to sysroot (13/18) (0.0 s)
19:14:08,186 INF installation: Task started: Find additional packages & run preInstall() (14/18)
19:14:08,213 WRN payload: Platform group platform-kvm not available.
19:14:08,214 DBG installation: Task completed: Find additional packages & run preInstall() (14/18) (0.0 s)
19:14:08,214 INF progress: Running pre-installation tasks -- DONE
19:14:08,215 INF progress: Installing.
19:14:08,215 INF installation: Queue started: Payload installation (5/8)
19:14:08,215 INF installation: Task started: Install the payload (15/18)
19:14:08,216 INF progress: Starting package installation process
19:14:28,286 DBG ui.common: Left spoke: PasswordSpoke
19:14:28,365 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
19:14:30,701 DBG ui.common: Entered spoke: UserSpoke
19:14:35,345 INF progress: Preparing transaction from installation source
19:14:39,246 DBG ui.common: Left spoke: UserSpoke
19:17:30,212 DBG installation: Task completed: Install the payload (15/18) (202.0 s)
19:17:30,212 INF progress: Installing. -- DONE
19:17:30,213 INF progress: Configuring storage
19:17:30,213 INF installation: Queue started: Late storage configuration (6/8)
19:17:30,213 INF installation: Task started: Write late storage (16/18)
19:17:30,213 DBG installation: Task completed: Write late storage (16/18) (0.0 s)
19:17:30,214 INF progress: Configuring storage -- DONE
19:17:30,214 INF progress: Installing boot loader
19:17:30,214 INF installation: Queue started: Bootloader installation (7/8)
19:17:30,215 INF installation: Task started: Install bootloader (17/18)
19:17:30,215 INF bootloader: boot loader stage1 target device is vda
19:17:30,215 INF bootloader: boot loader stage2 target device is vda1
19:17:30,250 DBG bootloader: new default image: <pyanaconda.bootloader.LinuxBootLoaderImage object at 0x7f89c98c0278>
19:17:30,300 INF bootloader: bootloader.py: mbr will be updated for grub2
19:17:30,731 INF bootloader: bootloader.py: used boot args: rd.lvm.lv=fedora/root rd.lvm.lv=fedora/swap rhgb quiet
19:17:32,268 DBG installation: Task completed: Install bootloader (17/18) (2.1 s)
19:17:32,269 INF progress: Installing boot loader -- DONE
19:17:32,269 INF progress: Performing post-installation setup tasks
19:17:32,270 INF installation: Queue started: Post-installation setup tasks (8/8)
19:17:32,270 INF installation: Task started: Run post-installation setup tasks (18/18)
19:17:32,272 DBG installation: Task completed: Run post-installation setup tasks (18/18) (0.0 s)
19:17:32,274 INF progress: Performing post-installation setup tasks -- DONE
19:17:32,274 INF threading: Thread Done: AnaInstallThread (140229854775040)
19:17:32,395 INF threading: Running Thread: AnaConfigurationThread (140229854775040)
19:17:32,397 INF installation: Top-level task queue: Configuration queue
Number of task queues: 7
Number of tasks: 20
Task & task group listing:
Task queue: Installed system configuration
Task: Configure authconfig
Task: Configure SELinux
Task: Configure first boot tasks
Task: Configure services
Task: Configure keyboard
Task: Configure timezone
Task: Configure language
Task: Configure firewall
Task: Configure X
Task: Configure skip-X
Task queue: Network configuration
Task: Network configuration
Task queue: User creation
Task: Configure root
Task: Configure user groups
Task: Configure user
Task: Configure SSH key
Task queue: Anaconda addon configuration
Task: Configure Anaconda addons
Task queue: Initramfs generation
Task: Generate initramfs
Task queue: Post installation scripts
Task: Run post installation scripts
Task queue: Write configs and kickstarts
Task: Store kickstarts
Task: Store user interaction config
19:17:32,398 INF installation: Queue started: Installed system configuration (1/7)
19:17:32,398 INF progress: Configuring installed system
19:17:32,398 INF installation: Task started: Configure authconfig (1/20)
19:17:32,715 DBG installation: Task completed: Configure authconfig (1/20) (0.3 s)
19:17:32,716 INF installation: Task started: Configure SELinux (2/20)
19:17:32,716 DBG installation: Task completed: Configure SELinux (2/20) (0.0 s)
19:17:32,717 INF installation: Task started: Configure first boot tasks (3/20)
19:17:32,717 DBG installation: Task completed: Configure first boot tasks (3/20) (0.0 s)
19:17:32,717 INF installation: Task started: Configure services (4/20)
19:17:32,804 DBG installation: Task completed: Configure services (4/20) (0.1 s)
19:17:32,805 INF installation: Task started: Configure keyboard (5/20)
19:17:33,002 DBG installation: Task completed: Configure keyboard (5/20) (0.2 s)
19:17:33,002 INF installation: Task started: Configure timezone (6/20)
19:17:33,003 DBG kickstart.kickstart.timezone: Modifying installed chrony configuration
19:17:33,004 DBG installation: Task completed: Configure timezone (6/20) (0.0 s)
19:17:33,005 INF installation: Task started: Configure language (7/20)
19:17:33,005 DBG installation: Task completed: Configure language (7/20) (0.0 s)
19:17:33,005 INF installation: Task started: Configure firewall (8/20)
19:17:33,362 DBG installation: Task completed: Configure firewall (8/20) (0.4 s)
19:17:33,362 INF installation: Task started: Configure X (9/20)
19:17:33,363 DBG installation: Task completed: Configure X (9/20) (0.0 s)
19:17:33,363 INF installation: Task started: Configure skip-X (10/20)
19:17:33,363 DBG installation: Task completed: Configure skip-X (10/20) (0.0 s)
19:17:33,364 INF progress: Configuring installed system -- DONE
19:17:33,364 INF installation: Queue started: Network configuration (2/7)
19:17:33,368 INF progress: Writing network configuration
19:17:33,369 INF installation: Task started: Network configuration (11/20)
19:17:33,409 DBG installation: Task completed: Network configuration (11/20) (0.0 s)
19:17:33,409 INF progress: Writing network configuration -- DONE
19:17:33,409 INF installation: Queue started: User creation (3/7)
19:17:33,410 INF progress: Creating users
19:17:33,410 INF installation: Task started: Configure root (12/20)
19:17:33,489 DBG installation: Task completed: Configure root (12/20) (0.1 s)
19:17:33,489 INF installation: Task started: Configure user groups (13/20)
19:17:33,490 DBG installation: Task completed: Configure user groups (13/20) (0.0 s)
19:17:33,490 INF installation: Task started: Configure user (14/20)
19:17:33,608 DBG installation: Task completed: Configure user (14/20) (0.1 s)
19:17:33,608 INF installation: Task started: Configure SSH key (15/20)
19:17:33,609 DBG installation: Task completed: Configure SSH key (15/20) (0.0 s)
19:17:33,609 INF progress: Creating users -- DONE
19:17:33,609 INF installation: Queue started: Anaconda addon configuration (4/7)
19:17:33,609 INF progress: Configuring addons
19:17:33,609 INF installation: Task started: Configure Anaconda addons (16/20)
19:17:33,610 INF progress: Executing com_redhat_docker addon
19:17:33,612 INF progress: Executing com_redhat_kdump addon
19:17:33,613 DBG installation: Task completed: Configure Anaconda addons (16/20) (0.0 s)
19:17:33,613 INF progress: Configuring addons -- DONE
19:17:33,613 INF installation: Queue started: Initramfs generation (5/7)
19:17:33,613 INF progress: Generating initramfs
19:17:33,613 INF installation: Task started: Generate initramfs (17/20)
19:17:33,628 INF payload: recreating initrd for 4.13.0-0.rc0.git3.1.fc27.x86_64
19:18:04,685 DBG installation: Task completed: Generate initramfs (17/20) (31.1 s)
19:18:04,686 INF progress: Generating initramfs -- DONE
19:18:04,686 INF installation: Queue started: Post installation scripts (6/7)
19:18:04,686 INF progress: Running post-installation scripts
19:18:04,687 INF installation: Task started: Run post installation scripts (18/20)
19:18:04,687 INF kickstart.script: Running kickstart %%post script(s)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment