... Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: /dev/sdb is multipath device member? False Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: /dev/sdb is multipath device member? False Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: found candidate disks [{'/dev/sdb'}, {'/dev/sdb'}] Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['partprobe', '/dev/sdb'] with allowed return codes [0, 1] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED udevadm_settle(): 0.042 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: devsync happy - path /dev/sdb now exists Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return volume path /dev/sdb Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['partprobe', '/dev/sdb'] with allowed return codes [0, 1] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED udevadm_settle(): 0.051 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: devsync happy - path /dev/sdb now exists Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return volume path /dev/sdb3 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-2 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring dm_crypt: dm_crypt-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: get_path_to_storage_volume for volume partition-2({'device': 'disk-sdb', 'size': 1997122043904, 'wipe': 'superblock', 'number': 3, 'preserve': False, 'offset': 3275751424, 'id': 'partition-2', 'type': 'partition'}) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: get_path_to_storage_volume for volume disk-sdb({'ptable': 'gpt', 'serial': 'Samsung_PSSD_T7_S6XJNS0W501581L-0:0', 'path': '/dev/sdb', 'wipe': 'superblock-recursive', 'preserve': False, 'name': '', 'grub_device': False, 'id': 'disk-sdb', 'type': 'disk'}) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: /dev/sdb is multipath device member? False Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: /dev/sdb is multipath device member? False Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: found candidate disks [{'/dev/sdb'}, {'/dev/sdb'}] Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['partprobe', '/dev/sdb'] with allowed return codes [0, 1] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED udevadm_settle(): 0.048 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: devsync happy - path /dev/sdb now exists Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return volume path /dev/sdb Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['partprobe', '/dev/sdb'] with allowed return codes [0, 1] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED udevadm_settle(): 0.044 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: devsync happy - path /dev/sdb now exists Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return volume path /dev/sdb3 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Checking if zkey encryption is supported... Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Loading kernel module pkey via modprobe Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['modprobe', '--use-blacklist', 'pkey'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: modprobe: FATAL: Module pkey not found in directory /lib/modules/6.8.0-31-generic Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Failed to load 'pkey' kernel module Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Using cryptsetup on /dev/sdb3 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['cryptsetup', 'luksFormat', '/dev/sdb3', '/tmp/luks-key-ll746yk3'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Merkwürdige Optimale-Datenübertragungs-Größe für Datengerät (33553920 Bytes) wird ignoriert. Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['cryptsetup', 'open', '--type', 'luks', '/dev/sdb3', 'dm_crypt-0', '--key-file', '/tmp/luks-key-ll746yk3'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['blkid', '-o', 'full', '/dev/sdb3'] with allowed return codes [0] (capture=True) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring dm_crypt: dm_crypt-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_volgroup: lvm_volgroup-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: get_path_to_storage_volume for volume dm_crypt-0({'volume': 'partition-2', 'keyfile': '/tmp/luks-key-ll746yk3', 'recovery_keyfile': None, 'preserve': False, 'id': 'dm_crypt-0', 'type': 'dm_crypt'}) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['partprobe', '/dev/mapper/dm_crypt-0'] with allowed return codes [0, 1] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED udevadm_settle(): 0.013 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: devsync happy - path /dev/mapper/dm_crypt-0 now exists Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return volume path /dev/mapper/dm_crypt-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['vgcreate', '--force', '--zero=y', '--yes', 'ubuntu-vg', '/dev/mapper/dm_crypt-0'] with allowed return codes [0] (capture=True) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['pvscan'] with allowed return codes [0] (capture=True) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['vgscan'] with allowed return codes [0] (capture=True) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_volgroup: lvm_volgroup-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: lvm_partition-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Running command ['lvcreate', 'ubuntu-vg', '--name', 'ubuntu-lv', '--zero=y', '--wipesignatures=y', '--yes', '--size', '1997101072384B'] with allowed return codes [0] (capture=False) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Volume group "ubuntu-vg" has insufficient free space (476139 extents): 476146 required. Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: An error occured handling 'lvm_partition-0': ProcessExecutionError - Unexpected error while running command. Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Command: ['lvcreate', 'ubuntu-vg', '--name', 'ubuntu-lv', '--zero=y', '--wipesignatures=y', '--yes', '--size', '1997101072384B'] Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Exit code: 5 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Reason: - Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stdout: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stderr: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: configuring lvm_partition: lvm_partition-0 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: TIMED BLOCK_META: 11.345 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: curtin command block-meta Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Traceback (most recent call last): Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/commands/main.py", line 202, in main Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: ret = args.func(args) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/log.py", line 97, in wrapper Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return log_time("TIMED %s: " % msg, func, *args, **kwargs) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/log.py", line 79, in log_time Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return func(*args, **kwargs) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/commands/block_meta.py", line 127, in block_meta Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return meta_custom(args) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/commands/block_meta.py", line 2278, in meta_custom Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: handler(command, storage_config_dict, context) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/commands/block_meta.py", line 1585, in lvm_partition_handler Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: util.subp(cmd) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/util.py", line 323, in subp Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: return _subp(*args, **kwargs) Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: File "/snap/ubuntu-desktop-bootstrap/171/lib/python3.10/site-packages/curtin/util.py", line 172, in _subp Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: raise ProcessExecutionError(stdout=out, stderr=err, Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: curtin.util.ProcessExecutionError: Unexpected error while running command. Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Command: ['lvcreate', 'ubuntu-vg', '--name', 'ubuntu-lv', '--zero=y', '--wipesignatures=y', '--yes', '--size', '1997101072384B'] Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Exit code: 5 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Reason: - Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stdout: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stderr: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Unexpected error while running command. Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Command: ['lvcreate', 'ubuntu-vg', '--name', 'ubuntu-lv', '--zero=y', '--wipesignatures=y', '--yes', '--size', '1997101072384B'] Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Exit code: 5 Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Reason: - Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stdout: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stderr: '' Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Apr 27 09:37:11 ubuntu subiquity_log.3752[7608]: Stderr: '' Apr 27 09:37:11 ubuntu subiquity_event.3752[3752]: curtin command install Apr 27 09:37:11 ubuntu subiquity_event.3752[3752]: executing curtin install partitioning step Apr 27 09:37:11 ubuntu subiquity_event.3752[3752]: installing system Apr 27 09:37:11 ubuntu subiquity_event.3752[3752]: