subiquity

Ubuntu 20.04.5 server fail to install on Dell R840

Bug #1995005 reported by Charles Prince on 2022-10-27

This bug affects 1 person

Affects		Status	Importance	Assigned to	Milestone
	subiquity	Expired	Undecided	Unassigned

Bug Description

During the Integrity check these issues came up:

integrity check: blk_update_request: dev I/O error, dev sr0, sector 2687268 op 0x0 (READ) flags 0x80700 phys_seq 2 prio class 0

integrity check: blk_update_request: dev I/O error, dev sr0, sector 2687524 op 0x0 (READ) flags 0x80700 phys_seq 2 prio class 0

integrity check: blk_update_request: dev I/O error, dev sr0, sector 2687444 op 0x0 (READ) flags 0x0 phys_seq 1 prio class 0

1) I have passed this to Dell too and awaiting back to hear from them, I really do not know whos bug it is.

2) I am not sure if my files are attaching correctly in this web interface, so here are some of the items that I noticed int the logs, not sure if it is helpful:

syslog:
Oct 26 15:37:34 ubuntu-server systemd[1]: Condition check resulted in Process error reports when automatic reporting is enabled (file watch) being skipped.
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359219] pci 0000:25:00.0: BAR 6: failed to assign [mem size 0x00100000 pref]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359329] pci 0000:5b:00.0: BAR 6: failed to assign [mem size 0x00200000 pref]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359330] pci 0000:5b:00.1: BAR 6: failed to assign [mem size 0x00200000 pref]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359405] pci 0000:87:01.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359409] pci 0000:87:00.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359496] pci 0000:9a:01.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359498] pci 0000:9a:02.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359500] pci 0000:9a:03.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359504] pci 0000:9a:02.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359506] pci 0000:9a:01.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359507] pci 0000:9a:00.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359675] pci 0000:c7:01.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359677] pci 0000:c7:02.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359679] pci 0000:c7:03.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359682] pci 0000:c7:02.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359684] pci 0000:c7:01.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359686] pci 0000:c7:00.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359824] pci 0000:da:03.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 3.359827] pci 0000:da:02.0: BAR 13: failed to assign [io size 0x1000]
Oct 26 15:37:34 ubuntu-server kernel: [ 66.918274] sr 2:0:0:0: [sr0] tag#22 Add. Sense: L-EC uncorrectable error
Oct 26 15:37:34 ubuntu-server kernel: [ 66.918285] blk_update_request: I/O error, dev sr0, sector 2687268 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 0
Oct 26 15:37:34 ubuntu-server kernel: [ 74.394237] sr 2:0:0:0: [sr0] tag#0 Add. Sense: L-EC uncorrectable error
Oct 26 15:37:34 ubuntu-server kernel: [ 74.394246] blk_update_request: I/O error, dev sr0, sector 2687524 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 0
Oct 26 15:37:34 ubuntu-server kernel: [ 82.898257] sr 2:0:0:0: [sr0] tag#21 Add. Sense: L-EC uncorrectable error
Oct 26 15:37:34 ubuntu-server kernel: [ 82.898267] blk_update_request: I/O error, dev sr0, sector 2687444 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
Oct 26 15:37:34 ubuntu-server kernel: [ 408.541166] ACPI Error: Aborting method \_SB.PMI0._GHL due to previous error (AE_NOT_EXIST) (20190816/psparse-529)
Oct 26 15:37:34 ubuntu-server kernel: [ 408.541810] ACPI Error: Aborting method \_SB.PMI0._PMC due to previous error (AE_NOT_EXIST) (20190816/psparse-529)
Oct 26 15:37:34 ubuntu-server kernel: [ 409.373542] mei 0000:00:16.0-55213584-9a29-4916-badf-0fb7ed682aeb: FW version command failed -5
Oct 26 15:37:35 ubuntu-server udisksd[3063]: failed to load module mdraid: libbd_mdraid.so.2: cannot open shared object file: No such file or directory
Oct 26 15:37:35 ubuntu-server pollinate[3050]: WARNING: Network communication failed [6] % Total % Received % Xferd Average Speed Time Time Time Current#012 Dload Upload Total Spent Left Speed#012#015 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 015:37:35.457170 * Could not resolve host: entropy.ubuntu.com#01215:37:35.457204 * Closing connection 0#012curl: (6) Could not resolve host: entropy.ubuntu.com
Oct 26 15:37:48 ubuntu-server kernel: [ 434.143342] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Oct 26 15:37:48 ubuntu-server kernel: [ 434.143483] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Oct 26 15:37:48 ubuntu-server kernel: [ 434.144428] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
Oct 26 15:37:48 ubuntu-server kernel: [ 434.144431] cfg80211: failed to load regulatory.db
Oct 26 15:38:53 ubuntu-server kernel: [ 498.907086] overlayfs: lowerdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior.

