Attachment 1450065 Details for Bug 1589871 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 37.32 KB, created by Paul Whalen on 2018-06-11 15:01:24 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Paul Whalen

Created: 2018-06-11 15:01:24 UTC

Size: 37.32 KB

patch

obsolete

>10:29:10,856 INF main: /sbin/anaconda 29.16-1.fc29
>10:29:12,574 INF isys: 1144916 kB (1118 MB) are available
>10:29:12,707 INF startup_utils: check_memory(): total:1118, needed:320, graphical:410
>10:29:12,828 INF main: anaconda called with cmdline = ['/sbin/anaconda']
>10:29:12,834 INF main: Default encoding = utf-8 
>10:29:13,015 INF dbus.connection: Connecting to a default bus at unix:abstract=/tmp/dbus-VyUMqpuoGS,guid=45771284e97a78eedb8070de5af57079.
>10:29:16,815 INF startup_utils: Waiting 600 sec for modules to be started.
>10:29:17,857 INF startup_utils: Waiting 599 sec for modules to be started.
>10:29:18,873 INF startup_utils: Waiting 598 sec for modules to be started.
>10:29:19,891 INF startup_utils: Waiting 597 sec for modules to be started.
>10:29:20,907 INF startup_utils: Waiting 596 sec for modules to be started.
>10:29:21,925 INF startup_utils: Waiting 595 sec for modules to be started.
>10:29:22,941 INF startup_utils: Waiting 594 sec for modules to be started.
>10:29:23,958 INF startup_utils: Waiting 593 sec for modules to be started.
>10:29:24,975 INF startup_utils: Waiting 592 sec for modules to be started.
>10:29:25,992 INF startup_utils: Waiting 591 sec for modules to be started.
>10:29:27,009 INF startup_utils: Waiting 590 sec for modules to be started.
>10:29:28,025 INF startup_utils: Waiting 589 sec for modules to be started.
>10:29:29,041 INF startup_utils: Waiting 588 sec for modules to be started.
>10:29:30,058 INF startup_utils: Waiting 587 sec for modules to be started.
>10:29:31,072 INF startup_utils: Waiting 586 sec for modules to be started.
>10:29:32,119 INF startup_utils: Waiting 585 sec for modules to be started.
>10:29:33,138 INF startup_utils: Waiting 584 sec for modules to be started.
>10:29:34,157 INF startup_utils: Waiting 583 sec for modules to be started.
>10:29:35,883 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>10:29:36,219 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'
>10:29:39,172 WRN kickstart: Command firstboot will be parsed in DBus module.
>10:29:39,366 DBG localization: setting locale to: en_US.UTF-8
>10:29:39,485 DBG network: devices found ['enp1s0', 'wlan0']
>10:29:39,566 DBG network: ensure single initramfs connections
>10:29:40,416 DBG network: apply kickstart
>10:29:40,420 DBG network: create missing ifcfg files
>10:29:41,055 DBG network: ifcfg file for enp1s0 not found
>10:29:42,573 DBG network: dumping ifcfg file for enp1s0 from default autoconnection afeccdcd-aa8a-38d4-8faa-6bf90c61f65e
>10:29:42,660 DBG network: missing ifcfgs created for devices ['enp1s0']
>10:29:42,765 DBG network: set real ONBOOT value
>10:29:42,791 INF threading: Running Thread: AnaWaitForConnectingNMThread (-1313086368)
>10:29:42,810 DBG anaconda: setting display mode to TUI
>10:29:42,969 DBG simpleline: Scheduling screen AskVNCSpoke
>10:29:42,980 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>10:29:42,983 DBG simpleline: Starting main loop
>10:29:42,987 DBG simpleline: Processing signal RenderScreenSignal
>10:29:42,994 DBG simpleline: Processing screen ScreenData(AskVNCSpoke,None,False)
>10:29:43,016 DBG simpleline: Input is required by ScreenData(AskVNCSpoke,None,False) screen
>10:29:43,123 INF network: got 0 NTP servers from DHCP
>10:29:43,150 INF threading: Thread Done: AnaWaitForConnectingNMThread (-1313086368)
>10:31:45,166 DBG simpleline: New signal InputReceivedSignal enqueued with source InputHandlerRequest
>10:31:45,169 DBG simpleline: Processing signal InputReceivedSignal
>10:31:45,175 DBG simpleline: New signal InputReadySignal enqueued with source AskVNCSpoke
>10:31:45,177 DBG simpleline: Processing signal InputReadySignal
>10:31:45,185 DBG simpleline: Pushing modal screen VNCPassSpoke to stack
>10:31:45,190 DBG simpleline: Executing inner loop
>10:31:45,192 DBG simpleline: New signal RenderScreenSignal enqueued with source ScreenScheduler
>10:31:45,194 DBG simpleline: Processing signal RenderScreenSignal
>10:31:45,197 DBG simpleline: Processing screen ScreenData(VNCPassSpoke,None,True)
>10:31:45,207 DBG simpleline: Input is required by ScreenData(VNCPassSpoke,None,True) screen
>10:31:45,319 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>10:31:45,322 DBG simpleline: Processing signal InputReceivedSignal
>10:31:45,324 DBG simpleline: New signal InputReadySignal enqueued with source InputManager
>10:31:45,327 DBG simpleline: Processing signal InputReadySignal
>10:31:45,459 DBG simpleline: New signal InputReceivedSignal enqueued with source PasswordInputHandlerRequest
>10:31:45,462 DBG simpleline: Processing signal InputReceivedSignal
>10:31:45,464 DBG simpleline: New signal InputReadySignal enqueued with source InputManager
>10:31:45,467 DBG simpleline: Processing signal InputReadySignal
>10:31:45,470 DBG simpleline: New signal CloseScreenSignal enqueued with source VNCPassSpoke
>10:31:45,472 DBG simpleline: Processing signal CloseScreenSignal
>10:31:45,478 DBG simpleline: Closing screen ScreenData(VNCPassSpoke,None,True) from VNCPassSpoke
>10:31:45,480 DBG simpleline: Closing inner loop
>10:31:45,482 DBG simpleline: Inner loop is closed
>10:31:45,484 DBG simpleline: Closing screen ScreenData(AskVNCSpoke,None,False) from None
>10:31:45,486 DBG simpleline: Main loop ended. Running callback if set.
>10:31:45,488 INF display: VNC requested via VNC question, switching Anaconda to GUI mode.
>10:31:45,490 DBG anaconda: changing display mode from TUI to GUI
>10:31:45,492 INF anaconda: Display mode is set to 'interactive graphical mode'.
>10:31:45,502 INF isys: 1144916 kB (1118 MB) are available
>10:31:45,600 INF startup_utils: check_memory(): total:1118, needed:320, graphical:410
>10:31:45,606 INF stdout: Starting VNC...
>10:31:46,120 DBG core.util: Setting timeout 60 seconds for starting X.
>10:31:53,577 DBG core.util: X server has signalled a successful start.
>10:31:53,586 INF stdout: The VNC server is now running.
>10:31:53,592 WRN stdout: 
>
>WARNING!!! VNC server running with NO PASSWORD!
>You can use the vncpassword=PASSWORD boot option
>if you would like to secure the server.
>
>
>10:31:53,597 INF stdout: Please manually connect your vnc client to trimslice.friendly-neighbours.com:1 (192.168.0.53:1) to begin the install.
>10:31:53,601 INF stdout: Attempting to start vncconfig
>10:31:59,297 DBG installclass: Searching /usr/lib/python3.6/site-packages/pyanaconda/installclasses.
>10:31:59,396 DBG installclass: Found Fedora (FedoraBaseInstallClass).
>10:31:59,398 DBG installclass: Found CentOS Linux (CentOSBaseInstallClass).
>10:31:59,400 DBG installclass: Found Fedora (DefaultInstallClass).
>10:31:59,402 DBG installclass: Found Fedora Server (FedoraServerInstallClass).
>10:31:59,404 DBG installclass: Found Scientific Linux (ScientificBaseInstallClass).
>10:31:59,406 DBG installclass: Found Red Hat Enterprise Linux (RHELBaseInstallClass).
>10:31:59,407 DBG installclass: Found Fedora Workstation (FedoraWorkstationInstallClass).
>10:31:59,409 DBG installclass: Found Atomic Host (AtomicHostInstallClass).
>10:31:59,411 INF installclass: Using a visible install class Fedora (FedoraBaseInstallClass).
>10:32:03,781 DBG payload: getting release version from tree at None (rawhide)
>10:32:03,784 DBG payload: using default release version of rawhide
>10:32:05,738 INF bootloader: bootloader EXTLINUX on ARM platform
>10:32:05,746 INF autopart: Detected 1.09 GiB of memory
>10:32:05,756 INF autopart: Suggested swap size (2.18 GiB) exceeds 10 % of disk space, using 10 % of disk space (0.0) instead.
>10:32:05,759 INF autopart: Swap attempt of 0.0
>10:32:05,765 INF bootloader: bootloader EXTLINUX on ARM platform
>10:32:06,733 DBG storage_utils: LABEL=OEMDRV matches [] for devicetree=None and disks_only=True
>10:32:06,749 INF threading: Running Thread: AnaStorageThread (-1436335008)
>10:32:06,790 INF threading: Running Thread: AnaTimeInitThread (-1444727712)
>10:32:07,075 DBG payload: Restarting payload thread
>10:32:07,081 INF threading: Running Thread: AnaPayloadRestartThread (-1453120416)
>10:32:07,083 INF geoloc: Geolocation is enabled.
>10:32:07,094 INF threading: Running Thread: AnaPayloadThread (-1461513120)
>10:32:07,095 INF threading: Thread Done: AnaPayloadRestartThread (-1453120416)
>10:32:07,101 DBG payload: Updating payload thread state: 0
>10:32:07,108 DBG payload: Updating payload thread state: 1
>10:32:07,110 INF threading: Running Thread: AnaGeolocationRefreshThread (-1469905824)
>10:32:07,117 INF geoloc: Starting geolocation lookup
>10:32:07,119 INF geoloc: Geolocation provider: Fedora GeoIP
>14:37:03,627 INF geoloc: Geolocation lookup finished in 2693096.5 seconds
>14:37:03,638 INF geoloc: got results from geolocation
>14:37:03,652 INF threading: Thread Done: AnaGeolocationRefreshThread (-1469905824)
>14:37:06,315 DBG ui.gui: Detected primary monitor: 1024x768 95dpix 96dpiy
>14:37:11,468 DBG localization: setting locale to: en_CA.UTF-8
>14:37:11,758 DBG localization: setting locale to: en_US.UTF-8
>14:37:11,819 DBG localization: setting locale to: en_CA.UTF-8
>14:37:12,082 DBG ui.common: Entered spoke: WelcomeLanguageSpoke
>14:37:12,097 WRN misc: /usr/lib/python3.6/site-packages/gi/overrides/Gdk.py:336: DeprecationWarning: Gdk.Cursor.new is deprecated
>  return cls.new(*args, **kwds)
>
>14:37:20,999 INF threading: Thread Done: AnaStorageThread (-1436335008)
>14:37:21,018 DBG payload: Updating payload thread state: 2
>14:37:21,022 DBG payload: Updating payload thread state: 4
>14:37:19,054 INF threading: Thread Done: AnaTimeInitThread (-1444727712)
>14:37:20,217 DBG payload: getting release version from tree at https://kojipkgs.fedoraproject.org/compose/rawhide//Fedora-Rawhide-20180609.n.0/compose/Everything/armhfp/os/ (rawhide)
>14:37:20,219 DBG payload: retrieving treeinfo from https://kojipkgs.fedoraproject.org/compose/rawhide//Fedora-Rawhide-20180609.n.0/compose/Everything/armhfp/os/ (proxy:  ; sslverify: True)
>14:37:20,221 INF payload: Trying to download '.treeinfo'
>14:37:20,851 DBG payload: Retrieved '.treeinfo' from https://kojipkgs.fedoraproject.org/compose/rawhide//Fedora-Rawhide-20180609.n.0/compose/Everything/armhfp/os/
>14:37:20,858 DBG payload: using treeinfo release version of rawhide
>14:37:35,398 DBG payload: Updating payload thread state: 5
>14:41:25,712 INF payload: Refreshing environmentAddons
>14:42:15,619 DBG payload: Updating payload thread state: 6
>14:42:15,621 INF threading: Thread Done: AnaPayloadThread (-1461513120)
>14:43:56,375 DBG localization: setting locale to: en_CA.UTF-8
>14:43:56,407 INF ui.gui.spokes.welcome: using timezone determined by geolocation
>14:43:57,430 DBG ui.gui.spokes.network: network standalone spoke (init): completed: ['enp1s0']
>14:43:57,574 DBG ui.common: Left spoke: WelcomeLanguageSpoke
>14:43:57,878 INF lifecycle: Adding controller: SummaryHub
>14:44:05,438 INF threading: Running Thread: AnaAddLayoutsInitThread (-1461513120)
>14:44:05,452 DBG ui.gui.utils: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0xa9e14e10>> on <list object at 0xa9e27da0>
>14:44:05,487 INF threading: Running Thread: AnaGtkBatchPre1 (-1436335008)
>14:44:05,552 INF threading: Thread Done: AnaGtkBatchPre1 (-1436335008)
>14:44:06,123 INF threading: Running Thread: AnaKeyboardThread (-1436335008)
>14:44:07,643 INF lifecycle: Module initialized: LangsupportSpoke
>14:44:11,023 INF threading: Running Thread: AnaNTPserver1 (-1469905824)
>14:44:11,030 INF threading: Running Thread: AnaDateTimeThread (-1453120416)
>14:44:11,671 INF threading: Thread Done: AnaNTPserver1 (-1469905824)
>14:44:12,671 INF threading: Running Thread: AnaSourceWatcher (-1469905824)
>14:44:12,678 DBG ui.gui.spokes.installation_source: Clearing checks in source spoke
>14:44:12,681 DBG ui.gui.spokes.installation_source: Setting up repos: []
>14:44:12,720 INF lifecycle: Module initialized: SourceSpoke
>14:44:12,726 INF threading: Thread Done: AnaSourceWatcher (-1469905824)
>14:44:12,851 INF ui.gui.spokes.software_selection: using environment from kickstart: None
>14:44:12,881 INF threading: Running Thread: AnaSoftwareWatcher (-1469905824)
>14:44:12,893 DBG ui.gui.hubs: incomplete spokes: [SoftwareSelectionSpoke]
>14:44:13,208 INF threading: Running Thread: AnaStorageWatcher (-1575926688)
>14:44:13,717 DBG ui.gui.hubs: incomplete spokes: [SoftwareSelectionSpoke, StorageSpoke]
>14:44:13,791 INF lifecycle: Module initialized: StorageSpoke
>14:44:13,795 INF threading: Thread Done: AnaStorageWatcher (-1575926688)
>14:44:14,006 INF threading: Running Thread: AnaCustomStorageInit (-1575926688)
>14:44:15,449 INF lifecycle: Module initialized: BlivetGuiSpoke
>14:44:15,900 INF lifecycle: Module initialized: FilterSpoke
>14:44:16,585 DBG ui.gui.spokes.network: can't get remote connection of active connection of device enp1s0
>14:44:16,600 DBG ui.gui.spokes.network: device configuration added: connection afeccdcd-aa8a-38d4-8faa-6bf90c61f65e device enp1s0
>14:44:16,735 DBG ui.gui.spokes.network: device configuration added: connection None device wlan0
>14:44:16,873 DBG ui.gui.spokes.network: not adding connection afeccdcd-aa8a-38d4-8faa-6bf90c61f65e, already in list
>14:44:20,135 INF lifecycle: Module initialized: CustomPartitioningSpoke
>14:44:20,138 INF threading: Thread Done: AnaCustomStorageInit (-1575926688)
>14:44:20,187 DBG network: updating host name localhost.localdomain
>14:44:20,568 INF lifecycle: Module initialized: NetworkSpoke
>14:44:21,359 DBG payload: Source doesn't require network for installation
>14:44:21,365 INF lifecycle: Initialization of all modules (10) has been started.
>14:44:21,383 DBG ui.gui.hubs: Starting event loop for hub SummaryHub
>14:44:21,445 DBG ui.common: Entered hub: SummaryHub
>14:44:21,530 DBG ui.gui.hubs: spoke is not ready: KeyboardSpoke
>14:44:21,533 DBG ui.gui.hubs: setting KeyboardSpoke status to: Getting list of layouts...
>14:44:21,548 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
>14:44:21,560 DBG ui.gui.hubs: incomplete spokes: [SoftwareSelectionSpoke, StorageSpoke, SourceSpoke]
>14:44:21,564 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
>14:44:21,567 DBG ui.gui.hubs: setting SourceSpoke status to: Setting up installation source...
>14:44:21,569 DBG ui.gui.hubs: setting SourceSpoke status to: Probing storage...
>14:44:21,571 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading package metadata...
>14:44:21,573 DBG ui.gui.hubs: setting SourceSpoke status to: Downloading group metadata...
>14:44:21,587 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>14:44:21,600 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>14:44:21,613 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>14:44:21,615 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading package metadata...
>14:44:21,619 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Downloading group metadata...
>14:44:21,622 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>14:44:21,624 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>14:44:21,685 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>14:44:25,121 INF lifecycle: Module initialized: DatetimeSpoke
>14:44:25,129 INF threading: Thread Done: AnaDateTimeThread (-1453120416)
>14:44:26,127 INF threading: Running Thread: AnaCheckSoftwareThread (-1453120416)
>14:44:26,129 INF lifecycle: Module initialized: SoftwareSelectionSpoke
>14:44:26,135 INF threading: Thread Done: AnaSoftwareWatcher (-1469905824)
>14:44:26,526 DBG payload: apply with result True called on requirements []
>14:44:26,669 DBG ui.gui.hubs: spoke is ready: DatetimeSpoke
>14:44:26,809 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>14:44:27,038 DBG ui.gui.hubs: spoke is not ready: SoftwareSelectionSpoke
>14:44:27,519 DBG ui.gui.hubs: spoke is not ready: SourceSpoke
>14:44:27,528 DBG ui.gui.hubs: setting SoftwareSelectionSpoke status to: Checking software dependencies...
>14:44:28,532 INF threading: Thread Done: AnaCheckSoftwareThread (-1453120416)
>14:44:28,912 DBG ui.gui.hubs: incomplete spokes: [StorageSpoke, SourceSpoke]
>14:44:28,916 DBG ui.gui.hubs: spoke is ready: SoftwareSelectionSpoke
>14:44:28,924 DBG ui.gui.hubs: incomplete spokes: [StorageSpoke]
>14:44:28,928 DBG ui.gui.hubs: spoke is ready: SourceSpoke
>14:44:54,334 DBG ui.gui.utils: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0xa9e14e10>> on <list object at 0xa9e27da0>
>14:44:54,337 INF threading: Thread Done: AnaAddLayoutsInitThread (-1461513120)
>14:44:54,341 INF lifecycle: Module initialized: KeyboardSpoke
>14:44:54,343 INF lifecycle: All modules have been initialized.
>14:44:54,346 INF threading: Thread Done: AnaKeyboardThread (-1436335008)
>14:44:54,479 DBG ui.gui.hubs: spoke is ready: KeyboardSpoke
>14:45:28,973 DBG ui.common: Entered spoke: StorageSpoke
>14:45:41,301 WRN misc: /usr/lib/python3.6/site-packages/gi/overrides/GObject.py:553: Warning: gsignal.c:2641: instance '0x2c2adf8' has no handler with id '17645'
>  return func(*args, **kwargs)
>
>14:45:41,303 WRN misc: /usr/lib/python3.6/site-packages/gi/overrides/GObject.py:553: Warning: gsignal.c:2641: instance '0x2c2adf8' has no handler with id '17646'
>  return func(*args, **kwargs)
>
>14:45:42,631 INF autopart: Detected 1.09 GiB of memory
>14:45:42,636 INF autopart: Swap attempt of 2.18 GiB
>14:45:42,639 DBG ui.gui.spokes.storage: disk free: 7.42 GiB  fs free: 0 B  sw needs: 1.22 GiB  auto swap: 2.18 GiB
>14:45:42,701 INF autopart: Detected 1.09 GiB of memory
>14:45:42,705 INF autopart: Suggested swap size (2.18 GiB) exceeds 10 % of disk space, using 10 % of disk space (760 MiB) instead.
>14:45:42,708 INF autopart: Swap attempt of 760 MiB
>14:45:42,997 INF threading: Running Thread: AnaExecuteStorageThread (-1461513120)
>14:45:43,004 DBG ui.common: Left spoke: StorageSpoke
>14:45:43,355 DBG storage: clearpart: initializing mmcblk1
>14:45:44,016 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run True.
>14:45:44,097 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>14:45:44,115 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>14:45:44,198 DBG kickstart.kickstart.bootloader: Applying drive order: []
>14:45:44,203 DBG bootloader: new disk order: []
>14:45:44,261 DBG kickstart.kickstart.bootloader: The /boot partition doesn't exist.
>14:45:44,264 DBG kickstart.kickstart.bootloader: Use the first usable drive.
>14:45:44,266 DBG kickstart.kickstart.bootloader: Using a boot drive: mmcblk1
>14:45:44,361 INF autopart: Detected 1.09 GiB of memory
>14:45:44,369 INF autopart: Suggested swap size (2.18 GiB) exceeds 10 % of disk space, using 10 % of disk space (760 MiB) instead.
>14:45:44,372 INF autopart: Swap attempt of 760 MiB
>14:45:44,467 DBG autopart: do_autopart: True
>14:45:44,470 DBG autopart: encrypted_autopart: True
>14:45:44,472 DBG autopart: autopart_type: 2
>14:45:44,474 DBG autopart: clear_part_type: 0
>14:45:44,476 DBG autopart: clear_part_disks: ['mmcblk1']
>14:45:44,482 DBG autopart: autopart_requests:
>PartSpec instance (0xab876dd0) -- 
>  mountpoint = /  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 1024 MiB  max_size = 50 GiB  grow = True
>PartSpec instance (0xab876d50) -- 
>  mountpoint = /home  lv = True  thin = True  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 500 MiB  max_size = None  grow = True
>PartSpec instance (0xab876e70) -- 
>  mountpoint = /boot  lv = False  thin = False  btrfs = False
>  weight = 0  fstype = ext4  encrypted = False
>  size = 1024 MiB  max_size = None  grow = False
>PartSpec instance (0xab876e90) -- 
>  mountpoint = None  lv = True  thin = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 760 MiB  max_size = None  grow = False
>
>14:45:44,555 DBG autopart: storage.disks: ['mmcblk1']
>14:45:44,559 DBG autopart: storage.partitioned: ['mmcblk1']
>14:45:44,564 DBG autopart: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/run/install/repo/images/install.img', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'mmcblk1', 'sda']
>14:45:44,567 DBG autopart: boot disk: mmcblk1
>14:45:44,759 DBG payload: Source doesn't require network for installation
>14:45:44,778 DBG autopart: candidate disks: [DiskDevice instance (0xa7c3dd30) --
>  name = mmcblk1  status = True  id = 7
>  children = []
>  parents = []
>  uuid = None  size = 7.42 GiB
>  format = non-existent msdos disklabel
>  major = 179  minor = 0  exists = True  protected = False
>  sysfs path = /sys/devices/soc0/c8000600.sdhci/mmc_host/mmc1/mmc1:0007/block/mmcblk1
>  target size = 7.42 GiB  path = /dev/mmcblk1
>  format args = []  original_format = disklabel  removable = False  wwn = None]
>14:45:44,806 DBG autopart: devs: [PartitionDevice instance (0xa9b576f0) --
>  name = req0  status = False  id = 148
>  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
>]
>14:45:44,852 DBG bootloader: _is_valid_disklabel(mmcblk1) returning True
>14:45:44,855 DBG bootloader: _is_valid_size(mmcblk1) returning True
>14:45:44,857 DBG bootloader: _is_valid_location(mmcblk1) returning True
>14:45:44,860 DBG bootloader: _is_valid_format(mmcblk1) returning True
>14:45:44,862 DBG bootloader: is_valid_stage1_device(mmcblk1) returning True
>14:45:44,877 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>14:45:44,880 DBG ui.gui.hubs: setting StorageSpoke status to: Saving storage configuration...
>14:45:48,102 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>14:45:48,152 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>14:45:48,163 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>14:45:48,226 DBG kickstart.kickstart.bootloader: Applying drive order: []
>14:45:48,229 DBG bootloader: new disk order: []
>14:45:48,284 DBG kickstart.kickstart.bootloader: Found a boot drive: mmcblk1
>14:45:48,286 DBG kickstart.kickstart.bootloader: Use a usable drive with a /boot partition.
>14:45:48,287 DBG kickstart.kickstart.bootloader: Using a boot drive: mmcblk1
>14:45:48,385 DBG bootloader: stage1 device cannot be of type lvmvg
>14:45:48,391 DBG bootloader: stage1 device cannot be of type lvmlv
>14:45:48,397 DBG bootloader: stage1 device cannot be of type lvmlv
>14:45:48,404 DBG bootloader: stage1 device cannot be of type luks/dm-crypt
>14:45:48,417 DBG bootloader: _is_valid_disklabel(mmcblk1) returning True
>14:45:48,420 DBG bootloader: _is_valid_size(mmcblk1) returning True
>14:45:48,422 DBG bootloader: _is_valid_location(mmcblk1) returning True
>14:45:48,424 DBG bootloader: _is_valid_format(mmcblk1) returning True
>14:45:48,426 DBG bootloader: is_valid_stage1_device(mmcblk1) returning True
>14:45:48,471 DBG bootloader: _is_valid_disklabel(mmcblk1) returning True
>14:45:48,474 DBG bootloader: _is_valid_size(mmcblk1) returning True
>14:45:48,477 DBG bootloader: _is_valid_location(mmcblk1) returning True
>14:45:48,481 DBG bootloader: _is_valid_format(mmcblk1) returning True
>14:45:48,484 DBG bootloader: is_valid_stage1_device(mmcblk1) returning True
>14:45:48,492 DBG bootloader: _is_valid_disklabel(mmcblk1p1) returning True
>14:45:48,497 DBG bootloader: _is_valid_size(mmcblk1p1) returning True
>14:45:48,502 DBG bootloader: _is_valid_location(mmcblk1p1) returning True
>14:45:48,504 DBG bootloader: _is_valid_partition(mmcblk1p1) returning True
>14:45:48,509 DBG bootloader: _is_valid_format(mmcblk1p1) returning True
>14:45:48,511 DBG bootloader: is_valid_stage2_device(mmcblk1p1) returning True
>14:45:48,686 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': {'/usr/share', '/tmp', '/usr/lib', '/var', '/home', '/', '/usr'}, 'must_be_on_root': {'/sbin', 'lost+found', '/root', '/bin', '/etc', '/proc', '/dev', '/lib', '/mnt'}, 'swap_is_recommended': True}.
>14:45:48,688 DBG kickstart.kickstart.autopart: Run sanity check verify_root.
>14:45:48,690 DBG kickstart.kickstart.autopart: Run sanity check verify_s390_constraints.
>14:45:48,692 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_sizes.
>14:45:48,694 DBG kickstart.kickstart.autopart: Run sanity check verify_partition_format_sizes.
>14:45:48,696 DBG kickstart.kickstart.autopart: Run sanity check verify_bootloader.
>14:45:48,697 DBG kickstart.kickstart.autopart: Run sanity check verify_gpt_biosboot.
>14:45:48,699 DBG kickstart.kickstart.autopart: Run sanity check verify_swap.
>14:45:48,701 DBG kickstart.kickstart.autopart: Run sanity check verify_swap_uuid.
>14:45:48,703 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_linuxfs.
>14:45:48,704 DBG kickstart.kickstart.autopart: Run sanity check verify_mountpoints_on_root.
>14:45:48,706 DBG kickstart.kickstart.autopart: Run sanity check verify_luks_devices_have_key.
>14:45:48,708 DBG kickstart.kickstart.autopart: Run sanity check verify_mounted_partitions.
>14:45:48,710 DBG kickstart.kickstart.autopart: Storage check finished with success.
>14:45:48,712 DBG kickstart.kickstart.bootloader: Execute the bootloader with dry run False.
>14:45:48,762 DBG kickstart.kickstart.bootloader: Applying bootloader arguments: []
>14:45:48,780 DBG kickstart.kickstart.bootloader: Applying bootloader location: DEFAULT
>14:45:48,838 DBG kickstart.kickstart.bootloader: Applying drive order: []
>14:45:48,840 DBG bootloader: new disk order: []
>14:45:48,889 DBG kickstart.kickstart.bootloader: Use the requested boot drive.
>14:45:49,596 DBG storage_utils: mmcblk1 matches ['mmcblk1'] for devicetree=existing 0 B disk sda (2)
>existing 7.42 GiB disk mmcblk1 (7) with non-existent msdos disklabel
>  non-existent 1024 MiB partition mmcblk1p1 (155) with non-existent ext4 filesystem mounted at /boot
>  non-existent 6.42 GiB partition mmcblk1p2 (148) with non-existent luks
>    non-existent 6.42 GiB luks/dm-crypt luks-mmcblk1p2 (162) with non-existent lvmpv
>      non-existent 6.42 GiB lvmvg fedora_trimslice (168)
>        non-existent 5.68 GiB lvmlv fedora_trimslice-root (174) with non-existent ext4 filesystem mounted at /
>        non-existent 760 MiB lvmlv fedora_trimslice-swap (183) with non-existent swap
>existing 0 B file /run/install/repo/images/install.img (17)
>  existing 0 B loop loop0 (21)
>existing 0 B file /LiveOS/rootfs.img (31)
>  existing 0 B loop loop1 (35) with existing ext4 filesystem
>    existing 2 GiB dm live-rw (54) with existing ext4 filesystem
>    existing 2 GiB dm live-base (64) with existing ext4 filesystem
>existing 0 B file /overlay (deleted) (45)
>  existing 0 B loop loop2 (49)
> and disks_only=True
>14:45:49,647 DBG kickstart.kickstart.bootloader: Using a boot drive: mmcblk1
>14:45:49,725 DBG bootloader: stage1 device cannot be of type lvmvg
>14:45:49,731 DBG bootloader: stage1 device cannot be of type lvmlv
>14:45:49,736 DBG bootloader: stage1 device cannot be of type lvmlv
>14:45:49,743 DBG bootloader: stage1 device cannot be of type luks/dm-crypt
>14:45:49,756 DBG bootloader: _is_valid_disklabel(mmcblk1) returning True
>14:45:49,759 DBG bootloader: _is_valid_size(mmcblk1) returning True
>14:45:49,761 DBG bootloader: _is_valid_location(mmcblk1) returning True
>14:45:49,763 DBG bootloader: _is_valid_format(mmcblk1) returning True
>14:45:49,765 DBG bootloader: is_valid_stage1_device(mmcblk1) returning True
>14:45:49,769 INF threading: Running Thread: AnaCheckStorageThread (-1453120416)
>14:45:49,856 INF threading: Thread Done: AnaExecuteStorageThread (-1461513120)
>14:45:49,908 DBG bootloader: _is_valid_disklabel(mmcblk1) returning True
>14:45:49,912 DBG bootloader: _is_valid_size(mmcblk1) returning True
>14:45:49,914 DBG bootloader: _is_valid_location(mmcblk1) returning True
>14:45:49,916 DBG bootloader: _is_valid_format(mmcblk1) returning True
>14:45:49,918 DBG bootloader: is_valid_stage1_device(mmcblk1) returning True
>14:45:49,937 DBG bootloader: _is_valid_disklabel(mmcblk1p1) returning True
>14:45:49,946 DBG bootloader: _is_valid_size(mmcblk1p1) returning True
>14:45:49,951 DBG bootloader: _is_valid_location(mmcblk1p1) returning True
>14:45:49,954 DBG bootloader: _is_valid_partition(mmcblk1p1) returning True
>14:45:49,963 DBG bootloader: _is_valid_format(mmcblk1p1) returning True
>14:45:49,966 DBG bootloader: is_valid_stage2_device(mmcblk1p1) returning True
>14:45:50,012 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>14:45:50,087 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>14:45:50,090 DBG ui.gui.hubs: setting StorageSpoke status to: Checking storage configuration...
>14:45:50,219 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': {'/usr/share', '/tmp', '/usr/lib', '/var', '/home', '/', '/usr'}, 'must_be_on_root': {'/sbin', 'lost+found', '/root', '/bin', '/etc', '/proc', '/dev', '/lib', '/mnt'}, 'swap_is_recommended': True}.
>14:45:50,223 DBG ui.helpers: Run sanity check verify_root.
>14:45:50,225 DBG ui.helpers: Run sanity check verify_s390_constraints.
>14:45:50,227 DBG ui.helpers: Run sanity check verify_partition_sizes.
>14:45:50,229 DBG ui.helpers: Run sanity check verify_partition_format_sizes.
>14:45:50,231 DBG ui.helpers: Run sanity check verify_bootloader.
>14:45:50,232 DBG ui.helpers: Run sanity check verify_gpt_biosboot.
>14:45:50,234 DBG ui.helpers: Run sanity check verify_swap.
>14:45:50,236 DBG ui.helpers: Run sanity check verify_swap_uuid.
>14:45:50,238 DBG ui.helpers: Run sanity check verify_mountpoints_on_linuxfs.
>14:45:50,240 DBG ui.helpers: Run sanity check verify_mountpoints_on_root.
>14:45:50,241 DBG ui.helpers: Run sanity check verify_luks_devices_have_key.
>14:45:50,243 DBG ui.helpers: Run sanity check verify_mounted_partitions.
>14:45:50,245 DBG ui.helpers: Storage check finished with success.
>14:45:50,247 INF threading: Thread Done: AnaCheckStorageThread (-1453120416)
>14:45:50,344 DBG ui.gui.hubs: incomplete spokes: []
>14:45:51,583 INF ui.lib.space: fs space: 4.82 GiB  needed: 1.59 GiB
>14:45:51,593 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>14:45:54,083 DBG ui.common: Left hub: SummaryHub
>14:45:54,260 INF lifecycle: Adding controller: ProgressHub
>14:45:54,363 INF lifecycle: Module initialized: PasswordSpoke
>14:45:54,460 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke]
>14:45:54,595 INF lifecycle: Module initialized: UserSpoke
>14:45:54,653 DBG ui.gui.hubs: incomplete spokes: [PasswordSpoke, UserSpoke]
>14:45:54,658 INF lifecycle: Initialization of all modules (2) has been started.
>14:45:54,660 INF lifecycle: All modules have been initialized.
>14:45:54,674 DBG ui.gui.hubs: Disabling event loop for hub SummaryHub
>14:45:54,676 DBG ui.gui.hubs: Starting event loop for hub ProgressHub
>14:45:54,680 INF threading: Running Thread: AnaInstallThread (-1436335008)
>14:45:54,741 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
>14:45:54,742 DBG ui.common: Entered hub: ProgressHub
>14:45:54,750 INF installation: Task started: Save system time to HW clock (1/18)
>14:45:55,159 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
>14:45:55,533 INF progress: Save system time to HW clock
>14:45:55,538 DBG installation: Task completed: Save system time to HW clock (1/18) (0.8 s)
>14:45:55,545 INF installation: Queue started: Installation environment setup (1/9)
>14:45:55,554 INF progress: Setting up the installation environment
>14:45:55,564 INF installation: Task started: Setup addons (2/18)
>14:45:55,568 INF progress: Setup addons
>14:45:55,570 DBG installation: Task completed: Setup addons (2/18) (0.0 s)
>14:45:55,581 INF installation: Queue started: Early storage configuration (2/9)
>14:45:55,592 INF progress: Configuring storage
>14:45:55,595 INF installation: Task started: Insert custom storage to ksdata (3/18)
>14:45:55,951 INF progress: Insert custom storage to ksdata
>14:45:55,956 DBG installation: Task completed: Insert custom storage to ksdata (3/18) (0.4 s)
>14:45:55,960 INF installation: Task started: Run pre-storage tasks (4/18)
>14:45:55,964 INF progress: Run pre-storage tasks
>14:45:55,968 DBG installation: Task completed: Run pre-storage tasks (4/18) (0.0 s)
>14:45:55,973 INF installation: Task started: Activate filesystems (5/18)
>14:45:57,063 DBG ui.common: Entered spoke: PasswordSpoke
>14:45:59,622 INF progress: Creating disklabel on /dev/mmcblk1
>14:46:04,412 INF progress: Creating ext4 on /dev/mmcblk1p1
>14:46:05,248 INF ui.gui.spokes.root_password: Return to hub prevented by password checking rules.
>14:46:05,584 DBG ui.common: Left spoke: PasswordSpoke
>14:46:05,719 DBG ui.gui.hubs: incomplete spokes: [UserSpoke]
>14:46:05,757 DBG ui.gui.hubs: incomplete spokes: []
>14:46:05,983 DBG ui.gui.hubs: spoke is ready: PasswordSpoke
>14:46:19,401 INF progress: Creating luks on /dev/mmcblk1p2
>14:46:28,673 INF progress: Creating lvmpv on /dev/mapper/luks-540636b3-d282-4e84-9c84-f50dc92d12dd
>14:46:33,798 INF threading: Thread Failed: AnaInstallThread (-1436335008)
>14:46:33,815 DBG exception: running handleException
>14:46:33,871 CRT exception: Traceback (most recent call last):
>
>  File "/usr/lib/python3.6/site-packages/gi/overrides/BlockDev.py", line 843, in wrapped
>    ret = orig_obj(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/gi/overrides/BlockDev.py", line 430, in lvm_pvcreate
>    return _lvm_pvcreate(device, data_alignment, metadata_size, extra)
>
>GLib.GError: g-io-error-quark: Failed to call the 'PvCreate' method on the '/com/redhat/lvmdbus1/Manager' object: Timeout was reached (24)
>
>
>During handling of the above exception, another exception occurred:
>
>
>Traceback (most recent call last):
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/threading.py", line 286, in run
>    threading.Thread.run(self)
>
>  File "/usr/lib/python3.6/threading.py", line 864, in run
>    self._target(*self._args, **self._kwargs)
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/installation.py", line 372, in doInstall
>    installation_queue.start()
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/installation_tasks.py", line 304, in start
>    item.start()
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/installation_tasks.py", line 304, in start
>    item.start()
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/installation_tasks.py", line 472, in start
>    self.run_task()
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/installation_tasks.py", line 438, in run_task
>    self._task(*self._task_args, **self._task_kwargs)
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/storage/osinstall.py", line 2144, in turn_on_filesystems
>    storage.do_it(callbacks)
>
>  File "/usr/lib/python3.6/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/pyanaconda/storage/osinstall.py", line 1223, in do_it
>    super().do_it(callbacks=callbacks)
>
>  File "/usr/lib/python3.6/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/blivet.py", line 135, in do_it
>    self.devicetree.actions.process(callbacks=callbacks, devices=self.devices)
>
>  File "/usr/lib/python3.6/site-packages/blivet/actionlist.py", line 48, in wrapped_func
>    return func(obj, *args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/actionlist.py", line 327, in process
>    action.execute(callbacks)
>
>  File "/usr/lib/python3.6/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/deviceaction.py", line 616, in execute
>    options=self.device.format_args)
>
>  File "/usr/lib/python3.6/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/formats/__init__.py", line 513, in create
>    self._create(**kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/threads.py", line 53, in run_with_lock
>    return m(*args, **kwargs)
>
>  File "/usr/lib/python3.6/site-packages/blivet/formats/lvmpv.py", line 125, in _create
>    blockdev.lvm.pvcreate(self.device, data_alignment=self.data_alignment)
>
>  File "/usr/lib/python3.6/site-packages/gi/overrides/BlockDev.py", line 869, in wrapped
>    raise transform[1](msg)
>
>gi.overrides.BlockDev.LVMError: Failed to call the 'PvCreate' method on the '/com/redhat/lvmdbus1/Manager' object: Timeout was reached
>
>14:46:33,956 DBG exception: Gtk running, queuing exception handler to the main loop
>14:46:33,958 INF threading: Thread Done: AnaInstallThread (-1436335008)
>14:47:21,672 INF core.util: Running kickstart %%onerror script(s)
>14:47:21,674 INF core.util: All kickstart %%onerror script(s) have been run
>14:47:21,676 INF kickstart.script: Running kickstart %%traceback script(s)
>14:47:21,678 INF kickstart.script: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1589871: 1450064 | 1450065 | 1450066 | 1450067 | 1450068 | 1450069 | 1450070 | 1450071 | 1450072 | 1450073 | 1450074 | 1450075 | 1450076 | 1450077 | 1450078