Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Early/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Early/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Reporting/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Reporting/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Error/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Error/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Userdata/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Userdata/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Package/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Package/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Debconf/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Debconf/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Kernel/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Kernel/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Zdev/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Zdev/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Ad/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Ad/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Late/apply_autoinstall_config: 
Apr 16 13:47:59 ubuntu subiquity_event.3128[3128]: subiquity/Late/apply_autoinstall_config: 
Apr 16 13:48:26 ubuntu subiquity_event.3128[3128]: subiquity/Ad/has_support_GET: 
Apr 16 13:48:36 ubuntu subiquity_event.3128[3128]: configuring apt
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: start: cmd-apt-config: curtin command apt-config
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Handling apt to target /tmp/tmpop1gfff2/mount with config {'debconf_selections': {'subiquity': ''}, 'disable_components': [], 'disable_suites': ['security'], 'preserve_sources_list': False, 'primary': [{'arches': ['default'], 'uri': 'http://archive.ubuntu.com/ubuntu/'}], 'security': [{'arches': ['amd64', 'i386'], 'uri': 'http://security.ubuntu.com/ubuntu/'}, {'arches': ['s390x', 'arm64', 'armhf', 'powerpc', 'ppc64el', 'riscv64'], 'uri': 'http://ports.ubuntu.com/ubuntu-ports'}]}
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--bind', '/dev', '/tmp/tmpop1gfff2/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--bind', '/proc', '/tmp/tmpop1gfff2/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--bind', '/run', '/tmp/tmpop1gfff2/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--bind', '/sys', '/tmp/tmpop1gfff2/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--bind', '/tmp/tmpop1gfff2/mount/usr/bin/true', '/tmp/tmpop1gfff2/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: got primary mirror: http://archive.ubuntu.com/ubuntu/
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: got security mirror: http://security.ubuntu.com/ubuntu/
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Apt Mirror info: {'PRIMARY': 'http://archive.ubuntu.com/ubuntu/', 'SECURITY': 'http://security.ubuntu.com/ubuntu/', 'MIRROR': 'http://archive.ubuntu.com/ubuntu/'}
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Applying debconf selections
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'debconf-set-selections'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: No custom template provided, fall back to modifymirrors in /etc/apt/sources.list.d/ubuntu.sources on the target system
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'dpkg-query', '--show', '--showformat', '${Version}', 'cloud-init'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Set preserve_sources_list to True in /etc/cloud/cloud.cfg.d/curtin-preserve-sources.cfg with: {'apt': {'preserve_sources_list': True}}
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Checking if target_proc (/tmp/tmpop1gfff2/mount/proc) is a mount
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: It is, so unshare will use --mount-proc=/tmp/tmpop1gfff2/mount/proc
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpop1gfff2/mount/proc', '--', 'chroot', '/tmp/tmpop1gfff2/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: TIMED subp(['udevadm', 'settle']): 0.008
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--make-private', '/tmp/tmpop1gfff2/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['umount', '/tmp/tmpop1gfff2/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--make-private', '/tmp/tmpop1gfff2/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['umount', '/tmp/tmpop1gfff2/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--make-private', '/tmp/tmpop1gfff2/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['umount', '/tmp/tmpop1gfff2/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--make-private', '/tmp/tmpop1gfff2/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['umount', '/tmp/tmpop1gfff2/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['mount', '--make-private', '/tmp/tmpop1gfff2/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: Running command ['umount', '/tmp/tmpop1gfff2/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:48:58 ubuntu subiquity_log.3128[4131]: finish: cmd-apt-config: SUCCESS: curtin command apt-config
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: start: cmd-apt-config: curtin command apt-config
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Handling apt to target /tmp/tmpqhrlr99d/mount with config {'debconf_selections': {'subiquity': ''}, 'disable_components': [], 'disable_suites': ['security'], 'preserve_sources_list': False, 'primary': [{'arches': ['default'], 'uri': 'http://archive.ubuntu.com/ubuntu/'}], 'security': [{'arches': ['amd64', 'i386'], 'uri': 'http://security.ubuntu.com/ubuntu/'}, {'arches': ['s390x', 'arm64', 'armhf', 'powerpc', 'ppc64el', 'riscv64'], 'uri': 'http://ports.ubuntu.com/ubuntu-ports'}]}
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--bind', '/dev', '/tmp/tmpqhrlr99d/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--bind', '/proc', '/tmp/tmpqhrlr99d/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--bind', '/run', '/tmp/tmpqhrlr99d/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--bind', '/sys', '/tmp/tmpqhrlr99d/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--bind', '/tmp/tmpqhrlr99d/mount/usr/bin/true', '/tmp/tmpqhrlr99d/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: got primary mirror: http://archive.ubuntu.com/ubuntu/
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: got security mirror: http://security.ubuntu.com/ubuntu/
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Apt Mirror info: {'PRIMARY': 'http://archive.ubuntu.com/ubuntu/', 'SECURITY': 'http://security.ubuntu.com/ubuntu/', 'MIRROR': 'http://archive.ubuntu.com/ubuntu/'}
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Applying debconf selections
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'debconf-set-selections'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: No custom template provided, fall back to modifymirrors in /etc/apt/sources.list.d/ubuntu.sources on the target system
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'dpkg-query', '--show', '--showformat', '${Version}', 'cloud-init'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Set preserve_sources_list to True in /etc/cloud/cloud.cfg.d/curtin-preserve-sources.cfg with: {'apt': {'preserve_sources_list': True}}
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Checking if target_proc (/tmp/tmpqhrlr99d/mount/proc) is a mount
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: It is, so unshare will use --mount-proc=/tmp/tmpqhrlr99d/mount/proc
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['unshare', '--fork', '--pid', '--mount-proc=/tmp/tmpqhrlr99d/mount/proc', '--', 'chroot', '/tmp/tmpqhrlr99d/mount', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: TIMED subp(['udevadm', 'settle']): 0.007
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--make-private', '/tmp/tmpqhrlr99d/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['umount', '/tmp/tmpqhrlr99d/mount/usr/bin/ischroot'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--make-private', '/tmp/tmpqhrlr99d/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['umount', '/tmp/tmpqhrlr99d/mount/sys'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--make-private', '/tmp/tmpqhrlr99d/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['umount', '/tmp/tmpqhrlr99d/mount/run'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--make-private', '/tmp/tmpqhrlr99d/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['umount', '/tmp/tmpqhrlr99d/mount/proc'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['mount', '--make-private', '/tmp/tmpqhrlr99d/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: Running command ['umount', '/tmp/tmpqhrlr99d/mount/dev'] with allowed return codes [0] (capture=False)
Apr 16 13:53:10 ubuntu subiquity_log.3128[4289]: finish: cmd-apt-config: SUCCESS: curtin command apt-config
Apr 16 13:57:11 ubuntu subiquity_event.3128[3128]: configuring apt
Apr 16 13:57:11 ubuntu subiquity_event.3128[3128]: