Bug 1956088 - kickstart dies due to OOM killer on minimum recommended 2048MB RAM
Summary: kickstart dies due to OOM killer on minimum recommended 2048MB RAM
Keywords:
Status: NEW
Alias: None
Product: Fedora
Classification: Fedora
Component: anaconda
Version: 34
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Anaconda Maintenance Team
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2021-05-02 16:34 UTC by Gabriel Somlo
Modified: 2021-05-14 16:45 UTC (History)
7 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed:
Type: Bug


Attachments (Terms of Use)
kickstart file used to illustrate the problem (2.14 KB, text/plain)
2021-05-02 16:34 UTC, Gabriel Somlo
no flags Details
logs collected from the crashed installation (322.45 KB, application/gzip)
2021-05-03 23:57 UTC, Gabriel Somlo
no flags Details

Description Gabriel Somlo 2021-05-02 16:34:16 UTC
Created attachment 1778610 [details]
kickstart file used to illustrate the problem

Description of problem:
installer is killed by OOM during kickstart package installation

Version-Release number of selected component (if applicable):
using Fedora-Everything-netinst-x86_64-34-1.2.iso

How reproducible:
kickstart a VM with 2048MB using the attached kickstart file

Steps to Reproduce:
1. create VM (e.g. in virt-manager, using "fedora-uknown" template) with minimum recommended 2048MB RAM
2. kickstart via "inst.ks=http://path.to.attached/test34.ks
3. watch (text-mode) process during package installation

