Attachment 1484082 Details for Bug 1629908 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 38.62 KB, created by Wilbert van Bakel on 2018-09-17 15:51:34 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Wilbert van Bakel

Created: 2018-09-17 15:51:34 UTC

Size: 38.62 KB

patch

obsolete

>15:37:20,210 INF main: /sbin/anaconda 29.24.1-1.fc29
>15:37:20,401 INF isys: 8182828 kB (7991 MB) are available
>15:37:20,423 INF startup_utils: check_memory(): total:7991, needed:320, graphical:410
>15:37:20,432 INF main: anaconda called with cmdline = ['/sbin/anaconda']
>15:37:20,432 INF main: Default encoding = utf-8 
>15:37:20,447 INF dbus.connection: Connecting to a default bus at unix:abstract=/tmp/dbus-R1ka71hMCA,guid=a25331ddc5f99e6158ec46365b9fca30.
>15:37:20,679 INF startup_utils: Waiting 600 sec for modules to be started.
>15:37:21,681 INF startup_utils: Waiting 599 sec for modules to be started.
>15:37:22,754 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>15:37:22,818 ERR core.util: Failed to import module docker from path /usr/share/anaconda/addons/com_redhat_docker/ks/docker.py in collect: No module named 'pyanaconda.iutil'
>15:37:23,132 WRN kickstart: Command firstboot will be parsed in DBus module.
>15:37:23,135 WRN kickstart: Command firewall will be parsed in DBus module.
>15:37:23,157 DBG localization: setting locale to: en_US.UTF-8
>15:37:23,169 DBG network: devices found ['enp2s0']
>15:37:23,179 DBG network: ensure single initramfs connections
>15:37:23,231 DBG network: apply kickstart
>15:37:23,232 DBG network: create missing ifcfg files
>15:37:23,301 DBG network: ifcfg file for enp2s0 not found
>15:37:23,472 DBG network: dumping ifcfg file for enp2s0 from default autoconnection 9a079a9b-652a-378c-a75c-fc5258a795b6
>15:37:23,473 DBG network: missing ifcfgs created for devices ['enp2s0']
>15:37:23,482 DBG network: set real ONBOOT value
>15:37:23,486 INF threading: Running Thread: AnaWaitForConnectingNMThread (140490886743808)
>15:37:23,487 DBG anaconda: setting display mode to GUI
>15:37:23,495 INF anaconda: Display mode is set to 'interactive graphical mode'.
>15:37:23,497 INF isys: 8182828 kB (7991 MB) are available
>15:37:23,505 INF startup_utils: check_memory(): total:7991, needed:320, graphical:410
>15:37:23,505 DBG core.util: Setting timeout 60 seconds for starting X.
>15:37:23,523 INF network: got 0 NTP servers from DHCP
>15:37:23,527 INF threading: Thread Done: AnaWaitForConnectingNMThread (140490886743808)
>15:37:24,725 DBG core.util: X server has signalled a successful start.
>15:37:24,834 INF display: spice-vdagent exited with status 1
>15:37:25,351 DBG installclass: Searching /usr/lib64/python3.7/site-packages/pyanaconda/installclasses.
>15:37:25,367 DBG installclass: Found Fedora (DefaultInstallClass).
>15:37:25,368 DBG installclass: Found Fedora (FedoraBaseInstallClass).
>15:37:25,368 DBG installclass: Found Fedora Server (FedoraServerInstallClass).
>15:37:25,368 DBG installclass: Found Scientific Linux (ScientificBaseInstallClass).
>15:37:25,368 DBG installclass: Found Atomic Host (AtomicHostInstallClass).
>15:37:25,369 DBG installclass: Found Red Hat Enterprise Linux (RHELBaseInstallClass).
>15:37:25,369 DBG installclass: Found Fedora Workstation (FedoraWorkstationInstallClass).
>15:37:25,369 DBG installclass: Found CentOS Linux (CentOSBaseInstallClass).
>15:37:25,370 INF installclass: Using a visible install class Fedora (FedoraBaseInstallClass).
>15:37:25,370 DBG storage: trying to set new default luks version to 'luks1'
>15:37:25,623 INF bootloader: bootloader EFIGRUB on EFI platform
>15:37:25,627 INF autopart: Detected 7.8 GiB of memory
>15:37:25,627 INF autopart: Suggested swap size (7.8 GiB) exceeds 10 % of disk space, using 10 % of disk space (0.0) instead.
>15:37:25,628 INF autopart: Swap attempt of 0.0
>15:37:25,628 INF bootloader: bootloader EFIGRUB on EFI platform
>15:37:25,716 DBG storage_utils: LABEL=OEMDRV matches [] for devicetree=None and disks_only=True
>15:37:25,717 INF threading: Running Thread: AnaStorageThread (140490836162304)
>15:37:25,721 INF threading: Running Thread: AnaTimeInitThread (140490827769600)
>15:37:25,745 DBG payload: Restarting payload thread
>15:37:25,746 INF threading: Running Thread: AnaPayloadRestartThread (140490818590464)
>15:37:25,747 INF geoloc: Geolocation is enabled.
>15:37:25,748 INF threading: Running Thread: AnaPayloadThread (140490810197760)
>15:37:25,748 DBG payload: Updating payload thread state: 0
>15:37:25,748 DBG payload: Updating payload thread state: 1
>15:37:25,749 INF threading: Thread Done: AnaPayloadRestartThread (140490818590464)
>15:37:25,749 INF threading: Running Thread: AnaGeolocationRefreshThread (140490818590464)
>15:37:25,750 INF geoloc: Starting geolocation lookup
>15:37:25,750 INF geoloc: Geolocation provider: Fedora GeoIP
>15:37:25,764 DBG geoloc: Geoloc: RequestException for Fedora GeoIP API lookup:
>HTTPSConnectionPool(host='geoip.fedoraproject.org', port=443): Max retries exceeded with url: /city (Caused by NewConnectionError('<urllib3.connection.VerifiedHTTPSConnection object at 0x7fc692db5780>: Failed to establish a new connection: [Errno -2] Name or service not known'))
>15:37:25,768 INF geoloc: Geolocation lookup finished in 0.0 seconds
>15:37:25,769 INF geoloc: got results from geolocation
>15:37:25,769 INF threading: Thread Done: AnaGeolocationRefreshThread (140490818590464)
>15:37:26,700 DBG ui.gui: Detected primary monitor: 1280x1024 96dpix 96dpiy
>15:37:27,298 DBG localization: setting locale to: en_US.UTF-8
>15:37:27,309 DBG localization: setting locale to: en_US.UTF-8
>15:37:27,353 DBG ui.common: Entered spoke: WelcomeLanguageSpoke
>15:37:27,354 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/Gdk.py:346: DeprecationWarning: Gdk.Cursor.new is deprecated
>  return cls.new(*args, **kwds)
>
>15:37:33,185 DBG storage: protected device spec LABEL=Fedora-SB-ostree-x86_64-29 resolved to sdd
>15:37:33,231 DBG localization: setting locale to: en_US.UTF-8
>15:37:33,300 DBG ui.gui.spokes.network: network standalone spoke (init): completed: ['enp2s0']
>15:37:33,319 DBG ui.common: Left spoke: WelcomeLanguageSpoke
>15:37:33,344 INF lifecycle: Adding controller: SummaryHub
>15:37:33,379 DBG storage: blkid.tab devs: []
>15:37:33,380 DBG storage: crypttab maps: []
>15:37:33,380 DBG storage: parsing /mnt/sysimage/etc/fstab
>15:37:34,500 INF threading: Running Thread: AnaAddLayoutsInitThread (140490694018816)
>15:37:34,501 DBG ui.gui.utils: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7fc69029a7f0>> on <list object at 0x7fc6904c47c8>
>15:37:34,520 INF threading: Running Thread: AnaGtkBatchPre1 (140490685626112)
>15:37:34,529 INF threading: Thread Done: AnaGtkBatchPre1 (140490685626112)
>15:37:34,617 INF threading: Running Thread: AnaKeyboardThread (140490685626112)
>15:37:34,751 INF lifecycle: Module initialized: LangsupportSpoke
>15:37:35,367 INF threading: Running Thread: AnaNTPserver1 (140490676328192)
>15:37:35,384 INF threading: Running Thread: AnaDateTimeThread (140490667935488)
>15:37:35,498 INF threading: Thread Done: AnaNTPserver1 (140490676328192)
>15:37:35,526 INF threading: Running Thread: AnaStorageWatcher (140490676328192)
>15:37:35,805 INF threading: Thread Done: AnaStorageThread (140490836162304)
>15:37:35,806 DBG payload: Updating payload thread state: 2
>15:37:35,806 DBG payload: Updating payload thread state: 6
>15:37:35,807 INF ui.gui.spokes.software_selection: using environment from kickstart: None
>15:37:35,807 INF threading: Thread Done: AnaPayloadThread (140490810197760)
>15:37:35,808 DBG bootloader: _is_valid_disklabel(sdb2) returning True
>15:37:35,810 DBG ui.gui.hubs: incomplete spokes: [StorageSpoke]
>15:37:35,811 INF lifecycle: Module initialized: StorageSpoke
>15:37:35,811 INF threading: Thread Done: AnaStorageWatcher (140490676328192)
>15:37:35,812 DBG bootloader: _is_valid_size(sdb2) returning True
>15:37:35,812 DBG bootloader: _is_valid_partition(sdb2) returning True
>15:37:35,813 DBG bootloader: _is_valid_format(sdb2) returning True
>15:37:35,813 DBG bootloader: is_valid_stage2_device(sdb2) returning True
>15:37:35,855 INF threading: Running Thread: AnaCustomStorageInit (140490676328192)
>15:37:36,094 INF threading: Thread Done: AnaTimeInitThread (140490827769600)
>15:37:36,112 INF lifecycle: Module initialized: BlivetGuiSpoke
>15:37:36,159 INF lifecycle: Module initialized: FilterSpoke
>15:37:36,221 INF lifecycle: Module initialized: CustomPartitioningSpoke
>15:37:36,221 INF threading: Thread Done: AnaCustomStorageInit (140490676328192)
>15:37:36,228 DBG ui.gui.spokes.network: can't get remote connection of active connection of device enp2s0
>15:37:36,228 DBG ui.gui.spokes.network: device configuration added: connection 9a079a9b-652a-378c-a75c-fc5258a795b6 device enp2s0
>15:37:36,230 DBG ui.gui.spokes.network: not adding connection 9a079a9b-652a-378c-a75c-fc5258a795b6, already in list
>15:37:36,235 INF lifecycle: Module initialized: NetworkSpoke
>15:37:36,317 INF lifecycle: Initialization of all modules (8) has been started.
>15:37:36,320 DBG ui.gui.hubs: Starting event loop for hub SummaryHub
>15:37:36,329 DBG ui.common: Entered hub: SummaryHub
>11:37:36,000 INF isys: System time set to Mon Sep 17 15:37:36 2018 UTC
>11:37:36,001 INF isys: System time set to Mon Sep 17 15:37:36 2018 UTC
>11:37:36,000 INF isys: System time set to Mon Sep 17 15:37:36 2018 UTC
>11:37:36,002 INF lifecycle: Module initialized: DatetimeSpoke
>11:37:36,003 INF threading: Thread Done: AnaDateTimeThread (140490667935488)
>11:37:36,008 DBG ui.gui.hubs: spoke is not ready: KeyboardSpoke
>11:37:36,009 DBG ui.gui.hubs: setting KeyboardSpoke status to: Getting list of layouts...
>11:37:36,009 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>11:37:36,009 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>11:37:36,014 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>11:37:36,021 DBG ui.gui.hubs: spoke is ready: DatetimeSpoke
>11:37:38,069 DBG ui.gui.utils: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7fc69029a7f0>> on <list object at 0x7fc6904c47c8>
>11:37:38,069 INF threading: Thread Done: AnaAddLayoutsInitThread (140490694018816)
>11:37:38,070 INF lifecycle: Module initialized: KeyboardSpoke
>11:37:38,070 INF lifecycle: All modules have been initialized.
>11:37:38,070 INF threading: Thread Done: AnaKeyboardThread (140490685626112)
>11:37:38,125 DBG ui.gui.hubs: spoke is ready: KeyboardSpoke
>11:37:42,183 DBG ui.common: Entered spoke: NetworkSpoke
>11:37:51,608 INF network: setting installation environment host name to kitchen-desktop
>11:37:54,110 INF network: setting installation environment host name to kitchen-desktop
>11:37:56,776 DBG network: updating host name kitchen-desktop
>11:37:56,808 DBG ui.gui.spokes.network: apply ksdata # Network information
>network  --bootproto=dhcp --device=enp2s0 --ipv6=auto --activate
>network  --hostname=kitchen-desktop
>
>11:37:56,809 DBG ui.common: Left spoke: NetworkSpoke
>11:37:59,606 INF threading: Running Thread: AnaSyncTime_2.fedora.pool.ntp.org (140490818590464)
>11:37:59,639 DBG ui.common: Entered spoke: DatetimeSpoke
>11:38:00,000 INF isys: System time set to Mon Sep 17 15:38:00 2018 UTC
>11:38:00,000 INF threading: Thread Done: AnaSyncTime_2.fedora.pool.ntp.org (140490818590464)
>11:38:00,425 INF threading: Running Thread: AnaNTPserver2 (140490818590464)
>11:38:00,500 INF threading: Thread Done: AnaNTPserver2 (140490818590464)
>10:38:20,371 DBG ui.common: Left spoke: DatetimeSpoke
>10:38:27,731 DBG ui.common: Entered spoke: StorageSpoke
>10:38:36,665 INF autopart: Detected 7.8 GiB of memory
>10:38:36,666 INF autopart: Swap attempt of 7.8 GiB
>10:38:36,666 DBG ui.gui.spokes.storage: disk free: 9.34 GiB  fs free: 97.76 GiB  sw needs: 476.84 MiB  auto swap: 7.8 GiB
>10:38:36,668 INF autopart: Detected 7.8 GiB of memory
>10:38:36,668 INF autopart: Suggested swap size (7.8 GiB) exceeds 10 % of disk space, using 10 % of disk space (955.94 MiB) instead.
>10:38:36,669 INF autopart: Swap attempt of 955.94 MiB
>10:38:36,706 INF threading: Running Thread: AnaExecuteStorageThread (140490685626112)
>10:38:36,707 DBG ui.common: Left spoke: StorageSpoke
>10:38:36,719 DBG storage: clearpart: looking at sda3
>10:38:36,719 DBG storage: clearpart: looking at sda2
>10:38:36,720 DBG storage: clearpart: looking at sda1
>10:38:36,727 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run True.
>10:38:36,737 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>10:38:36,738 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>10:38:36,742 DBG kickstart.kickstart.bootloader: Applying drive order: []
>10:38:36,743 DBG bootloader: new disk order: []
>10:38:36,745 DBG kickstart.kickstart.bootloader: The /boot partition doesn't exist.
>10:38:36,745 DBG kickstart.kickstart.bootloader: Use the first usable drive.
>10:38:36,746 DBG kickstart.kickstart.bootloader: Using a boot drive: sda
>10:38:36,759 INF autopart: Detected 7.8 GiB of memory
>10:38:36,760 INF autopart: Suggested swap size (7.8 GiB) exceeds 10 % of disk space, using 10 % of disk space (955.94 MiB) instead.
>10:38:36,760 INF autopart: Swap attempt of 955.94 MiB
>10:38:36,766 DBG autopart: do_autopart: True
>10:38:36,766 DBG autopart: encrypted_autopart: False
>10:38:36,766 DBG autopart: autopart_type: 2
>10:38:36,767 DBG autopart: clear_part_type: 0
>10:38:36,767 DBG autopart: clear_part_disks: ['sda']
>10:38:36,767 DBG autopart: autopart_requests:
>PartSpec instance (0x7fc692b46f28) -- 
>  mountpoint = /  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 1024 MiB  max_size = 50 GiB  grow = True
>PartSpec instance (0x7fc692b46e80) -- 
>  mountpoint = /home  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 500 MiB  max_size = None  grow = True
>PartSpec instance (0x7fc692b46dd8) -- 
>  mountpoint = /boot/efi  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = efi  encrypted = False
>  size = 20 MiB  max_size = 200 MiB  grow = True
>PartSpec instance (0x7fc692b46cf8) -- 
>  mountpoint = /boot  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = ext4  encrypted = False
>  size = 1024 MiB  max_size = None  grow = False
>PartSpec instance (0x7fc6957231d0) -- 
>  mountpoint = None  lv = True  thin = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 955.94 MiB  max_size = None  grow = False
>
>10:38:36,768 DBG autopart: storage.disks: ['sda', 'sdd']
>10:38:36,769 DBG autopart: storage.partitioned: ['sda']
>10:38:36,769 DBG autopart: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/run/install/repo/images/install.img', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'sda', 'sda1', 'sda2', 'sda3', 'sdd']
>10:38:36,769 DBG autopart: boot disk: sda
>10:38:36,784 DBG autopart: candidate disks: [DiskDevice instance (0x7fc692b82f98) --
>  name = sda  status = True  id = 13
>  children = ['existing 101.98 GiB partition sda1 (23) with existing ext4 filesystem',
> 'existing 244 MiB partition sda2 (33) with existing efi filesystem',
> 'existing 244 MiB partition sda3 (41) with existing ext4 filesystem']
>  parents = []
>  uuid = None  size = 111.79 GiB
>  format = existing gpt disklabel
>  major = 8  minor = 0  exists = True  protected = False
>  sysfs path = /sys/devices/pci0000:00/0000:00:1f.2/ata1/host0/target0:0:0/0:0:0:0/block/sda
>  target size = 111.79 GiB  path = /dev/sda
>  format args = []  original_format = disklabel  removable = False  wwn = 50026b77820e19a7]
>10:38:36,789 DBG autopart: devs: [PartitionDevice instance (0x7fc692503550) --
>  name = req0  status = False  id = 251
>  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
>]
>10:38:36,791 DBG bootloader: stage1 device cannot be of type disk
>10:38:36,792 DBG bootloader: _is_valid_disklabel(sda1) returning True
>10:38:36,793 DBG bootloader: _is_valid_size(sda1) returning True
>10:38:36,794 DBG bootloader: _is_valid_location(sda1) returning True
>10:38:36,794 WRN bootloader: sda1 not bootable
>10:38:36,794 DBG bootloader: _is_valid_format(sda1) returning False
>10:38:36,795 DBG bootloader: is_valid_stage1_device(sda1) returning False
>10:38:36,796 DBG bootloader: _is_valid_disklabel(sda2) returning True
>10:38:36,797 DBG bootloader: _is_valid_size(sda2) returning True
>10:38:36,797 DBG bootloader: _is_valid_location(sda2) returning True
>10:38:36,797 DBG bootloader: _is_valid_format(sda2) returning True
>10:38:36,798 DBG bootloader: is_valid_stage1_device(sda2) returning True
>10:38:36,798 INF autopart: skipping unneeded stage1 efi request
>10:38:36,798 DBG autopart: PartSpec instance (0x7fc692b46dd8) -- 
>  mountpoint = /boot/efi  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = efi  encrypted = False
>  size = 20 MiB  max_size = 200 MiB  grow = True
>
>10:38:36,799 DBG autopart: existing 244 MiB partition sda2 (33) with existing efi filesystem mounted at /boot/efi
>10:38:36,890 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>10:38:36,890 DBG ui.gui.hubs: setting StorageSpoke status to: Saving storage configuration...
>10:38:37,021 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>10:38:37,029 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>10:38:37,031 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>10:38:37,038 DBG kickstart.kickstart.bootloader: Applying drive order: []
>10:38:37,039 DBG bootloader: new disk order: []
>10:38:37,043 DBG kickstart.kickstart.bootloader: Found a boot drive: sda
>10:38:37,043 DBG kickstart.kickstart.bootloader: Use a usable drive with a /boot partition.
>10:38:37,043 DBG kickstart.kickstart.bootloader: Using a boot drive: sda
>10:38:37,056 DBG bootloader: stage1 device cannot be of type lvmvg
>10:38:37,056 DBG bootloader: stage1 device cannot be of type lvmlv
>10:38:37,057 DBG bootloader: stage1 device cannot be of type lvmlv
>10:38:37,058 DBG bootloader: stage1 device cannot be of type disk
>10:38:37,059 DBG bootloader: _is_valid_disklabel(sda1) returning True
>10:38:37,060 DBG bootloader: _is_valid_size(sda1) returning True
>10:38:37,060 DBG bootloader: _is_valid_location(sda1) returning True
>10:38:37,060 WRN bootloader: sda1 not bootable
>10:38:37,061 DBG bootloader: _is_valid_format(sda1) returning False
>10:38:37,061 DBG bootloader: is_valid_stage1_device(sda1) returning False
>10:38:37,062 DBG bootloader: _is_valid_disklabel(sda2) returning True
>10:38:37,063 DBG bootloader: _is_valid_size(sda2) returning True
>10:38:37,063 DBG bootloader: _is_valid_location(sda2) returning True
>10:38:37,064 DBG bootloader: _is_valid_format(sda2) returning True
>10:38:37,064 DBG bootloader: is_valid_stage1_device(sda2) returning True
>10:38:37,068 DBG bootloader: _is_valid_disklabel(sda2) returning True
>10:38:37,069 DBG bootloader: _is_valid_size(sda2) returning True
>10:38:37,069 DBG bootloader: _is_valid_location(sda2) returning True
>10:38:37,069 DBG bootloader: _is_valid_format(sda2) returning True
>10:38:37,070 DBG bootloader: is_valid_stage1_device(sda2) returning True
>10:38:37,070 DBG bootloader: _is_valid_disklabel(sda4) returning True
>10:38:37,071 DBG bootloader: _is_valid_size(sda4) returning True
>10:38:37,071 DBG bootloader: _is_valid_partition(sda4) returning True
>10:38:37,071 DBG bootloader: _is_valid_format(sda4) returning True
>10:38:37,072 DBG bootloader: is_valid_stage2_device(sda4) returning True
>10:38:37,074 DBG storage_utils: Available memory: 6.81 GiB
>10:38:37,106 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': {'/home', '/usr', '/usr/share', '/', '/usr/lib', '/tmp', '/var'}, 'must_be_on_root': {'/lib', '/bin', '/etc', '/mnt', '/proc', '/dev', 'lost+found', '/root', '/sbin'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>10:38:37,106 DBG kickstart.kickstart.autopart: Run sanity check verify_root.
>10:38:37,107 DBG kickstart.kickstart.autopart: Run sanity check verify_s390_constraints.
>10:38:37,107 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_sizes.
>10:38:37,107 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_format_sizes.
>10:38:37,107 DBG kickstart.kickstart.autopart: Run sanity check verify_bootloader.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_gpt_biosboot.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_swap.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_swap_uuid.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_linuxfs.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_root.
>10:38:37,108 DBG kickstart.kickstart.autopart: Run sanity check verify_luks_devices_have_key.
>10:38:37,109 DBG kickstart.kickstart.autopart: Run sanity check verify_luks2_memory_requirements.
>10:38:37,109 DBG kickstart.kickstart.autopart: Run sanity check verify_mounted_partitions.
>10:38:37,109 DBG kickstart.kickstart.autopart: Storage check finished with success.
>10:38:37,112 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>10:38:37,123 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>10:38:37,125 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>10:38:37,130 DBG kickstart.kickstart.bootloader: Applying drive order: []
>10:38:37,131 DBG bootloader: new disk order: []
>10:38:37,136 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>10:38:37,245 DBG storage_utils: sda matches ['sda'] for devicetree=existing 7.46 GiB disk sdd (3) with existing iso9660 filesystem
>existing 111.79 GiB disk sda (13) with existing gpt disklabel
>  existing 101.98 GiB partition sda1 (23) with existing ext4 filesystem
>  existing 244 MiB partition sda2 (33) with existing efi filesystem mounted at /boot/efi
>  existing 244 MiB partition sda3 (41) with existing ext4 filesystem
>  non-existent 1024 MiB partition sda4 (258) with non-existent ext4 filesystem mounted at /boot
>  non-existent 8.33 GiB partition sda5 (251) with non-existent lvmpv
>    non-existent 8.33 GiB lvmvg fedora_kitchen-desktop (264)
>      non-existent 7.4 GiB lvmlv fedora_kitchen-desktop-root (270) with non-existent ext4 filesystem mounted at /
>      non-existent 955.94 MiB lvmlv fedora_kitchen-desktop-swap (279) with non-existent swap
>existing 0 B file /run/install/repo/images/install.img (96)
>  existing 0 B loop loop0 (100)
>existing 0 B file /LiveOS/rootfs.img (110)
>  existing 0 B loop loop1 (114) with existing ext4 filesystem
>    existing 8 GiB dm live-rw (138) with existing ext4 filesystem
>    existing 8 GiB dm live-base (148) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (124)
>  existing 0 B loop loop2 (128)
> and disks_only=True
>10:38:37,250 DBG kickstart.kickstart.bootloader: Using a boot drive: sda
>10:38:37,259 DBG bootloader: stage1 device cannot be of type lvmvg
>10:38:37,260 DBG bootloader: stage1 device cannot be of type lvmlv
>10:38:37,260 DBG bootloader: stage1 device cannot be of type lvmlv
>10:38:37,261 DBG bootloader: stage1 device cannot be of type disk
>10:38:37,262 DBG bootloader: _is_valid_disklabel(sda1) returning True
>10:38:37,262 DBG bootloader: _is_valid_size(sda1) returning True
>10:38:37,263 DBG bootloader: _is_valid_location(sda1) returning True
>10:38:37,263 WRN bootloader: sda1 not bootable
>10:38:37,263 DBG bootloader: _is_valid_format(sda1) returning False
>10:38:37,264 DBG bootloader: is_valid_stage1_device(sda1) returning False
>10:38:37,265 DBG bootloader: _is_valid_disklabel(sda2) returning True
>10:38:37,265 DBG bootloader: _is_valid_size(sda2) returning True
>10:38:37,266 DBG bootloader: _is_valid_location(sda2) returning True
>10:38:37,266 DBG bootloader: _is_valid_format(sda2) returning True
>10:38:37,266 DBG bootloader: is_valid_stage1_device(sda2) returning True
>10:38:37,267 INF threading: Running Thread: AnaCheckStorageThread (140490818590464)
>10:38:37,276 INF threading: Thread Done: AnaExecuteStorageThread (140490685626112)
>10:38:37,285 DBG bootloader: _is_valid_disklabel(sda2) returning True
>10:38:37,286 DBG bootloader: _is_valid_size(sda2) returning True
>10:38:37,287 DBG bootloader: _is_valid_location(sda2) returning True
>10:38:37,287 DBG bootloader: _is_valid_format(sda2) returning True
>10:38:37,287 DBG bootloader: is_valid_stage1_device(sda2) returning True
>10:38:37,288 DBG bootloader: _is_valid_disklabel(sda4) returning True
>10:38:37,289 DBG bootloader: _is_valid_size(sda4) returning True
>10:38:37,289 DBG bootloader: _is_valid_partition(sda4) returning True
>10:38:37,290 DBG bootloader: _is_valid_format(sda4) returning True
>10:38:37,290 DBG bootloader: is_valid_stage2_device(sda4) returning True
>10:38:37,293 DBG storage_utils: Available memory: 6.81 GiB
>10:38:37,304 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>10:38:37,317 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>10:38:37,317 DBG ui.gui.hubs: setting StorageSpoke status to: Checking storage configuration...
>10:38:37,331 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': {'/home', '/usr', '/usr/share', '/', '/usr/lib', '/tmp', '/var'}, 'must_be_on_root': {'/lib', '/bin', '/etc', '/mnt', '/proc', '/dev', 'lost+found', '/root', '/sbin'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>10:38:37,331 DBG ui.helpers: Run sanity check verify_root.
>10:38:37,331 DBG ui.helpers: Run sanity check verify_s390_constraints.
>10:38:37,331 DBG ui.helpers: Run sanity check verify_partition_sizes.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_partition_format_sizes.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_bootloader.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_gpt_biosboot.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_swap.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_swap_uuid.
>10:38:37,332 DBG ui.helpers: Run sanity check verify_mountpoints_on_linuxfs.
>10:38:37,333 DBG ui.helpers: Run sanity check verify_mountpoints_on_root.
>10:38:37,333 DBG ui.helpers: Run sanity check verify_luks_devices_have_key.
>10:38:37,333 DBG ui.helpers: Run sanity check verify_luks2_memory_requirements.
>10:38:37,333 DBG ui.helpers: Run sanity check verify_mounted_partitions.
>10:38:37,333 DBG ui.helpers: Storage check finished with success.
>10:38:37,333 INF threading: Thread Done: AnaCheckStorageThread (140490818590464)
>10:38:37,395 DBG ui.gui.hubs: incomplete spokes: []
>10:38:37,401 INF ui.lib.space: fs space: 6.29 GiB  needed: 476.84 MiB
>10:38:37,402 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>10:38:41,421 DBG ui.common: Left hub: SummaryHub
>10:38:41,433 INF lifecycle: Adding controller: ProgressHub
>10:38:41,444 INF lifecycle: Module initialized: PasswordSpoke
>10:38:41,457 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke]
>10:38:41,483 INF lifecycle: Module initialized: UserSpoke
>10:38:41,492 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke, UserSpoke]
>10:38:41,493 INF lifecycle: Initialization of all modules (2) has been started.
>10:38:41,493 INF lifecycle: All modules have been initialized.
>10:38:41,495 DBG ui.gui.hubs: Disabling event loop for hub SummaryHub
>10:38:41,495 DBG ui.gui.hubs: Starting event loop for hub ProgressHub
>10:38:41,496 INF threading: Running Thread: AnaInstallThread (140490818590464)
>10:38:41,504 INF installation: Top-level task queue: Installation queue
>Number of task queues: 9
>Number of tasks: 18
>Task & task group listing:
> Task: Save system time to HW clock
> Task queue: Installation environment setup
>  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: Realm discover
>  Task: Discover realm to join
> Task queue: Pre install tasks
>  Task: Setup authselect
>  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
>10:38:41,505 INF installation: Task started: Save system time to HW clock (1/18)
>10:38:41,505 DBG ui.common: Entered hub: ProgressHub
>10:38:41,607 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
>10:38:42,501 INF progress: Save system time to HW clock
>10:38:42,501 DBG installation: Task completed: Save system time to HW clock (1/18) (1.0 s)
>10:38:42,501 INF progress: Setting up the installation environment
>10:38:42,502 INF installation: Queue started: Installation environment setup (1/9)
>10:38:42,502 INF installation: Task started: Setup addons (2/18)
>10:38:42,502 INF progress: Setup addons
>10:38:42,502 DBG installation: Task completed: Setup addons (2/18) (0.0 s)
>10:38:42,503 INF progress: Configuring storage
>10:38:42,503 INF installation: Queue started: Early storage configuration (2/9)
>10:38:42,503 INF installation: Task started: Insert custom storage to ksdata (3/18)
>10:38:42,522 INF progress: Insert custom storage to ksdata
>10:38:42,522 DBG installation: Task completed: Insert custom storage to ksdata (3/18) (0.0 s)
>10:38:42,523 INF installation: Task started: Run pre-storage tasks (4/18)
>10:38:42,523 INF progress: Run pre-storage tasks
>10:38:42,523 DBG installation: Task completed: Run pre-storage tasks (4/18) (0.0 s)
>10:38:42,523 INF installation: Task started: Activate filesystems (5/18)
>10:38:43,826 INF progress: Creating ext4 on /dev/sda4
>10:38:44,339 DBG ui.common: Entered spoke: UserSpoke
>10:38:44,343 DBG ui.common: Entered spoke: UserSpoke
>10:38:44,497 INF progress: Creating lvmpv on /dev/sda5
>10:38:45,541 INF progress: Creating swap on /dev/mapper/fedora_kitchen--desktop-swap
>10:38:45,784 INF progress: Creating ext4 on /dev/mapper/fedora_kitchen--desktop-root
>10:38:46,169 INF storage: setting boot flag on sda2
>10:38:46,171 INF storage: Setting label on existing 244 MiB partition sda2 (33) with existing efi filesystem mounted at /boot/efi to 'EFI System Partition'
>10:38:49,650 INF progress: Activate filesystems
>10:38:49,650 DBG installation: Task completed: Activate filesystems (5/18) (7.1 s)
>10:38:49,651 INF installation: Task started: Write early storage (6/18)
>10:38:49,651 INF progress: Write early storage
>10:38:49,651 DBG installation: Task completed: Write early storage (6/18) (0.0 s)
>10:38:49,652 INF progress: Running pre-installation scripts
>10:38:49,652 INF installation: Queue started: Pre-install scripts (3/9)
>10:38:49,653 INF installation: Task started: Run %pre-install scripts (7/18)
>10:38:49,653 INF progress: Run %pre-install scripts
>10:38:49,653 DBG installation: Task completed: Run %pre-install scripts (7/18) (0.0 s)
>10:38:49,654 INF progress: Discovering realm to join
>10:38:49,654 INF installation: Queue started: Realm discover (4/9)
>10:38:49,654 INF installation: Task started: Discover realm to join (8/18)
>10:38:49,659 INF progress: Discover realm to join
>10:38:49,659 DBG installation: Task completed: Discover realm to join (8/18) (0.0 s)
>10:38:49,660 INF progress: Running pre-installation tasks
>10:38:49,660 INF installation: Queue started: Pre install tasks (5/9)
>10:38:49,660 INF installation: Task started: Setup authselect (9/18)
>10:38:49,665 INF progress: Setup authselect
>10:38:49,665 DBG installation: Task completed: Setup authselect (9/18) (0.0 s)
>10:38:49,665 INF installation: Task started: Setup firewall (10/18)
>10:38:49,668 INF progress: Setup firewall
>10:38:49,668 DBG installation: Task completed: Setup firewall (10/18) (0.0 s)
>10:38:49,669 INF installation: Task started: Setup network (11/18)
>10:38:49,683 INF progress: Setup network
>10:38:49,683 DBG installation: Task completed: Setup network (11/18) (0.0 s)
>10:38:49,683 INF installation: Task started: Setup timezone (12/18)
>10:38:49,720 INF progress: Setup timezone
>10:38:49,720 DBG installation: Task completed: Setup timezone (12/18) (0.0 s)
>10:38:49,721 INF installation: Task started: Copy /resolv.conf to sysroot (13/18)
>10:38:49,722 INF progress: Copy /resolv.conf to sysroot
>10:38:49,722 DBG installation: Task completed: Copy /resolv.conf to sysroot (13/18) (0.0 s)
>10:38:49,722 INF installation: Task started: Find additional packages & run preInstall() (14/18)
>10:38:49,723 DBG payload: added package requirement 'lvm2' for storage, strong=True
>10:38:49,724 DBG payload: added package requirement 'e2fsprogs' for storage, strong=True
>10:38:49,724 DBG payload: added package requirement 'dosfstools' for storage, strong=True
>10:38:49,724 DBG payload: no package requirement added for realm
>10:38:49,724 DBG payload: added package requirement 'authselect' for authselect, strong=True
>10:38:49,724 DBG payload: added package requirement 'firewalld' for firewall, strong=True
>10:38:49,724 DBG payload: no package requirement added for network
>10:38:49,724 DBG payload: added package requirement 'chrony' for ntp, strong=False
>10:38:49,725 DBG payload: added package requirement 'grub2-efi-x64' for bootloader, strong=True
>10:38:49,725 DBG payload: added package requirement 'shim-x64' for bootloader, strong=True
>10:38:49,725 DBG payload: added package requirement 'efibootmgr' for bootloader, strong=True
>10:38:49,725 DBG payload: added package requirement 'grub2' for bootloader, strong=True
>10:38:49,725 DBG payload: added package requirement 'grub2-tools' for bootloader, strong=True
>10:38:49,725 DBG payload: no group requirement added for language groups
>10:38:49,726 DBG payload: no package requirement added for langpacks
>10:38:49,726 INF progress: Find additional packages & run preInstall()
>10:38:49,726 DBG installation: Task completed: Find additional packages & run preInstall() (14/18) (0.0 s)
>10:38:49,726 INF progress: Installing.
>10:38:49,727 INF installation: Queue started: Payload installation (6/9)
>10:38:49,727 INF installation: Task started: Install the payload (15/18)
>10:38:49,732 INF payload.rpmostreepayload: executing ostreesetup=<pykickstart.commands.ostreesetup.F21_OSTreeSetup object at 0x7fc69589a710>
>10:39:09,478 DBG ui.common: Left spoke: UserSpoke
>10:39:09,488 DBG ui.gui.hubs: incomplete spokes: [UserSpoke]
>10:39:09,492 DBG ui.gui.hubs: incomplete spokes: []
>10:42:49,575 INF payload.rpmostreepayload: ostree pull: 6994 metadata, 96750 content objects imported
>10:42:50,915 INF payload.rpmostreepayload: ostree admin deploy starting
>10:43:02,002 INF payload.rpmostreepayload: ostree admin deploy complete
>10:43:02,005 INF payload.rpmostreepayload: Copying bootloader data: grub2
>10:43:02,096 INF progress: Install the payload
>10:43:02,096 DBG installation: Task completed: Install the payload (15/18) (252.4 s)
>10:43:02,097 INF progress: Configuring storage
>10:43:02,097 INF installation: Queue started: Late storage configuration (7/9)
>10:43:02,098 INF installation: Task started: Write late storage (16/18)
>10:43:02,687 INF storage: not writing out mpath configuration
>10:43:02,688 INF progress: Write late storage
>10:43:02,688 DBG installation: Task completed: Write late storage (16/18) (0.6 s)
>10:43:02,689 INF progress: Installing boot loader
>10:43:02,689 INF installation: Queue started: Bootloader installation (8/9)
>10:43:02,689 INF installation: Task started: Install bootloader (17/18)
>10:43:02,689 INF bootloader: boot loader stage1 target device is sda2
>10:43:02,689 INF bootloader: boot loader stage2 target device is sda4
>10:43:04,011 INF bootloader: bootloader.py: used boot args: resume=/dev/mapper/fedora_kitchen--desktop-swap rd.lvm.lv=fedora_kitchen-desktop/root rd.lvm.lv=fedora_kitchen-desktop/swap rhgb quiet 
>10:43:05,181 ERR bootloader: bootloader.write failed: failed to write boot loader configuration
>10:43:05,259 WRN misc: /usr/lib64/python3.7/site-packages/pyanaconda/ui/gui/__init__.py:919: PyGTKDeprecationWarning: The keyword(s) "message_format" have been deprecated in favor of "text" respectively. See: https://wiki.gnome.org/PyGObject/InitializerDeprecations
>  message_format=message)
>
>10:43:05,259 WRN misc: /usr/lib64/python3.7/site-packages/pyanaconda/ui/gui/__init__.py:919: PyGTKDeprecationWarning: The "flags" argument for dialog construction is deprecated. Please use initializer keywords: modal=True and/or destroy_with_parent=True. See: https://wiki.gnome.org/PyGObject/InitializerDeprecations
>  message_format=message)
>
>10:45:52,208 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x55ecace7a330' has no handler with id '55160'
>  return func(*args, **kwargs)
>
>10:45:52,209 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x55ecace7a330' has no handler with id '55161'
>  return func(*args, **kwargs)
>
>10:45:52,211 INF threading: Thread Failed: AnaInstallThread (140490818590464)
>10:45:52,211 DBG exception: running handleException
>10:45:52,232 CRT exception: Traceback (most recent call last):
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/threading.py", line 286, in run
>    threading.Thread.run(self)
>
>  File "/usr/lib64/python3.7/threading.py", line 865, in run
>    self._target(*self._args, **self._kwargs)
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/installation.py", line 375, in doInstall
>    installation_queue.start()
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/installation_tasks.py", line 304, in start
>    item.start()
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/installation_tasks.py", line 304, in start
>    item.start()
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/installation_tasks.py", line 472, in start
>    self.run_task()
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/installation_tasks.py", line 438, in run_task
>    self._task(*self._task_args, **self._task_kwargs)
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/bootloader.py", line 2549, in writeBootLoader
>    writeBootLoaderFinal(storage, payload, instClass, ksdata)
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/bootloader.py", line 2519, in writeBootLoaderFinal
>    storage.bootloader.write()
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/bootloader.py", line 1820, in write
>    self.write_config()  # pylint: disable=no-member
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/bootloader.py", line 1629, in write_config
>    raise BootLoaderError("failed to write boot loader configuration")
>
>pyanaconda.bootloader.BootLoaderError: failed to write boot loader configuration
>
>10:45:52,233 DBG exception: Gtk running, queuing exception handler to the main loop
>10:45:52,234 INF threading: Thread Done: AnaInstallThread (140490818590464)
>10:46:00,800 INF core.util: Running kickstart %%onerror script(s)
>10:46:00,800 INF core.util: All kickstart %%onerror script(s) have been run
>10:46:00,800 INF kickstart.script: Running kickstart %%traceback script(s)
>10:46:00,801 INF kickstart.script: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1629908: 1484081 | 1484082 | 1484083 | 1484084 | 1484085 | 1484086 | 1484087 | 1484088 | 1484089 | 1484090 | 1484091 | 1484092 | 1484093