Attachment 1480411 Details for Bug 1624680 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 38.56 KB, created by lnie on 2018-09-03 04:43:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: lnie

Created: 2018-09-03 04:43:12 UTC

Size: 38.56 KB

patch

obsolete

>04:38:29,299 INF main: /sbin/anaconda 29.24.1-1.fc29
>04:38:29,509 INF isys: 2068836 kB (2020 MB) are available
>04:38:29,520 INF startup_utils: check_memory(): total:2020, needed:320, graphical:410
>04:38:29,532 INF main: anaconda called with cmdline = ['/sbin/anaconda']
>04:38:29,533 INF main: Default encoding = utf-8 
>04:38:29,547 INF dbus.connection: Connecting to a default bus at unix:abstract=/tmp/dbus-3IDkhw4byU,guid=c1c6ff7a25c57c12271dd8e25b8cbac5.
>04:38:29,877 INF startup_utils: Waiting 600 sec for modules to be started.
>04:38:30,880 INF startup_utils: Waiting 599 sec for modules to be started.
>04:38:31,883 INF startup_utils: Waiting 598 sec for modules to be started.
>04:38:32,973 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>04:38:33,020 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'
>04:38:33,322 WRN kickstart: Command firstboot will be parsed in DBus module.
>04:38:33,342 DBG localization: setting locale to: en_US.UTF-8
>04:38:33,358 DBG network: devices found ['ens3']
>04:38:33,373 DBG network: ensure single initramfs connections
>04:38:33,439 DBG network: apply kickstart
>04:38:33,440 DBG network: create missing ifcfg files
>04:38:33,536 DBG network: set real ONBOOT value
>04:38:33,539 INF threading: Running Thread: AnaWaitForConnectingNMThread (139940409915136)
>04:38:33,541 DBG anaconda: setting display mode to GUI
>04:38:33,548 INF anaconda: Display mode is set to 'interactive graphical mode'.
>04:38:33,550 INF isys: 2068836 kB (2020 MB) are available
>04:38:33,584 INF startup_utils: check_memory(): total:2020, needed:320, graphical:410
>04:38:33,585 DBG core.util: Setting timeout 60 seconds for starting X.
>04:38:33,604 INF network: got 0 NTP servers from DHCP
>04:38:33,610 INF threading: Thread Done: AnaWaitForConnectingNMThread (139940409915136)
>04:38:35,160 DBG core.util: X server has signalled a successful start.
>04:38:35,783 INF display: Started spice-vdagent.
>04:38:36,130 DBG installclass: Searching /usr/lib64/python3.7/site-packages/pyanaconda/installclasses.
>04:38:36,140 DBG installclass: Found Atomic Host (AtomicHostInstallClass).
>04:38:36,140 DBG installclass: Found Fedora (FedoraBaseInstallClass).
>04:38:36,140 DBG installclass: Found Red Hat Enterprise Linux (RHELBaseInstallClass).
>04:38:36,141 DBG installclass: Found Fedora Workstation (FedoraWorkstationInstallClass).
>04:38:36,141 DBG installclass: Found CentOS Linux (CentOSBaseInstallClass).
>04:38:36,141 DBG installclass: Found Fedora Server (FedoraServerInstallClass).
>04:38:36,142 DBG installclass: Found Fedora (DefaultInstallClass).
>04:38:36,142 DBG installclass: Found Scientific Linux (ScientificBaseInstallClass).
>04:38:36,142 INF installclass: Using a visible install class Fedora Server (FedoraServerInstallClass).
>04:38:36,289 DBG storage: trying to set new default luks version to 'luks1'
>04:38:36,890 DBG payload: getting release version from tree at None (29)
>04:38:36,890 DBG payload: using default release version of 29
>04:38:37,101 INF bootloader: bootloader GRUB2 on X86 platform
>04:38:37,110 INF autopart: Detected 1.97 GiB of memory
>04:38:37,112 INF autopart: Suggested swap size (3.95 GiB) exceeds 10 % of disk space, using 10 % of disk space (0.0) instead.
>04:38:37,113 INF autopart: Swap attempt of 0.0
>04:38:37,114 INF bootloader: bootloader GRUB2 on X86 platform
>04:38:37,183 DBG storage_utils: LABEL=OEMDRV matches [] for devicetree=None and disks_only=True
>04:38:37,185 INF threading: Running Thread: AnaStorageThread (139940409915136)
>04:38:37,193 INF threading: Running Thread: AnaTimeInitThread (139940352947968)
>04:38:37,251 DBG payload: Restarting payload thread
>04:38:37,253 INF threading: Running Thread: AnaPayloadRestartThread (139940344555264)
>04:38:37,254 INF geoloc: Geolocation is enabled.
>04:38:37,255 INF threading: Running Thread: AnaPayloadThread (139940336162560)
>04:38:37,256 INF threading: Thread Done: AnaPayloadRestartThread (139940344555264)
>04:38:37,256 DBG payload: Updating payload thread state: 0
>04:38:37,257 DBG payload: Updating payload thread state: 1
>04:38:37,257 INF threading: Running Thread: AnaGeolocationRefreshThread (139940327769856)
>04:38:37,265 INF geoloc: Starting geolocation lookup
>04:38:37,266 INF geoloc: Geolocation provider: Fedora GeoIP
>04:38:38,628 DBG ui.gui: Detected primary monitor: 1024x768 95dpix 96dpiy
>04:38:39,126 INF geoloc: Geolocation lookup finished in 1.9 seconds
>04:38:39,135 INF geoloc: got results from geolocation
>04:38:39,136 INF threading: Thread Done: AnaGeolocationRefreshThread (139940327769856)
>04:38:39,286 DBG localization: setting locale to: zh_CN.UTF-8
>04:38:39,321 DBG localization: setting locale to: zh_CN.UTF-8
>04:38:39,662 DBG ui.common: Entered spoke: WelcomeLanguageSpoke
>04:38:39,671 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/Gdk.py:346: DeprecationWarning: Gdk.Cursor.new is deprecated
>  return cls.new(*args, **kwds)
>
>04:38:41,351 INF threading: Thread Done: AnaStorageThread (139940409915136)
>04:38:41,352 DBG payload: Updating payload thread state: 2
>04:38:41,354 DBG payload: Updating payload thread state: 4
>04:38:41,426 DBG payload: retrieving treeinfo from http://10.66.128.196/os (proxy:  ; sslverify: True)
>04:38:41,427 INF payload: Trying to download '.treeinfo'
>04:38:41,457 DBG payload: Retrieved '.treeinfo' from http://10.66.128.196/os
>04:38:41,463 DBG payload: getting release version from tree at http://10.66.128.196/os (29)
>04:38:41,464 DBG payload: using treeinfo release version of 29
>04:38:41,569 DBG payload: Updating payload thread state: 5
>04:38:42,002 INF threading: Thread Done: AnaTimeInitThread (139940352947968)
>04:38:42,909 INF payload: Refreshing environmentAddons
>04:38:42,921 DBG payload: Updating payload thread state: 6
>04:38:42,921 INF threading: Thread Done: AnaPayloadThread (139940336162560)
>04:40:35,160 DBG localization: setting locale to: en_US.UTF-8
>04:40:37,312 DBG localization: setting locale to: en_US.UTF-8
>04:40:37,317 INF ui.gui.spokes.welcome: using timezone determined by geolocation
>04:40:37,432 DBG ui.gui.spokes.network: network standalone spoke (init): completed: ['ens3']
>04:40:37,448 DBG ui.common: Left spoke: WelcomeLanguageSpoke
>04:40:37,466 INF lifecycle: Adding controller: SummaryHub
>04:40:38,530 INF threading: Running Thread: AnaAddLayoutsInitThread (139940327769856)
>04:40:38,537 DBG ui.gui.utils: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f4667640a58>> on <list object at 0x7f4667817588>
>04:40:38,543 INF threading: Running Thread: AnaGtkBatchPre1 (139940336162560)
>04:40:38,545 INF threading: Thread Done: AnaGtkBatchPre1 (139940336162560)
>04:40:38,601 INF threading: Running Thread: AnaKeyboardThread (139940336162560)
>04:40:38,673 INF lifecycle: Module initialized: LangsupportSpoke
>04:40:39,056 INF threading: Running Thread: AnaNTPserver1 (139940352947968)
>04:40:39,069 INF threading: Running Thread: AnaDateTimeThread (139940409915136)
>04:40:40,173 INF threading: Running Thread: AnaSourceWatcher (139940283938560)
>04:40:40,180 DBG ui.gui.spokes.installation_source: Clearing checks in source spoke
>04:40:40,183 DBG ui.gui.spokes.installation_source: Setting up repos: []
>04:40:40,185 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke]
>04:40:40,186 INF lifecycle: Module initialized: SourceSpoke
>04:40:40,188 INF threading: Thread Done: AnaSourceWatcher (139940283938560)
>04:40:40,195 INF ui.gui.spokes.software_selection: using environment from kickstart: None
>04:40:40,200 INF threading: Running Thread: AnaSoftwareWatcher (139940283938560)
>04:40:40,202 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke, SoftwareSelectionSpoke]
>04:40:40,247 INF threading: Running Thread: AnaStorageWatcher (139940232677120)
>04:40:40,252 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke, SoftwareSelectionSpoke, StorageSpoke]
>04:40:40,265 INF lifecycle: Module initialized: StorageSpoke
>04:40:40,266 INF threading: Thread Done: AnaStorageWatcher (139940232677120)
>04:40:40,306 INF threading: Running Thread: AnaCustomStorageInit (139940232677120)
>04:40:40,422 INF lifecycle: Module initialized: BlivetGuiSpoke
>04:40:40,545 INF lifecycle: Module initialized: FilterSpoke
>04:40:40,657 DBG ui.gui.spokes.network: can't get remote connection of active connection of device ens3
>04:40:40,658 DBG ui.gui.spokes.network: device configuration added: connection 8307138a-0b85-4d7f-a6ce-e0fbf439522a device ens3
>04:40:40,663 DBG ui.gui.spokes.network: not adding connection 8307138a-0b85-4d7f-a6ce-e0fbf439522a, already in list
>04:40:40,852 DBG network: updating host name localhost.localdomain
>04:40:40,938 INF lifecycle: Module initialized: NetworkSpoke
>04:40:41,112 INF lifecycle: Module initialized: CustomPartitioningSpoke
>04:40:41,112 INF threading: Thread Done: AnaCustomStorageInit (139940232677120)
>04:40:41,118 DBG payload: Source http://10.66.128.196/os needs network for installation
>04:40:41,130 INF lifecycle: Initialization of all modules (10) has been started.
>04:40:41,133 DBG ui.gui.hubs: Starting event loop for hub SummaryHub
>04:40:41,144 DBG ui.common: Entered hub: SummaryHub
>04:40:41,249 DBG ui.gui.hubs: spoke is not ready: KeyboardSpoke
>04:40:41,250 DBG ui.gui.hubs: setting KeyboardSpoke status to: Getting list of layouts...
>04:40:41,252 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
>04:40:41,254 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
>04:40:41,254 DBG ui.gui.hubs: setting SourceSpoke status to: Setting up installation source...
>04:40:41,255 DBG ui.gui.hubs: setting SourceSpoke status to: Probing storage...
>04:40:41,255 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading package metadata...
>04:40:41,255 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading group metadata...
>04:40:41,257 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>04:40:41,259 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>04:40:41,260 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>04:40:41,260 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading package metadata...
>04:40:41,261 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading group metadata...
>04:40:41,261 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>04:40:41,262 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>04:40:41,270 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>07:40:41,000 INF isys: System time set to Mon Sep  3 04:40:41 2018 UTC
>07:40:41,000 INF isys: System time set to Mon Sep  3 04:40:41 2018 UTC
>07:40:41,000 INF isys: System time set to Mon Sep  3 04:40:41 2018 UTC
>12:40:41,073 INF lifecycle: Module initialized: DatetimeSpoke
>12:40:41,073 INF threading: Thread Done: AnaDateTimeThread (139940409915136)
>12:40:41,088 INF threading: Running Thread: AnaCheckSoftwareThread (139940409915136)
>12:40:41,088 INF lifecycle: Module initialized: SoftwareSelectionSpoke
>12:40:41,092 INF threading: Thread Done: AnaSoftwareWatcher (139940283938560)
>12:40:41,097 DBG ui.gui.hubs: spoke is ready: DatetimeSpoke
>12:40:41,107 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>12:40:41,109 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
>12:40:41,111 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
>12:40:41,111 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Checking software dependencies...
>12:40:41,112 DBG payload: apply with result True called on requirements []
>12:40:41,527 INF threading: Thread Done: AnaCheckSoftwareThread (139940409915136)
>12:40:41,597 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke, StorageSpoke]
>12:40:41,598 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>12:40:41,600 DBG ui.gui.hubs: incomplete spokes: [StorageSpoke]
>12:40:41,600 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>12:40:43,511 INF threading: Thread Done: AnaNTPserver1 (139940352947968)
>12:40:44,438 DBG ui.common: Entered spoke: StorageSpoke
>12:40:44,555 DBG ui.gui.utils: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f4667640a58>> on <list object at 0x7f4667817588>
>12:40:44,556 INF threading: Thread Done: AnaAddLayoutsInitThread (139940327769856)
>12:40:44,557 INF lifecycle: Module initialized: KeyboardSpoke
>12:40:44,557 INF lifecycle: All modules have been initialized.
>12:40:44,557 INF threading: Thread Done: AnaKeyboardThread (139940336162560)
>12:40:44,563 DBG ui.gui.hubs: spoke is ready: KeyboardSpoke
>12:40:54,713 INF ui.gui.spokes.storage: Partitioning method changed from auto to custom.
>12:40:54,714 INF ui.gui.spokes.storage: Rolling back planed storage configuration changes.
>12:41:02,976 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x56028514e290' has no handler with id '18326'
>  return func(*args, **kwargs)
>
>12:41:02,977 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x56028514e290' has no handler with id '18327'
>  return func(*args, **kwargs)
>
>12:41:03,011 INF threading: Running Thread: AnaExecuteStorageThread (139940336162560)
>12:41:03,011 DBG ui.common: Left spoke: StorageSpoke
>12:41:03,065 DBG storage: clearpart: initializing vda
>12:41:03,220 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run True.
>12:41:03,222 DBG payload: Source http://10.66.128.196/os needs network for installation
>12:41:03,240 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:41:03,243 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>12:41:03,246 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:41:03,247 DBG bootloader: new disk order: []
>12:41:03,248 DBG kickstart.kickstart.bootloader: The /boot partition doesn't exist.
>12:41:03,249 DBG kickstart.kickstart.bootloader: Use the first usable drive.
>12:41:03,249 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:41:03,259 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:41:03,265 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:41:03,266 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>12:41:03,269 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:41:03,270 DBG bootloader: new disk order: []
>12:41:03,272 DBG kickstart.kickstart.bootloader: The /boot partition doesn't exist.
>12:41:03,273 DBG kickstart.kickstart.bootloader: Use the first usable drive.
>12:41:03,273 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:41:03,282 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:03,282 DBG bootloader: _is_valid_size(vda) returning True
>12:41:03,283 DBG bootloader: _is_valid_location(vda) returning True
>12:41:03,283 DBG bootloader: _is_valid_format(vda) returning True
>12:41:03,283 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:03,295 INF threading: Thread Done: AnaExecuteStorageThread (139940336162560)
>12:41:03,307 DBG ui.gui.spokes.custom_storage: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url1/install.img (deleted)', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'vda']
>12:41:03,307 DBG ui.gui.spokes.custom_storage: ui: unused=[]
>12:41:03,308 DBG ui.gui.spokes.custom_storage: ui: new_devices=[]
>12:41:03,371 DBG ui.gui.spokes.custom_storage: page clicked: New Fedora 29 Installation
>12:41:03,372 DBG ui.gui.spokes.custom_storage: show mountpoint: New Fedora 29 Installation
>12:41:03,372 DBG ui.gui.spokes.custom_storage: show mountpoint: New Fedora 29 Installation
>12:41:03,393 DBG ui.common: Entered spoke: CustomPartitioningSpoke
>12:41:03,401 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>12:41:03,401 DBG ui.gui.hubs: setting StorageSpoke status to: Saving storage configuration...
>12:41:03,407 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>12:41:04,652 DBG ui.gui.spokes.custom_storage: running automatic partitioning
>12:41:04,653 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:41:04,662 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:41:04,664 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>12:41:04,668 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:41:04,668 DBG bootloader: new disk order: []
>12:41:04,671 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>12:41:04,746 DBG storage_utils: vda matches ['vda'] for devicetree=existing 20 GiB disk vda (4) with non-existent msdos disklabel
>existing 0 B file /tmp/curl_fetch_url1/install.img (deleted) (9)
>  existing 0 B loop loop0 (13)
>existing 0 B file /LiveOS/rootfs.img (23)
>  existing 0 B loop loop1 (27) with existing ext4 filesystem
>    existing 2 GiB dm live-rw (46) with existing ext4 filesystem
>    existing 2 GiB dm live-base (56) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (37)
>  existing 0 B loop loop2 (41)
> and disks_only=True
>12:41:04,748 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:41:04,755 INF autopart: Detected 1.97 GiB of memory
>12:41:04,756 INF autopart: Suggested swap size (3.95 GiB) exceeds 10 % of disk space, using 10 % of disk space (2 GiB) instead.
>12:41:04,756 INF autopart: Swap attempt of 2 GiB
>12:41:04,757 DBG autopart: do_autopart: True
>12:41:04,757 DBG autopart: encrypted_autopart: True
>12:41:04,757 DBG autopart: autopart_type: 2
>12:41:04,757 DBG autopart: clear_part_type: 0
>12:41:04,758 DBG autopart: clear_part_disks: ['vda']
>12:41:04,758 DBG autopart: autopart_requests:
>PartSpec instance (0x7f46660acda0) -- 
>  mountpoint = /  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = xfs  encrypted = True
>  size = 2 GiB  max_size = 15 GiB  grow = True
>PartSpec instance (0x7f46660acc88) -- 
>  mountpoint = None  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = biosboot  encrypted = False
>  size = 1024 KiB  max_size = None  grow = False
>PartSpec instance (0x7f46660acd68) -- 
>  mountpoint = /boot  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = ext4  encrypted = False
>  size = 1024 MiB  max_size = None  grow = False
>PartSpec instance (0x7f46660acf28) -- 
>  mountpoint = None  lv = True  thin = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 2 GiB  max_size = None  grow = False
>
>12:41:04,759 DBG autopart: storage.disks: ['vda']
>12:41:04,759 DBG autopart: storage.partitioned: ['vda']
>12:41:04,760 DBG autopart: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url1/install.img (deleted)', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'vda']
>12:41:04,760 DBG autopart: boot disk: vda
>12:41:04,776 DBG autopart: candidate disks: [DiskDevice instance (0x7f46660ace80) --
>  name = vda  status = True  id = 4
>  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 = None  removable = False  wwn = None]
>12:41:04,778 DBG autopart: devs: [PartitionDevice instance (0x7f466613c438) --
>  name = req0  status = False  id = 198
>  children = []
>  parents = []
>  uuid = None  size = 500 MiB
>  format = non-existent luks
>  major = 0  minor = 0  exists = False  protected = False
>  sysfs path = 
>  target size = 500 MiB  path = /dev/req0
>  format args = []  original_format = luks  grow = True  max size = 0 B  bootable = None
>  part type = None  primary = False  start sector = None  end sector = None
>  parted_partition = None
>  disk = None
>]
>12:41:04,782 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:04,782 DBG bootloader: _is_valid_size(vda) returning True
>12:41:04,782 DBG bootloader: _is_valid_location(vda) returning True
>12:41:04,783 DBG bootloader: _is_valid_format(vda) returning True
>12:41:04,783 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:04,784 INF autopart: skipping unneeded stage1 biosboot request
>12:41:04,784 DBG autopart: PartSpec instance (0x7f46660acc88) -- 
>  mountpoint = None  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = biosboot  encrypted = False
>  size = 1024 KiB  max_size = None  grow = False
>
>12:41:04,784 DBG autopart: existing 20 GiB disk vda (4) with non-existent msdos disklabel
>12:41:05,004 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:41:05,010 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:41:05,011 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>12:41:05,015 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:41:05,015 DBG bootloader: new disk order: []
>12:41:05,018 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>12:41:05,060 DBG storage_utils: vda matches ['vda'] for devicetree=existing 20 GiB disk vda (4) with non-existent msdos disklabel
>  non-existent 1024 MiB partition vda1 (205) with non-existent ext4 filesystem mounted at /boot
>  non-existent 19 GiB partition vda2 (198) with non-existent luks
>    non-existent 19 GiB luks/dm-crypt luks-vda2 (212) with non-existent lvmpv
>      non-existent 19 GiB lvmvg fedora (218)
>        non-existent 15 GiB lvmlv fedora-root (224) with non-existent xfs filesystem mounted at /
>        non-existent 2 GiB lvmlv fedora-swap (233) with non-existent swap
>existing 0 B file /tmp/curl_fetch_url1/install.img (deleted) (9)
>  existing 0 B loop loop0 (13)
>existing 0 B file /LiveOS/rootfs.img (23)
>  existing 0 B loop loop1 (27) with existing ext4 filesystem
>    existing 2 GiB dm live-rw (46) with existing ext4 filesystem
>    existing 2 GiB dm live-base (56) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (37)
>  existing 0 B loop loop2 (41)
> and disks_only=True
>12:41:05,065 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:41:05,074 DBG bootloader: stage1 device cannot be of type lvmvg
>12:41:05,075 DBG bootloader: stage1 device cannot be of type lvmlv
>12:41:05,076 DBG bootloader: stage1 device cannot be of type lvmlv
>12:41:05,077 DBG bootloader: stage1 device cannot be of type luks/dm-crypt
>12:41:05,079 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:05,079 DBG bootloader: _is_valid_size(vda) returning True
>12:41:05,080 DBG bootloader: _is_valid_location(vda) returning True
>12:41:05,080 DBG bootloader: _is_valid_format(vda) returning True
>12:41:05,080 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:05,081 DBG ui.gui.spokes.custom_storage: finished automatic partitioning
>12:41:05,085 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:05,086 DBG bootloader: _is_valid_size(vda) returning True
>12:41:05,086 DBG bootloader: _is_valid_location(vda) returning True
>12:41:05,086 DBG bootloader: _is_valid_format(vda) returning True
>12:41:05,087 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:05,087 DBG bootloader: _is_valid_disklabel(vda1) returning True
>12:41:05,088 DBG bootloader: _is_valid_size(vda1) returning True
>12:41:05,088 DBG bootloader: _is_valid_partition(vda1) returning True
>12:41:05,089 DBG bootloader: _is_valid_format(vda1) returning True
>12:41:05,089 DBG bootloader: is_valid_stage2_device(vda1) returning True
>12:41:05,092 DBG storage_utils: Available memory: 633.22 MiB
>12:41:05,106 DBG ui.gui.spokes.custom_storage: 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/share', '/var', '/usr', '/usr/lib', '/tmp'}, 'must_be_on_root': {'/dev', '/lib', '/bin', '/sbin', '/root', '/mnt', '/etc', 'lost+found', '/proc'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>12:41:05,106 DBG ui.gui.spokes.custom_storage: Run sanity check verify_root.
>12:41:05,106 DBG ui.gui.spokes.custom_storage: Run sanity check verify_s390_constraints.
>12:41:05,106 DBG ui.gui.spokes.custom_storage: Run sanity check verify_partition_sizes.
>12:41:05,107 DBG ui.gui.spokes.custom_storage: Run sanity check verify_partition_format_sizes.
>12:41:05,107 DBG ui.gui.spokes.custom_storage: Run sanity check verify_bootloader.
>12:41:05,107 DBG ui.gui.spokes.custom_storage: Run sanity check verify_gpt_biosboot.
>12:41:05,108 DBG ui.gui.spokes.custom_storage: Run sanity check verify_swap.
>12:41:05,108 DBG ui.gui.spokes.custom_storage: Run sanity check verify_swap_uuid.
>12:41:05,108 DBG ui.gui.spokes.custom_storage: Run sanity check verify_mountpoints_on_linuxfs.
>12:41:05,108 DBG ui.gui.spokes.custom_storage: Run sanity check verify_mountpoints_on_root.
>12:41:05,109 DBG ui.gui.spokes.custom_storage: Skipped sanity check verify_luks_devices_have_key.
>12:41:05,109 DBG ui.gui.spokes.custom_storage: Run sanity check verify_luks2_memory_requirements.
>12:41:05,109 DBG ui.gui.spokes.custom_storage: Run sanity check verify_mounted_partitions.
>12:41:05,110 DBG ui.gui.spokes.custom_storage: Storage check finished with success.
>12:41:05,110 DBG ui.gui.spokes.custom_storage: refreshing ui
>12:41:05,116 DBG ui.gui.spokes.custom_storage: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url1/install.img (deleted)', 'fedora', 'fedora-root', 'fedora-swap', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'luks-vda2', 'vda', 'vda1', 'vda2']
>12:41:05,116 DBG ui.gui.spokes.custom_storage: ui: unused=[]
>12:41:05,117 DBG ui.gui.spokes.custom_storage: ui: new_devices=['vda1', 'fedora-root', 'fedora-swap']
>12:41:05,126 DBG ui.gui.spokes.custom_storage: page clicked: New Fedora 29 Installation
>12:41:05,127 DBG ui.gui.spokes.custom_storage: show mountpoint: New Fedora 29 Installation
>12:41:05,127 DBG ui.gui.spokes.lib.accordion: Accordion: unselecting all items
>12:41:05,128 DBG ui.gui.spokes.lib.accordion: Accordion: select non-existent 1024 MiB partition vda1 (205) with non-existent ext4 filesystem mounted at /boot device
>12:41:05,133 DBG ui.gui.spokes.custom_storage: populate_right_side: non-existent 1024 MiB partition vda1 (205) with non-existent ext4 filesystem mounted at /boot
>12:41:05,136 DBG ui.gui.spokes.custom_storage: updated device_disks to ['vda']
>12:41:05,136 DBG ui.gui.spokes.custom_storage: updated device_container_name to None
>12:41:05,137 DBG ui.gui.spokes.custom_storage: updated device_container_raid_level to None
>12:41:05,137 DBG ui.gui.spokes.custom_storage: updated device_container_encrypted to False
>12:41:05,137 DBG ui.gui.spokes.custom_storage: updated device_container_size to 0
>12:41:05,150 DBG ui.gui.spokes.custom_storage: device_type_changed: None
>12:41:05,151 DBG ui.gui.spokes.custom_storage: device_type_changed: DEVICE_TYPE_PARTITION
>12:41:05,152 DBG ui.gui.spokes.custom_storage: populate_raid: 2, None
>12:41:05,153 DBG ui.gui.spokes.custom_storage: populate_raid: 2, None
>12:41:05,160 DBG ui.gui.spokes.custom_storage: show mountpoint: New Fedora 29 Installation
>12:41:05,161 DBG ui.gui.spokes.custom_storage: finished refreshing ui
>12:41:05,162 DBG ui.gui.spokes.custom_storage: updating space display
>12:41:05,171 DBG ui.gui.spokes.custom_storage: finished updating space display
>12:41:08,110 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x5602826730f0' has no handler with id '20075'
>  return func(*args, **kwargs)
>
>12:41:08,111 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x5602826730f0' has no handler with id '20076'
>  return func(*args, **kwargs)
>
>12:41:08,112 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:41:08,119 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:41:08,120 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>12:41:08,124 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:41:08,124 DBG bootloader: new disk order: []
>12:41:08,126 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>12:41:08,170 DBG storage_utils: vda matches ['vda'] for devicetree=existing 20 GiB disk vda (4) with non-existent msdos disklabel
>  non-existent 1024 MiB partition vda1 (205) with non-existent ext4 filesystem mounted at /boot
>  non-existent 19 GiB partition vda2 (198) with non-existent luks
>    non-existent 19 GiB luks/dm-crypt luks-vda2 (212) with non-existent lvmpv
>      non-existent 19 GiB lvmvg fedora (218)
>        non-existent 15 GiB lvmlv fedora-root (224) with non-existent xfs filesystem mounted at /
>        non-existent 2 GiB lvmlv fedora-swap (233) with non-existent swap
>existing 0 B file /tmp/curl_fetch_url1/install.img (deleted) (9)
>  existing 0 B loop loop0 (13)
>existing 0 B file /LiveOS/rootfs.img (23)
>  existing 0 B loop loop1 (27) with existing ext4 filesystem
>    existing 2 GiB dm live-rw (46) with existing ext4 filesystem
>    existing 2 GiB dm live-base (56) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (37)
>  existing 0 B loop loop2 (41)
> and disks_only=True
>12:41:08,176 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:41:08,185 DBG bootloader: stage1 device cannot be of type lvmvg
>12:41:08,186 DBG bootloader: stage1 device cannot be of type lvmlv
>12:41:08,186 DBG bootloader: stage1 device cannot be of type lvmlv
>12:41:08,187 DBG bootloader: stage1 device cannot be of type luks/dm-crypt
>12:41:08,189 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:08,190 DBG bootloader: _is_valid_size(vda) returning True
>12:41:08,190 DBG bootloader: _is_valid_location(vda) returning True
>12:41:08,190 DBG bootloader: _is_valid_format(vda) returning True
>12:41:08,191 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:08,196 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:41:08,196 DBG bootloader: _is_valid_size(vda) returning True
>12:41:08,196 DBG bootloader: _is_valid_location(vda) returning True
>12:41:08,197 DBG bootloader: _is_valid_format(vda) returning True
>12:41:08,197 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:41:08,198 DBG bootloader: _is_valid_disklabel(vda1) returning True
>12:41:08,198 DBG bootloader: _is_valid_size(vda1) returning True
>12:41:08,199 DBG bootloader: _is_valid_partition(vda1) returning True
>12:41:08,199 DBG bootloader: _is_valid_format(vda1) returning True
>12:41:08,200 DBG bootloader: is_valid_stage2_device(vda1) returning True
>12:41:08,202 DBG storage_utils: Available memory: 632.11 MiB
>12:41:08,217 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/share', '/var', '/usr', '/usr/lib', '/tmp'}, 'must_be_on_root': {'/dev', '/lib', '/bin', '/sbin', '/root', '/mnt', '/etc', 'lost+found', '/proc'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>12:41:08,217 DBG ui.helpers: Run sanity check verify_root.
>12:41:08,217 DBG ui.helpers: Run sanity check verify_s390_constraints.
>12:41:08,218 DBG ui.helpers: Run sanity check verify_partition_sizes.
>12:41:08,218 DBG ui.helpers: Run sanity check verify_partition_format_sizes.
>12:41:08,218 DBG ui.helpers: Run sanity check verify_bootloader.
>12:41:08,218 DBG ui.helpers: Run sanity check verify_gpt_biosboot.
>12:41:08,219 DBG ui.helpers: Run sanity check verify_swap.
>12:41:08,219 DBG ui.helpers: Run sanity check verify_swap_uuid.
>12:41:08,219 DBG ui.helpers: Run sanity check verify_mountpoints_on_linuxfs.
>12:41:08,220 DBG ui.helpers: Run sanity check verify_mountpoints_on_root.
>12:41:08,220 DBG ui.helpers: Run sanity check verify_luks_devices_have_key.
>12:41:08,220 DBG ui.helpers: Run sanity check verify_luks2_memory_requirements.
>12:41:08,220 DBG ui.helpers: Run sanity check verify_mounted_partitions.
>12:41:08,221 DBG ui.helpers: Storage check finished with success.
>12:41:08,268 DBG ui.gui.hubs: incomplete spokes: []
>12:41:08,376 INF ui.lib.space: fs space: 14.55 GiB  needed: 2.17 GiB
>12:41:08,378 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>12:41:08,378 DBG ui.gui.hubs: setting StorageSpoke status to: Checking storage configuration...
>12:41:08,484 INF ui.lib.space: fs space: 14.55 GiB  needed: 2.17 GiB
>12:41:08,485 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>12:41:09,548 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x5602859142a0' has no handler with id '20602'
>  return func(*args, **kwargs)
>
>12:41:09,549 WRN misc: /usr/lib64/python3.7/site-packages/gi/overrides/GObject.py:554: Warning: gsignal.c:2641: instance '0x5602859142a0' has no handler with id '20603'
>  return func(*args, **kwargs)
>
>12:41:09,555 DBG ui.common: Left spoke: CustomPartitioningSpoke
>12:41:09,695 DBG payload: Source http://10.66.128.196/os needs network for installation
>12:41:09,852 INF ui.lib.space: fs space: 14.55 GiB  needed: 2.17 GiB
>12:41:09,968 INF ui.lib.space: fs space: 14.55 GiB  needed: 2.17 GiB
>12:41:09,969 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>12:41:12,697 DBG ui.common: Entered spoke: SoftwareSelectionSpoke
>12:41:58,591 INF threading: Running Thread: AnaCheckSoftwareThread (139940327769856)
>12:41:58,592 DBG ui.common: Left spoke: SoftwareSelectionSpoke
>12:41:58,613 DBG payload: apply with result True called on requirements []
>12:41:58,623 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke]
>12:41:58,637 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke, SoftwareSelectionSpoke]
>12:41:58,789 DBG payload: Source http://10.66.128.196/os needs network for installation
>12:41:58,817 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
>12:41:58,819 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
>12:41:58,819 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Checking software dependencies...
>12:41:59,191 INF threading: Thread Done: AnaCheckSoftwareThread (139940327769856)
>12:41:59,228 DBG ui.gui.hubs: incomplete spokes: [SourceSpoke]
>12:41:59,231 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>12:41:59,235 DBG ui.gui.hubs: incomplete spokes: []
>12:41:59,358 INF ui.lib.space: fs space: 14.55 GiB  needed: 2.17 GiB
>12:41:59,359 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>12:42:05,510 DBG ui.common: Left hub: SummaryHub
>12:42:05,527 INF lifecycle: Adding controller: ProgressHub
>12:42:05,540 INF lifecycle: Module initialized: PasswordSpoke
>12:42:05,552 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke]
>12:42:05,571 INF lifecycle: Module initialized: UserSpoke
>12:42:05,578 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke, UserSpoke]
>12:42:05,579 INF lifecycle: Initialization of all modules (2) has been started.
>12:42:05,579 INF lifecycle: All modules have been initialized.
>12:42:05,581 DBG ui.gui.hubs: Disabling event loop for hub SummaryHub
>12:42:05,582 DBG ui.gui.hubs: Starting event loop for hub ProgressHub
>12:42:05,582 INF threading: Running Thread: AnaInstallThread (139940327769856)
>12:42:05,588 DBG ui.common: Entered hub: ProgressHub
>12:42:05,597 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
>12:42:05,599 INF installation: Task started: Save system time to HW clock (1/18)
>12:42:05,707 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
>12:42:06,517 DBG installation: Task completed: Save system time to HW clock (1/18) (0.9 s)
>12:42:06,518 INF progress: Save system time to HW clock
>12:42:06,519 INF installation: Queue started: Installation environment setup (1/9)
>12:42:06,519 INF progress: Setting up the installation environment
>12:42:06,520 INF installation: Task started: Setup addons (2/18)
>12:42:06,520 DBG installation: Task completed: Setup addons (2/18) (0.0 s)
>12:42:06,521 INF progress: Setup addons
>12:42:06,521 INF installation: Queue started: Early storage configuration (2/9)
>12:42:06,522 INF progress: Configuring storage
>12:42:06,523 INF installation: Task started: Insert custom storage to ksdata (3/18)
>12:42:06,537 INF threading: Thread Failed: AnaInstallThread (139940327769856)
>12:42:06,538 DBG exception: running handleException
>12:42:06,540 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/lib/python3.7/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib64/python3.7/site-packages/pyanaconda/storage/osinstall.py", line 1559, in update_ksdata
>    auto_part_proxy.SetLUKSVersion(self.autopart_luks_version)
>
>  File "/usr/lib/python3.7/site-packages/pydbus/proxy_method.py", line 80, in __call__
>    GLib.Variant(self._sinargs, args),
>
>  File "/usr/lib64/python3.7/site-packages/gi/overrides/GLib.py", line 172, in __new__
>    v = creator._create(format_string, value)
>
>  File "/usr/lib64/python3.7/site-packages/gi/overrides/GLib.py", line 150, in _create
>    builder.add_value(self._create(dup, i))
>
>  File "/usr/lib64/python3.7/site-packages/gi/overrides/GLib.py", line 118, in _create
>    return self._LEAF_CONSTRUCTORS[format](value)
>
>TypeError: Argument 0 does not allow None as a value
>
>12:42:06,540 DBG exception: Gtk running, queuing exception handler to the main loop
>12:42:06,547 INF threading: Thread Done: AnaInstallThread (139940327769856)
>12:42:13,765 INF core.util: Running kickstart %%onerror script(s)
>12:42:13,766 INF core.util: All kickstart %%onerror script(s) have been run
>12:42:13,766 INF kickstart.script: Running kickstart %%traceback script(s)
>12:42:13,766 INF kickstart.script: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1624680: 1480410 | 1480411 | 1480412 | 1480413 | 1480414 | 1480415 | 1480416 | 1480417 | 1480418 | 1480419 | 1480420 | 1480421 | 1480422 | 1480423 | 1480424