Actual results:
installer appears to "restart" unexpectedly. Watching anaconda.log and other logs in /tmp/*.log from additional consoles will catch an OOM-killer entry flying by.

Expected results:
installation completes successfully.

Additional info:
increasing the RAM on the VM to 4096MB will make the problem go away.

Comment 1 Vendula Poncova 2021-05-03 09:56:47 UTC
Hello, I wasn't able to reproduce the issue. Could you attach the logs from the killed installation, please?

Comment 2 Gabriel Somlo 2021-05-03 23:57:24 UTC
Created attachment 1779160 [details]
logs collected from the crashed installation

Comment 3 Gabriel Somlo 2021-05-03 23:59:07 UTC
Sorry, after trying again, I can only reproduce it if I use `autopart`. The kickstart I provided has:

#autopart --type=lvm --nohome
part /boot --fstype=ext4 --recommended
part pv.0 --fstype=lvmpv --size=1 --grow
volgroup vg.0 --pesize=4096 pv.0
logvol swap  --fstype=swap --name=swap --vgname=vg.0 --recommended
logvol /  --fstype=ext4 --name=root --vgname=vg.0 --size=1 --grow

but if I uncomment the `autopart` line and comment all the others, the problem can be reliably reproduced.

I am attaching logs from the crashed install, hopefully there's something useful in there. I *thought* I saw something about OOM in there the other day, but now I'm unable to grep for it.

Thanks for looking into it!

Comment 4 Martin Kolman 2021-05-14 16:01:10 UTC
OK, this is super strange, the syslog file in the attached log bundle does include the OOM activating and killing Anaconda - twice! :P

First case:

23:48:24,217 WARNING kernel:Mem-Info:
23:48:24,217 WARNING kernel:active_anon:16271 inactive_anon:33498 isolated_anon:0#012 active_file:34 inactive_file:78 isolated_file:0#012 unevictable:6951 dirty:0 writeback:0#012 slab_reclaimable:8006 slab_unreclaimable:16078#012 mapped:2486 shmem:17688 pagetables:1341 bounce:0#012 free:14113 free_pcp:422 free_cma:0
23:48:24,217 WARNING kernel:Node 0 active_anon:65084kB inactive_anon:133992kB active_file:136kB inactive_file:312kB unevictable:27804kB isolated(anon):0kB isolated(file):0kB mapped:9944kB dirty:0kB writeback:0kB shmem:70752kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:3040kB pagetables:5364kB all_unreclaimable? yes
23:48:24,217 WARNING kernel:Node 0 DMA free:7864kB min:368kB low:460kB high:552kB reserved_highatomic:0KB active_anon:788kB inactive_anon:1800kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
23:48:24,217 WARNING kernel:lowmem_reserve[]: 0 1874 1874 1874 1874
23:48:24,217 WARNING kernel:Node 0 DMA32 free:48588kB min:44684kB low:55852kB high:67020kB reserved_highatomic:4096KB active_anon:64296kB inactive_anon:132192kB active_file:136kB inactive_file:312kB unevictable:27804kB writepending:0kB present:2080624kB managed:2008224kB mlocked:27804kB bounce:0kB free_pcp:1688kB local_pcp:20kB free_cma:0kB
23:48:24,217 WARNING kernel:lowmem_reserve[]: 0 0 0 0 0
23:48:24,217 WARNING kernel:Node 0 DMA: 10*4kB (UME) 8*8kB (UME) 7*16kB (UE) 5*32kB (UME) 7*64kB (UME) 5*128kB (UME) 3*256kB (UME) 1*512kB (U) 1*1024kB (E) 0*2048kB 1*4096kB (U) = 7864kB
23:48:24,217 WARNING kernel:Node 0 DMA32: 581*4kB (UME) 293*8kB (UE) 191*16kB (UE) 69*32kB (UE) 38*64kB (UE) 23*128kB (UE) 16*256kB (UME) 9*512kB (UM) 6*1024kB (UME) 3*2048kB (M) 3*4096kB (ME) = 48588kB
23:48:24,217 INFO kernel:Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB
23:48:24,217 INFO kernel:Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
23:48:24,218 WARNING kernel:21909 total pagecache pages
23:48:24,218 WARNING kernel:1815 pages in swap cache
23:48:24,218 WARNING kernel:Swap cache stats: add 995384, delete 993570, find 196438/245609
23:48:24,218 WARNING kernel:Free swap  = 0kB
23:48:24,218 WARNING kernel:Total swap = 2023420kB
23:48:24,218 WARNING kernel:524154 pages RAM
23:48:24,218 WARNING kernel:0 pages HighMem/MovableOnly
23:48:24,218 WARNING kernel:18121 pages reserved
23:48:24,218 WARNING kernel:0 pages cma reserved
23:48:24,218 WARNING kernel:0 pages hwpoisoned
23:48:24,218 INFO kernel:Tasks state (memory values in pages):
23:48:24,218 INFO kernel:[  pid  ]   uid  tgid total_vm      rss pgtables_bytes swapents oom_score_adj name
23:48:24,218 INFO kernel:[   1512]     0  1512     9830     1152    81920      240          -250 systemd-journal
23:48:24,218 INFO kernel:[   1525]     0  1525     8323      788    86016      834         -1000 systemd-udevd
23:48:24,218 INFO kernel:[   1593]     0  1593    88729     6788   114688        0         -1000 multipathd
23:48:24,218 INFO kernel:[   1659]     0  1659    40102      437    73728      237             0 rngd
23:48:24,225 INFO kernel:[   1660]     0  1660    60335      793   114688      285             0 rsyslogd
23:48:24,225 INFO kernel:[   1671]     0  1671     4784      638    86016      607             0 systemd-logind
23:48:24,225 INFO kernel:[   1675]     0  1675   119532      482   135168      603             0 NetworkManager
23:48:24,225 INFO kernel:[   1678]    81  1678     2426      851    57344      187          -900 dbus-broker-lau
23:48:24,225 INFO kernel:[   1691]    81  1691     1424      658    49152      126          -900 dbus-broker
23:48:24,225 INFO kernel:[   1702]   193  1702     7820      507   110592     2184             0 systemd-resolve
23:48:24,225 INFO kernel:[   1726]     0  1726    56198      704    57344      353             0 tmux: server
23:48:24,225 INFO kernel:[   1738]     0  1738   132585     2001   634880    47030             0 anaconda
23:48:24,225 INFO kernel:[   1739]     0  1739    57463      466    69632      162             0 bash
23:48:24,225 INFO kernel:[   1740]     0  1740    55184      339    61440       17             0 tail
23:48:24,225 INFO kernel:[   1741]     0  1741    55184      331    65536       17             0 tail
23:48:24,225 INFO kernel:[   1742]     0  1742    55184      334    65536       16             0 tail
23:48:24,225 INFO kernel:[   1743]     0  1743    55619      472    61440       87             0 tmux: client
23:48:24,225 INFO kernel:[   1760]     0  1760      650      107    40960       30         -1000 auditd
23:48:24,225 INFO kernel:[   1761]     0  1761     3248      814    69632      243             0 dbus-daemon
23:48:24,225 INFO kernel:[   1764]     0  1764    76877     1047   200704     6876             0 python3
23:48:24,225 INFO kernel:[   1785]     0  1785    76595     1051   196608     6598             0 python3
23:48:24,225 INFO kernel:[   1788]     0  1788    74636      975   196608     6608             0 python3
23:48:24,225 INFO kernel:[   1794]     0  1794    77329      969   204800     7337             0 python3
23:48:24,225 INFO kernel:[   1796]     0  1796    80732     1114   229376    10527             0 python3
23:48:24,226 INFO kernel:[   1798]     0  1798    83516     1067   253952    13556             0 python3
23:48:24,226 INFO kernel:[   1799]     0  1799    81026     1018   221184     7193             0 python3
23:48:24,226 INFO kernel:[   1801]     0  1801    74473     1005   192512     6550             0 python3
23:48:24,226 INFO kernel:[   1803]     0  1803    81543     1008   253952    13571             0 python3
23:48:24,226 INFO kernel:[   1834]     0  1834   138469      697   147456     3546             0 lvmdbusd
23:48:24,226 INFO kernel:[   1860]     0  1860    98799      900   131072      555             0 udisksd
23:48:24,226 INFO kernel:[   1864]   996  1864   627580      697   192512     1048             0 polkitd
23:48:24,226 INFO kernel:[   1946]   995  1946    21470      405    77824      171             0 chronyd
23:48:24,226 INFO kernel:[   2237]     0  2237    57463      482    61440      163             0 bash
23:48:24,226 INFO kernel:[   2254]     0  2254    56993       45    53248       30             0 tail
23:48:24,226 INFO kernel:[   2255]     0  2255   136642     9823   655360    43357             0 anaconda
23:48:24,226 INFO kernel:[   2257]     0  2257    57463      325    65536      164             0 bash
23:48:24,226 INFO kernel:[   2272]     0  2272    56993       58    61440       30             0 tail
23:48:24,226 INFO kernel:[   2274]     0  2274    57463      456    69632      161             0 bash
23:48:24,226 INFO kernel:[   2289]     0  2289    56993       57    57344       16             0 tail
23:48:24,226 INFO kernel:[   2923]     0  2923     1726       56    45056        0             0 sh
23:48:24,226 INFO kernel:[   2924]     0  2924    20902    19815   212992        0             0 semodule
23:48:24,226 INFO kernel:oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/system.slice/anaconda.service,task=anaconda,pid=2255,uid=0
23:48:24,226 ERR kernel:Out of memory: Killed process 2255 (anaconda) total-vm:546568kB, anon-rss:35476kB, file-rss:3808kB, shmem-rss:8kB, UID:0 pgtables:640kB oom_score_adj:0
23:48:24,226 INFO kernel:oom_reaper: reaped process 2255 (anaconda), now anon-rss:0kB, file-rss:0kB, shmem-rss:8kB


Second case, 10 seconds later:


23:48:34,252 WARNING kernel:Mem-Info:
23:48:34,252 WARNING kernel:active_anon:16231 inactive_anon:32922 isolated_anon:0#012 active_file:1 inactive_file:183 isolated_file:15#012 unevictable:6951 dirty:0 writeback:0#012 slab_reclaimable:7968 slab_unreclaimable:16002#012 mapped:2459 shmem:16194 pagetables:1203 bounce:0#012 free:14101 free_pcp:117 free_cma:0
23:48:34,252 WARNING kernel:Node 0 active_anon:64924kB inactive_anon:131688kB active_file:4kB inactive_file:732kB unevictable:27804kB isolated(anon):0kB isolated(file):60kB mapped:9836kB dirty:0kB writeback:0kB shmem:64776kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:3008kB pagetables:4812kB all_unreclaimable? yes
23:48:34,252 WARNING kernel:Node 0 DMA free:7840kB min:368kB low:460kB high:552kB reserved_highatomic:0KB active_anon:732kB inactive_anon:1920kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15908kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
23:48:34,252 WARNING kernel:lowmem_reserve[]: 0 1874 1874 1874 1874
23:48:34,252 WARNING kernel:Node 0 DMA32 free:48564kB min:44684kB low:55852kB high:67020kB reserved_highatomic:4096KB active_anon:64192kB inactive_anon:129768kB active_file:4kB inactive_file:640kB unevictable:27804kB writepending:0kB present:2080624kB managed:2008224kB mlocked:27804kB bounce:0kB free_pcp:468kB local_pcp:216kB free_cma:0kB
23:48:34,252 WARNING kernel:lowmem_reserve[]: 0 0 0 0 0
23:48:34,252 WARNING kernel:Node 0 DMA: 6*4kB (UME) 7*8kB (UE) 7*16kB (UE) 5*32kB (UME) 7*64kB (UME) 5*128kB (UME) 3*256kB (UME) 1*512kB (U) 1*1024kB (E) 0*2048kB 1*4096kB (U) = 7840kB
23:48:34,252 WARNING kernel:Node 0 DMA32: 691*4kB (UE) 345*8kB (UME) 198*16kB (UME) 74*32kB (UME) 38*64kB (UE) 24*128kB (UE) 11*256kB (UME) 9*512kB (UM) 6*1024kB (UME) 3*2048kB (M) 3*4096kB (ME) = 48564kB
23:48:34,253 INFO kernel:Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB
23:48:34,253 INFO kernel:Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
23:48:34,253 WARNING kernel:19250 total pagecache pages
23:48:34,253 WARNING kernel:568 pages in swap cache
23:48:34,253 WARNING kernel:Swap cache stats: add 997567, delete 997000, find 196475/245939
23:48:34,253 WARNING kernel:Free swap  = 0kB
23:48:34,253 WARNING kernel:Total swap = 2023420kB
23:48:34,253 WARNING kernel:524154 pages RAM
23:48:34,253 WARNING kernel:0 pages HighMem/MovableOnly
23:48:34,253 WARNING kernel:18121 pages reserved
23:48:34,253 WARNING kernel:0 pages cma reserved
23:48:34,253 WARNING kernel:0 pages hwpoisoned
23:48:34,253 INFO kernel:Tasks state (memory values in pages):
23:48:34,253 INFO kernel:[  pid  ]   uid  tgid total_vm      rss pgtables_bytes swapents oom_score_adj name
23:48:34,253 INFO kernel:[   1512]     0  1512     9830     1070    81920      264          -250 systemd-journal
23:48:34,253 INFO kernel:[   1525]     0  1525     8323      788    86016      834         -1000 systemd-udevd
23:48:34,253 INFO kernel:[   1593]     0  1593    88729     6788   114688        0         -1000 multipathd
23:48:34,253 INFO kernel:[   1659]     0  1659    40102      437    73728      244             0 rngd
23:48:34,253 INFO kernel:[   1660]     0  1660    60335      785   114688      273             0 rsyslogd
23:48:34,254 INFO kernel:[   1671]     0  1671     4784      804    86016      620             0 systemd-logind
23:48:34,254 INFO kernel:[   1675]     0  1675   119532      707   135168      655             0 NetworkManager
23:48:34,254 INFO kernel:[   1678]    81  1678     2426      851    57344      187          -900 dbus-broker-lau
23:48:34,254 INFO kernel:[   1691]    81  1691     1424      592    49152      195          -900 dbus-broker
23:48:34,254 INFO kernel:[   1702]   193  1702     7820      507   110592     2184             0 systemd-resolve
23:48:34,254 INFO kernel:[   1726]     0  1726    56198      602    57344      363             0 tmux: server
23:48:34,254 INFO kernel:[   1738]     0  1738   132585     2259   634880    47038             0 anaconda
23:48:34,254 INFO kernel:[   1739]     0  1739    57463      466    69632      162             0 bash
23:48:34,254 INFO kernel:[   1740]     0  1740    55184      285    61440       17             0 tail
23:48:34,254 INFO kernel:[   1741]     0  1741    55184      280    65536       17             0 tail
23:48:34,254 INFO kernel:[   1742]     0  1742    55184      269    65536       16             0 tail
23:48:34,254 INFO kernel:[   1743]     0  1743    55619      472    61440       87             0 tmux: client
23:48:34,257 INFO kernel:[   1760]     0  1760      650      107    40960       30         -1000 auditd
23:48:34,257 INFO kernel:[   1761]     0  1761     3248      814    69632      243             0 dbus-daemon
23:48:34,257 INFO kernel:[   1764]     0  1764    76877     1047   200704     6876             0 python3
23:48:34,258 INFO kernel:[   1785]     0  1785    76595     1051   196608     6598             0 python3
23:48:34,258 INFO kernel:[   1788]     0  1788    74636      975   196608     6608             0 python3
23:48:34,258 INFO kernel:[   1794]     0  1794    77329      969   204800     7337             0 python3
23:48:34,258 INFO kernel:[   1796]     0  1796    80732     1114   229376    10527             0 python3
23:48:34,258 INFO kernel:[   1798]     0  1798    83516     1067   253952    13556             0 python3
23:48:34,258 INFO kernel:[   1799]     0  1799    81026     1018   221184     7193             0 python3
23:48:34,258 INFO kernel:[   1801]     0  1801    74473     1005   192512     6550             0 python3
23:48:34,258 INFO kernel:[   1803]     0  1803    81543     1008   253952    13571             0 python3
23:48:34,258 INFO kernel:[   1834]     0  1834   138469      667   147456     3585             0 lvmdbusd
23:48:34,258 INFO kernel:[   1860]     0  1860    98799      900   131072      555             0 udisksd
23:48:34,258 INFO kernel:[   1864]   996  1864   627580      697   192512     1048             0 polkitd
23:48:34,259 INFO kernel:[   1946]   995  1946    21470      392    77824      193             0 chronyd
23:48:34,259 INFO kernel:[   2237]     0  2237    57463      482    61440      163             0 bash
23:48:34,259 INFO kernel:[   2254]     0  2254    56993       45    53248       30             0 tail
23:48:34,259 INFO kernel:[   2257]     0  2257    57463      325    65536      164             0 bash
23:48:34,259 INFO kernel:[   2272]     0  2272    56993       58    61440       30             0 tail
23:48:34,259 INFO kernel:[   2274]     0  2274    57463      456    69632      161             0 bash
23:48:34,259 INFO kernel:[   2289]     0  2289    56993       57    57344       16             0 tail
23:48:34,259 INFO kernel:[   2923]     0  2923     1726       56    45056        0             0 sh
23:48:34,259 INFO kernel:[   2924]     0  2924    31117    29934   294912        0             0 semodule
23:48:34,259 INFO kernel:oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/system.slice/anaconda.service,task=anaconda,pid=1738,uid=0
23:48:34,259 ERR kernel:Out of memory: Killed process 1738 (anaconda) total-vm:530340kB, anon-rss:4872kB, file-rss:4156kB, shmem-rss:8kB, UID:0 pgtables:620kB oom_score_adj:0
23:48:34,259 INFO kernel:oom_reaper: reaped process 1738 (anaconda), now anon-rss:0kB, file-rss:0kB, shmem-rss:8kB


After the second OOM kill the Anaconda system unit reports it's process has been killed:


23:48:34,268 NOTICE systemd:anaconda.service: A process of this unit has been killed by the OOM killer.
23:48:34,887 NOTICE systemd:anaconda-tmux@tty1.service: Main process exited, code=exited, status=1/FAILURE
23:48:34,888 WARNING systemd:anaconda-tmux@tty1.service: Failed with result 'exit-code'.


Not really sure what exactly was killed, but each of those distinct processes seems to have been consuming about 500 MB of RAM (so about 1 GB of the 2 GB available to the VM). I would assume one of those could have been the GUI process but even for that 500 MB seems excessive.
Also the "python3" processes in the table are likely our DBus modules, so I would rather expect the storage DBus module to leak memory and get killed if using autopart seems to be involved but that did not happen.


Also to get things even more confusing, it looks like the Anaconda unit restarts (?!) a short while later:


23:48:45,666 WARNING systemd:anaconda.service: Failed with result 'oom-kill'.
23:48:45,667 NOTICE systemd:anaconda.service: Consumed 5min 3.259s CPU time.
23:48:45,667 NOTICE audit:SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
23:48:45,668 NOTICE kernel:audit: type=1131 audit(1620085725.665:318): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
23:48:45,671 INFO dbus-broker-launch:avc:  op=load_policy lsm=selinux seqno=3 res=1
23:48:45,727 INFO systemd:Starting Anaconda...
23:48:46,011 INFO systemd:Started Anaconda.
23:48:46,011 INFO systemd:Condition check resulted in Restrict Anaconda Text Console being skipped.
23:48:46,011 NOTICE audit:SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
23:48:46,011 NOTICE kernel:audit: type=1130 audit(1620085726.007:319): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
23:48:46,034 INFO systemd:Started Anaconda Text Console.
23:48:46,039 NOTICE audit:SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda-tmux@tty1 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
23:48:46,039 NOTICE kernel:audit: type=1130 audit(1620085726.037:320): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=anaconda-tmux@tty1 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
23:48:46,611 NOTICE audit:BPF prog-id=56 op=LOAD
23:48:46,611 NOTICE audit:BPF prog-id=57 op=LOAD
23:48:46,616 INFO systemd:Starting Hostname Service...
23:48:46,786 INFO systemd:Started Hostname Service.
23:48:46,792 NOTICE audit:SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
23:48:50,905 NOTICE audit:CONFIG_CHANGE op=set audit_pid=3160 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
23:48:50,906 NOTICE audit:SYSCALL arch=c000003e syscall=44 success=yes exit=60 a0=0 a1=7ffd9faffac0 a2=3c a3=0 items=0 ppid=1 pid=3160 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=pts0 ses=4294967295 comm="auditd" exe="/usr/sbin/auditd" subj=system_u:system_r:kernel_t:s0 key=(null)
23:48:50,906 NOTICE audit:PROCTITLE proctitle="/sbin/auditd"
23:48:50,906 NOTICE audit:CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
23:48:50,906 NOTICE audit:SYSCALL arch=c000003e syscall=44 success=yes exit=60 a0=0 a1=7ffd9fb01e10 a2=3c a3=0 items=0 ppid=1 pid=3160 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=pts0 ses=4294967295 comm="auditd" exe="/usr/sbin/auditd" subj=system_u:system_r:kernel_t:s0 key=(null)
23:48:50,906 NOTICE audit:PROCTITLE proctitle="/sbin/auditd"
23:48:50,954 INFO dbus-daemon:Activating service name='org.fedoraproject.Anaconda.Boss' requested by ':1.0' (uid=0 pid=3137 comm="/usr/bin/python3 /sbin/anaconda " label="system_u:system_r:kernel_t:s0")
23:48:51,247 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:anaconda.modules.common:The configuration is loaded from: ['/run/anaconda/anaconda.conf']
23:48:51,455 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:anaconda.modules.common.base.base:Publish the service.
23:48:51,456 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:dasbus.connection:Publishing an object at /org/fedoraproject/Anaconda/Boss/UserInterface.
23:48:51,457 WARNING org.fedoraproject.Anaconda.Boss:INFO:anaconda.core.dbus:Connecting to the Anaconda bus at unix:abstract=/tmp/dbus-hNbjUszFw6,guid=1a77981b0185572eb14ca00d60908be2.
23:48:51,462 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:dasbus.connection:Publishing an object at /org/fedoraproject/Anaconda/Boss.
23:48:51,463 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:dasbus.connection:Registering a service name org.fedoraproject.Anaconda.Boss.
23:48:51,468 INFO dbus-daemon:Successfully activated service 'org.fedoraproject.Anaconda.Boss'
23:48:51,469 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:anaconda.modules.common.base.base:Start the loop.
23:48:51,473 WARNING org.fedoraproject.Anaconda.Boss:DEBUG:dasbus.connection:Publishing an object at /org/fedoraproject/Anaconda/Boss/Task/1.
23:48:51,478 WARNING org.fedoraproject.Anaconda.Boss:INFO:anaconda.threading:Running Thread: AnaTaskThread-StartModulesTask-1 (140232771819072)

Comment 5 Gabriel Somlo 2021-05-14 16:45:42 UTC
The observable behavior is that the installer (anaconda?) appears to hang during package installation (past two thirds of packages, approximately), then restarts. If I attempt to follow through on the restarted installer, I get all sorts of additional errors, but that's to be expected, since it's in a pretty badly broken state by that point.

The interesting thing is that the whole broken behavior only occurs when `autopart` is selected -- manual partitioning won't trigger the buggy behavior. I do have another `autopart` related bug -- 1832570 -- which may or may not be completely unrelated to this one. Just figured I'd mention it, since it's an interesting coincidence...


Note You need to log in before you can comment on or make changes to this bug.