Attachment 1701327 Details for Bug 1857530 – anaconda.log

anaconda.log

bug1857530-anaconda.log (text/plain), 21.24 KB, created by Chris Murphy on 2020-07-16 05:53:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chris Murphy

Created: 2020-07-16 05:53:44 UTC

Size: 21.24 KB

patch

obsolete

>05:32:34,862 INF core.configuration.product: Loading information about products from /etc/anaconda/product.d.
>05:32:34,865 INF core.configuration.product: Found oVirt Node Next at /etc/anaconda/product.d/ovirt.conf.
>05:32:34,866 INF core.configuration.product: Found Fedora Silverblue at /etc/anaconda/product.d/fedora-silverblue.conf.
>05:32:34,869 INF core.configuration.product: Found Scientific Linux at /etc/anaconda/product.d/scientific-linux.conf.
>05:32:34,870 INF core.configuration.product: Found Fedora Server at /etc/anaconda/product.d/fedora-server.conf.
>05:32:34,870 INF core.configuration.product: Found Red Hat Enterprise Linux at /etc/anaconda/product.d/rhel.conf.
>05:32:34,871 INF core.configuration.product: Found Red Hat Virtualization at /etc/anaconda/product.d/rhev.conf.
>05:32:34,872 INF core.configuration.product: Found Fedora Workstation at /etc/anaconda/product.d/fedora-workstation.conf.
>05:32:34,873 INF core.configuration.product: Found Fedora Workstation Live at /etc/anaconda/product.d/fedora-workstation-live.conf.
>05:32:34,873 INF core.configuration.product: Found Fedora at /etc/anaconda/product.d/fedora.conf.
>05:32:34,874 INF core.configuration.product: Found CentOS Linux at /etc/anaconda/product.d/centos.conf.
>05:32:34,874 INF core.configuration.product: The product Fedora Silverblue is supported.
>05:32:34,882 INF main: /sbin/anaconda 33.22-1.fc33
>05:32:34,912 WRN misc: /usr/lib64/python3.9/site-packages/dbus/connection.py:337: DeprecationWarning: invalid escape sequence \ 
>  """Arrange for the given function to be called when a signal matching
>
>05:32:34,920 WRN misc: /usr/lib64/python3.9/site-packages/dbus/proxies.py:307: DeprecationWarning: invalid escape sequence \ 
>  """Arrange for the given function to be called when the given signal
>
>05:32:36,855 INF isys: 3091256 kB (3018 MB) are available
>05:32:36,857 WRN misc: /usr/lib64/python3.9/subprocess.py:937: RuntimeWarning: line buffering (buffering=1) isn't supported in binary mode, the default buffer size will be used
>  self.stdout = io.open(c2pread, 'rb', bufsize)
>
>05:32:36,891 INF startup_utils: check_memory(): total:3018, needed:320, graphical:410
>05:32:36,920 INF main: anaconda called with cmdline = ['/sbin/anaconda']
>05:32:36,920 INF main: Default encoding = utf-8 
>05:32:36,921 INF misc: Writing a temporary configuration loaded from: ['/etc/anaconda/anaconda.conf', '/etc/anaconda/product.d/fedora.conf', '/etc/anaconda/product.d/fedora-workstation.conf', '/etc/anaconda/product.d/fedora-workstation-live.conf', '/etc/anaconda/product.d/fedora-silverblue.conf', '/etc/anaconda/conf.d/00-do-nothing.conf', '/etc/anaconda/conf.d/01-still-do-nothing.conf']
>05:32:36,983 INF core.dbus: Connecting to the Anaconda bus at unix:abstract=/tmp/dbus-eeFT9nOYQT,guid=44a2f48a951d44daedf9055b5f0fe674.
>05:32:41,429 INF main: Found a kickstart file: /usr/share/anaconda/interactive-defaults.ks
>05:32:41,493 DBG addons: Loading ks section into module path for addon com_redhat_kdump
>05:32:41,494 DBG addons: Loading spokes into module path for addon com_redhat_kdump
>05:32:41,525 INF startup_utils: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>05:32:41,689 WRN kickstart: Command firstboot will be parsed in DBus module.
>05:32:41,691 WRN kickstart: Command firewall will be parsed in DBus module.
>05:32:41,719 DBG payload.flatpak: Configure flatpak for path /var/tmp/anaconda-flatpak-temp
>05:32:41,758 DBG localization: setting locale to: en_US.UTF-8
>05:32:41,781 DBG network: Initialization started.
>05:32:41,795 DBG network: Devices found: ['enp1s0']
>05:32:41,804 DBG network: Running task Consolidate initramfs connections
>05:32:41,813 DBG network: Consolidate initramfs connections result: []
>05:32:41,821 DBG network: Running task Apply kickstart
>05:32:41,830 DBG network: Apply kickstart result: []
>05:32:41,842 DBG network: Running task Dump missing ifcfg files
>05:32:42,859 DBG network: Dump missing ifcfg files result: ['enp1s0']
>05:32:42,868 DBG network: Running task Set real ONBOOT values from kickstart
>05:32:42,876 DBG network: Set real ONBOOT values from kickstart result: []
>05:32:42,878 DBG network: Creating network configurations.
>05:32:42,883 DBG network: Initialization finished.
>05:32:42,883 INF threading: Running Thread: AnaWaitForConnectingNMThread (140241528112704)
>05:32:42,884 DBG anaconda: setting display mode to GUI
>05:32:42,903 INF anaconda: Display mode is set to 'interactive graphical mode'.
>05:32:42,905 INF isys: 3091256 kB (3018 MB) are available
>05:32:42,907 INF network: got 0 NTP servers from DHCP
>05:32:42,907 WRN misc: /usr/lib64/python3.9/subprocess.py:937: RuntimeWarning: line buffering (buffering=1) isn't supported in binary mode, the default buffer size will be used
>  self.stdout = io.open(c2pread, 'rb', bufsize)
>
>05:32:42,927 INF threading: Thread Done: AnaWaitForConnectingNMThread (140241528112704)
>05:32:42,938 INF startup_utils: check_memory(): total:3018, needed:320, graphical:410
>05:32:42,939 DBG core.util: Setting timeout 60 seconds for starting X.
>05:32:45,736 DBG core.util: X server has signalled a successful start.
>05:32:46,493 INF display: Started spice-vdagent.
>05:32:47,375 DBG addons: Loading ks section into module path for addon com_redhat_kdump
>05:32:47,377 DBG addons: Loading spokes into module path for addon com_redhat_kdump
>05:32:47,585 DBG core.storage: LABEL=OEMDRV matches [] for devicetree=None and disks_only=True
>05:32:47,607 INF threading: Running Thread: AnaStorageThread (140241528112704)
>05:32:47,609 INF threading: Running Thread: AnaTimeInitThread (140241474340416)
>05:32:47,647 DBG payload.manager: Restarting payload thread
>05:32:47,648 INF threading: Running Thread: AnaPayloadRestartThread (140241465423424)
>05:32:47,648 INF geoloc: Geolocation is enabled.
>05:32:47,650 INF threading: Running Thread: AnaPayloadThread (140241457030720)
>05:32:47,650 DBG payload.manager: Updating payload thread state: STARTED
>05:32:47,650 DBG payload.manager: Updating payload thread state: WAITING_STORAGE
>05:32:47,651 INF threading: Thread Done: AnaPayloadRestartThread (140241465423424)
>05:32:47,651 INF threading: Running Thread: AnaGeolocationRefreshThread (140241448638016)
>05:32:47,651 INF geoloc: Starting geolocation lookup
>05:32:47,652 INF geoloc: Geolocation provider: Fedora GeoIP
>05:32:48,149 INF geoloc: Geolocation lookup finished in 0.5 seconds
>05:32:48,149 INF geoloc: got results from geolocation
>05:32:48,151 INF threading: Thread Done: AnaGeolocationRefreshThread (140241448638016)
>05:32:48,758 DBG ui.gui: Detected primary monitor: 1024x768 95dpix 96dpiy
>05:32:49,572 DBG localization: setting locale to: en_US.UTF-8
>05:32:49,595 DBG localization: setting locale to: en_US.UTF-8
>05:32:49,648 INF threading: Thread Done: AnaStorageThread (140241528112704)
>05:32:49,648 DBG payload.manager: Updating payload thread state: WAITING_NETWORK
>05:32:49,649 DBG payload.manager: Updating payload thread state: FINISHED
>05:32:49,649 INF threading: Thread Done: AnaPayloadThread (140241457030720)
>05:32:49,683 DBG ui.common: Entered spoke: WelcomeLanguageSpoke
>05:32:49,686 WRN misc: /usr/lib64/python3.9/site-packages/gi/overrides/Gdk.py:331: DeprecationWarning: Gdk.Cursor.new is deprecated
>  return cls.new(*args, **kwds)
>
>05:32:50,003 INF threading: Thread Done: AnaTimeInitThread (140241474340416)
>05:32:56,040 DBG core.util: This system doesn't support TAINT_HARDWARE_UNSUPPORTED.
>05:32:56,041 DBG core.util: This system doesn't support TAINT_SUPPORT_REMOVED.
>05:32:56,045 DBG localization: setting locale to: en_US.UTF-8
>05:32:56,067 INF ui.gui.spokes.welcome: using timezone determined by geolocation
>05:32:56,117 DBG ui.gui.spokes.network: network standalone spoke (init): completed: ['enp1s0']
>05:32:56,123 DBG ui.common: Left spoke: WelcomeLanguageSpoke
>05:32:56,139 INF ui.common: Spoke NetworkSpoke will not be displayed because it is hidden by the Anaconda configuration file.
>05:32:56,143 INF ui.common: Spoke PasswordSpoke will not be displayed because it is hidden by the Anaconda configuration file.
>05:32:56,143 INF ui.common: Spoke UserSpoke will not be displayed because it is hidden by the Anaconda configuration file.
>05:32:56,144 INF lifecycle: Adding controller: SummaryHub
>05:32:57,309 INF threading: Running Thread: AnaAddLayoutsInitThread (140241474340416)
>05:32:57,311 DBG ui.gui.utils: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f8c81063580>> on <list object at 0x7f8c80fe7d00>
>05:32:57,312 INF threading: Running Thread: AnaGtkBatchPre1 (140241457030720)
>05:32:57,316 INF threading: Thread Done: AnaGtkBatchPre1 (140241457030720)
>05:32:57,365 INF threading: Running Thread: AnaKeyboardThread (140241457030720)
>05:32:57,423 INF lifecycle: Module initialized: LangsupportSpoke
>05:32:58,105 INF threading: Running Thread: AnaDateTimeThread (140241528112704)
>05:32:58,208 INF ui.gui.spokes.software_selection: using environment from kickstart: None
>05:32:58,295 INF threading: Running Thread: AnaStorageWatcher (140241448638016)
>05:32:58,298 DBG ui.gui.hubs: incomplete spokes: [StorageSpoke]
>05:32:58,319 DBG ui.lib.storage: Selecting one or less disks by default: vda
>05:32:58,319 INF lifecycle: Module initialized: StorageSpoke
>05:32:58,319 INF threading: Thread Done: AnaStorageWatcher (140241448638016)
>05:32:58,339 INF lifecycle: Module initialized: CustomPartitioningSpoke
>05:32:58,382 INF lifecycle: Module initialized: BlivetGuiSpoke
>05:32:58,427 INF lifecycle: Module initialized: FilterSpoke
>05:32:58,427 INF lifecycle: Initialization of all modules (7) has been started.
>05:32:58,430 DBG ui.gui.hubs: Starting event loop for hub SummaryHub
>05:32:58,436 DBG ui.common: Entered hub: SummaryHub
>23:32:58,000 INF isys: System time set to Thu Jul 16 05:32:58 2020 UTC
>23:32:58,000 INF isys: System time set to Thu Jul 16 05:32:58 2020 UTC
>23:32:58,000 INF isys: System time set to Thu Jul 16 05:32:58 2020 UTC
>23:32:58,002 INF lifecycle: Module initialized: DatetimeSpoke
>23:32:58,002 INF threading: Thread Done: AnaDateTimeThread (140241528112704)
>23:32:58,007 DBG ui.gui.hubs: spoke is not ready: KeyboardSpoke
>23:32:58,007 DBG ui.gui.hubs: setting KeyboardSpoke status to: Getting list of layouts...
>23:32:58,008 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>23:32:58,008 DBG ui.gui.hubs: setting StorageSpoke status to: Probing storage...
>23:32:58,018 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>23:32:58,028 DBG ui.gui.hubs: spoke is ready: DatetimeSpoke
>23:32:59,823 DBG ui.common: Entered spoke: StorageSpoke
>23:32:59,973 DBG ui.gui.utils: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f8c81063580>> on <list object at 0x7f8c80fe7d00>
>23:32:59,973 INF threading: Thread Done: AnaAddLayoutsInitThread (140241474340416)
>23:32:59,973 INF lifecycle: Module initialized: KeyboardSpoke
>23:32:59,973 INF lifecycle: All modules have been initialized.
>23:32:59,974 INF threading: Thread Done: AnaKeyboardThread (140241457030720)
>23:33:00,051 DBG ui.gui.hubs: spoke is ready: KeyboardSpoke
>23:33:02,789 INF core.storage: Detected 2.95 GiB of memory
>23:33:02,789 INF core.storage: Swap attempt of 2.95 GiB
>23:33:02,790 DBG ui.gui.spokes.storage: disk free: 100 GiB  fs free: 0 B  sw needs: 2.46 GiB  auto swap: 2.95 GiB
>23:33:02,818 DBG ui.gui.spokes.storage: Running the execute method for the AUTOMATIC partitioning method.
>23:33:02,819 INF threading: Running Thread: AnaExecuteStorageThread (140241457030720)
>23:33:02,820 DBG ui.gui.spokes.storage: Saving storage configuration...
>23:33:02,820 DBG ui.common: Left spoke: StorageSpoke
>23:33:02,843 DBG ui.gui.hubs: spoke is not ready: StorageSpoke
>23:33:02,844 DBG ui.gui.hubs: setting StorageSpoke status to: Saving storage configuration...
>23:33:03,835 DBG ui.gui.spokes.storage: Checking storage configuration...
>23:33:03,857 DBG ui.gui.hubs: setting StorageSpoke status to: Checking storage configuration...
>23:33:04,903 DBG ui.gui.spokes.storage: Partitioning has been applied: ValidationReport(error_messages=[], warning_messages=[])
>23:33:04,904 INF threading: Thread Done: AnaExecuteStorageThread (140241457030720)
>23:33:04,977 DBG ui.gui.hubs: incomplete spokes: []
>23:33:04,982 INF ui.lib.space: fs space: 78.73 GiB  needed: 2.46 GiB
>23:33:04,983 INF core.util: Skipping detection of SMT.
>23:33:04,983 DBG ui.gui.hubs: spoke is ready: StorageSpoke
>23:33:08,775 DBG ui.common: Left hub: SummaryHub
>23:33:08,786 INF threading: Running Thread: AnaInstallThread (140241457030720)
>23:33:08,787 DBG ui.gui.spokes.installation_progress: The installation has started.
>23:33:08,792 DBG ui.common: Entered spoke: ProgressSpoke
>23:33:08,956 INF installation: Top-level task queue: Complete installation queue
>Number of task queues: 17
>Number of tasks: 41
>Task & task group listing:
> Task queue: Installation queue
>  Task: Save system time to HW clock
>  Task queue: Installation environment setup
>   Task: Setup addons
>   Task: Configure the runtime system
>  Task queue: Early storage configuration
>   Task: Activate filesystems
>   Task: Mount filesystems
>  Task queue: Pre-install scripts
>   Task: Run %pre-install scripts
>  Task queue: Pre install tasks
>   Task: Configure NTP service enablement
>   Task: Copy resolv.conf to sysroot
>   Task: Discover information about a realm
>   Task: Find additional packages & run pre_install()
>  Task queue: Payload installation
>   Task: Install the payload
>  Task queue: Late storage configuration
>   Task: Write the storage configuration
>  Task queue: Bootloader installation
>   Task: Install the bootloader
>  Task queue: Post-installation setup tasks
>   Task: Run post-installation setup tasks
> Task queue: Configuration queue
>  Task queue: Installed system configuration
>   Task: Configure SELinux
>   Task: Configure fingerprint authentication
>   Task: Authselect configuration
>   Task: Authconfig configuration
>   Task: Configure Initial Setup
>   Task: Configure post-installation tools
>   Task: Configure services
>   Task: Configure systemd default target
>   Task: Configure default desktop
>   Task: Configure time zone
>   Task: Configure NTP
>   Task: Configure language
>   Task: Configure keyboard
>   Task: Configure firewall
>   Task: Create groups
>   Task: Create users
>   Task: Configure root password
>   Task: Set SSH keys
>   Task: Configure optional root password SSH login
>  Task queue: Network configuration
>   Task: Network configuration
>  Task queue: User creation
>  Task queue: Anaconda addon configuration
>   Task: Configure Anaconda addons
>   Task: Install the system
>  Task queue: Initramfs generation
>   Task: Generate initramfs
>   Task: Rerun zipl
>  Task: Join a realm
>  Task queue: Post installation scripts
>   Task: Run post installation scripts
>  Task queue: Write configs and kickstarts
>   Task: Store kickstarts
>23:33:08,957 INF installation: Queue started: Installation queue (1/17)
>23:33:08,957 INF installation: Task started: Save system time to HW clock (1/41)
>23:33:09,503 INF progress: Save system time to HW clock
>23:33:09,503 DBG installation: Task completed: Save system time to HW clock (1/41) (0.5 s)
>23:33:09,504 INF progress: Setting up the installation environment
>23:33:09,504 INF installation: Queue started: Installation environment setup (2/17)
>23:33:09,504 INF installation: Task started: Setup addons (2/41)
>23:33:09,505 INF progress: Setting up com_redhat_kdump addon
>23:33:09,505 INF progress: Setup addons
>23:33:09,505 DBG installation: Task completed: Setup addons (2/41) (0.0 s)
>23:33:09,505 INF installation: Task started: Configure the runtime system (3/41)
>23:33:09,510 INF progress: Configure the runtime system
>23:33:09,510 DBG installation: Task completed: Configure the runtime system (3/41) (0.0 s)
>23:33:09,511 INF progress: Configuring storage
>23:33:09,511 INF installation: Queue started: Early storage configuration (3/17)
>23:33:09,511 INF installation: Task started: Activate filesystems (4/41)
>23:33:10,014 INF progress: Creating disklabel on /dev/vda
>23:33:11,068 INF progress: Creating ext4 on /dev/vda2
>23:33:11,701 INF progress: Creating btrfs on /dev/vda3
>23:33:12,108 INF progress: Creating btrfs on /dev/vda3
>23:33:12,753 INF progress: Creating efi on /dev/vda1
>23:33:13,533 INF progress: Activate filesystems
>23:33:13,533 DBG installation: Task completed: Activate filesystems (4/41) (4.0 s)
>23:33:13,534 INF installation: Task started: Mount filesystems (5/41)
>23:33:20,577 INF progress: Mount filesystems
>23:33:20,577 DBG installation: Task completed: Mount filesystems (5/41) (7.0 s)
>23:33:20,577 INF progress: Running pre-installation scripts
>23:33:20,578 INF installation: Queue started: Pre-install scripts (4/17)
>23:33:20,578 INF installation: Task started: Run %pre-install scripts (6/41)
>23:33:20,578 INF progress: Run %pre-install scripts
>23:33:20,578 DBG installation: Task completed: Run %pre-install scripts (6/41) (0.0 s)
>23:33:20,579 INF progress: Running pre-installation tasks
>23:33:20,579 INF installation: Queue started: Pre install tasks (5/17)
>23:33:20,579 INF installation: Task started: Configure NTP service enablement (7/41)
>23:33:21,592 INF progress: Configure NTP service enablement
>23:33:21,593 DBG installation: Task completed: Configure NTP service enablement (7/41) (1.0 s)
>23:33:21,594 INF installation: Task started: Copy resolv.conf to sysroot (8/41)
>23:33:21,596 INF progress: Copy resolv.conf to sysroot
>23:33:21,596 DBG installation: Task completed: Copy resolv.conf to sysroot (8/41) (0.0 s)
>23:33:21,596 INF installation: Task started: Discover information about a realm (9/41)
>23:33:21,603 INF progress: Discover information about a realm
>23:33:21,603 DBG installation: Task completed: Discover information about a realm (9/41) (0.0 s)
>23:33:21,604 INF installation: Task started: Find additional packages & run pre_install() (10/41)
>23:33:21,604 DBG payload.requirement: no group requirement added for language groups
>23:33:21,604 DBG payload.requirement: no package requirement added for langpacks
>23:33:21,611 DBG installation: Adding requirements for module org.fedoraproject.Anaconda.Modules.Security : [Requirement(name='authselect', reason='Needed by authselect kickstart command & for fingerprint authentication support.', type='package')]
>23:33:21,611 DBG payload.requirement: added package requirement 'authselect' for Needed by authselect kickstart command & for fingerprint authentication support., strong=True
>23:33:21,619 DBG installation: Adding requirements for module org.fedoraproject.Anaconda.Modules.Network : [Requirement(name='firewalld', reason='Requested by the firewall kickstart command.', type='package')]
>23:33:21,620 DBG payload.requirement: added package requirement 'firewalld' for Requested by the firewall kickstart command., strong=True
>23:33:21,630 DBG installation: Adding requirements for module org.fedoraproject.Anaconda.Modules.Timezone : [Requirement(name='chrony', reason='Needed to run NTP service.', type='package')]
>23:33:21,630 DBG payload.requirement: added package requirement 'chrony' for Needed to run NTP service., strong=True
>23:33:21,648 DBG installation: Adding requirements for module org.fedoraproject.Anaconda.Modules.Storage : [Requirement(name='dosfstools', reason='Required to manage storage devices.', type='package'), Requirement(name='e2fsprogs', reason='Required to manage storage devices.', type='package'), Requirement(name='btrfs-progs', reason='Required to manage storage devices.', type='package'), Requirement(name='grub2-efi-x64', reason='Necessary for the bootloader configuration.', type='package'), Requirement(name='shim-x64', reason='Necessary for the bootloader configuration.', type='package'), Requirement(name='efibootmgr', reason='Necessary for the bootloader configuration.', type='package'), Requirement(name='grub2-tools', reason='Necessary for the bootloader configuration.', type='package')]
>23:33:21,650 DBG payload.requirement: added package requirement 'dosfstools' for Required to manage storage devices., strong=True
>23:33:21,650 DBG payload.requirement: added package requirement 'e2fsprogs' for Required to manage storage devices., strong=True
>23:33:21,651 DBG payload.requirement: added package requirement 'btrfs-progs' for Required to manage storage devices., strong=True
>23:33:21,651 DBG payload.requirement: added package requirement 'grub2-efi-x64' for Necessary for the bootloader configuration., strong=True
>23:33:21,651 DBG payload.requirement: added package requirement 'shim-x64' for Necessary for the bootloader configuration., strong=True
>23:33:21,651 DBG payload.requirement: added package requirement 'efibootmgr' for Necessary for the bootloader configuration., strong=True
>23:33:21,652 DBG payload.requirement: added package requirement 'grub2-tools' for Necessary for the bootloader configuration., strong=True
>23:33:21,663 INF progress: Find additional packages & run pre_install()
>23:33:21,664 DBG installation: Task completed: Find additional packages & run pre_install() (10/41) (0.1 s)
>23:33:21,664 INF progress: Installing.
>23:33:21,664 INF installation: Queue started: Payload installation (6/17)
>23:33:21,665 INF installation: Task started: Install the payload (11/41)
>23:33:21,667 INF payload.rpmostreepayload: executing ostreesetup=<pykickstart.commands.ostreesetup.F21_OSTreeSetup object at 0x7f8c87a710d0>
>23:40:20,481 INF payload.rpmostreepayload: ostree pull: 11964 metadata, 113388 content objects imported
>23:40:22,047 INF payload.rpmostreepayload: ostree admin deploy starting
>23:40:55,335 INF payload.rpmostreepayload: ostree admin deploy complete
>23:40:55,962 INF payload.rpmostreepayload: Copying bootloader data: efi
>23:40:56,134 INF payload.rpmostreepayload: Copying bootloader data: grub2

Actions: View

Attachments on bug 1857530: 1701327 | 1701328 | 1701329 | 1701330 | 1701334