[Bug 1967458] Re: ubuntu 20.4.4 Server Autoinstall Error
Masato Yoshida
1967458 at bugs.launchpad.net
Sun Apr 3 09:54:25 UTC 2022
syslog
---------------------------------------
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[2479]: Copying curtin install log from /var/log/curtin/install.log to target//var/log/installer/curtin-install.log
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[2479]: Skipping unmount: config disabled target unmounting
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[2479]: TIMED INSTALL_COMMAND: 74.749
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[2479]: finish: cmd-install: SUCCESS: curtin command install
Apr 3 09:08:55 ubuntu-server curtin_event.2004.3[2479]: finish: cmd-install: SUCCESS: curtin command install
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install/cmd-install/stage-hook/builtin: running 'curtin hook'
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install/cmd-install/stage-hook: finalizing installation
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install/cmd-install/stage-late: executing late commands
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install/cmd-install/stage-late: executing late commands
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install/cmd-install: curtin command install
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/curtin_install: installing system
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall: final system configuration
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/configure_cloud_init: configuring cloud-init
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Meta/status_GET
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/configure_cloud_init: configuring cloud-init
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/get_target_packages: calculating extra packages to install
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/get_target_packages: calculating extra packages to install
Apr 3 09:08:55 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/install_bridge-utils: installing bridge-utils
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[15974]: start: cmd-system-install: curtin command system-install
Apr 3 09:08:55 ubuntu-server curtin_event.2004.4[15974]: start: cmd-system-install: curtin command system-install
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Apr 3 09:08:55 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--bind', '/sys/firmware/efi/efivars', '/target/sys/firmware/efi/efivars'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'eatmydata', 'apt-get', '--quiet', '--assume-yes', '--option=Dpkg::options::=--force-unsafe-io', '--option=Dpkg::Options::=--force-confold', 'install', 'bridge-utils'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15986]: Reading package lists...
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15986]: Building dependency tree...
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15986]: Reading state information...
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15986]: E: Unable to locate package bridge-utils
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: TIMED subp(['udevadm', 'settle']): 0.020
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--make-private', '/target/sys/firmware/efi/efivars'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['umount', '/target/sys/firmware/efi/efivars'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server systemd[1]: target-sys-firmware-efi-efivars.mount: Succeeded.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server systemd[1]: target-sys.mount: Succeeded.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server systemd[1]: target-run.mount: Succeeded.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server systemd[1]: target-proc.mount: Succeeded.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Apr 3 09:08:56 ubuntu-server systemd[1]: target-dev.mount: Succeeded.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: system install failed for ['bridge-utils']: Unexpected error while running command.
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Command: ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'eatmydata', 'apt-get', '--quiet', '--assume-yes', '--option=Dpkg::options::=--force-unsafe-io', '--option=Dpkg::Options::=--force-confold', 'install', 'bridge-utils']
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Exit code: 100
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Reason: -
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Stdout: ''
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: Stderr: ''
Apr 3 09:08:56 ubuntu-server subiquity_log.2004[15974]: finish: cmd-system-install: FAIL: curtin command system-install
Apr 3 09:08:56 ubuntu-server curtin_event.2004.4[15974]: finish: cmd-system-install: FAIL: curtin command system-install
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/install_bridge-utils: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/ErrorReporter/1648976936.147035599.install_fail/add_info
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/install_bridge-utils/cmd-system-install: curtin command system-install
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/Install/install/postinstall/install_bridge-utils/cmd-system-install: curtin command system-install
Apr 3 09:08:56 ubuntu-server subiquity_event.2004[2004]: subiquity/ErrorReporter/1648976936.147035599.install_fail/add_info: written to /var/crash/1648976936.147035599.install_fail.crash
---------------------------------------
subiquity-server-debug.log
---------------------------------------
2022-04-03 09:08:55,508 INFO root:39 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system
2022-04-03 09:08:55,509 INFO root:39 start: subiquity/Install/install/postinstall: final system configuration
2022-04-03 09:08:55,510 DEBUG subiquity.models.filesystem:1229 mountpoints {'/': 'mount-3', '/boot': 'mount-2', '/boot/efi': 'mount-1'}
2022-04-03 09:08:55,515 DEBUG root:39 start: subiquity/Install/install/postinstall/configure_cloud_init: configuring cloud-init
2022-04-03 09:08:55,530 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_RUNNING", "confirming_tty": "", "error": null, "cloud_init_ok...
2022-04-03 09:08:55,545 INFO aiohttp.access:233 [03/Apr/2022:09:07:38 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 417 "-" "Python/3.8 aiohttp/3.6.2"
2022-04-03 09:08:55,552 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group']
2022-04-03 09:08:55,553 INFO root:39 start: subiquity/Meta/status_GET:
2022-04-03 09:08:55,557 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0
2022-04-03 09:08:55,563 DEBUG root:39 finish: subiquity/Install/install/postinstall/configure_cloud_init: SUCCESS: configuring cloud-init
2022-04-03 09:08:55,563 DEBUG root:39 start: subiquity/Install/install/postinstall/get_target_packages: calculating extra packages to install
2022-04-03 09:08:55,564 DEBUG root:39 finish: subiquity/Install/install/postinstall/get_target_packages: SUCCESS: calculating extra packages to install
2022-04-03 09:08:55,564 DEBUG root:39 start: subiquity/Install/install/postinstall/install_bridge-utils: installing bridge-utils
2022-04-03 09:08:55,568 DEBUG subiquitycore.utils:94 astart_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2004', '/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']
2022-04-03 09:08:56,146 ERROR root:39 finish: subiquity/Install/install/postinstall/install_bridge-utils: FAIL: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
2022-04-03 09:08:56,146 ERROR root:39 finish: subiquity/Install/install/postinstall: FAIL: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
2022-04-03 09:08:56,146 DEBUG subiquitycore.common.errorreport:384 generating crash report
2022-04-03 09:08:56,169 INFO subiquitycore.common.errorreport:406 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1648976936.147035599.install_fail.crash
2022-04-03 09:08:56,169 ERROR root:39 finish: subiquity/Install/install: FAIL: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
2022-04-03 09:08:56,169 INFO root:39 start: subiquity/ErrorReporter/1648976936.147035599.install_fail/add_info:
2022-04-03 09:08:56,170 ERROR subiquity.server.server:414 top level error
Traceback (most recent call last):
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
await Install.install_task
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 169, in install
await self.postinstall(context=context)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 192, in postinstall
await self.install_package(context=context, package=package)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 212, in install_package
await run_curtin_command(
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/curtin.py", line 166, in run_curtin_command
await cmd.wait()
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/curtin.py", line 113, in wait
await self.runner.wait(self.proc)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/runner.py", line 37, in wait
raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
2022-04-03 09:08:56,171 ERROR subiquity.server.server:414 top level error
Traceback (most recent call last):
File "/snap/subiquity/3119/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/3119/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 104, in _run
await Install.install_task
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 169, in install
await self.postinstall(context=context)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 192, in postinstall
await self.install_package(context=context, package=package)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 212, in install_package
await run_curtin_command(
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/curtin.py", line 166, in run_curtin_command
await cmd.wait()
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/curtin.py", line 113, in wait
await self.runner.wait(self.proc)
File "/snap/subiquity/3119/lib/python3.8/site-packages/subiquity/server/runner.py", line 37, in wait
raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['/snap/subiquity/3119/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2004.4"}}', 'system-install', '-t', '/target', '--', 'bridge-utils']' returned non-zero exit status 100.
2022-04-03 09:08:56,419 DEBUG root:39 start: subiquity/Install/install/postinstall/install_bridge-utils/cmd-system-install: curtin command system-install
2022-04-03 09:08:56,423 ERROR root:39 finish: subiquity/Install/install/postinstall/install_bridge-utils/cmd-system-install: FAIL: curtin command system-install
2022-04-03 09:08:56,657 INFO root:39 finish: subiquity/ErrorReporter/1648976936.147035599.install_fail/add_info: SUCCESS: written to /var/crash/1648976936.147035599.install_fail.crash
2022-04-03 09:08:56,658 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "", "error": {"state": "DONE", "base": "...
2022-04-03 09:08:56,660 INFO aiohttp.access:233 [03/Apr/2022:09:08:55 +0000] "GET /meta/status?cur=%22POST_RUNNING%22 HTTP/1.1" 200 524 "-" "Python/3.8 aiohttp/3.6.2"
---------------------------------------
--
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to subiquity in Ubuntu.
https://bugs.launchpad.net/bugs/1967458
Title:
ubuntu 20.4.4 Server Autoinstall Error
Status in subiquity package in Ubuntu:
Incomplete
Bug description:
When ubuntu20.04.4 Server is installed by autoinstall, an error occurs
when executing the packages section.
Since [/target/run/systemd/resolve] is not mounted, it seems that
there is an error in dns name resolution.
If interactive-sections is specified, [/target/run/systemd/resolve]
will be mounted, otherwise it will not be mounted.
ProblemType: Bug
DistroRelease: Ubuntu 20.04
Uname: Linux 5.4.0-100-generic x86_64
Architecture: amd64
Date: Fri Apr 1 13:31:39 2022
ProcEnviron:
TERM=linux
PATH=(custom, no user)
LANG=C.UTF-8
Snap: subiquity 22.02.2 ()
Symptom: installation
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/subiquity/+bug/1967458/+subscriptions
More information about the foundations-bugs
mailing list