Juju bootstrap - FAIL: curtin command curthooks - FAIL: installing kernel

Hi guys,

Need help with Juju Controller deployment.

I am trying to install Openstack with Maas and Juju on my lab.
I plan 5 VM : 1 for Maas/Juju Client, 1 for Juju Controller, and 3 Openstack nodes.

I keep getting stuck at juju bootstrap step. I tried to look through cloud-init-output.log, these seem to be the reason:

finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: FAIL: installing kernel
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks: FAIL: curtin command curthooks

Pls find the full log here. ( I can’t upload attachment yet …)

ubuntu@Juju-Controller:~$ cat /var/log/cloud-init-output.log 
Cloud-init v. 20.3-2-g371b392c-0ubuntu1~18.04.1 running 'init-local' at Thu, 26 Nov 2020 03:03:40 +0000. Up 9.54 seconds.
Cloud-init v. 20.3-2-g371b392c-0ubuntu1~18.04.1 running 'init' at Thu, 26 Nov 2020 03:03:42 +0000. Up 10.64 seconds.
ci-info: ++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+
ci-info: | Device |  Up  |           Address           |      Mask     | Scope  |     Hw-Address    |
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+
ci-info: | ens160 | True |        10.124.112.235       | 255.255.255.0 | global | 00:50:56:94:43:ac |
ci-info: | ens160 | True | fe80::250:56ff:fe94:43ac/64 |       .       |  link  | 00:50:56:94:43:ac |
ci-info: |   lo   | True |          127.0.0.1          |   255.0.0.0   |  host  |         .         |
ci-info: |   lo   | True |           ::1/128           |       .       |  host  |         .         |
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+
ci-info: +++++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++++
ci-info: +-------+----------------+----------------+-----------------+-----------+-------+
ci-info: | Route |  Destination   |    Gateway     |     Genmask     | Interface | Flags |
ci-info: +-------+----------------+----------------+-----------------+-----------+-------+
ci-info: |   0   |    0.0.0.0     | 10.124.112.254 |     0.0.0.0     |   ens160  |   UG  |
ci-info: |   1   |    0.0.0.0     | 10.124.112.254 |     0.0.0.0     |   ens160  |   UG  |
ci-info: |   2   |  10.124.112.0  |    0.0.0.0     |  255.255.255.0  |   ens160  |   U   |
ci-info: |   3   | 10.124.112.254 |    0.0.0.0     | 255.255.255.255 |   ens160  |   UH  |
ci-info: +-------+----------------+----------------+-----------------+-----------+-------+
ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++
ci-info: +-------+-------------+---------+-----------+-------+
ci-info: | Route | Destination | Gateway | Interface | Flags |
ci-info: +-------+-------------+---------+-----------+-------+
ci-info: |   1   |  fe80::/64  |    ::   |   ens160  |   U   |
ci-info: |   3   |    local    |    ::   |   ens160  |   U   |
ci-info: |   4   |   ff00::/8  |    ::   |   ens160  |   U   |
ci-info: +-------+-------------+---------+-----------+-------+
Generating public/private rsa key pair.
Your identification has been saved in /etc/ssh/ssh_host_rsa_key.
Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub.
The key fingerprint is:
SHA256:3kNSbIai6Eu/3WrD2RcRRzCUVKOk+crvbHCHPYcg2IA root@Juju-Controller
The key's randomart image is:
+---[RSA 2048]----+
|     .   o*=+    |
|    E .  *oo..   |
|      .++ *o     |
|   . ...o=o      |
|  . .   S.o= .   |
| .     o.=+ = .  |
|  o  . o+ooo o   |
| . o .=..oo.     |
|  . oooo.++      |
+----[SHA256]-----+
Generating public/private dsa key pair.
Your identification has been saved in /etc/ssh/ssh_host_dsa_key.
Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub.
The key fingerprint is:
SHA256:Gle8GtFwOQiT6Tos2wovziN9jD/7Qmqo8Y28q3Xb17w root@Juju-Controller
The key's randomart image is:
+---[DSA 1024]----+
|      o+....     |
|      o..=o      |
|     .  . +.     |
|      .  o .     |
|   . .. S .      |
|  . =  + o       |
|oo.O... .o       |
|=O*+*o  . o      |
|**X*==o.  E.     |
+----[SHA256]-----+
Generating public/private ecdsa key pair.
Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key.
Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub.
The key fingerprint is:
SHA256:tUNfthW1F9c6tz5OYXw1O1IGll5v9/eX/bkHj5Z8AQ8 root@Juju-Controller
The key's randomart image is:
+---[ECDSA 256]---+
|             o.o=|
|            ....*|
|          o . +*=|
|         o o E*oX|
|        S o ..=X=|
|           .  ++*|
|             ..**|
|              *+O|
|             ..=B|
+----[SHA256]-----+
Generating public/private ed25519 key pair.
Your identification has been saved in /etc/ssh/ssh_host_ed25519_key.
Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub.
The key fingerprint is:
SHA256:OxrB+1OjwBgrYijzy8qYED9FWJXZvk2zLy03460+eFc root@Juju-Controller
The key's randomart image is:
+--[ED25519 256]--+
|    ...+         |
|   o  o .        |
|  . .  .         |
|   ...  . o      |
|o   .=o S+ o     |
|=+..o oo..=     E|
|++o.  o.oo =   . |
|+o..   +o.+ O..  |
|+oo.  . .. B+*.  |
+----[SHA256]-----+
Cloud-init v. 20.3-2-g371b392c-0ubuntu1~18.04.1 running 'modules:config' at Thu, 26 Nov 2020 03:04:26 +0000. Up 54.99 seconds.
Hit:1 http://archive.ubuntu.com/ubuntu bionic InRelease
Get:2 http://archive.ubuntu.com/ubuntu bionic-updates InRelease [88.7 kB]
Get:3 http://archive.ubuntu.com/ubuntu bionic-security InRelease [88.7 kB]
Get:4 http://archive.ubuntu.com/ubuntu bionic-backports InRelease [74.6 kB]
Get:5 http://archive.ubuntu.com/ubuntu bionic/multiverse amd64 Packages [151 kB]
Get:6 http://archive.ubuntu.com/ubuntu bionic/universe amd64 Packages [8570 kB]
Get:7 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 Packages [1761 kB]
Get:8 http://archive.ubuntu.com/ubuntu bionic-updates/multiverse amd64 Packages [36.1 kB]
Get:9 http://archive.ubuntu.com/ubuntu bionic-updates/universe amd64 Packages [1693 kB]
Get:10 http://archive.ubuntu.com/ubuntu bionic-security/main amd64 Packages [1425 kB]
Get:11 http://archive.ubuntu.com/ubuntu bionic-security/restricted amd64 Packages [173 kB]
Get:12 http://archive.ubuntu.com/ubuntu bionic-security/multiverse amd64 Packages [13.3 kB]
Get:13 http://archive.ubuntu.com/ubuntu bionic-security/universe amd64 Packages [1089 kB]
Get:14 http://archive.ubuntu.com/ubuntu bionic-backports/main amd64 Packages [10.0 kB]
Get:15 http://archive.ubuntu.com/ubuntu bionic-backports/universe amd64 Packages [10.3 kB]
Fetched 15.2 MB in 7s (2047 kB/s)
Reading package lists...
Reading package lists...
Building dependency tree...
Reading state information...
The following package was automatically installed and is no longer required:
  libfreetype6
Use 'apt autoremove' to remove it.
The following additional packages will be installed:
  libnvpair1linux libuutil1linux libzfs2linux libzpool2linux
Suggested packages:
  nfs-kernel-server samba-common-bin zfs-initramfs | zfs-dracut zfs-modules
  | zfs-dkms
Recommended packages:
  zfs-zed
The following NEW packages will be installed:
  libnvpair1linux libuutil1linux libzfs2linux libzpool2linux python3-pyudev
  zfsutils-linux
0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded.
Need to get 1146 kB of archives.
After this operation, 4246 kB of additional disk space will be used.
Get:1 http://archive.ubuntu.com/ubuntu bionic/main amd64 python3-pyudev all 0.21.0-1 [36.7 kB]
Get:2 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 libnvpair1linux amd64 0.7.5-1ubuntu16.10 [44.5 kB]
Get:3 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 libuutil1linux amd64 0.7.5-1ubuntu16.10 [47.2 kB]
Get:4 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 libzpool2linux amd64 0.7.5-1ubuntu16.10 [548 kB]
Get:5 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 libzfs2linux amd64 0.7.5-1ubuntu16.10 [128 kB]
Get:6 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 zfsutils-linux amd64 0.7.5-1ubuntu16.10 [341 kB]
Fetched 1146 kB in 0s (52.1 MB/s)
Selecting previously unselected package python3-pyudev.
(Reading database ... 28767 files and directories currently installed.)
Preparing to unpack .../0-python3-pyudev_0.21.0-1_all.deb ...
Unpacking python3-pyudev (0.21.0-1) ...
Selecting previously unselected package libnvpair1linux.
Preparing to unpack .../1-libnvpair1linux_0.7.5-1ubuntu16.10_amd64.deb ...
Unpacking libnvpair1linux (0.7.5-1ubuntu16.10) ...
Selecting previously unselected package libuutil1linux.
Preparing to unpack .../2-libuutil1linux_0.7.5-1ubuntu16.10_amd64.deb ...
Unpacking libuutil1linux (0.7.5-1ubuntu16.10) ...
Selecting previously unselected package libzpool2linux.
Preparing to unpack .../3-libzpool2linux_0.7.5-1ubuntu16.10_amd64.deb ...
Unpacking libzpool2linux (0.7.5-1ubuntu16.10) ...
Selecting previously unselected package libzfs2linux.
Preparing to unpack .../4-libzfs2linux_0.7.5-1ubuntu16.10_amd64.deb ...
Unpacking libzfs2linux (0.7.5-1ubuntu16.10) ...
Selecting previously unselected package zfsutils-linux.
Preparing to unpack .../5-zfsutils-linux_0.7.5-1ubuntu16.10_amd64.deb ...
Unpacking zfsutils-linux (0.7.5-1ubuntu16.10) ...
Setting up libuutil1linux (0.7.5-1ubuntu16.10) ...
Setting up python3-pyudev (0.21.0-1) ...
Setting up libnvpair1linux (0.7.5-1ubuntu16.10) ...
Setting up libzpool2linux (0.7.5-1ubuntu16.10) ...
Setting up libzfs2linux (0.7.5-1ubuntu16.10) ...
Setting up zfsutils-linux (0.7.5-1ubuntu16.10) ...
Created symlink /etc/systemd/system/zfs-import.target.wants/zfs-import-cache.service → /lib/systemd/system/zfs-import-cache.service.
Created symlink /etc/systemd/system/zfs-mount.service.wants/zfs-import.target → /lib/systemd/system/zfs-import.target.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-import.target → /lib/systemd/system/zfs-import.target.
Created symlink /etc/systemd/system/zfs-mount.service.wants/zfs-load-module.service → /lib/systemd/system/zfs-load-module.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-load-module.service → /lib/systemd/system/zfs-load-module.service.
Created symlink /etc/systemd/system/zfs-share.service.wants/zfs-mount.service → /lib/systemd/system/zfs-mount.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-mount.service → /lib/systemd/system/zfs-mount.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-share.service → /lib/systemd/system/zfs-share.service.
Created symlink /etc/systemd/system/multi-user.target.wants/zfs.target → /lib/systemd/system/zfs.target.
zfs-import-scan.service is a disabled or a static unit, not starting it.
Processing triggers for man-db (2.8.3-2ubuntu0.1) ...
Processing triggers for libc-bin (2.27-3ubuntu1.3) ...
start: cmd-install: curtin command install
unknown type for url 'cp:///media/root-ro', assuming type 'tgz'
curtin: Installation started. (20.1-2-g42a9667f-0ubuntu1~18.04.1)
LANG=C.UTF-8
merged config: {'sources': {'00_cmdline': {'type': 'tgz', 'uri': 'cp:///media/root-ro'}}, 'stages': ['early', 'partitioning', 'network', 'extract', 'curthooks', 'hook', 'late'], 'extract_commands': {'builtin': ['curtin', 'extract']}, 'hook_commands': {'builtin': ['curtin', 'hook']}, 'partitioning_commands': {'builtin': ['curtin', 'block-meta', 'custom']}, 'curthooks_commands': {'builtin': ['curtin', 'curthooks']}, 'late_commands': {'builtin': [], 'maas': ['wget', '--no-proxy', 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/latest/by-id/xx8fap/', '--post-data', 'op=netboot_off', '-O', '/dev/null']}, 'network_commands': {'builtin': ['curtin', 'net-meta', 'custom']}, 'apply_net_commands': {'builtin': []}, 'install': {'log_file': '/tmp/install.log', 'error_tarfile': '/tmp/curtin-logs.tar', 'post_files': ['/tmp/install.log', '/tmp/curtin-logs.tar']}, 'storage': {'config': [{'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'}, {'device': 'sda', 'flag': 'bios_grub', 'id': 'sda-part1', 'number': 1, 'offset': '4194304B', 'size': '1048576B', 'type': 'partition', 'wipe': 'zero'}, {'device': 'sda', 'id': 'sda-part2', 'name': 'sda-part2', 'number': 2, 'size': '85890957312B', 'type': 'partition', 'uuid': 'c7263149-c816-40ac-ac2d-38ed1bdaac5d', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'sda-part2_format', 'label': 'root', 'type': 'format', 'uuid': '8ff6f4f8-e3b5-4fc8-86d1-09316e86515d', 'volume': 'sda-part2'}, {'device': 'sda-part2_format', 'id': 'sda-part2_mount', 'path': '/', 'type': 'mount'}], 'version': 1}, 'apt': {'preserve_sources_list': False, 'proxy': 'http://10-124-112-0--24.maas-internal:8000/', 'sources_list': 'deb http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n'}, 'reporting': {'maas': {'consumer_key': 'sUHRpGW2wCJvkVdagV', 'endpoint': 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap', 'token_key': '44vgxvbfnf8MEHnaJW', 'token_secret': 'vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B', 'type': 'webhook'}}, 'network': {'version': 2, 'ethernets': {'ens160': {'addresses': ['10.124.112.235/24'], 'gateway4': '10.124.112.254', 'match': {'macaddress': '00:50:56:94:43:ac'}, 'mtu': 1500, 'nameservers': {'addresses': ['10.124.112.231'], 'search': ['maas']}, 'set-name': 'ens160'}}}, 'kernel': {'mapping': {}, 'package': 'linux-generic'}, 'showtrace': True, 'verbosity': 3, 'cloudconfig': {'maas-cloud-config': {'content': "#cloud-config\ndatasource:\n  MAAS: {consumer_key: sUHRpGW2wCJvkVdagV, metadata_url: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/',\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B}\n", 'path': '/etc/cloud/cloud.cfg.d/90_maas_cloud_config.cfg'}, 'maas-datasource': {'content': 'datasource_list: [ MAAS ]', 'path': '/etc/cloud/cloud.cfg.d/90_maas_datasource.cfg'}, 'maas-reporting': {'content': "#cloud-config\nreporting:\n  maas: {consumer_key: sUHRpGW2wCJvkVdagV, endpoint: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap',\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B,\n    type: webhook}\n", 'path': '/etc/cloud/cloud.cfg.d/90_maas_cloud_init_reporting.cfg'}, 'maas-ubuntu-sso': {'content': '#cloud-config\nsnappy: {email: admin@lab.com}\n', 'path': '/etc/cloud/cloud.cfg.d/90_maas_ubuntu_sso.cfg'}}, 'debconf_selections': {'grub2': 'grub2   grub2/update_nvram  boolean false', 'maas': "cloud-init   cloud-init/datasources  multiselect MAAS\ncloud-init   cloud-init/maas-metadata-url  string http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/\ncloud-init   cloud-init/maas-metadata-credentials  string oauth_consumer_key=sUHRpGW2wCJvkVdagV&oauth_token_key=44vgxvbfnf8MEHnaJW&oauth_token_secret=vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B\ncloud-init   cloud-init/local-cloud-config  string manage_etc_hosts: true\\nmanual_cache_clean: true\\nreporting:\\n  maas: {consumer_key: sUHRpGW2wCJvkVdagV, endpoint: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap',\\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B,\\n    type: webhook}\\n\n"}, 'early_commands': {'driver_00': ['sh', '-c', 'echo third party drivers not installed or necessary.']}}
'reporter' not found in config file.
curtin: Installation started. (20.1-2-g42a9667f-0ubuntu1~18.04.1)
{'WORKING_DIR': '/tmp/tmpi6_dlk2q/scratch', 'OUTPUT_FSTAB': '/tmp/tmpi6_dlk2q/state/fstab', 'OUTPUT_INTERFACES': '/tmp/tmpi6_dlk2q/state/interfaces', 'OUTPUT_NETWORK_CONFIG': '/tmp/tmpi6_dlk2q/state/network_config', 'OUTPUT_NETWORK_STATE': '/tmp/tmpi6_dlk2q/state/network_state', 'TARGET_MOUNT_POINT': '/tmp/tmpi6_dlk2q/target', 'CONFIG': '/tmp/tmpi6_dlk2q/state/config'}
start: cmd-install/stage-early: preparing for installation
start: cmd-install/stage-early/driver_00: running 'sh -c echo third party drivers not installed or necessary.'
third party drivers not installed or necessary.
finish: cmd-install/stage-early/driver_00: SUCCESS: running 'sh -c echo third party drivers not installed or necessary.'
driver_00 took 0.009 seconds
stage_early took 0.009 seconds
finish: cmd-install/stage-early: SUCCESS: preparing for installation
start: cmd-install/stage-partitioning: configuring storage
start: cmd-install/stage-partitioning/builtin: running 'curtin block-meta custom'
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.029
devsync happy - path /dev/sda now exists
return volume path /dev/sda
get_path_to_storage_volume for volume sda-part1({'device': 'sda', 'flag': 'bios_grub', 'id': 'sda-part1', 'number': 1, 'offset': '4194304B', 'size': '1048576B', 'type': 'partition', 'wipe': 'zero'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.018
devsync happy - path /dev/sda now exists
return volume path /dev/sda
get_path_to_storage_volume for volume sda-part2({'device': 'sda', 'id': 'sda-part2', 'name': 'sda-part2', 'number': 2, 'size': '85890957312B', 'type': 'partition', 'uuid': 'c7263149-c816-40ac-ac2d-38ed1bdaac5d', 'wipe': 'superblock'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.020
devsync happy - path /dev/sda now exists
return volume path /dev/sda
block-meta: extracted devices to clear: ['/dev/sda']
Declared block devices: ['/dev/sda']
clearing devices=['/dev/sda']
start: cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: removing previous storage devices
Running command ['mdadm', '--assemble', '--scan', '-v'] with allowed return codes [0, 1, 2] (capture=True)
mdadm assemble scan results:

mdadm: looking for devices for further assembly
mdadm: no recogniseable superblock on /dev/sda
mdadm: cannot open device /dev/sr0: No medium found
mdadm: no recogniseable superblock on /dev/loop0
mdadm: No arrays found in config file or automatically

Running command ['mdadm', '--detail', '--scan', '-v'] with allowed return codes [0, 1] (capture=True)
mdadm detail scan after assemble:


Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.019
Running command ['pvscan', '--cache'] with allowed return codes [0] (capture=True)
Running command ['vgscan', '--cache'] with allowed return codes [0] (capture=True)
Running command ['vgchange', '--activate=y'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.020
Loading kernel module bcache via modprobe
Running command ['modprobe', '--use-blacklist', 'bcache'] with allowed return codes [0] (capture=False)
Generating device storage trees for path(s): ['/dev/sda']
devname '/sys/class/block/sda' had holders: []
/sys/class/block/sda is multipath device partition? False
/sys/class/block/sda is multipath device partition? False
/sys/class/block/sda is multipath device partition? False
Current device storage tree:
sda
Shutdown Plan:
{'level': 0, 'device': '/sys/class/block/sda', 'dev_type': 'disk'}
shutdown running on holder type: 'disk' syspath: '/sys/class/block/sda'
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
 "sda": {
  "ALIGNMENT": "0",
  "DISC-ALN": "0",
  "DISC-GRAN": "0",
  "DISC-MAX": "0",
  "DISC-ZERO": "0",
  "FSTYPE": "",
  "GROUP": "disk",
  "KNAME": "sda",
  "LABEL": "",
  "LOG-SEC": "512",
  "MAJ:MIN": "8:0",
  "MIN-IO": "512",
  "MODE": "brw-rw----",
  "MODEL": "Virtual disk    ",
  "MOUNTPOINT": "",
  "NAME": "sda",
  "OPT-IO": "0",
  "OWNER": "root",
  "PHY-SEC": "512",
  "RM": "0",
  "RO": "0",
  "ROTA": "1",
  "RQ-SIZE": "128",
  "SIZE": "85899345920",
  "STATE": "running",
  "TYPE": "disk",
  "UUID": "",
  "device_path": "/dev/sda"
 }
}
get_blockdev_sector_size: (log=512, phys=512)
Running command ['fdasd', '--table', '/dev/sda'] with allowed return codes [0] (capture=False)
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID'] with allowed return codes [0] (capture=True)
Checking if /dev/sda is a swap device
Found swap magic: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
wiping superblock on /dev/sda
wiping /dev/sda attempt 1/4
wiping 1M on /dev/sda at offsets [0, -1048576]
successfully wiped device /dev/sda on attempt 1/4
devname '/sys/class/block/sda' had holders: []
/sys/class/block/sda is multipath device partition? False
/sys/class/block/sda is multipath device partition? False
/sys/class/block/sda is multipath device partition? False
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: SUCCESS: removing previous storage devices
blockmeta: detected storage config, using mode=custom
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring disk: sda
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.023
devsync happy - path /dev/sda now exists
return volume path /dev/sda
wiping 1M on /dev/sda at offsets [0, -1048576]
labeling device: '/dev/sda' with 'gpt' partition table
wiping 1M on /dev/sda at offsets [0, -1048576]
devname '/dev/sda' had holders: []
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.039
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['blkid', '-o', 'export', '/dev/sda'] with allowed return codes [0, 2] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
Cannot create disk tag udev rule for /dev/sda [id=sda], missing 'serial' or 'wwn' value
Can't find a uuid for volume: sda. Skipping dname.
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring disk: sda
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: sda-part1
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.035
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
 "sda": {
  "ALIGNMENT": "0",
  "DISC-ALN": "0",
  "DISC-GRAN": "0",
  "DISC-MAX": "0",
  "DISC-ZERO": "0",
  "FSTYPE": "",
  "GROUP": "disk",
  "KNAME": "sda",
  "LABEL": "",
  "LOG-SEC": "512",
  "MAJ:MIN": "8:0",
  "MIN-IO": "512",
  "MODE": "brw-rw----",
  "MODEL": "Virtual disk    ",
  "MOUNTPOINT": "",
  "NAME": "sda",
  "OPT-IO": "0",
  "OWNER": "root",
  "PHY-SEC": "512",
  "RM": "0",
  "RO": "0",
  "ROTA": "1",
  "RQ-SIZE": "128",
  "SIZE": "85899345920",
  "STATE": "running",
  "TYPE": "disk",
  "UUID": "",
  "device_path": "/dev/sda"
 }
}
get_blockdev_sector_size: (log=512, phys=512)
sda logical_block_size_bytes: 512
adding partition 'sda-part1' to disk 'sda' (ptable: 'gpt')
partnum: 1 offset_sectors: 2048 length_sectors: 2047
Preparing partition location on disk /dev/sda
Wiping 1M on /dev/sda at offset 1048576
Running command ['sgdisk', '--new', '1:2048:4095', '--typecode=1:ef02', '/dev/sda'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['blockdev', '--rereadpt', '/dev/sda'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.140
TIMED udevadm_settle(exists='/dev/sda1'): 0.000
Wiping partition /dev/sda1 mode=zero
/dev/sda1 is 1048576 bytes. wiping with buflen=4194304
get_path_to_storage_volume for volume sda-part1({'device': 'sda', 'flag': 'bios_grub', 'id': 'sda-part1', 'number': 1, 'offset': '4194304B', 'size': '1048576B', 'type': 'partition', 'wipe': 'zero'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.076
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.079
devsync happy - path /dev/sda now exists
return volume path /dev/sda1
Running command ['blkid', '-o', 'export', '/dev/sda1'] with allowed return codes [0, 2] (capture=True)
No partition-specific dname
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: sda-part1
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: sda-part2
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.080
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
 "sda": {
  "ALIGNMENT": "0",
  "DISC-ALN": "0",
  "DISC-GRAN": "0",
  "DISC-MAX": "0",
  "DISC-ZERO": "0",
  "FSTYPE": "",
  "GROUP": "disk",
  "KNAME": "sda",
  "LABEL": "",
  "LOG-SEC": "512",
  "MAJ:MIN": "8:0",
  "MIN-IO": "512",
  "MODE": "brw-rw----",
  "MODEL": "Virtual disk    ",
  "MOUNTPOINT": "",
  "NAME": "sda",
  "OPT-IO": "0",
  "OWNER": "root",
  "PHY-SEC": "512",
  "RM": "0",
  "RO": "0",
  "ROTA": "1",
  "RQ-SIZE": "128",
  "SIZE": "85899345920",
  "STATE": "running",
  "TYPE": "disk",
  "UUID": "",
  "device_path": "/dev/sda"
 },
 "sda1": {
  "ALIGNMENT": "0",
  "DISC-ALN": "0",
  "DISC-GRAN": "0",
  "DISC-MAX": "0",
  "DISC-ZERO": "0",
  "FSTYPE": "",
  "GROUP": "disk",
  "KNAME": "sda1",
  "LABEL": "",
  "LOG-SEC": "512",
  "MAJ:MIN": "8:1",
  "MIN-IO": "512",
  "MODE": "brw-rw----",
  "MODEL": "",
  "MOUNTPOINT": "",
  "NAME": "sda1",
  "OPT-IO": "0",
  "OWNER": "root",
  "PHY-SEC": "512",
  "RM": "0",
  "RO": "0",
  "ROTA": "1",
  "RQ-SIZE": "128",
  "SIZE": "1048576",
  "STATE": "",
  "TYPE": "part",
  "UUID": "",
  "device_path": "/dev/sda1"
 }
}
get_blockdev_sector_size: (log=512, phys=512)
sda logical_block_size_bytes: 512
previous partition number for 'sda-part2' found to be '1'
partition_kname=sda1
previous partition: /sys/class/block/sda/sda1 size_sectors=2048 start_sectors=2048
adding partition 'sda-part2' to disk 'sda' (ptable: 'gpt')
partnum: 2 offset_sectors: 4096 length_sectors: 167755775
Preparing partition location on disk /dev/sda
Wiping 1M on /dev/sda at offset 2097152
Running command ['sgdisk', '--new', '2:4096:167759871', '--typecode=2:8300', '/dev/sda'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['blockdev', '--rereadpt', '/dev/sda'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.190
TIMED udevadm_settle(exists='/dev/sda2'): 0.000
get_path_to_storage_volume for volume sda-part2({'device': 'sda', 'id': 'sda-part2', 'name': 'sda-part2', 'number': 2, 'size': '85890957312B', 'type': 'partition', 'uuid': 'c7263149-c816-40ac-ac2d-38ed1bdaac5d', 'wipe': 'superblock'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.107
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.100
devsync happy - path /dev/sda now exists
return volume path /dev/sda2
Running command ['blkid', '-o', 'export', '/dev/sda2'] with allowed return codes [0, 2] (capture=True)
Creating dname udev rule '['SUBSYSTEM=="block"', 'ACTION=="add|change"', 'ENV{DEVTYPE}=="partition"', 'ENV{ID_PART_ENTRY_UUID}=="2cff2ce3-347a-4e5e-ac58-1ca7dfdfb909"', 'SYMLINK+="disk/by-dname/sda-part2"\n']'
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: sda-part2
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: sda-part2_format
get_path_to_storage_volume for volume sda-part2({'device': 'sda', 'id': 'sda-part2', 'name': 'sda-part2', 'number': 2, 'size': '85890957312B', 'type': 'partition', 'uuid': 'c7263149-c816-40ac-ac2d-38ed1bdaac5d', 'wipe': 'superblock'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.083
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.087
devsync happy - path /dev/sda now exists
return volume path /dev/sda2
mkfs /dev/sda2 info: {'fstype': 'ext4', 'id': 'sda-part2_format', 'label': 'root', 'type': 'format', 'uuid': '8ff6f4f8-e3b5-4fc8-86d1-09316e86515d', 'volume': 'sda-part2'}
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda2'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
 "sda2": {
  "ALIGNMENT": "0",
  "DISC-ALN": "0",
  "DISC-GRAN": "0",
  "DISC-MAX": "0",
  "DISC-ZERO": "0",
  "FSTYPE": "",
  "GROUP": "disk",
  "KNAME": "sda2",
  "LABEL": "",
  "LOG-SEC": "512",
  "MAJ:MIN": "8:2",
  "MIN-IO": "512",
  "MODE": "brw-rw----",
  "MODEL": "",
  "MOUNTPOINT": "",
  "NAME": "sda2",
  "OPT-IO": "0",
  "OWNER": "root",
  "PHY-SEC": "512",
  "RM": "0",
  "RO": "0",
  "ROTA": "1",
  "RQ-SIZE": "128",
  "SIZE": "85890957312",
  "STATE": "",
  "TYPE": "part",
  "UUID": "",
  "device_path": "/dev/sda2"
 }
}
get_blockdev_sector_size: (log=512, phys=512)
Running command ['mkfs.ext4', '-F', '-L', 'root', '-U', '8ff6f4f8-e3b5-4fc8-86d1-09316e86515d', '/dev/sda2'] with allowed return codes [0] (capture=True)
Formated device type: partition
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: sda-part2_format
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: sda-part2_mount
get_path_to_storage_volume for volume sda-part2({'device': 'sda', 'id': 'sda-part2', 'name': 'sda-part2', 'number': 2, 'size': '85890957312B', 'type': 'partition', 'uuid': 'c7263149-c816-40ac-ac2d-38ed1bdaac5d', 'wipe': 'superblock'})
get_path_to_storage_volume for volume sda({'grub_device': True, 'id': 'sda', 'name': 'sda', 'path': '/dev/sda', 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'})
Running command ['multipath', '-c', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.092
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['partprobe', '/dev/sda'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.099
devsync happy - path /dev/sda now exists
return volume path /dev/sda2
volume_path=/dev/sda2 found slaves: ['sda2']
kname_is_iscsi: no iscsi disk found for kname sda2
kname_is_iscsi: no iscsi disk found for kname sda2
Running command ['mount', '-t', 'ext4', '-o', 'defaults', '/dev/sda2', '/tmp/tmpi6_dlk2q/target/'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda2'] with allowed return codes [0] (capture=True)
Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda2'] with allowed return codes [0] (capture=True)
volspec: path=/dev/sda2 type=part
info[DEVLINKS] = ['/dev/disk/by-label/root', '/dev/disk/by-uuid/8ff6f4f8-e3b5-4fc8-86d1-09316e86515d', '/dev/disk/by-path/pci-0000:00:10.0-scsi-0:0:0:0-part2', '/dev/disk/by-partuuid/2cff2ce3-347a-4e5e-ac58-1ca7dfdfb909']
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: sda-part2_mount
TIMED BLOCK_META: 66.244
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: curtin command block-meta
finish: cmd-install/stage-partitioning/builtin: SUCCESS: running 'curtin block-meta custom'
builtin took 66.736 seconds
stage_partitioning took 66.736 seconds
finish: cmd-install/stage-partitioning: SUCCESS: configuring storage
start: cmd-install/stage-network: configuring network
start: cmd-install/stage-network/builtin: running 'curtin net-meta custom'
start: cmd-install/stage-network/builtin/cmd-net-meta: curtin command net-meta
net-meta mode is 'custom'.  devices=[]
writing to file /tmp/tmpi6_dlk2q/state/network_config with network config: network:
  ethernets:
    ens160:
      addresses:
      - 10.124.112.235/24
      gateway4: 10.124.112.254
      match:
        macaddress: 00:50:56:94:43:ac
      mtu: 1500
      nameservers:
        addresses:
        - 10.124.112.231
        search:
        - maas
      set-name: ens160
  version: 2

finish: cmd-install/stage-network/builtin/cmd-net-meta: SUCCESS: curtin command net-meta
finish: cmd-install/stage-network/builtin: SUCCESS: running 'curtin net-meta custom'
builtin took 0.499 seconds
stage_network took 0.499 seconds
finish: cmd-install/stage-network: SUCCESS: configuring network
start: cmd-install/stage-extract: writing install sources to disk
start: cmd-install/stage-extract/builtin: running 'curtin extract'
start: cmd-install/stage-extract/builtin/cmd-extract: curtin command extract
Installing sources: [{'type': 'tgz', 'uri': 'cp:///media/root-ro'}] to target at /tmp/tmpi6_dlk2q/target
start: cmd-install/stage-extract/builtin/cmd-extract: acquiring and extracting image from cp:///media/root-ro
Running command ['sh', '-c', 'mkdir -p "$2" && cd "$2" && rsync -aXHAS --one-file-system "$1/" .', '--', '/media/root-ro', '/tmp/tmpi6_dlk2q/target'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-extract/builtin/cmd-extract: SUCCESS: acquiring and extracting image from cp:///media/root-ro
Applying write_files from config.
finish: cmd-install/stage-extract/builtin/cmd-extract: SUCCESS: curtin command extract
finish: cmd-install/stage-extract/builtin: SUCCESS: running 'curtin extract'
builtin took 18.834 seconds
stage_extract took 18.834 seconds
finish: cmd-install/stage-extract: SUCCESS: writing install sources to disk
start: cmd-install/stage-curthooks: configuring installed system
start: cmd-install/stage-curthooks/builtin: running 'curtin curthooks'
start: cmd-install/stage-curthooks/builtin/cmd-curthooks: curtin command curthooks
Running curtin builtin curthooks
Configuring target system for distro: ubuntu osfamily: debian
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: configuring apt configuring apt
Transferred {'grub2': 'grub2   grub2/update_nvram  boolean false', 'maas': "cloud-init   cloud-init/datasources  multiselect MAAS\ncloud-init   cloud-init/maas-metadata-url  string http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/\ncloud-init   cloud-init/maas-metadata-credentials  string oauth_consumer_key=sUHRpGW2wCJvkVdagV&oauth_token_key=44vgxvbfnf8MEHnaJW&oauth_token_secret=vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B\ncloud-init   cloud-init/local-cloud-config  string manage_etc_hosts: true\\nmanual_cache_clean: true\\nreporting:\\n  maas: {consumer_key: sUHRpGW2wCJvkVdagV, endpoint: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap',\\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B,\\n    type: webhook}\\n\n"} into new format: {'preserve_sources_list': False, 'proxy': 'http://10-124-112-0--24.maas-internal:8000/', 'sources_list': 'deb http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n', 'debconf_selections': {'grub2': 'grub2   grub2/update_nvram  boolean false', 'maas': "cloud-init   cloud-init/datasources  multiselect MAAS\ncloud-init   cloud-init/maas-metadata-url  string http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/\ncloud-init   cloud-init/maas-metadata-credentials  string oauth_consumer_key=sUHRpGW2wCJvkVdagV&oauth_token_key=44vgxvbfnf8MEHnaJW&oauth_token_secret=vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B\ncloud-init   cloud-init/local-cloud-config  string manage_etc_hosts: true\\nmanual_cache_clean: true\\nreporting:\\n  maas: {consumer_key: sUHRpGW2wCJvkVdagV, endpoint: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap',\\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B,\\n    type: webhook}\\n\n"}}
curthooks handling apt to target /tmp/tmpi6_dlk2q/target with config {'preserve_sources_list': False, 'proxy': 'http://10-124-112-0--24.maas-internal:8000/', 'sources_list': 'deb http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-updates multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-security multiverse universe restricted main\ndeb http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n# deb-src http://archive.ubuntu.com/ubuntu $RELEASE-backports multiverse universe restricted main\n', 'debconf_selections': {'grub2': 'grub2   grub2/update_nvram  boolean false', 'maas': "cloud-init   cloud-init/datasources  multiselect MAAS\ncloud-init   cloud-init/maas-metadata-url  string http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/\ncloud-init   cloud-init/maas-metadata-credentials  string oauth_consumer_key=sUHRpGW2wCJvkVdagV&oauth_token_key=44vgxvbfnf8MEHnaJW&oauth_token_secret=vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B\ncloud-init   cloud-init/local-cloud-config  string manage_etc_hosts: true\\nmanual_cache_clean: true\\nreporting:\\n  maas: {consumer_key: sUHRpGW2wCJvkVdagV, endpoint: 'http://10-124-112-0--24.maas-internal:5248/MAAS/metadata/status/xx8fap',\\n    token_key: 44vgxvbfnf8MEHnaJW, token_secret: vdBghzGF45yVQ2bwZF9fuggEGBfcPE7B,\\n    type: webhook}\\n\n"}}
Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
got primary mirror: None
got security mirror: None
Apt Mirror info: {'PRIMARY': 'http://archive.ubuntu.com/ubuntu/', 'SECURITY': 'http://security.ubuntu.com/ubuntu/', 'MIRROR': 'http://archive.ubuntu.com/ubuntu/'}
Applying debconf selections
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'debconf-set-selections'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
unconfiguring cloud-init
cleaning cloud-init config from: ['/tmp/tmpi6_dlk2q/target/etc/cloud/cloud.cfg.d/90_dpkg.cfg']
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg-reconfigure', '--frontend=noninteractive', 'cloud-init'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg-query', '--show', '--showformat', '${Version}', 'cloud-init'] with allowed return codes [0] (capture=True)
Set preserve_sources_list to True in /etc/cloud/cloud.cfg.d/curtin-preserve-sources.cfg with: {'apt': {'preserve_sources_list': True}}
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
write apt proxy info to /tmp/tmpi6_dlk2q/target/etc/apt/apt.conf.d/90curtin-aptproxy
renaming /tmp/tmpi6_dlk2q/target/etc/overlayroot.local.conf to /tmp/tmpi6_dlk2q/target/etc/overlayroot.local.conf.old
Running command ['mount', '--bind', '/dev', '/tmp/tmpi6_dlk2q/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/tmp/tmpi6_dlk2q/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Diverting original update-initramfs in target.
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg-divert', '--add', '--rename', '--divert', '/usr/sbin/update-initramfs.curtin-disabled', '/usr/sbin/update-initramfs'] with allowed return codes [0] (capture=False)
Adding 'local diversion of /usr/sbin/update-initramfs to /usr/sbin/update-initramfs.curtin-disabled'
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.012
Running command ['umount', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: SUCCESS: configuring apt configuring apt
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: installing missing packages
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Curtin config dependencies requires additional packages: ['e2fsprogs']
Skipping install of {'vlan', 'ifenslave', 'bridge-utils'}.  Not needed on netplan system.
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: SUCCESS: installing missing packages
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: configuring iscsi service
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: SUCCESS: configuring iscsi service
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: configuring raid (mdadm) service
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: SUCCESS: configuring raid (mdadm) service
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: installing kernel
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
Running command ['mount', '--bind', '/dev', '/tmp/tmpi6_dlk2q/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/tmp/tmpi6_dlk2q/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'apt-get', '--quiet', '--option=Acquire::Languages=none', '--option=Dir::Etc::sourcelist=/tmp/tmpa_j_m8lm/sources.list', '--option=Dir::Etc::sourceparts=/tmp/tmpa_j_m8lm/sources.list.d', 'update'] with allowed return codes [0] (capture=False)
Hit:1 http://archive.ubuntu.com/ubuntu bionic InRelease
Get:2 http://archive.ubuntu.com/ubuntu bionic-updates InRelease [88.7 kB]
Get:3 http://archive.ubuntu.com/ubuntu bionic-security InRelease [88.7 kB]
Get:4 http://archive.ubuntu.com/ubuntu bionic-backports InRelease [74.6 kB]
Get:5 http://archive.ubuntu.com/ubuntu bionic/multiverse amd64 Packages [151 kB]
Get:6 http://archive.ubuntu.com/ubuntu bionic/universe amd64 Packages [8570 kB]
Get:7 http://archive.ubuntu.com/ubuntu bionic-updates/multiverse amd64 Packages [36.1 kB]
Get:8 http://archive.ubuntu.com/ubuntu bionic-updates/universe amd64 Packages [1693 kB]
Get:9 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 Packages [1761 kB]
Get:10 http://archive.ubuntu.com/ubuntu bionic-security/multiverse amd64 Packages [13.3 kB]
Get:11 http://archive.ubuntu.com/ubuntu bionic-security/universe amd64 Packages [1089 kB]
Get:12 http://archive.ubuntu.com/ubuntu bionic-security/restricted amd64 Packages [173 kB]
Get:13 http://archive.ubuntu.com/ubuntu bionic-security/main amd64 Packages [1425 kB]
Get:14 http://archive.ubuntu.com/ubuntu bionic-backports/universe amd64 Packages [10.3 kB]
Get:15 http://archive.ubuntu.com/ubuntu bionic-backports/main amd64 Packages [10.0 kB]
Fetched 15.2 MB in 8s (1986 kB/s)
Reading package lists...
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.011
Running command ['umount', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/tmp/tmpi6_dlk2q/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/tmp/tmpi6_dlk2q/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/tmp/tmpi6_dlk2q/target', 'eatmydata', 'apt-get', '--quiet', '--assume-yes', '--option=Dpkg::options::=--force-unsafe-io', '--option=Dpkg::Options::=--force-confold', 'install', 'linux-generic'] with allowed return codes [0] (capture=False)
Reading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
  amd64-microcode crda grub-common grub-gfxpayload-lists grub-pc grub-pc-bin
  grub2-common intel-microcode iucode-tool iw libdbus-glib-1-2 libnl-3-200
  libnl-genl-3-200 linux-firmware linux-headers-4.15.0-124
  linux-headers-4.15.0-124-generic linux-headers-generic
  linux-image-4.15.0-124-generic linux-image-generic
  linux-modules-4.15.0-124-generic linux-modules-extra-4.15.0-124-generic
  os-prober thermald wireless-regdb
Suggested packages:
  multiboot-doc grub-emu xorriso desktop-base fdutils linux-doc-4.15.0
  | linux-source-4.15.0 linux-tools
The following NEW packages will be installed:
  amd64-microcode crda grub-common grub-gfxpayload-lists grub-pc grub-pc-bin
  grub2-common intel-microcode iucode-tool iw libdbus-glib-1-2 libnl-3-200
  libnl-genl-3-200 linux-firmware linux-generic linux-headers-4.15.0-124
  linux-headers-4.15.0-124-generic linux-headers-generic
  linux-image-4.15.0-124-generic linux-image-generic
  linux-modules-4.15.0-124-generic linux-modules-extra-4.15.0-124-generic
  os-prober thermald wireless-regdb
0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded.
Need to get 147 MB of archives.
After this operation, 693 MB of additional disk space will be used.
Get:1 http://archive.ubuntu.com/ubuntu bionic/main amd64 libnl-3-200 amd64 3.2.29-0ubuntu3 [52.8 kB]
Get:2 http://archive.ubuntu.com/ubuntu bionic/main amd64 libnl-genl-3-200 amd64 3.2.29-0ubuntu3 [11.2 kB]
Get:3 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 wireless-regdb all 2018.05.09-0ubuntu1~18.04.1 [11.1 kB]
Get:4 http://archive.ubuntu.com/ubuntu bionic/main amd64 iw amd64 4.14-0.1 [75.4 kB]
Get:5 http://archive.ubuntu.com/ubuntu bionic/main amd64 crda amd64 3.18-1build1 [63.5 kB]
Get:6 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 grub-common amd64 2.02-2ubuntu8.20 [1774 kB]
Get:7 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 grub2-common amd64 2.02-2ubuntu8.20 [532 kB]
Get:8 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 grub-pc-bin amd64 2.02-2ubuntu8.20 [901 kB]
Get:9 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 grub-pc amd64 2.02-2ubuntu8.20 [138 kB]
Get:10 http://archive.ubuntu.com/ubuntu bionic/main amd64 grub-gfxpayload-lists amd64 0.7 [3658 B]
Get:11 http://archive.ubuntu.com/ubuntu bionic/main amd64 iucode-tool amd64 2.3.1-1 [45.6 kB]
Get:12 http://archive.ubuntu.com/ubuntu bionic/main amd64 libdbus-glib-1-2 amd64 0.110-2 [58.3 kB]
Get:13 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-firmware all 1.173.19 [74.8 MB]
Get:14 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-modules-4.15.0-124-generic amd64 4.15.0-124.127 [13.1 MB]
Get:15 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-image-4.15.0-124-generic amd64 4.15.0-124.127 [8012 kB]
Get:16 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-modules-extra-4.15.0-124-generic amd64 4.15.0-124.127 [32.8 MB]
Get:17 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 intel-microcode amd64 3.20201110.0ubuntu0.18.04.2 [2742 kB]
Get:18 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 amd64-microcode amd64 3.20191021.1+really3.20181128.1~ubuntu0.18.04.1 [31.6 kB]
Get:19 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-image-generic amd64 4.15.0.124.111 [2624 B]
Get:20 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-headers-4.15.0-124 all 4.15.0-124.127 [10.9 MB]
Get:21 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-headers-4.15.0-124-generic amd64 4.15.0-124.127 [1084 kB]
Get:22 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-headers-generic amd64 4.15.0.124.111 [2520 B]
Get:23 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 linux-generic amd64 4.15.0.124.111 [1868 B]
Get:24 http://archive.ubuntu.com/ubuntu bionic/main amd64 os-prober amd64 1.74ubuntu1 [19.8 kB]
Get:25 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 thermald amd64 1.7.0-5ubuntu5 [192 kB]
Preconfiguring packages ...
Fetched 147 MB in 3s (50.0 MB/s)
E: Can not write log (Is /dev/pts mounted?) - posix_openpt (19: No such device)
Selecting previously unselected package libnl-3-200:amd64.
(Reading database ... 28768 files and directories currently installed.)
Preparing to unpack .../00-libnl-3-200_3.2.29-0ubuntu3_amd64.deb ...
Unpacking libnl-3-200:amd64 (3.2.29-0ubuntu3) ...
Selecting previously unselected package libnl-genl-3-200:amd64.
Preparing to unpack .../01-libnl-genl-3-200_3.2.29-0ubuntu3_amd64.deb ...
Unpacking libnl-genl-3-200:amd64 (3.2.29-0ubuntu3) ...
Selecting previously unselected package wireless-regdb.
Preparing to unpack .../02-wireless-regdb_2018.05.09-0ubuntu1~18.04.1_all.deb ...
Unpacking wireless-regdb (2018.05.09-0ubuntu1~18.04.1) ...
Selecting previously unselected package iw.
Preparing to unpack .../03-iw_4.14-0.1_amd64.deb ...
Unpacking iw (4.14-0.1) ...
Selecting previously unselected package crda.
Preparing to unpack .../04-crda_3.18-1build1_amd64.deb ...
Unpacking crda (3.18-1build1) ...
Selecting previously unselected package grub-common.
Preparing to unpack .../05-grub-common_2.02-2ubuntu8.20_amd64.deb ...
Unpacking grub-common (2.02-2ubuntu8.20) ...
Selecting previously unselected package grub2-common.
Preparing to unpack .../06-grub2-common_2.02-2ubuntu8.20_amd64.deb ...
Unpacking grub2-common (2.02-2ubuntu8.20) ...
Selecting previously unselected package grub-pc-bin.
Preparing to unpack .../07-grub-pc-bin_2.02-2ubuntu8.20_amd64.deb ...
Unpacking grub-pc-bin (2.02-2ubuntu8.20) ...
Selecting previously unselected package grub-pc.
Preparing to unpack .../08-grub-pc_2.02-2ubuntu8.20_amd64.deb ...
Unpacking grub-pc (2.02-2ubuntu8.20) ...
Selecting previously unselected package grub-gfxpayload-lists.
Preparing to unpack .../09-grub-gfxpayload-lists_0.7_amd64.deb ...
Unpacking grub-gfxpayload-lists (0.7) ...
Selecting previously unselected package iucode-tool.
Preparing to unpack .../10-iucode-tool_2.3.1-1_amd64.deb ...
Unpacking iucode-tool (2.3.1-1) ...
Selecting previously unselected package libdbus-glib-1-2:amd64.
Preparing to unpack .../11-libdbus-glib-1-2_0.110-2_amd64.deb ...
Unpacking libdbus-glib-1-2:amd64 (0.110-2) ...
Selecting previously unselected package linux-firmware.
Preparing to unpack .../12-linux-firmware_1.173.19_all.deb ...
Unpacking linux-firmware (1.173.19) ...
Selecting previously unselected package linux-modules-4.15.0-124-generic.
Preparing to unpack .../13-linux-modules-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-modules-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package linux-image-4.15.0-124-generic.
Preparing to unpack .../14-linux-image-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-image-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package linux-modules-extra-4.15.0-124-generic.
Preparing to unpack .../15-linux-modules-extra-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-modules-extra-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package intel-microcode.
Preparing to unpack .../16-intel-microcode_3.20201110.0ubuntu0.18.04.2_amd64.deb ...
Unpacking intel-microcode (3.20201110.0ubuntu0.18.04.2) ...
ubuntu@Juju-Controller:~$ tail -f -n 20 /var/log/cloud-init-output.log 
Preparing to unpack .../14-linux-image-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-image-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package linux-modules-extra-4.15.0-124-generic.
Preparing to unpack .../15-linux-modules-extra-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-modules-extra-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package intel-microcode.
Preparing to unpack .../16-intel-microcode_3.20201110.0ubuntu0.18.04.2_amd64.deb ...
Unpacking intel-microcode (3.20201110.0ubuntu0.18.04.2) ...
Selecting previously unselected package amd64-microcode.
Preparing to unpack .../17-amd64-microcode_3.20191021.1+really3.20181128.1~ubuntu0.18.04.1_amd64.deb ...
Unpacking amd64-microcode (3.20191021.1+really3.20181128.1~ubuntu0.18.04.1) ...
Selecting previously unselected package linux-image-generic.
Preparing to unpack .../18-linux-image-generic_4.15.0.124.111_amd64.deb ...
Unpacking linux-image-generic (4.15.0.124.111) ...
Selecting previously unselected package linux-headers-4.15.0-124.
Preparing to unpack .../19-linux-headers-4.15.0-124_4.15.0-124.127_all.deb ...
Unpacking linux-headers-4.15.0-124 (4.15.0-124.127) ...
Selecting previously unselected package linux-headers-4.15.0-124-generic.
Preparing to unpack .../20-linux-headers-4.15.0-124-generic_4.15.0-124.127_amd64.deb ...
Unpacking linux-headers-4.15.0-124-generic (4.15.0-124.127) ...
Selecting previously unselected package linux-headers-generic.
Preparing to unpack .../21-linux-headers-generic_4.15.0.124.111_amd64.deb ...
Unpacking linux-headers-generic (4.15.0.124.111) ...
Selecting previously unselected package linux-generic.
Preparing to unpack .../22-linux-generic_4.15.0.124.111_amd64.deb ...
Unpacking linux-generic (4.15.0.124.111) ...
Selecting previously unselected package os-prober.
Preparing to unpack .../23-os-prober_1.74ubuntu1_amd64.deb ...
Unpacking os-prober (1.74ubuntu1) ...
Selecting previously unselected package thermald.
Preparing to unpack .../24-thermald_1.7.0-5ubuntu5_amd64.deb ...
Unpacking thermald (1.7.0-5ubuntu5) ...
Setting up libdbus-glib-1-2:amd64 (0.110-2) ...
Setting up linux-modules-4.15.0-124-generic (4.15.0-124.127) ...
Setting up linux-image-4.15.0-124-generic (4.15.0-124.127) ...
I: /vmlinuz.old is now a symlink to boot/vmlinuz-4.15.0-124-generic
I: /initrd.img.old is now a symlink to boot/initrd.img-4.15.0-124-generic
I: /vmlinuz is now a symlink to boot/vmlinuz-4.15.0-124-generic
I: /initrd.img is now a symlink to boot/initrd.img-4.15.0-124-generic
Setting up wireless-regdb (2018.05.09-0ubuntu1~18.04.1) ...
Setting up iucode-tool (2.3.1-1) ...
Setting up grub-common (2.02-2ubuntu8.20) ...
update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults
Running in chroot, ignoring request: daemon-reload
Running in chroot, ignoring request.
invoke-rc.d: policy-rc.d denied execution of start.
Setting up linux-headers-4.15.0-124 (4.15.0-124.127) ...
Setting up libnl-3-200:amd64 (3.2.29-0ubuntu3) ...
Setting up amd64-microcode (3.20191021.1+really3.20181128.1~ubuntu0.18.04.1) ...
amd64-microcode: microcode will be updated at next boot
Setting up linux-firmware (1.173.19) ...
Setting up intel-microcode (3.20201110.0ubuntu0.18.04.2) ...
intel-microcode: microcode will be updated at next boot
Setting up grub-pc-bin (2.02-2ubuntu8.20) ...
Setting up thermald (1.7.0-5ubuntu5) ...
Created symlink /etc/systemd/system/dbus-org.freedesktop.thermald.service → /lib/systemd/system/thermald.service.
Created symlink /etc/systemd/system/multi-user.target.wants/thermald.service → /lib/systemd/system/thermald.service.
Running in chroot, ignoring request: daemon-reload
/usr/sbin/policy-rc.d returned 101, not running 'start thermald.service'
Setting up grub2-common (2.02-2ubuntu8.20) ...
Setting up libnl-genl-3-200:amd64 (3.2.29-0ubuntu3) ...
Setting up os-prober (1.74ubuntu1) ...
Setting up linux-headers-4.15.0-124-generic (4.15.0-124.127) ...
Setting up iw (4.14-0.1) ...
Setting up linux-headers-generic (4.15.0.124.111) ...
Setting up crda (3.18-1build1) ...
Setting up linux-modules-extra-4.15.0-124-generic (4.15.0-124.127) ...
Setting up linux-image-generic (4.15.0.124.111) ...
Setting up linux-generic (4.15.0.124.111) ...
Setting up grub-gfxpayload-lists (0.7) ...
Setting up grub-pc (2.02-2ubuntu8.20) ...

Creating config file /etc/default/grub with new version
Processing triggers for libc-bin (2.27-3ubuntu1.3) ...
Processing triggers for systemd (237-3ubuntu10.43) ...
Running in chroot, ignoring request: daemon-reload
Processing triggers for man-db (2.8.3-2ubuntu0.1) ...
Processing triggers for dbus (1.12.2-1ubuntu1.2) ...
Processing triggers for ureadahead (0.100.0-21) ...
Processing triggers for install-info (6.5.0.dfsg.1-2) ...
Processing triggers for linux-image-4.15.0-124-generic (4.15.0-124.127) ...
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.011
Running command ['umount', '/tmp/tmpi6_dlk2q/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/tmp/tmpi6_dlk2q/target/run'] with allowed return codes [0] (capture=False)
umount: /tmp/tmpi6_dlk2q/target/run: target is busy.
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: FAIL: installing kernel
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks: FAIL: curtin command curthooks
Traceback (most recent call last):
  File "/curtin/curtin/commands/main.py", line 202, in main
    ret = args.func(args)
  File "/curtin/curtin/commands/curthooks.py", line 1770, in curthooks
    builtin_curthooks(cfg, target, state)
  File "/curtin/curtin/commands/curthooks.py", line 1613, in builtin_curthooks
    install_kernel(cfg, target)
  File "/curtin/curtin/commands/curthooks.py", line 371, in install_kernel
    distro.install_packages([kernel_package], target=target)
  File "/curtin/curtin/distro.py", line 406, in install_packages
    env=env, allow_daemons=allow_daemons)
  File "/curtin/curtin/distro.py", line 292, in run_apt_command
    return inchroot.subp(cmd, env=env)
  File "/curtin/curtin/util.py", line 698, in __exit__
    do_umount(p)
  File "/curtin/curtin/util.py", line 529, in do_umount
    subp(['umount', curmp])
  File "/curtin/curtin/util.py", line 275, in subp
    return _subp(*args, **kwargs)
  File "/curtin/curtin/util.py", line 141, in _subp
    cmd=args)
curtin.util.ProcessExecutionError: Unexpected error while running command.
Command: ['umount', '/tmp/tmpi6_dlk2q/target/run']
Exit code: 32
Reason: -
Stdout: ''
Stderr: ''
Unexpected error while running command.
Command: ['umount', '/tmp/tmpi6_dlk2q/target/run']
Exit code: 32
Reason: -
Stdout: ''
Stderr: ''
builtin command failed
finish: cmd-install/stage-curthooks/builtin: FAIL: running 'curtin curthooks'
builtin took 198.792 seconds
stage_curthooks took 198.792 seconds
finish: cmd-install/stage-curthooks: FAIL: configuring installed system