Description of problem: Attempt F29 installation with encrypted root on armhfp Version-Release number of selected component: anaconda-29.15 The following was filed automatically by anaconda: anaconda 29.15 exception report Traceback (most recent call first): File "/usr/lib/python3.6/site-packages/gi/overrides/BlockDev.py", line 869, in wrapped raise transform[1](msg) 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/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/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/actionlist.py", line 327, in process action.execute(callbacks) 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/blivet.py", line 135, in do_it self.devicetree.actions.process(callbacks=callbacks, devices=self.devices) 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 1222, 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/pyanaconda/storage/osinstall.py", line 2142, in turn_on_filesystems storage.do_it(callbacks) 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/installation_tasks.py", line 472, in start self.run_task() 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.py", line 369, in doInstall installation_queue.start() 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/threading.py", line 291, in run threading.Thread.run(self) gi.overrides.BlockDev.LVMError: Failed to call the 'PvCreate' method on the '/com/redhat/lvmdbus1/Manager' object: Timeout was reached Additional info: addons: com_redhat_kdump, com_redhat_docker blivet-gui-utils.log: cmdline: /usr/bin/python3 /sbin/anaconda cmdline_file: ip=eth0:dhcp inst.repo=https://kojipkgs.fedoraproject.org/compose/rawhide//Fedora-Rawhide-20180603.n.1/compose/Everything/armhfp/os/ inst.stage2=nfs://192.168.0.99:/srv/fedora/fedora-release/Fedora-Rawhide-20180603.n.1/everything/armhfp/ executable: /sbin/anaconda hashmarkername: anaconda kernel: 4.17.0-0.rc7.git2.1.fc29.armv7hl product: Fedora release: Cannot get release name. type: anaconda version: rawhide
Created attachment 1447890 [details] File: anaconda-tb
Created attachment 1447891 [details] File: anaconda.log
Created attachment 1447892 [details] File: dnf.librepo.log
Created attachment 1447893 [details] File: environ
Created attachment 1447894 [details] File: hawkey.log
Created attachment 1447895 [details] File: lorax-packages.log
Created attachment 1447896 [details] File: lsblk_output
Created attachment 1447897 [details] File: lvm.log
Created attachment 1447898 [details] File: nmcli_dev_list
Created attachment 1447899 [details] File: os_info
Created attachment 1447900 [details] File: program.log
Created attachment 1447901 [details] File: storage.log
Created attachment 1447902 [details] File: syslog
Created attachment 1447903 [details] File: ifcfg.log
Created attachment 1447904 [details] File: packaging.log
Looks like some issue with the LVM DBUS API Blivet uses, so reassigning to Blivet for further triage.
*** Bug 1589871 has been marked as a duplicate of this bug. ***
Happens on low end systems with 2048M RAM when doing graphical or VNC installs. Reproduced on x86_64 and aarch64
Similar problem has been detected: Tried to run rawhide server iso created on July 2, 2018. Pretty much default settings. addons: com_redhat_docker, com_redhat_kdump blivet-gui-utils.log: cmdline: /usr/bin/python3 /sbin/anaconda cmdline_file: BOOT_IMAGE=vmlinuz initrd=initrd.img inst.stage2=hd:LABEL=Fedora-S-dvd-x86_64-rawh rd.live.check quiet hashmarkername: anaconda kernel: 4.18.0-0.rc2.git4.1.fc29.x86_64 package: anaconda-29.19 product: Fedora reason: gi.overrides.BlockDev.LVMError: Failed to call the 'PvCreate' method on the '/com/redhat/lvmdbus1/Manager' object: Timeout was reached release: Cannot get release name. version: rawhide
I tried with the Basic graphical option under the troubleshooting' menu during the boot process. For me it seems to be installing normally now. I am running this installation on VirtualBox and it is configure with less than 2GB of RAM. I am not using a encrypted Hard Drive.
I tried to duplicate this with Virtualbox using the following install images: Fedora-Server-netinst-x86_64-Rawhide-20180710.n.0.iso Fedora-Workstation-Live-x86_64-Rawhide-20180710.n.0.iso Fedora-Workstation-netinst-x86_64-Rawhide-20180710.n.0.iso Note: Fedora-Workstation-Live-x86_64-Rawhide-20180710.n.0.iso does not appear to have /usr/sbin/lvmdbusd available, so unable to test. I used 1024 memory, which resulted in a failure to exec `udevadm settle` with an out of memory error. I then tried 2048 & 1536 MiB with default encrypted disk layout, and the storage setup worked. Would it be possible to get lvmdbusd started with--debug added during install? Just need to add to lvm2-lvmdbusd.service. Looking at the available logs, I don't see anything obvious. Libblockdev is setting a 5 second socket timeout and passing a 1 second API timeout to the lvmdbusd service. So the service should be returning a job object if it takes longer than 1 second. However, I don't know how accurate the timer support is in python when running in a VM or on ARM hardware, but I would think that we could get back to the caller within 5 seconds.
Actually the problem is that lvmdbusd takes too long to start -- the PvCreate call is the first use of the API so the service is not running and it takes 8 seconds to start. -------- 13:12:49,171 INFO dbus-daemon:[system] Activating via systemd: service name='com.redhat.lvmdbus1' unit='lvm2-lvmdbusd.service' requested by ':1.7' (uid=0 pid=1673 comm="/usr/bin/python3 /sbin/anaconda " label="system_u:system_r:initrc_t:s0") 13:12:49,336 INFO systemd:Starting LVM2 D-Bus service... 13:12:57,934 INFO dbus-daemon:[system] Successfully activated service 'com.redhat.lvmdbus1' 13:12:57,960 NOTICE audit:SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=lvm2-lvmdbusd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' 13:12:57,976 INFO systemd:Started LVM2 D-Bus service. --------
Upstream PR: https://github.com/storaged-project/libblockdev/pull/375
This bug appears to have been reported against 'rawhide' during the Fedora 29 development cycle. Changing version to '29'.
*** Bug 1588697 has been marked as a duplicate of this bug. ***