Attachment 1484352 Details for Bug 1630350 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 40.23 KB, created by Lukas Ruzicka on 2018-09-18 12:50:12 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Lukas Ruzicka

Created: 2018-09-18 12:50:12 UTC

Size: 40.23 KB

patch

obsolete

>12:43:36,263 INF main: /sbin/anaconda 29.24.3-1.fc29
>12:43:36,415 INF isys: 2625892 kB (2564 MB) are available
>12:43:36,428 INF startup_utils: check_memory(): total:2564, needed:320, graphical:410
>12:43:36,438 INF main: anaconda called with cmdline = ['/sbin/anaconda']
>12:43:36,439 INF main: Default encoding = utf-8 
>12:43:36,450 INF dbus.connection: Connecting to a default bus at unix:abstract=/tmp/dbus-QLopfyXRhy,guid=eca054dccd2e2b51fc9d924f5ba0f2f8.
>12:43:36,663 INF startup_utils: Waiting 600 sec for modules to be started.
>12:43:37,665 INF startup_utils: Waiting 599 sec for modules to be started.
>12:43:38,667 INF startup_utils: Waiting 598 sec for modules to be started.
>12:43:39,755 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>12:43:39,787 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'
>12:43:40,027 WRN kickstart: Command firstboot will be parsed in DBus module.
>12:43:40,042 DBG localization: setting locale to: en_US.UTF-8
>12:43:40,053 DBG network: devices found ['ens3']
>12:43:40,062 DBG network: ensure single initramfs connections
>12:43:40,111 DBG network: apply kickstart
>12:43:40,112 DBG network: create missing ifcfg files
>12:43:40,181 DBG network: ifcfg file for ens3 not found
>12:43:40,341 DBG network: dumping ifcfg file for ens3 from default autoconnection 16fb9515-ee17-31f4-9eb6-c0f4f2cfacc4
>12:43:40,342 DBG network: missing ifcfgs created for devices ['ens3']
>12:43:40,352 DBG network: set real ONBOOT value
>12:43:40,355 INF threading: Running Thread: AnaWaitForConnectingNMThread (140018901403392)
>12:43:40,356 DBG anaconda: setting display mode to TUI
>12:43:40,385 DBG simpleline: Scheduling screen AskVNCSpoke
>12:43:40,390 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:43:40,390 DBG simpleline: Starting main loop
>12:43:40,391 DBG simpleline: Processing signal RenderScreenSignal
>12:43:40,391 INF network: got 0 NTP servers from DHCP
>12:43:40,394 INF threading: Thread Done: AnaWaitForConnectingNMThread (140018901403392)
>12:43:40,391 DBG simpleline: Processing screen ScreenData(AskVNCSpoke,None,False)
>12:43:40,396 DBG simpleline: Input is required by ScreenData(AskVNCSpoke,None,False) screen
>12:43:45,047 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:43:45,051 DBG simpleline: Processing signal InputReceivedSignal
>12:43:45,053 DBG simpleline: New signal InputReadySignal enqueued with source AskVNCSpoke
>12:43:45,055 DBG simpleline: Processing signal InputReadySignal
>12:43:45,058 DBG simpleline: Closing screen ScreenData(AskVNCSpoke,None,False) from None
>12:43:45,060 DBG simpleline: Main loop ended. Running callback if set.
>12:43:45,061 INF anaconda: Display mode is set to 'interactive text mode'.
>12:43:45,066 INF isys: 2625892 kB (2564 MB) are available
>12:43:45,083 INF startup_utils: check_memory(): total:2564, needed:320, graphical:410
>12:43:45,140 DBG installclass: Searching /usr/lib64/python3.7/site-packages/pyanaconda/installclasses.
>12:43:45,148 DBG installclass: Found Atomic Host (AtomicHostInstallClass).
>12:43:45,148 DBG installclass: Found Fedora (DefaultInstallClass).
>12:43:45,148 DBG installclass: Found Scientific Linux (ScientificBaseInstallClass).
>12:43:45,149 DBG installclass: Found Fedora (FedoraBaseInstallClass).
>12:43:45,149 DBG installclass: Found Red Hat Enterprise Linux (RHELBaseInstallClass).
>12:43:45,149 DBG installclass: Found CentOS Linux (CentOSBaseInstallClass).
>12:43:45,149 DBG installclass: Found Fedora Workstation (FedoraWorkstationInstallClass).
>12:43:45,149 DBG installclass: Found Fedora Server (FedoraServerInstallClass).
>12:43:45,149 INF installclass: Using a visible install class Fedora (FedoraBaseInstallClass).
>12:43:45,150 DBG storage: trying to set new default luks version to 'luks1'
>12:43:45,647 DBG payload: getting release version from tree at None (29)
>12:43:45,647 DBG payload: using default release version of 29
>12:43:45,649 INF bootloader: bootloader GRUB2 on X86 platform
>12:43:45,650 INF autopart: Detected 2.5 GiB of memory
>12:43:45,651 INF autopart: Suggested swap size (2.5 GiB) exceeds 10 % of disk space, using 10 % of disk space (0.0) instead.
>12:43:45,651 INF autopart: Swap attempt of 0.0
>12:43:45,651 INF bootloader: bootloader GRUB2 on X86 platform
>12:43:45,701 DBG storage_utils: LABEL=OEMDRV matches [] for devicetree=None and disks_only=True
>12:43:45,702 INF threading: Running Thread: AnaStorageThread (140018901403392)
>12:43:45,707 INF threading: Running Thread: AnaTimeInitThread (140018869765888)
>12:43:45,744 DBG payload: Restarting payload thread
>12:43:45,746 INF threading: Running Thread: AnaPayloadRestartThread (140018861373184)
>12:43:45,746 INF geoloc: Geolocation is enabled.
>12:43:45,748 INF threading: Running Thread: AnaPayloadThread (140018852980480)
>12:43:45,748 INF threading: Thread Done: AnaPayloadRestartThread (140018861373184)
>12:43:45,748 DBG payload: Updating payload thread state: 0
>12:43:45,749 DBG payload: Updating payload thread state: 1
>12:43:45,750 INF threading: Running Thread: AnaGeolocationRefreshThread (140018844587776)
>12:43:45,755 INF geoloc: Starting geolocation lookup
>12:43:45,755 INF geoloc: Geolocation provider: Fedora GeoIP
>12:43:45,838 DBG simpleline: GLib event loop is used!
>12:43:45,944 INF lifecycle: Adding controller: SummaryHub
>12:43:45,953 INF lifecycle: Module initialized: TimeSpoke
>12:43:46,139 INF lifecycle: Module initialized: LangSpoke
>12:43:46,142 INF threading: Running Thread: AnaSourceWatcher (140018861373184)
>12:43:46,143 INF threading: Running Thread: AnaSoftwareWatcher (140018836195072)
>12:43:46,576 DBG network: updating host name localhost.localdomain
>12:43:46,609 INF geoloc: Geolocation lookup finished in 0.9 seconds
>12:43:46,618 INF geoloc: got results from geolocation
>12:43:46,618 INF threading: Thread Done: AnaGeolocationRefreshThread (140018844587776)
>12:43:46,642 INF lifecycle: Module initialized: NetworkSpoke
>12:43:46,660 INF threading: Running Thread: AnaStorageWatcher (140018844587776)
>12:43:46,674 INF lifecycle: Module initialized: PasswordSpoke
>12:43:46,683 INF lifecycle: Module initialized: UserSpoke
>12:43:46,684 INF lifecycle: Initialization of all modules (8) has been started.
>12:43:46,685 DBG simpleline: Scheduling screen SummaryHub
>12:43:46,686 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:43:46,687 DBG simpleline: Scheduling screen ProgressSpoke
>12:43:46,693 DBG simpleline: Starting main loop
>12:43:46,697 DBG simpleline: Processing screen ScreenData(SummaryHub,None,False)
>12:43:48,845 DBG storage: protected device spec LABEL=Fedora-E-dvd-x86_64-29 resolved to sr0
>12:43:49,658 DBG storage: blkid.tab devs: []
>12:43:49,659 DBG storage: parsing /mnt/sysimage/etc/crypttab
>12:43:49,659 DBG storage: crypttab maps: []
>12:43:49,659 DBG storage: parsing /mnt/sysimage/etc/fstab
>12:43:50,250 INF threading: Thread Done: AnaStorageThread (140018901403392)
>12:43:50,252 DBG payload: Updating payload thread state: 2
>12:43:50,253 DBG payload: Updating payload thread state: 4
>12:43:50,256 INF lifecycle: Module initialized: StorageSpoke
>12:43:50,257 INF threading: Thread Done: AnaStorageWatcher (140018844587776)
>12:43:50,262 DBG payload: Setting up cdrom install device
>12:43:50,390 DBG simpleline: Input is required by ScreenData(SummaryHub,None,False) screen
>12:43:50,574 DBG payload: Source doesn't require network for installation
>12:43:50,597 DBG payload: retrieving treeinfo from file:///run/install/repo (proxy: None ; sslverify: True)
>12:43:50,597 INF payload: Trying to download '.treeinfo'
>12:43:50,598 INF payload: Server returned 404 code
>12:43:50,599 INF payload: Trying to download 'treeinfo'
>12:43:50,599 INF payload: Server returned 404 code
>12:43:50,600 ERR payload: Got HTTP 404 Error when downloading [.]treeinfo files
>12:43:50,600 DBG payload: getting release version from tree at file:///run/install/repo (29)
>12:43:50,600 DBG payload: using default release version of 29
>12:43:51,688 INF threading: Thread Done: AnaTimeInitThread (140018869765888)
>12:43:51,699 DBG payload: Updating payload thread state: 5
>12:44:28,560 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:44:28,561 DBG simpleline: New signal InputReadySignal enqueued with source SummaryHub
>12:44:28,561 DBG simpleline: Pushing screen StorageSpoke to stack
>12:44:28,562 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:44:28,562 DBG simpleline: Processing screen ScreenData(StorageSpoke,None,False)
>12:44:28,629 DBG simpleline: Input is required by ScreenData(StorageSpoke,None,False) screen
>12:44:37,744 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:44:37,745 DBG simpleline: New signal InputReadySignal enqueued with source StorageSpoke
>12:44:37,746 DBG simpleline: No callback registered for user input c
>12:44:37,800 DBG simpleline: Pushing modal screen PartTypeSpoke to stack
>12:44:37,801 DBG simpleline: Executing inner loop
>12:44:37,802 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:44:37,803 DBG simpleline: Processing screen ScreenData(PartTypeSpoke,None,True)
>12:44:37,818 DBG simpleline: Input is required by ScreenData(PartTypeSpoke,None,True) screen
>12:44:44,313 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:44:44,314 DBG simpleline: New signal InputReadySignal enqueued with source PartTypeSpoke
>12:44:44,323 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:44:44,324 DBG simpleline: Processing screen ScreenData(PartTypeSpoke,None,True)
>12:44:44,348 DBG simpleline: Input is required by ScreenData(PartTypeSpoke,None,True) screen
>12:44:51,613 INF payload: Refreshing environmentAddons
>12:44:53,723 DBG payload: Updating payload thread state: 6
>12:44:53,724 DBG ui.tui.spokes.software_selection: Payload restarted, set new info and clear the old one.
>12:44:53,724 INF threading: Thread Done: AnaPayloadThread (140018852980480)
>12:44:53,726 DBG ui.tui.spokes.software_selection: Apply called old env None, new env custom-environment and addons set()
>12:44:53,727 DBG ui.tui.spokes.software_selection: Setting new software selection old env None, new env custom-environment and addons set()
>12:44:53,733 INF threading: Running Thread: AnaCheckSoftwareThread (140018901403392)
>12:44:53,801 DBG payload: apply with result True called on requirements []
>12:44:54,659 INF lifecycle: Module initialized: SourceSpoke
>12:44:54,660 INF threading: Thread Done: AnaSourceWatcher (140018861373184)
>12:44:54,683 INF threading: Thread Done: AnaCheckSoftwareThread (140018901403392)
>12:44:54,683 INF lifecycle: Module initialized: SoftwareSpoke
>12:44:54,684 INF lifecycle: All modules have been initialized.
>12:44:54,684 INF threading: Thread Done: AnaSoftwareWatcher (140018836195072)
>12:44:55,645 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:44:55,647 DBG simpleline: New signal InputReadySignal enqueued with source PartTypeSpoke
>12:44:55,656 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:44:55,656 DBG simpleline: Processing screen ScreenData(PartTypeSpoke,None,True)
>12:44:55,671 DBG simpleline: Input is required by ScreenData(PartTypeSpoke,None,True) screen
>12:44:57,258 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:44:57,262 DBG simpleline: New signal InputReadySignal enqueued with source PartTypeSpoke
>12:44:57,263 DBG simpleline: No callback registered for user input c
>12:44:57,345 DBG simpleline: Pushing modal screen PartitionSchemeSpoke to stack
>12:44:57,346 DBG simpleline: Executing inner loop
>12:44:57,346 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:44:57,346 DBG simpleline: Processing screen ScreenData(PartitionSchemeSpoke,None,True)
>12:44:57,362 DBG simpleline: Input is required by ScreenData(PartitionSchemeSpoke,None,True) screen
>12:45:03,298 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:45:03,304 DBG simpleline: New signal InputReadySignal enqueued with source PartitionSchemeSpoke
>12:45:03,312 DBG simpleline: No callback registered for user input c
>12:45:03,326 DBG simpleline: Closing screen ScreenData(PartitionSchemeSpoke,None,True) from None
>12:45:03,328 DBG simpleline: Closing inner loop
>12:45:03,330 DBG simpleline: Closing screen ScreenData(PartTypeSpoke,None,True) from None
>12:45:03,331 DBG simpleline: Closing inner loop
>12:45:03,358 DBG storage: clearpart: looking at vda2
>12:45:03,396 DBG storage: partitions: ['vda1']
>12:45:03,396 DBG storage: clearpart: looking at vda1
>12:45:03,409 DBG storage: partitions: []
>12:45:03,416 DBG storage: clearpart: initializing vda
>12:45:03,443 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run True.
>12:45:03,449 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:45:03,450 DBG kickstart.kickstart.bootloader: Applying bootloader location: MBR
>12:45:03,453 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:45:03,453 DBG bootloader: new disk order: []
>12:45:03,454 DBG kickstart.kickstart.bootloader: The /boot partition doesn't exist.
>12:45:03,455 DBG kickstart.kickstart.bootloader: Use the first usable drive.
>12:45:03,455 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:45:03,462 INF autopart: Detected 2.5 GiB of memory
>12:45:03,462 INF autopart: Suggested swap size (2.5 GiB) exceeds 10 % of disk space, using 10 % of disk space (2 GiB) instead.
>12:45:03,463 INF autopart: Swap attempt of 2 GiB
>12:45:03,465 DBG autopart: do_autopart: True
>12:45:03,466 DBG autopart: encrypted_autopart: False
>12:45:03,466 DBG autopart: autopart_type: 2
>12:45:03,466 DBG autopart: clear_part_type: 3
>12:45:03,466 DBG autopart: clear_part_disks: ['vda']
>12:45:03,467 DBG autopart: autopart_requests:
>PartSpec instance (0x7f58b0581748) -- 
>  mountpoint = /  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 1024 MiB  max_size = 50 GiB  grow = True
>PartSpec instance (0x7f58b0581828) -- 
>  mountpoint = /home  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 500 MiB  max_size = None  grow = True
>PartSpec instance (0x7f58b0581780) -- 
>  mountpoint = None  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = biosboot  encrypted = False
>  size = 1024 KiB  max_size = None  grow = False
>PartSpec instance (0x7f58b0581860) -- 
>  mountpoint = /boot  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = ext4  encrypted = False
>  size = 1024 MiB  max_size = None  grow = False
>PartSpec instance (0x7f58b0581b70) -- 
>  mountpoint = None  lv = True  thin = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 2 GiB  max_size = None  grow = False
>
>12:45:03,467 DBG autopart: storage.disks: ['vda']
>12:45:03,467 DBG autopart: storage.partitioned: ['vda']
>12:45:03,468 DBG autopart: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/run/install/repo/images/install.img', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'sr0', 'vda']
>12:45:03,468 DBG autopart: boot disk: vda
>12:45:03,478 DBG autopart: candidate disks: [DiskDevice instance (0x7f58af4f89b0) --
>  name = vda  status = True  id = 13
>  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  wwn = None]
>12:45:03,479 DBG autopart: devs: [PartitionDevice instance (0x7f58aefe02b0) --
>  name = req0  status = False  id = 155
>  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
>]
>12:45:03,481 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:45:03,482 DBG bootloader: _is_valid_size(vda) returning True
>12:45:03,482 DBG bootloader: _is_valid_location(vda) returning True
>12:45:03,482 DBG bootloader: _is_valid_format(vda) returning True
>12:45:03,482 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:45:03,483 INF autopart: skipping unneeded stage1 biosboot request
>12:45:03,483 DBG autopart: PartSpec instance (0x7f58b0581780) -- 
>  mountpoint = None  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = biosboot  encrypted = False
>  size = 1024 KiB  max_size = None  grow = False
>
>12:45:03,483 DBG autopart: existing 20 GiB disk vda (13) with non-existent msdos disklabel
>12:45:03,675 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:45:03,680 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:45:03,681 DBG kickstart.kickstart.bootloader: Applying bootloader location: MBR
>12:45:03,684 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:45:03,684 DBG bootloader: new disk order: []
>12:45:03,687 DBG kickstart.kickstart.bootloader: Found a boot drive: vda
>12:45:03,687 DBG kickstart.kickstart.bootloader: Use a usable drive with a /boot partition.
>12:45:03,687 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:45:03,694 DBG bootloader: stage1 device cannot be of type lvmvg
>12:45:03,695 DBG bootloader: stage1 device cannot be of type lvmlv
>12:45:03,696 DBG bootloader: stage1 device cannot be of type lvmlv
>12:45:03,697 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:45:03,697 DBG bootloader: _is_valid_size(vda) returning True
>12:45:03,697 DBG bootloader: _is_valid_location(vda) returning True
>12:45:03,697 DBG bootloader: _is_valid_format(vda) returning True
>12:45:03,697 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:45:03,700 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:45:03,700 DBG bootloader: _is_valid_size(vda) returning True
>12:45:03,701 DBG bootloader: _is_valid_location(vda) returning True
>12:45:03,701 DBG bootloader: _is_valid_format(vda) returning True
>12:45:03,701 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:45:03,701 DBG bootloader: _is_valid_disklabel(vda1) returning True
>12:45:03,702 DBG bootloader: _is_valid_size(vda1) returning True
>12:45:03,702 DBG bootloader: _is_valid_partition(vda1) returning True
>12:45:03,702 DBG bootloader: _is_valid_format(vda1) returning True
>12:45:03,703 DBG bootloader: is_valid_stage2_device(vda1) returning True
>12:45:03,704 DBG storage_utils: Available memory: 1.53 GiB
>12:45:03,717 DBG kickstart.kickstart.autopart: Storage check started with constraints {'min_ram': 320, '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': {'/usr', '/usr/share', '/usr/lib', '/tmp', '/var', '/home', '/'}, 'must_be_on_root': {'/dev', '/proc', '/lib', '/sbin', '/etc', '/root', '/bin', 'lost+found', '/mnt'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>12:45:03,717 DBG kickstart.kickstart.autopart: Run sanity check verify_root.
>12:45:03,717 DBG kickstart.kickstart.autopart: Run sanity check verify_s390_constraints.
>12:45:03,717 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_sizes.
>12:45:03,717 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_format_sizes.
>12:45:03,718 DBG kickstart.kickstart.autopart: Run sanity check verify_bootloader.
>12:45:03,718 DBG kickstart.kickstart.autopart: Run sanity check verify_gpt_biosboot.
>12:45:03,718 DBG kickstart.kickstart.autopart: Run sanity check verify_swap.
>12:45:03,718 DBG kickstart.kickstart.autopart: Run sanity check verify_swap_uuid.
>12:45:03,718 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_linuxfs.
>12:45:03,719 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_root.
>12:45:03,719 DBG kickstart.kickstart.autopart: Run sanity check verify_luks_devices_have_key.
>12:45:03,719 DBG kickstart.kickstart.autopart: Run sanity check verify_luks2_memory_requirements.
>12:45:03,719 DBG kickstart.kickstart.autopart: Run sanity check verify_mounted_partitions.
>12:45:03,719 DBG kickstart.kickstart.autopart: Storage check finished with success.
>12:45:03,722 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>12:45:03,726 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>12:45:03,728 DBG kickstart.kickstart.bootloader: Applying bootloader location: MBR
>12:45:03,731 DBG kickstart.kickstart.bootloader: Applying drive order: []
>12:45:03,731 DBG bootloader: new disk order: []
>12:45:03,733 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>12:45:03,790 DBG storage_utils: vda matches ['vda'] for devicetree=existing 594 MiB cdrom sr0 (3) with existing iso9660 filesystem
>existing 20 GiB disk vda (13) with non-existent msdos disklabel
>  non-existent 1024 MiB partition vda1 (162) with non-existent ext4 filesystem mounted at /boot
>  non-existent 19 GiB partition vda2 (155) with non-existent lvmpv
>    non-existent 19 GiB lvmvg fedora (168)
>      non-existent 17 GiB lvmlv fedora-root (174) with non-existent ext4 filesystem mounted at /
>      non-existent 2 GiB lvmlv fedora-swap (183) with non-existent swap
>existing 0 B file /run/install/repo/images/install.img (73)
>  existing 0 B loop loop0 (77)
>existing 0 B file /LiveOS/rootfs.img (87)
>  existing 0 B loop loop1 (91) with existing ext4 filesystem
>    existing 2 GiB dm live-rw (115) with existing ext4 filesystem
>    existing 2 GiB dm live-base (125) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (101)
>  existing 0 B loop loop2 (105)
> and disks_only=True
>12:45:03,793 DBG kickstart.kickstart.bootloader: Using a boot drive: vda
>12:45:03,799 DBG bootloader: stage1 device cannot be of type lvmvg
>12:45:03,800 DBG bootloader: stage1 device cannot be of type lvmlv
>12:45:03,800 DBG bootloader: stage1 device cannot be of type lvmlv
>12:45:03,801 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:45:03,802 DBG bootloader: _is_valid_size(vda) returning True
>12:45:03,802 DBG bootloader: _is_valid_location(vda) returning True
>12:45:03,802 DBG bootloader: _is_valid_format(vda) returning True
>12:45:03,802 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:45:03,807 DBG bootloader: _is_valid_disklabel(vda) returning True
>12:45:03,812 DBG bootloader: _is_valid_size(vda) returning True
>12:45:03,813 DBG bootloader: _is_valid_location(vda) returning True
>12:45:03,813 DBG bootloader: _is_valid_format(vda) returning True
>12:45:03,814 DBG bootloader: is_valid_stage1_device(vda) returning True
>12:45:03,815 DBG bootloader: _is_valid_disklabel(vda1) returning True
>12:45:03,816 DBG bootloader: _is_valid_size(vda1) returning True
>12:45:03,816 DBG bootloader: _is_valid_partition(vda1) returning True
>12:45:03,817 DBG bootloader: _is_valid_format(vda1) returning True
>12:45:03,817 DBG bootloader: is_valid_stage2_device(vda1) returning True
>12:45:03,822 DBG storage_utils: Available memory: 1.53 GiB
>12:45:03,836 DBG ui.tui.spokes.storage: Storage check started with constraints {'min_ram': 320, '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': {'/usr', '/usr/share', '/usr/lib', '/tmp', '/var', '/home', '/'}, 'must_be_on_root': {'/dev', '/proc', '/lib', '/sbin', '/etc', '/root', '/bin', 'lost+found', '/mnt'}, 'swap_is_recommended': True, 'luks2_min_ram': Size (128 MiB)}.
>12:45:03,837 DBG ui.tui.spokes.storage: Run sanity check verify_root.
>12:45:03,837 DBG ui.tui.spokes.storage: Run sanity check verify_s390_constraints.
>12:45:03,837 DBG ui.tui.spokes.storage: Run sanity check verify_partition_sizes.
>12:45:03,838 DBG ui.tui.spokes.storage: Run sanity check verify_partition_format_sizes.
>12:45:03,838 DBG ui.tui.spokes.storage: Run sanity check verify_bootloader.
>12:45:03,838 DBG ui.tui.spokes.storage: Run sanity check verify_gpt_biosboot.
>12:45:03,838 DBG ui.tui.spokes.storage: Run sanity check verify_swap.
>12:45:03,839 DBG ui.tui.spokes.storage: Run sanity check verify_swap_uuid.
>12:45:03,839 DBG ui.tui.spokes.storage: Run sanity check verify_mountpoints_on_linuxfs.
>12:45:03,839 DBG ui.tui.spokes.storage: Run sanity check verify_mountpoints_on_root.
>12:45:03,839 DBG ui.tui.spokes.storage: Run sanity check verify_luks_devices_have_key.
>12:45:03,840 DBG ui.tui.spokes.storage: Run sanity check verify_luks2_memory_requirements.
>12:45:03,840 DBG ui.tui.spokes.storage: Run sanity check verify_mounted_partitions.
>12:45:03,840 DBG ui.tui.spokes.storage: Storage check finished with success.
>12:45:03,848 DBG simpleline: Closing screen ScreenData(StorageSpoke,None,False) from None
>12:45:03,848 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:03,849 DBG simpleline: Processing screen ScreenData(SummaryHub,None,False)
>12:45:03,962 DBG simpleline: Input is required by ScreenData(SummaryHub,None,False) screen
>12:45:08,995 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:45:09,001 DBG simpleline: New signal InputReadySignal enqueued with source SummaryHub
>12:45:09,006 DBG simpleline: Pushing screen PasswordSpoke to stack
>12:45:09,009 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:09,011 DBG simpleline: Processing screen ScreenData(PasswordSpoke,None,False)
>12:45:09,030 DBG simpleline: Pushing modal screen GetPasswordInputScreen to stack
>12:45:09,038 DBG simpleline: Executing inner loop
>12:45:09,041 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:09,046 DBG simpleline: Processing screen ScreenData(GetPasswordInputScreen,None,True)
>12:45:09,049 DBG simpleline: Input is required by ScreenData(GetPasswordInputScreen,None,True) screen
>12:45:12,250 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>12:45:12,256 DBG simpleline: New signal InputReadySignal enqueued with source GetPasswordInputScreen
>12:45:12,263 DBG simpleline: Closing screen ScreenData(GetPasswordInputScreen,None,True) from None
>12:45:12,267 DBG simpleline: Closing inner loop
>12:45:12,270 DBG simpleline: Pushing modal screen GetPasswordInputScreen to stack
>12:45:12,273 DBG simpleline: Executing inner loop
>12:45:12,275 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:12,278 DBG simpleline: Processing screen ScreenData(GetPasswordInputScreen,None,True)
>12:45:12,279 DBG simpleline: Input is required by ScreenData(GetPasswordInputScreen,None,True) screen
>12:45:15,059 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>12:45:15,062 DBG simpleline: New signal InputReadySignal enqueued with source GetPasswordInputScreen
>12:45:15,071 DBG simpleline: Closing screen ScreenData(GetPasswordInputScreen,None,True) from None
>12:45:15,079 DBG simpleline: Closing inner loop
>12:45:15,092 DBG simpleline: New signal RenderScreenSignal enqueued with source PasswordSpoke
>12:45:15,094 DBG simpleline: Processing screen ScreenData(PasswordSpoke,None,False)
>12:45:15,101 DBG simpleline: Pushing modal screen GetPasswordInputScreen to stack
>12:45:15,107 DBG simpleline: Executing inner loop
>12:45:15,110 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:15,113 DBG simpleline: Processing screen ScreenData(GetPasswordInputScreen,None,True)
>12:45:15,113 DBG simpleline: Input is required by ScreenData(GetPasswordInputScreen,None,True) screen
>12:45:18,739 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>12:45:18,745 DBG simpleline: New signal InputReadySignal enqueued with source GetPasswordInputScreen
>12:45:18,750 DBG simpleline: Closing screen ScreenData(GetPasswordInputScreen,None,True) from None
>12:45:18,752 DBG simpleline: Closing inner loop
>12:45:18,755 DBG simpleline: Pushing modal screen GetPasswordInputScreen to stack
>12:45:18,757 DBG simpleline: Executing inner loop
>12:45:18,759 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:18,762 DBG simpleline: Processing screen ScreenData(GetPasswordInputScreen,None,True)
>12:45:18,765 DBG simpleline: Input is required by ScreenData(GetPasswordInputScreen,None,True) screen
>12:45:20,701 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>12:45:20,708 DBG simpleline: New signal InputReadySignal enqueued with source GetPasswordInputScreen
>12:45:20,711 DBG simpleline: Closing screen ScreenData(GetPasswordInputScreen,None,True) from None
>12:45:20,713 DBG simpleline: Closing inner loop
>12:45:20,744 DBG simpleline: Pushing modal screen YesNoDialog to stack
>12:45:20,745 DBG simpleline: Executing inner loop
>12:45:20,745 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:20,745 DBG simpleline: Processing screen ScreenData(YesNoDialog,None,True)
>12:45:20,756 DBG simpleline: Input is required by ScreenData(YesNoDialog,None,True) screen
>12:45:24,202 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:45:24,207 DBG simpleline: New signal InputReadySignal enqueued with source YesNoDialog
>12:45:24,214 DBG simpleline: Closing screen ScreenData(YesNoDialog,None,True) from None
>12:45:24,219 DBG simpleline: Closing inner loop
>12:45:24,243 DBG simpleline: New signal CloseScreenSignal enqueued with source PasswordSpoke
>12:45:24,244 DBG simpleline: Closing screen ScreenData(PasswordSpoke,None,False) from PasswordSpoke
>12:45:24,244 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:24,244 DBG simpleline: Processing screen ScreenData(SummaryHub,None,False)
>12:45:24,357 DBG simpleline: Input is required by ScreenData(SummaryHub,None,False) screen
>12:45:31,321 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:45:31,324 DBG simpleline: New signal InputReadySignal enqueued with source SummaryHub
>12:45:31,330 DBG simpleline: No callback registered for user input b
>12:45:31,529 INF ui.lib.space: fs space: 14.45 GiB  needed: 1.75 GiB
>12:45:31,529 DBG simpleline: Closing screen ScreenData(SummaryHub,None,False) from None
>12:45:31,530 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>12:45:31,530 DBG simpleline: Processing screen ScreenData(ProgressSpoke,None,False)
>12:45:31,551 INF threading: Running Thread: AnaInstallThread (140018844587776)
>12:45:31,558 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:45:31,559 INF installation: Task started: Save system time to HW clock (1/18)
>12:45:32,502 INF progress: Save system time to HW clock
>12:45:32,502 DBG installation: Task completed: Save system time to HW clock (1/18) (0.9 s)
>12:45:32,503 INF progress: Setting up the installation environment
>12:45:32,508 INF installation: Queue started: Installation environment setup (1/9)
>12:45:32,508 INF installation: Task started: Setup addons (2/18)
>12:45:32,509 INF progress: Setup addons
>12:45:32,510 DBG installation: Task completed: Setup addons (2/18) (0.0 s)
>12:45:32,510 INF progress: Configuring storage
>12:45:32,515 INF installation: Queue started: Early storage configuration (2/9)
>12:45:32,515 INF installation: Task started: Insert custom storage to ksdata (3/18)
>12:45:32,533 INF progress: Insert custom storage to ksdata
>12:45:32,534 DBG installation: Task completed: Insert custom storage to ksdata (3/18) (0.0 s)
>12:45:32,534 INF installation: Task started: Run pre-storage tasks (4/18)
>12:45:32,534 INF progress: Run pre-storage tasks
>12:45:32,535 DBG installation: Task completed: Run pre-storage tasks (4/18) (0.0 s)
>12:45:32,535 INF installation: Task started: Activate filesystems (5/18)
>12:45:34,308 INF progress: Creating disklabel on /dev/vda
>12:45:34,603 INF progress: Creating ext4 on /dev/vda1
>12:45:35,160 INF progress: Creating lvmpv on /dev/vda2
>12:45:35,641 INF progress: Creating swap on /dev/mapper/fedora-swap
>12:45:35,865 INF progress: Creating ext4 on /dev/mapper/fedora-root
>12:45:37,415 INF storage: setting boot flag on vda1
>12:45:39,035 INF progress: Activate filesystems
>12:45:39,036 DBG installation: Task completed: Activate filesystems (5/18) (6.5 s)
>12:45:39,036 INF installation: Task started: Write early storage (6/18)
>12:45:39,040 INF storage: not writing out mpath configuration
>12:45:39,040 INF progress: Write early storage
>12:45:39,041 DBG installation: Task completed: Write early storage (6/18) (0.0 s)
>12:45:39,041 INF progress: Running pre-installation scripts
>12:45:39,045 INF installation: Queue started: Pre-install scripts (3/9)
>12:45:39,046 INF installation: Task started: Run %pre-install scripts (7/18)
>12:45:39,046 INF progress: Run %pre-install scripts
>12:45:39,047 DBG installation: Task completed: Run %pre-install scripts (7/18) (0.0 s)
>12:45:39,047 INF progress: Discovering realm to join
>12:45:39,052 INF installation: Queue started: Realm discover (4/9)
>12:45:39,053 INF installation: Task started: Discover realm to join (8/18)
>12:45:39,057 INF progress: Discover realm to join
>12:45:39,057 DBG installation: Task completed: Discover realm to join (8/18) (0.0 s)
>12:45:39,058 INF progress: Running pre-installation tasks
>12:45:39,063 INF installation: Queue started: Pre install tasks (5/9)
>12:45:39,064 INF installation: Task started: Setup authselect (9/18)
>12:45:39,068 INF progress: Setup authselect
>12:45:39,069 DBG installation: Task completed: Setup authselect (9/18) (0.0 s)
>12:45:39,069 INF installation: Task started: Setup firewall (10/18)
>12:45:39,072 INF progress: Setup firewall
>12:45:39,073 DBG installation: Task completed: Setup firewall (10/18) (0.0 s)
>12:45:39,073 INF installation: Task started: Setup network (11/18)
>12:45:39,091 INF progress: Setup network
>12:45:39,092 DBG installation: Task completed: Setup network (11/18) (0.0 s)
>12:45:39,092 INF installation: Task started: Setup timezone (12/18)
>12:45:39,122 INF progress: Setup timezone
>12:45:39,122 DBG installation: Task completed: Setup timezone (12/18) (0.0 s)
>12:45:39,123 INF installation: Task started: Copy /resolv.conf to sysroot (13/18)
>12:45:39,124 INF progress: Copy /resolv.conf to sysroot
>12:45:39,124 DBG installation: Task completed: Copy /resolv.conf to sysroot (13/18) (0.0 s)
>12:45:39,124 INF installation: Task started: Find additional packages & run preInstall() (14/18)
>12:45:39,126 DBG payload: added package requirement 'lvm2' for storage, strong=True
>12:45:39,126 DBG payload: added package requirement 'e2fsprogs' for storage, strong=True
>12:45:39,126 DBG payload: no package requirement added for realm
>12:45:39,126 DBG payload: added package requirement 'authselect' for authselect, strong=True
>12:45:39,126 DBG payload: no package requirement added for firewall
>12:45:39,127 DBG payload: no package requirement added for network
>12:45:39,127 DBG payload: added package requirement 'chrony' for ntp, strong=False
>12:45:39,127 DBG payload: added package requirement 'grub2' for bootloader, strong=True
>12:45:39,127 DBG payload: added package requirement 'grub2-tools' for bootloader, strong=True
>12:45:39,135 DBG payload: no group requirement added for language groups
>12:45:39,149 DBG payload: added package requirement 'langpacks-en' for langpacks, strong=False
>12:45:39,173 WRN payload: Platform group platform-kvm not available.
>12:45:39,174 INF progress: Find additional packages & run preInstall()
>12:45:39,174 DBG installation: Task completed: Find additional packages & run preInstall() (14/18) (0.1 s)
>12:45:39,175 INF progress: Installing.
>12:45:39,179 INF installation: Queue started: Payload installation (6/9)
>12:45:39,180 INF installation: Task started: Install the payload (15/18)
>12:45:39,181 INF progress: Starting package installation process
>12:45:39,192 DBG payload: apply with result True called on requirements [('package', 'lvm2', PayloadRequirement(id=lvm2, reasons=[PayloadRequirementReason(reason='storage', strong=True)])), ('package', 'e2fsprogs', PayloadRequirement(id=e2fsprogs, reasons=[PayloadRequirementReason(reason='storage', strong=True)])), ('package', 'authselect', PayloadRequirement(id=authselect, reasons=[PayloadRequirementReason(reason='authselect', strong=True)])), ('package', 'chrony', PayloadRequirement(id=chrony, reasons=[PayloadRequirementReason(reason='ntp', strong=False)])), ('package', 'grub2', PayloadRequirement(id=grub2, reasons=[PayloadRequirementReason(reason='bootloader', strong=True)])), ('package', 'grub2-tools', PayloadRequirement(id=grub2-tools, reasons=[PayloadRequirementReason(reason='bootloader', strong=True)])), ('package', 'langpacks-en', PayloadRequirement(id=langpacks-en, reasons=[PayloadRequirementReason(reason='langpacks', strong=False)]))]
>12:45:55,257 INF progress: Preparing transaction from installation source
>12:46:16,006 INF threading: Running Thread: AnaExceptionHandlingTest (140018861373184)
>12:46:16,013 INF threading: Thread Failed: AnaExceptionHandlingTest (140018861373184)
>12:46:16,015 DBG exception: running handleException
>12:46:16,023 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/exception.py", line 383, in raise_exception
>    eval(compile(code, "str_eval", "exec"))
>
>  File "str_eval", line 5, in <module>
>    # All rights reserved.
>
>  File "str_eval", line 3, in f1537274776
>    #
>
>RuntimeError: NOTABUG: testing exception handling
>
>12:46:16,415 DBG exception: Gtk cannot be initialized
>12:46:16,415 DBG exception: In a non-main thread, sending a message with exception data
>12:46:16,415 DBG simpleline: New signal ExceptionSignal enqueued with source ScreenScheduler
>12:46:16,418 INF threading: Thread Done: AnaExceptionHandlingTest (140018861373184)
>12:46:17,276 DBG simpleline: Executing inner loop
>12:46:17,278 DBG simpleline: New signal ExceptionSignal enqueued with source ScreenScheduler
>12:46:17,281 ERR ui.tui: 
>======= Screen stack =======
>----------- TOP ------------
>ScreenData(ProgressSpoke,None,False)
>============================
>
>12:46:17,283 DBG exception: running handleException
>12:46:17,284 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/exception.py", line 383, in raise_exception
>    eval(compile(code, "str_eval", "exec"))
>
>  File "str_eval", line 5, in <module>
>    # All rights reserved.
>
>  File "str_eval", line 3, in f1537274776
>    #
>
>RuntimeError: NOTABUG: testing exception handling
>
>12:46:17,287 DBG exception: Gtk cannot be initialized
>12:46:17,290 DBG exception: In the main thread, running exception handler
>12:47:59,131 INF core.util: Running kickstart %%onerror script(s)
>12:47:59,133 INF core.util: All kickstart %%onerror script(s) have been run
>12:47:59,133 INF kickstart.script: Running kickstart %%traceback script(s)
>12:47:59,134 INF kickstart.script: All kickstart %%traceback script(s) have been run
>12:48:56,131 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>12:48:56,132 DBG simpleline: New signal InputReadySignal enqueued with source TextIntf

Actions: View

Attachments on bug 1630350: 1484351 | 1484352 | 1484353 | 1484354 | 1484355 | 1484356 | 1484357 | 1484359 | 1484360 | 1484361 | 1484362 | 1484363 | 1484364 | 1484365 | 1484366