kernlog:
2022-10-26 15:38:59,698 DEBUG subiquity.client.client:569 show_error_report '1666798739.683290958.install_fail'
2022-10-26 15:39:02,760 INFO subiquity/ErrorReporter/1666798739.683290958.install_fail/load:107 start:
2022-10-26 15:39:12,981 DEBUG subiquity.ui.help:410 open help menu
2022-10-26 15:39:16,654 DEBUG subiquitycore.utils:112 astart_command called: ['bash']
2022-10-26 15:57:31,596 INFO subiquity/ErrorReporter/1666798739.683290958.install_fail/load:112 finish: SUCCESS

subiquity-server-debug.log.4343:
2022-10-26 15:38:53,235 DEBUG subiquitycore.utils:112 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmp37hpzqjv/mount/cdrom']
2022-10-26 15:38:53,272 DEBUG subiquitycore.utils:112 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']
2022-10-26 15:38:54,007 DEBUG root:39 start: subiquity/Install/install/configure_apt/cmd-in-target: curtin command in-target
2022-10-26 15:38:59,682 ERROR root:39 finish: subiquity/Install/install/configure_apt: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,683 DEBUG subiquitycore.common.errorreport:384 generating crash report
2022-10-26 15:38:59,690 INFO subiquitycore.common.errorreport:406 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1666798739.683290958.install_fail.crash
2022-10-26 15:38:59,690 ERROR root:39 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,691 INFO root:39 start: subiquity/ErrorReporter/1666798739.683290958.install_fail/add_info:
2022-10-26 15:38:59,691 ERROR subiquity.server.server:416 top level error
Traceback (most recent call last):
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
    await Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 160, in install
    for_install_path = await self.configure_apt(context=context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 131, in configure_apt
    return await configurer.configure_for_install(context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/apt.py", line 225, in configure_for_install
    await run_curtin_command(
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 181, in run_curtin_command
    return await cmd.wait()
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 118, in wait
    result = await self.runner.wait(self.proc)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/runner.py", line 81, in wait
    raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,694 ERROR subiquity.server.server:416 top level error
Traceback (most recent call last):
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/shutdown.py", line 77, in _wait_install
    await self.app.controllers.Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
    await Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 160, in install
    for_install_path = await self.configure_apt(context=context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 131, in configure_apt
    return await configurer.configure_for_install(context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/apt.py", line 225, in configure_for_install
    await run_curtin_command(
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 181, in run_curtin_command
    return await cmd.wait()
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 118, in wait
    result = await self.runner.wait(self.proc)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/runner.py", line 81, in wait
    raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,696 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL...
2022-10-26 15:38:59,696 INFO aiohttp.access:233 [26/Oct/2022:15:38:53 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 538 "-" "Python/3.8 aiohttp/3.6.2"
2022-10-26 15:38:59,705 INFO root:39 start: subiquity/Meta/status_GET:
2022-10-26 15:38:59,706 INFO root:39 start: subiquity/Error/wait_GET:
2022-10-26 15:39:00,032 ERROR root:39 finish: subiquity/Install/install/configure_apt/cmd-in-target: FAIL: curtin command in-target
2022-10-26 15:39:02,755 INFO root:39 finish: subiquity/ErrorReporter/1666798739.683290958.install_fail/add_info: SUCCESS: written to /var/crash/1666798739.683290958.install_fail.crash

subiquity-server-info.log.4343:
2022-10-26 15:38:59,682 ERROR root:39 finish: subiquity/Install/install/configure_apt: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,690 INFO subiquitycore.common.errorreport:406 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1666798739.683290958.install_fail.crash
2022-10-26 15:38:59,690 ERROR root:39 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,691 INFO root:39 start: subiquity/ErrorReporter/1666798739.683290958.install_fail/add_info:
2022-10-26 15:38:59,691 ERROR subiquity.server.server:416 top level error
Traceback (most recent call last):
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
    await Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 160, in install
    for_install_path = await self.configure_apt(context=context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 131, in configure_apt
    return await configurer.configure_for_install(context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/apt.py", line 225, in configure_for_install
    await run_curtin_command(
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 181, in run_curtin_command
    return await cmd.wait()
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 118, in wait
    result = await self.runner.wait(self.proc)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/runner.py", line 81, in wait
    raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,694 ERROR subiquity.server.server:416 top level error
Traceback (most recent call last):
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/shutdown.py", line 77, in _wait_install
    await self.app.controllers.Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
    await Install.install_task
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 160, in install
    for_install_path = await self.configure_apt(context=context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
    return await meth(self, **kw)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 131, in configure_apt
    return await configurer.configure_for_install(context)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/apt.py", line 225, in configure_for_install
    await run_curtin_command(
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 181, in run_curtin_command
    return await cmd.wait()
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/curtin.py", line 118, in wait
    result = await self.runner.wait(self.proc)
  File "/snap/subiquity/3698/lib/python3.8/site-packages/subiquity/server/runner.py", line 81, in wait
    raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4343', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/3698/bin:/snap/subiquity/3698/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/3698/bin', '--setenv', 'PYTHONPATH=:/snap/subiquity/3698/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/3698/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/3698', '--', '/snap/subiquity/3698/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4343.2"}}', 'in-target', '-t', '/tmp/tmp37hpzqjv/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2022-10-26 15:38:59,696 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL...

Revision history for this message

Charles Prince (cvcachagua) wrote on 2022-10-27:

syslog Edit (372.2 KiB, text/plain)

Revision history for this message

Dan Bungert (dbungert) wrote on 2022-10-27:

Hi, thanks for the report.
With those errors, the install media may be corrupt, at time of download or maybe when writing to the media.

It would be best to retry those steps, starting over with a fresh download and checksum verifying that the result is correct, and proceed from there.

affects:	ubiquity (Ubuntu) → ubuntu
affects:	ubuntu → subiquity
Changed in subiquity:
status:	New → Incomplete

Revision history for this message

Charles Prince (cvcachagua) wrote on 2022-10-27:

logs.tgz Edit (144.9 KiB, application/x-tar)

The sha256sum is correct and verified.
The burn was verified as correct

I re-installed and did not get any errors in integrity.

I did get some errors in:
copying package lists...[ 280.039879] /dev/loop: Can't open blockdev
[...] ACPI Error: No handler for Region [SYSI] (000000005fbc1f75) [IPMI] 920190816/evregion-129)
[...] ACPI Error: Region IPMI(ID=7) has no handler (20190816/exfldio-261)
[...] ACPI Error: Aborting method \_SB.PMIO._GHL due to prvious error (AD_NOT_EXIST) (20190816/psparse-529)
[...] ACPI Error: Aborting method \_SB.PMIO._PMC due to previous error (AD_NOT_EXIST) (20190816/psparse-529)
[...] ACPI Error: AE_NOT_EXIST, Evaluating _PMC (20190816/power_meter-743)
[...] mei 0000:00:16.0-55213584-9a29-4916-badf-0fb7ed682aeb: Could not read FW version
[...] mei 0000:00:16.0-55213584-9a29-4916-badf-0fb7ed682aeb: FW version command failed -5

The GUI part then came up and I selected English... Standard storage, and while I was typing my user name for the user, an error message popped up.

I then proceeded to the shell and copied the log files off the system -- saved as logs.tgz attached.

Revision history for this message

Dan Bungert (dbungert) wrote on 2022-10-27:

Thanks for confirming the integrity check, and providing the logs.

Oct 27 13:29:43 ubuntu-server subiquity_log.4319[4700]: E: Release file for http://us.archive.ubuntu.com/ubuntu/dists/focal-updates/InRelease is not valid yet (invalid for another 5h 48min 30s). Updates for this repository will not be applied.
...
Oct 27 13:29:55 ubuntu-server systemd-timesyncd[2638]: Timed out waiting for reply from 91.189.91.157:123 (ntp.ubuntu.com).

I think the system being installed has a bad time set.
If you retry after manually fixing time on the system you may get better results.

Revision history for this message

Charles Prince (cvcachagua) wrote on 2022-10-28:

UbuntuLogsPostNTP.tgz Edit (146.9 KiB, application/x-tar)

I configured the server for NTP and provided a time server, but still cannot install.

Revision history for this message

Dan Bungert (dbungert) wrote on 2022-10-28:

Ah, the same outcome I'm afraid.

Oct 28 12:59:25 ubuntu-server systemd-timesyncd[2663]: Timed out waiting for reply from 91.189.91.157:123 (ntp.ubuntu.com).
Oct 28 12:59:28 ubuntu-server subiquity_log.4313[4715]: Reading package lists...
Oct 28 12:59:28 ubuntu-server subiquity_log.4313[4715]: E: Release file for http://us.archive.ubuntu.com/ubuntu/dists/focal-updates/InRelease is not valid yet (invalid for another 5h 38min 33s). Updates for this repository will not be applied.

Revision history for this message

Launchpad Janitor (janitor) wrote on 2022-12-28:

[Expired for subiquity because there has been no activity for 60 days.]

Changed in subiquity:
status:	Incomplete → Expired

Report a bug

This report contains Public information

Everyone can see this information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Bug attachments

Add attachment

Remote bug watches

Bug watches keep track of this bug in other bug trackers.