Skip to content

Instantly share code, notes, and snippets.

@ammar0466

ammar0466/curtin-install.log

Created June 16, 2023 04:25
Show Gist options
  • Save ammar0466/7f455fc0ac39215564039a2f57f13247 to your computer and use it in GitHub Desktop.
Save ammar0466/7f455fc0ac39215564039a2f57f13247 to your computer and use it in GitHub Desktop.
Download ZIP
log error from pxe installer ubuntu 22
Raw
curtin-install.log
---- [[ subiquity step initial ]] ----
curtin: Installation started. (22.1-29-gc56c51aae)
curtin: Installation finished.
---- [[ subiquity step partitioning ]] ----
curtin: Installation started. (22.1-29-gc56c51aae)
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.086
devsync happy - path /dev/sda now exists
return volume path /dev/sda
get_path_to_storage_volume for volume boot-partition({'device': 'disk-sda', 'size': 536870912, 'wipe': 'superblock', 'flag': 'boot', 'number': 1, 'preserve': False, 'grub_device': True, 'type': 'partition', 'id': 'boot-partition'})
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.094
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/sda1
get_path_to_storage_volume for volume lvm-partition({'device': 'disk-sda', 'size': 20935868416, 'wipe': 'superblock', 'flag': '', 'number': 2, 'preserve': False, 'grub_device': False, 'type': 'partition', 'id': 'lvm-partition'})
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.075
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.084
devsync happy - path /dev/sda now exists
return volume path /dev/sda2
block-meta: extracted devices to clear: ['/dev/sda', '/dev/sda1', '/dev/sda2']
Declared block devices: ['/dev/sda', '/dev/sda1', '/dev/sda2']
clearing devices=['/dev/sda', '/dev/sda1', '/dev/sda2']
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/loop10
mdadm: no recogniseable superblock on /dev/loop9
mdadm: no recogniseable superblock on /dev/loop8
mdadm: no recogniseable superblock on /dev/dm-1
mdadm: no recogniseable superblock on /dev/dm-0
mdadm: no recogniseable superblock on /dev/sdb
mdadm: no recogniseable superblock on /dev/sda2
mdadm: no recogniseable superblock on /dev/sda1
mdadm: Cannot assemble mbr metadata on /dev/sda
mdadm: cannot open device /dev/sr0: No medium found
mdadm: no recogniseable superblock on /dev/loop7
mdadm: no recogniseable superblock on /dev/loop6
mdadm: no recogniseable superblock on /dev/loop5
mdadm: no recogniseable superblock on /dev/loop4
mdadm: no recogniseable superblock on /dev/loop3
mdadm: no recogniseable superblock on /dev/loop2
mdadm: no recogniseable superblock on /dev/loop1
mdadm: Cannot assemble mbr metadata 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
Detected multipath support, reload maps
Running command ['multipath', '-r'] with allowed return codes [0] (capture=False)
Verifying /dev/mapper/mpath* files are symlinks
Running command ['dmsetup', 'ls', '-o', 'blkdevname'] with allowed return codes [0] (capture=True)
multipath: dmsetup ls output:
anima--vg-root (dm-1)
anima--vg-swap (dm-0)
Running command ['pvscan', '--config', 'devices{ filter = [ "a|/dev/mapper/mpath.*|", "a|/dev/mapper/dm_crypt-.*|", "r|.*|" ] }'] with allowed return codes [0] (capture=True)
Running command ['vgscan', '--config', 'devices{ filter = [ "a|/dev/mapper/mpath.*|", "a|/dev/mapper/dm_crypt-.*|", "r|.*|" ] }'] with allowed return codes [0] (capture=True)
Running command ['vgchange', '--activate=y', '--config', 'devices{ filter = [ "a|/dev/mapper/mpath.*|", "a|/dev/mapper/dm_crypt-.*|", "r|.*|" ] }'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.021
Loading kernel module bcache via modprobe
Running command ['modprobe', '--use-blacklist', 'bcache'] with allowed return codes [0] (capture=False)
Loading kernel module zfs via modprobe
Running command ['modprobe', '--use-blacklist', 'zfs'] with allowed return codes [0] (capture=False)
zfs filesystem is not supported in this environment
Generating device storage trees for path(s): ['/dev/sda', '/dev/sda1', '/dev/sda2']
devname '/sys/class/block/sda' had holders: []
/dev/sda is multipath device partition? False
/dev/sda is multipath device partition? False
/dev/sda is multipath device partition? False
devname '/sys/class/block/sda2' had holders: ['dm-1', 'dm-0']
devname '/sys/class/block/dm-1' had holders: []
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device partition? False
Running command ['dmsetup', 'info', '/dev/dm-1', '-C', '-o', 'uuid', '--noheadings'] with allowed return codes [0] (capture=True)
devname '/sys/class/block/dm-0' had holders: []
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-0'] with allowed return codes [0] (capture=True)
/dev/dm-0 is multipath device partition? False
Running command ['dmsetup', 'info', '/dev/dm-0', '-C', '-o', 'uuid', '--noheadings'] with allowed return codes [0] (capture=True)
devname '/sys/class/block/sda1' had holders: []
devname '/sys/class/block/sda1' had holders: []
devname '/sys/class/block/sda2' had holders: ['dm-1', 'dm-0']
devname '/sys/class/block/dm-1' had holders: []
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device partition? False
Running command ['dmsetup', 'info', '/dev/dm-1', '-C', '-o', 'uuid', '--noheadings'] with allowed return codes [0] (capture=True)
devname '/sys/class/block/dm-0' had holders: []
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-0'] with allowed return codes [0] (capture=True)
/dev/dm-0 is multipath device partition? False
Running command ['dmsetup', 'info', '/dev/dm-0', '-C', '-o', 'uuid', '--noheadings'] with allowed return codes [0] (capture=True)
Current device storage tree:
sda
|-- sda2
| |-- dm-1
| `-- dm-0
`-- sda1
sda1
sda2
|-- dm-1
`-- dm-0
Shutdown Plan:
{'level': 6, 'device': '/sys/class/block/dm-1', 'dev_type': 'lvm'}
{'level': 6, 'device': '/sys/class/block/dm-0', 'dev_type': 'lvm'}
{'level': 4, 'device': '/sys/class/block/sda2', 'dev_type': 'partition'}
{'level': 4, 'device': '/sys/class/block/sda1', 'dev_type': 'partition'}
{'level': 2, 'device': '/sys/class/block/sda', 'dev_type': 'disk'}
shutdown running on holder type: 'lvm' syspath: '/sys/class/block/dm-1'
Running command ['dmsetup', 'splitname', 'anima--vg-root', '-c', '--noheadings', '--separator', '=', '-o', 'vg_name,lv_name'] with allowed return codes [0] (capture=True)
Wiping lvm logical volume: /dev/anima-vg/root
Running command ['wipefs', '--all', '--force', '/dev/anima-vg/root'] with allowed return codes [0] (capture=False)
/dev/anima-vg/root: 2 bytes were erased at offset 0x00000438 (ext4): 53 ef
wiping 1M on /dev/anima-vg/root at offsets [0, -1048576]
using "lvremove" on anima-vg/root
Running command ['lvremove', '--force', '--force', 'anima-vg/root'] with allowed return codes [0] (capture=False)
Logical volume "root" successfully removed
Running command ['lvdisplay', '-C', '--separator', '=', '--noheadings', '-o', 'vg_name,lv_name'] with allowed return codes [0] (capture=True)
Running command ['pvscan'] with allowed return codes [0] (capture=True)
Running command ['vgscan'] with allowed return codes [0] (capture=True)
shutdown running on holder type: 'lvm' syspath: '/sys/class/block/dm-0'
Running command ['dmsetup', 'splitname', 'anima--vg-swap', '-c', '--noheadings', '--separator', '=', '-o', 'vg_name,lv_name'] with allowed return codes [0] (capture=True)
Wiping lvm logical volume: /dev/anima-vg/swap
Running command ['wipefs', '--all', '--force', '/dev/anima-vg/swap'] with allowed return codes [0] (capture=False)
/dev/anima-vg/swap: 10 bytes were erased at offset 0x00000ff6 (swap): 53 57 41 50 53 50 41 43 45 32
wiping 1M on /dev/anima-vg/swap at offsets [0, -1048576]
using "lvremove" on anima-vg/swap
Running command ['lvremove', '--force', '--force', 'anima-vg/swap'] with allowed return codes [0] (capture=False)
Logical volume "swap" successfully removed
Running command ['lvdisplay', '-C', '--separator', '=', '--noheadings', '-o', 'vg_name,lv_name'] with allowed return codes [0] (capture=True)
Running command ['pvdisplay', '-C', '--separator', '=', '--noheadings', '-o', 'vg_name,pv_name'] with allowed return codes [0] (capture=True)
Running command ['vgremove', '--force', '--force', 'anima-vg'] with allowed return codes [0, 5] (capture=False)
Volume group "anima-vg" successfully removed
Wiping lvm physical volume: /dev/sda2
Running command ['wipefs', '--all', '--force', '/dev/sda2'] with allowed return codes [0] (capture=False)
/dev/sda2: 8 bytes were erased at offset 0x00000218 (LVM2_member): 4c 56 4d 32 20 30 30 31
wiping 1M on /dev/sda2 at offsets [0, -1048576]
Running command ['pvscan'] with allowed return codes [0] (capture=True)
Running command ['vgscan'] with allowed return codes [0] (capture=True)
shutdown running on holder type: 'partition' syspath: '/sys/class/block/sda2'
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": "VBOX HARDDISK",
"MOUNTPOINT": "",
"NAME": "sda",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "64",
"SIZE": "21474836480",
"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": "ext4",
"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": "64",
"SIZE": "536870912",
"STATE": "",
"TYPE": "part",
"UUID": "d1b85c34-8dba-4813-882a-826c5a9c00b8",
"device_path": "/dev/sda1"
},
"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": "64",
"SIZE": "20935868416",
"STATE": "",
"TYPE": "part",
"UUID": "",
"device_path": "/dev/sda2"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Checking if /dev/sda2 is a swap device
Found swap magic: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda2'] with allowed return codes [0] (capture=True)
/dev/sda2 is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda2'] with allowed return codes [0] (capture=True)
/dev/sda2 is multipath device member? False
wiping superblock on /dev/sda2
wiping /dev/sda2 attempt 1/4
Running command ['wipefs', '--all', '--force', '/dev/sda2'] with allowed return codes [0] (capture=False)
wiping 1M on /dev/sda2 at offsets [0, -1048576]
successfully wiped device /dev/sda2 on attempt 1/4
shutdown running on holder type: 'partition' syspath: '/sys/class/block/sda1'
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": "VBOX HARDDISK",
"MOUNTPOINT": "",
"NAME": "sda",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "64",
"SIZE": "21474836480",
"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": "ext4",
"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": "64",
"SIZE": "536870912",
"STATE": "",
"TYPE": "part",
"UUID": "d1b85c34-8dba-4813-882a-826c5a9c00b8",
"device_path": "/dev/sda1"
},
"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": "64",
"SIZE": "20935868416",
"STATE": "",
"TYPE": "part",
"UUID": "",
"device_path": "/dev/sda2"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Checking if /dev/sda1 is a swap device
Found swap magic: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device member? False
wiping superblock on /dev/sda1
wiping /dev/sda1 attempt 1/4
Running command ['wipefs', '--all', '--force', '/dev/sda1'] with allowed return codes [0] (capture=False)
/dev/sda1: 2 bytes were erased at offset 0x00000438 (ext4): 53 ef
wiping 1M on /dev/sda1 at offsets [0, -1048576]
successfully wiped device /dev/sda1 on attempt 1/4
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": "VBOX HARDDISK",
"MOUNTPOINT": "",
"NAME": "sda",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "64",
"SIZE": "21474836480",
"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": "64",
"SIZE": "536870912",
"STATE": "",
"TYPE": "part",
"UUID": "",
"device_path": "/dev/sda1"
},
"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": "64",
"SIZE": "20935868416",
"STATE": "",
"TYPE": "part",
"UUID": "",
"device_path": "/dev/sda2"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Checking if /dev/sda is a swap device
Found swap magic: b'\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00'
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
wiping superblock on /dev/sda
wiping /dev/sda attempt 1/4
Running command ['wipefs', '--all', '--force', '/dev/sda'] with allowed return codes [0] (capture=False)
/dev/sda: 8 bytes were erased at offset 0x00000200 (gpt): 45 46 49 20 50 41 52 54
/dev/sda: 8 bytes were erased at offset 0x4fffffe00 (gpt): 45 46 49 20 50 41 52 54
/dev/sda: 2 bytes were erased at offset 0x000001fe (PMBR): 55 aa
wiping 1M on /dev/sda at offsets [0, -1048576]
successfully wiped device /dev/sda on attempt 1/4
/sys/class/block/sda had partitions, issuing partition reread
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.019
block.sys_block_path: devname /dev/sda1 does not exist
block.sys_block_path: devname /dev/sda2 does not exist
devname '/sys/class/block/sda' had holders: []
/dev/sda is multipath device partition? False
/dev/sda is multipath device partition? False
/dev/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: disk-sda
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.082
devsync happy - path /dev/sda now exists
return volume path /dev/sda
Running command ['wipefs', '--all', '--force', '/dev/sda'] with allowed return codes [0] (capture=False)
wiping 1M on /dev/sda at offsets [0, -1048576]
labeling device: '/dev/sda' with 'gpt' partition table
Running command ['wipefs', '--all', '--force', '/dev/sda'] with allowed return codes [0] (capture=False)
wiping 1M on /dev/sda at offsets [0, -1048576]
devname '/dev/sda' had holders: []
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring disk: disk-sda
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: boot-partition
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.066
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": "VBOX HARDDISK",
"MOUNTPOINT": "",
"NAME": "sda",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "64",
"SIZE": "21474836480",
"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 'boot-partition' to disk 'disk-sda' (ptable: 'gpt')
partnum: 1 offset_sectors: 2048 length_sectors: 1048575
Preparing partition location on disk /dev/sda
Wiping 1M on /dev/sda at offset 1048576
Running command ['sgdisk', '--new', '1:2048:1050623', '--typecode=1:ef00', '/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.288
TIMED udevadm_settle(exists='/dev/sda1'): 0.000
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: boot-partition
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-0
get_path_to_storage_volume for volume boot-partition({'device': 'disk-sda', 'size': 536870912, 'wipe': 'superblock', 'flag': 'boot', 'number': 1, 'preserve': False, 'grub_device': True, 'type': 'partition', 'id': 'boot-partition'})
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.182
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.166
devsync happy - path /dev/sda now exists
return volume path /dev/sda1
mkfs /dev/sda1 info: {'fstype': 'ext4', 'volume': 'boot-partition', 'preserve': False, 'type': 'format', 'id': 'format-0'}
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/sda1'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
"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": "64",
"SIZE": "536870912",
"STATE": "",
"TYPE": "part",
"UUID": "",
"device_path": "/dev/sda1"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Running command ['mkfs.ext4', '-F', '-U', '9b59c814-1660-44fa-9784-8c0c1fc58c24', '/dev/sda1'] with allowed return codes [0] (capture=True)
Formated device type: partition
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-0
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: lvm-partition
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.235
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": "VBOX HARDDISK",
"MOUNTPOINT": "",
"NAME": "sda",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "64",
"SIZE": "21474836480",
"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": "ext4",
"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": "64",
"SIZE": "536870912",
"STATE": "",
"TYPE": "part",
"UUID": "9b59c814-1660-44fa-9784-8c0c1fc58c24",
"device_path": "/dev/sda1"
}
}
get_blockdev_sector_size: (log=512, phys=512)
sda logical_block_size_bytes: 512
previous partition number for 'lvm-partition' found to be '1'
partition_kname=sda1
calc_partition_info: sda1 size_sectors=1048576 start_sectors=2048
adding partition 'lvm-partition' to disk 'disk-sda' (ptable: 'gpt')
partnum: 2 offset_sectors: 1050624 length_sectors: 40890367
Preparing partition location on disk /dev/sda
Wiping 1M on /dev/sda at offset 537919488
Running command ['sgdisk', '--new', '2:1050624:41940991', '--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.428
TIMED udevadm_settle(exists='/dev/sda2'): 0.000
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: lvm-partition
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_volgroup: anima-vg
get_path_to_storage_volume for volume lvm-partition({'device': 'disk-sda', 'size': 20935868416, 'wipe': 'superblock', 'flag': '', 'number': 2, 'preserve': False, 'grub_device': False, 'type': 'partition', 'id': 'lvm-partition'})
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.206
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.210
devsync happy - path /dev/sda now exists
return volume path /dev/sda2
Running command ['vgcreate', '--force', '--zero=y', '--yes', 'anima-vg', '/dev/sda2'] with allowed return codes [0] (capture=True)
Running command ['pvscan'] with allowed return codes [0] (capture=True)
Running command ['vgscan'] with allowed return codes [0] (capture=True)
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_volgroup: anima-vg
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: swap
Running command ['lvcreate', 'anima-vg', '--name', 'swap', '--zero=y', '--wipesignatures=y', '--yes', '--size', '17179869184.0B'] with allowed return codes [0] (capture=False)
Logical volume "swap" created.
Running command ['pvscan'] with allowed return codes [0] (capture=True)
Running command ['vgscan'] with allowed return codes [0] (capture=True)
get_path_to_storage_volume for volume swap({'name': 'swap', 'volgroup': 'anima-vg', 'size': '17179869184B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'swap'})
Running command ['partprobe', '/dev/anima-vg/swap'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.062
devsync happy - path /dev/anima-vg/swap now exists
return volume path /dev/anima-vg/swap
Wiping logical volume /dev/anima-vg/swap mode=superblock
Running command ['wipefs', '--all', '--force', '/dev/anima-vg/swap'] with allowed return codes [0] (capture=False)
wiping 1M on /dev/anima-vg/swap at offsets [0, -1048576]
get_path_to_storage_volume for volume swap({'name': 'swap', 'volgroup': 'anima-vg', 'size': '17179869184B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'swap'})
Running command ['partprobe', '/dev/anima-vg/swap'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.043
devsync happy - path /dev/anima-vg/swap now exists
return volume path /dev/anima-vg/swap
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/anima-vg/swap'] with allowed return codes [0] (capture=True)
Creating dname udev rule '['SUBSYSTEM=="block"', 'ACTION=="add|change"', 'ENV{DM_NAME}=="anima--vg-swap"', 'SYMLINK+="disk/by-dname/anima--vg-swap"\n']'
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: swap
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-1
get_path_to_storage_volume for volume swap({'name': 'swap', 'volgroup': 'anima-vg', 'size': '17179869184B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'swap'})
Running command ['partprobe', '/dev/anima-vg/swap'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.043
devsync happy - path /dev/anima-vg/swap now exists
return volume path /dev/anima-vg/swap
mkfs /dev/anima-vg/swap info: {'fstype': 'swap', 'volume': 'swap', 'preserve': False, 'type': 'format', 'id': 'format-1'}
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/anima-vg/swap'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
"dm-0": {
"ALIGNMENT": "0",
"DISC-ALN": "0",
"DISC-GRAN": "0",
"DISC-MAX": "0",
"DISC-ZERO": "0",
"FSTYPE": "",
"GROUP": "disk",
"KNAME": "dm-0",
"LABEL": "",
"LOG-SEC": "512",
"MAJ:MIN": "253:0",
"MIN-IO": "512",
"MODE": "brw-rw----",
"MODEL": "",
"MOUNTPOINT": "",
"NAME": "anima--vg-swap",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "128",
"SIZE": "17179869184",
"STATE": "running",
"TYPE": "lvm",
"UUID": "",
"device_path": "/dev/dm-0"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Running command ['mkswap', '--force', '--uuid', '88be7603-aa33-405e-8dbd-a25c00b3fdc1', '/dev/anima-vg/swap'] with allowed return codes [0] (capture=True)
Formated device type: lvm_partition
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-1
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-1
get_path_to_storage_volume for volume swap({'name': 'swap', 'volgroup': 'anima-vg', 'size': '17179869184B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'swap'})
Running command ['partprobe', '/dev/anima-vg/swap'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.028
devsync happy - path /dev/anima-vg/swap now exists
return volume path /dev/anima-vg/swap
volume_path=/dev/anima-vg/swap found slaves: ['sda2']
kname_is_iscsi: no iscsi disk found for kname dm-0
kname_is_iscsi: no iscsi disk found for kname sda2
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/anima-vg/swap'] 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/anima-vg/swap'] with allowed return codes [0] (capture=True)
volspec: path=/dev/anima-vg/swap type=lvm
info[DEVLINKS] = ['/dev/anima-vg/swap', '/dev/disk/by-id/dm-name-anima--vg-swap', '/dev/mapper/anima--vg-swap', '/dev/disk/by-uuid/88be7603-aa33-405e-8dbd-a25c00b3fdc1', '/dev/disk/by-id/dm-uuid-LVM-cftqkqvxNOsePRTQFHYe8uX10lTOg8kOwEKy3LUwxDjL5dOs38oe2TPObYFOCBmn']
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-1
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: root
Running command ['lvcreate', 'anima-vg', '--name', 'root', '--zero=y', '--wipesignatures=y', '--yes', '--size', '3753902080.0B'] with allowed return codes [0] (capture=False)
Logical volume "root" created.
Running command ['pvscan'] with allowed return codes [0] (capture=True)
Running command ['vgscan'] with allowed return codes [0] (capture=True)
get_path_to_storage_volume for volume root({'name': 'root', 'volgroup': 'anima-vg', 'size': '3753902080B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'root'})
Running command ['partprobe', '/dev/anima-vg/root'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.070
devsync happy - path /dev/anima-vg/root now exists
return volume path /dev/anima-vg/root
Wiping logical volume /dev/anima-vg/root mode=superblock
Running command ['wipefs', '--all', '--force', '/dev/anima-vg/root'] with allowed return codes [0] (capture=False)
wiping 1M on /dev/anima-vg/root at offsets [0, -1048576]
get_path_to_storage_volume for volume root({'name': 'root', 'volgroup': 'anima-vg', 'size': '3753902080B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'root'})
Running command ['partprobe', '/dev/anima-vg/root'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.062
devsync happy - path /dev/anima-vg/root now exists
return volume path /dev/anima-vg/root
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/anima-vg/root'] with allowed return codes [0] (capture=True)
Creating dname udev rule '['SUBSYSTEM=="block"', 'ACTION=="add|change"', 'ENV{DM_NAME}=="anima--vg-root"', 'SYMLINK+="disk/by-dname/anima--vg-root"\n']'
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: root
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-2
get_path_to_storage_volume for volume root({'name': 'root', 'volgroup': 'anima-vg', 'size': '3753902080B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'root'})
Running command ['partprobe', '/dev/anima-vg/root'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.061
devsync happy - path /dev/anima-vg/root now exists
return volume path /dev/anima-vg/root
mkfs /dev/anima-vg/root info: {'fstype': 'ext4', 'volume': 'root', 'preserve': False, 'type': 'format', 'id': 'format-2'}
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/anima-vg/root'] with allowed return codes [0] (capture=True)
get_blockdev_sector_size: info:
{
"dm-1": {
"ALIGNMENT": "0",
"DISC-ALN": "0",
"DISC-GRAN": "0",
"DISC-MAX": "0",
"DISC-ZERO": "0",
"FSTYPE": "",
"GROUP": "disk",
"KNAME": "dm-1",
"LABEL": "",
"LOG-SEC": "512",
"MAJ:MIN": "253:1",
"MIN-IO": "512",
"MODE": "brw-rw----",
"MODEL": "",
"MOUNTPOINT": "",
"NAME": "anima--vg-root",
"OPT-IO": "0",
"OWNER": "root",
"PHY-SEC": "512",
"RM": "0",
"RO": "0",
"ROTA": "1",
"RQ-SIZE": "128",
"SIZE": "3753902080",
"STATE": "running",
"TYPE": "lvm",
"UUID": "",
"device_path": "/dev/dm-1"
}
}
get_blockdev_sector_size: (log=512, phys=512)
Running command ['mkfs.ext4', '-F', '-U', '3ca53f1d-b636-4181-b7f5-3c23b657851f', '/dev/anima-vg/root'] with allowed return codes [0] (capture=True)
Formated device type: lvm_partition
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-2
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-2
get_path_to_storage_volume for volume root({'name': 'root', 'volgroup': 'anima-vg', 'size': '3753902080B', 'wipe': 'superblock', 'preserve': False, 'type': 'lvm_partition', 'id': 'root'})
Running command ['partprobe', '/dev/anima-vg/root'] with allowed return codes [0, 1] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.050
devsync happy - path /dev/anima-vg/root now exists
return volume path /dev/anima-vg/root
volume_path=/dev/anima-vg/root found slaves: ['sda2']
kname_is_iscsi: no iscsi disk found for kname dm-1
kname_is_iscsi: no iscsi disk found for kname sda2
Running command ['mount', '-t', 'ext4', '-o', 'defaults', '/dev/anima-vg/root', '/target/'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/anima-vg/root'] 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/anima-vg/root'] with allowed return codes [0] (capture=True)
volspec: path=/dev/anima-vg/root type=lvm
info[DEVLINKS] = ['/dev/disk/by-id/dm-uuid-LVM-cftqkqvxNOsePRTQFHYe8uX10lTOg8kODv6DBNGSJzU3TbQIqFxcfRXgTOgcEViP', '/dev/anima-vg/root', '/dev/mapper/anima--vg-root', '/dev/disk/by-id/dm-name-anima--vg-root', '/dev/disk/by-uuid/3ca53f1d-b636-4181-b7f5-3c23b657851f']
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-2
start: cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-0
get_path_to_storage_volume for volume boot-partition({'device': 'disk-sda', 'size': 536870912, 'wipe': 'superblock', 'flag': 'boot', 'number': 1, 'preserve': False, 'grub_device': True, 'type': 'partition', 'id': 'boot-partition'})
get_path_to_storage_volume for volume disk-sda({'ptable': 'gpt', 'serial': 'VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'path': '/dev/sda', 'wipe': 'superblock', 'preserve': False, 'name': '', 'grub_device': True, 'type': 'disk', 'id': 'disk-sda'})
Processing serial VBOX_HARDDISK_VBbfa36849-2c4eb02e via udev to VBOX_HARDDISK_VBbfa36849-2c4eb02e
lookup_disks found: ['ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part2', 'scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', 'scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1']
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda'] with allowed return codes [0] (capture=True)
/dev/sda is multipath device member? False
block.lookup_disk() returning 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.094
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.086
devsync happy - path /dev/sda now exists
return volume path /dev/sda1
volume_path=/dev/sda1 found slaves: ['sda1']
kname_is_iscsi: no iscsi disk found for kname sda1
kname_is_iscsi: no iscsi disk found for kname sda1
Running command ['mount', '-t', 'ext4', '-o', 'defaults', '/dev/sda1', '/target/boot/efi'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] 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/sda1'] with allowed return codes [0] (capture=True)
volspec: path=/dev/sda1 type=part
info[DEVLINKS] = ['/dev/disk/by-path/pci-0000:00:0d.0-ata-1.0-part1', '/dev/disk/by-uuid/9b59c814-1660-44fa-9784-8c0c1fc58c24', '/dev/disk/by-id/scsi-0ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', '/dev/disk/by-path/pci-0000:00:0d.0-ata-1-part1', '/dev/disk/by-partuuid/d5a30389-2c38-4d47-b753-8988486dcba9', '/dev/disk/by-id/scsi-1ATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', '/dev/disk/by-id/scsi-SATA_VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1', '/dev/disk/by-id/ata-VBOX_HARDDISK_VBbfa36849-2c4eb02e-part1']
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-0
TIMED BLOCK_META: 11.262
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: curtin command block-meta
curtin: Installation finished.
---- [[ subiquity step extract ]] ----
curtin: Installation started. (22.1-29-gc56c51aae)
start: cmd-install/stage-extract/builtin/cmd-extract: curtin command extract
Installing sources: [{'type': 'tgz', 'uri': 'cp:///tmp/tmpt368at9e/mount'}] to target at /target
start: cmd-install/stage-extract/builtin/cmd-extract: acquiring and extracting image from cp:///tmp/tmpt368at9e/mount
Running command ['sh', '-c', 'mkdir -p "$2" && cd "$2" && rsync -aXHAS --one-file-system "$1/" .', '--', '/tmp/tmpt368at9e/mount', '/target'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-extract/builtin/cmd-extract: SUCCESS: acquiring and extracting image from cp:///tmp/tmpt368at9e/mount
Applying write_files from config.
finish: cmd-install/stage-extract/builtin/cmd-extract: SUCCESS: curtin command extract
curtin: Installation finished.
---- [[ subiquity step curthooks ]] ----
curtin: Installation started. (22.1-29-gc56c51aae)
start: cmd-install/stage-curthooks/002-setupcon-save-only/cmd-in-target: curtin command in-target
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'setupcon', '--save-only'] with allowed return codes [0] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.022
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/002-setupcon-save-only/cmd-in-target: SUCCESS: curtin command in-target
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 {'subiquity': ''} into new format: {'debconf_selections': {'subiquity': ''}}
curthooks handling apt to target /target with config {'debconf_selections': {'subiquity': ''}}
Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/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', '/target', 'debconf-set-selections'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Diverting original update-initramfs in target.
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/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.016
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/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', '/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', '/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Curtin config dependencies requires additional packages: ['lvm2', 'lvm2', 'e2fsprogs']
Skipping install of {'vlan', 'bridge-utils', 'ifenslave'}. 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', '/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', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'apt-get', '--quiet', '--option=Acquire::Languages=none', '--option=Dir::Etc::sourcelist=/tmp/tmpdt_akq_r/sources.list', '--option=Dir::Etc::sourceparts=/tmp/tmpdt_akq_r/sources.list.d', 'update'] with allowed return codes [0] (capture=False)
Running in chroot, ignoring command 'start'
Get:1 file:/cdrom jammy InRelease
Ign:1 file:/cdrom jammy InRelease
Get:2 file:/cdrom jammy Release [1486 B]
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Get:2 file:/cdrom jammy Release [1486 B]
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Err:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Reading package lists...
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-updates/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-backports/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-security/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Some index files failed to download. They have been ignored, or old ones used instead.
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.023
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'eatmydata', 'apt-get', '--quiet', '--assume-yes', '--option=Dpkg::options::=--force-unsafe-io', '--option=Dpkg::Options::=--force-confold', 'install', '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 firmware-sof-signed grub-common grub-gfxpayload-lists
grub-pc grub-pc-bin grub2-common intel-microcode iucode-tool
libdbus-glib-1-2 libevdev2 libfreetype6 libimobiledevice6 libplist3
libupower-glib3 libusbmuxd6 linux-firmware linux-headers-5.15.0-60
linux-headers-5.15.0-60-generic linux-headers-generic
linux-image-5.15.0-60-generic linux-image-generic
linux-modules-5.15.0-60-generic linux-modules-extra-5.15.0-60-generic
os-prober thermald upower usbmuxd wireless-regdb
Suggested packages:
multiboot-doc grub-emu mtools xorriso desktop-base libusbmuxd-tools fdutils
linux-doc | linux-source-5.15.0 linux-tools
The following NEW packages will be installed:
amd64-microcode firmware-sof-signed grub-common grub-gfxpayload-lists
grub-pc grub-pc-bin grub2-common intel-microcode iucode-tool
libdbus-glib-1-2 libevdev2 libfreetype6 libimobiledevice6 libplist3
libupower-glib3 libusbmuxd6 linux-firmware linux-generic
linux-headers-5.15.0-60 linux-headers-5.15.0-60-generic
linux-headers-generic linux-image-5.15.0-60-generic linux-image-generic
linux-modules-5.15.0-60-generic linux-modules-extra-5.15.0-60-generic
os-prober thermald upower usbmuxd wireless-regdb
0 upgraded, 30 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/370 MB of archives.
After this operation, 1554 MB of additional disk space will be used.
Get:1 file:/cdrom jammy/restricted amd64 firmware-sof-signed all 2.0-1ubuntu4.1 [820 kB]
Get:2 file:/cdrom jammy/main amd64 libfreetype6 amd64 2.11.1+dfsg-1ubuntu0.1 [389 kB]
Get:3 file:/cdrom jammy/main amd64 grub-common amd64 2.06-2ubuntu7.1 [2188 kB]
Get:4 file:/cdrom jammy/main amd64 grub2-common amd64 2.06-2ubuntu7.1 [651 kB]
Get:5 file:/cdrom jammy/main amd64 grub-pc-bin amd64 2.06-2ubuntu7.1 [1083 kB]
Get:6 file:/cdrom jammy/main amd64 grub-pc amd64 2.06-2ubuntu7.1 [132 kB]
Get:7 file:/cdrom jammy/main amd64 grub-gfxpayload-lists amd64 0.7 [3658 B]
Get:8 file:/cdrom jammy/main amd64 iucode-tool amd64 2.3.1-1build1 [46.9 kB]
Get:9 file:/cdrom jammy/main amd64 libdbus-glib-1-2 amd64 0.112-2build1 [65.4 kB]
Get:10 file:/cdrom jammy/main amd64 libplist3 amd64 2.2.0-6build2 [32.1 kB]
Get:11 file:/cdrom jammy/main amd64 libusbmuxd6 amd64 2.0.2-3build2 [20.4 kB]
Get:12 file:/cdrom jammy/main amd64 libimobiledevice6 amd64 1.3.0-6build3 [71.1 kB]
Get:13 file:/cdrom jammy/main amd64 libupower-glib3 amd64 0.99.17-1 [46.7 kB]
Get:14 file:/cdrom jammy/main amd64 linux-firmware all 20220329.git681281e4-0ubuntu3.10 [247 MB]
Get:15 file:/cdrom jammy/main amd64 linux-modules-5.15.0-60-generic amd64 5.15.0-60.66 [22.8 MB]
Get:16 file:/cdrom jammy/main amd64 linux-image-5.15.0-60-generic amd64 5.15.0-60.66 [11.4 MB]
Get:17 file:/cdrom jammy/main amd64 wireless-regdb all 2022.06.06-0ubuntu1~22.04.1 [10.3 kB]
Get:18 file:/cdrom jammy/main amd64 linux-modules-extra-5.15.0-60-generic amd64 5.15.0-60.66 [64.0 MB]
Get:19 file:/cdrom jammy/main amd64 intel-microcode amd64 3.20220809.0ubuntu0.22.04.1 [4039 kB]
Get:20 file:/cdrom jammy/main amd64 amd64-microcode amd64 3.20191218.1ubuntu2 [32.5 kB]
Get:21 file:/cdrom jammy/main amd64 linux-image-generic amd64 5.15.0.60.58 [2466 B]
Get:22 file:/cdrom jammy/main amd64 linux-headers-5.15.0-60 all 5.15.0-60.66 [12.3 MB]
Get:23 file:/cdrom jammy/main amd64 linux-headers-5.15.0-60-generic amd64 5.15.0-60.66 [2871 kB]
Get:24 file:/cdrom jammy/main amd64 linux-headers-generic amd64 5.15.0.60.58 [2316 B]
Get:25 file:/cdrom jammy/main amd64 linux-generic amd64 5.15.0.60.58 [1698 B]
Get:26 file:/cdrom jammy/main amd64 os-prober amd64 1.79ubuntu2 [19.3 kB]
Get:27 file:/cdrom jammy/main amd64 libevdev2 amd64 1.12.1+dfsg-1 [39.5 kB]
Get:28 file:/cdrom jammy/main amd64 thermald amd64 2.4.9-1ubuntu0.1 [212 kB]
Get:29 file:/cdrom jammy/main amd64 upower amd64 0.99.17-1 [86.7 kB]
Get:30 file:/cdrom jammy/main amd64 usbmuxd amd64 1.1.1-2build2 [42.8 kB]
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
dpkg-preconfigure: unable to re-open stdin:
E: Can not write log (Is /dev/pts mounted?) - posix_openpt (19: No such device)
Selecting previously unselected package firmware-sof-signed.
(Reading database ... 34175 files and directories currently installed.)
Preparing to unpack .../00-firmware-sof-signed_2.0-1ubuntu4.1_all.deb ...
Unpacking firmware-sof-signed (2.0-1ubuntu4.1) ...
Selecting previously unselected package libfreetype6:amd64.
Preparing to unpack .../01-libfreetype6_2.11.1+dfsg-1ubuntu0.1_amd64.deb ...
Unpacking libfreetype6:amd64 (2.11.1+dfsg-1ubuntu0.1) ...
Selecting previously unselected package grub-common.
Preparing to unpack .../02-grub-common_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-common (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub2-common.
Preparing to unpack .../03-grub2-common_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub2-common (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-pc-bin.
Preparing to unpack .../04-grub-pc-bin_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-pc-bin (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-pc.
Preparing to unpack .../05-grub-pc_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-pc (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-gfxpayload-lists.
Preparing to unpack .../06-grub-gfxpayload-lists_0.7_amd64.deb ...
Unpacking grub-gfxpayload-lists (0.7) ...
Selecting previously unselected package iucode-tool.
Preparing to unpack .../07-iucode-tool_2.3.1-1build1_amd64.deb ...
Unpacking iucode-tool (2.3.1-1build1) ...
Selecting previously unselected package libdbus-glib-1-2:amd64.
Preparing to unpack .../08-libdbus-glib-1-2_0.112-2build1_amd64.deb ...
Unpacking libdbus-glib-1-2:amd64 (0.112-2build1) ...
Selecting previously unselected package libplist3:amd64.
Preparing to unpack .../09-libplist3_2.2.0-6build2_amd64.deb ...
Unpacking libplist3:amd64 (2.2.0-6build2) ...
Selecting previously unselected package libusbmuxd6:amd64.
Preparing to unpack .../10-libusbmuxd6_2.0.2-3build2_amd64.deb ...
Unpacking libusbmuxd6:amd64 (2.0.2-3build2) ...
Selecting previously unselected package libimobiledevice6:amd64.
Preparing to unpack .../11-libimobiledevice6_1.3.0-6build3_amd64.deb ...
Unpacking libimobiledevice6:amd64 (1.3.0-6build3) ...
Selecting previously unselected package libupower-glib3:amd64.
Preparing to unpack .../12-libupower-glib3_0.99.17-1_amd64.deb ...
Unpacking libupower-glib3:amd64 (0.99.17-1) ...
Selecting previously unselected package linux-firmware.
Preparing to unpack .../13-linux-firmware_20220329.git681281e4-0ubuntu3.10_all.deb ...
Unpacking linux-firmware (20220329.git681281e4-0ubuntu3.10) ...
Selecting previously unselected package linux-modules-5.15.0-60-generic.
Preparing to unpack .../14-linux-modules-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-modules-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package linux-image-5.15.0-60-generic.
Preparing to unpack .../15-linux-image-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-image-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package wireless-regdb.
Preparing to unpack .../16-wireless-regdb_2022.06.06-0ubuntu1~22.04.1_all.deb ...
Unpacking wireless-regdb (2022.06.06-0ubuntu1~22.04.1) ...
Selecting previously unselected package linux-modules-extra-5.15.0-60-generic.
Preparing to unpack .../17-linux-modules-extra-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-modules-extra-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package intel-microcode.
Preparing to unpack .../18-intel-microcode_3.20220809.0ubuntu0.22.04.1_amd64.deb ...
Unpacking intel-microcode (3.20220809.0ubuntu0.22.04.1) ...
Selecting previously unselected package amd64-microcode.
Preparing to unpack .../19-amd64-microcode_3.20191218.1ubuntu2_amd64.deb ...
Unpacking amd64-microcode (3.20191218.1ubuntu2) ...
Selecting previously unselected package linux-image-generic.
Preparing to unpack .../20-linux-image-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-image-generic (5.15.0.60.58) ...
Selecting previously unselected package linux-headers-5.15.0-60.
Preparing to unpack .../21-linux-headers-5.15.0-60_5.15.0-60.66_all.deb ...
Unpacking linux-headers-5.15.0-60 (5.15.0-60.66) ...
Selecting previously unselected package linux-headers-5.15.0-60-generic.
Preparing to unpack .../22-linux-headers-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-headers-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package linux-headers-generic.
Preparing to unpack .../23-linux-headers-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-headers-generic (5.15.0.60.58) ...
Selecting previously unselected package linux-generic.
Preparing to unpack .../24-linux-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-generic (5.15.0.60.58) ...
Selecting previously unselected package os-prober.
Preparing to unpack .../25-os-prober_1.79ubuntu2_amd64.deb ...
Unpacking os-prober (1.79ubuntu2) ...
Selecting previously unselected package libevdev2:amd64.
Preparing to unpack .../26-libevdev2_1.12.1+dfsg-1_amd64.deb ...
Unpacking libevdev2:amd64 (1.12.1+dfsg-1) ...
Selecting previously unselected package thermald.
Preparing to unpack .../27-thermald_2.4.9-1ubuntu0.1_amd64.deb ...
Unpacking thermald (2.4.9-1ubuntu0.1) ...
Selecting previously unselected package upower.
Preparing to unpack .../28-upower_0.99.17-1_amd64.deb ...
Unpacking upower (0.99.17-1) ...
Selecting previously unselected package usbmuxd.
Preparing to unpack .../29-usbmuxd_1.1.1-2build2_amd64.deb ...
Unpacking usbmuxd (1.1.1-2build2) ...
Setting up libplist3:amd64 (2.2.0-6build2) ...
Setting up iucode-tool (2.3.1-1build1) ...
Setting up linux-firmware (20220329.git681281e4-0ubuntu3.10) ...
Setting up intel-microcode (3.20220809.0ubuntu0.22.04.1) ...
intel-microcode: microcode will be updated at next boot
Setting up firmware-sof-signed (2.0-1ubuntu4.1) ...
Setting up wireless-regdb (2022.06.06-0ubuntu1~22.04.1) ...
Setting up amd64-microcode (3.20191218.1ubuntu2) ...
amd64-microcode: microcode will be updated at next boot
Setting up libfreetype6:amd64 (2.11.1+dfsg-1ubuntu0.1) ...
Setting up libdbus-glib-1-2:amd64 (0.112-2build1) ...
Setting up libusbmuxd6:amd64 (2.0.2-3build2) ...
Setting up libupower-glib3:amd64 (0.99.17-1) ...
Setting up libimobiledevice6:amd64 (1.3.0-6build3) ...
Setting up linux-headers-5.15.0-60 (5.15.0-60.66) ...
Setting up libevdev2:amd64 (1.12.1+dfsg-1) ...
Setting up upower (0.99.17-1) ...
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start upower.service'
Setting up linux-headers-5.15.0-60-generic (5.15.0-60.66) ...
Setting up linux-headers-generic (5.15.0.60.58) ...
Setting up grub-common (2.06-2ubuntu7.1) ...
Created symlink /etc/systemd/system/multi-user.target.wants/grub-common.service → /lib/systemd/system/grub-common.service.
Created symlink /etc/systemd/system/sleep.target.wants/grub-common.service → /lib/systemd/system/grub-common.service.
Created symlink /etc/systemd/system/multi-user.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/rescue.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/emergency.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/sleep.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start grub-initrd-fallback.service'
update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults
Running in chroot, ignoring command 'daemon-reload'
Running in chroot, ignoring request.
invoke-rc.d: policy-rc.d denied execution of start.
Setting up os-prober (1.79ubuntu2) ...
Setting up usbmuxd (1.1.1-2build2) ...
Warning: The home dir /var/lib/usbmux you specified can't be accessed: No such file or directory
Adding system user `usbmux' (UID 113) ...
Adding new user `usbmux' (UID 113) with group `plugdev' ...
ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored.
Not creating home directory `/var/lib/usbmux'.
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start usbmuxd.service'
Setting up thermald (2.4.9-1ubuntu0.1) ...
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 command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start thermald.service'
Setting up grub2-common (2.06-2ubuntu7.1) ...
Setting up grub-pc-bin (2.06-2ubuntu7.1) ...
Setting up linux-image-5.15.0-60-generic (5.15.0-60.66) ...
I: /boot/vmlinuz.old is now a symlink to vmlinuz-5.15.0-60-generic
I: /boot/initrd.img.old is now a symlink to initrd.img-5.15.0-60-generic
I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-60-generic
I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-60-generic
Setting up grub-gfxpayload-lists (0.7) ...
Setting up linux-modules-5.15.0-60-generic (5.15.0-60.66) ...
Setting up linux-modules-extra-5.15.0-60-generic (5.15.0-60.66) ...
Setting up grub-pc (2.06-2ubuntu7.1) ...
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
Creating config file /etc/default/grub with new version
Setting up linux-image-generic (5.15.0.60.58) ...
Setting up linux-generic (5.15.0.60.58) ...
Processing triggers for libc-bin (2.35-0ubuntu3.1) ...
Processing triggers for man-db (2.10.2-1) ...
Processing triggers for dbus (1.12.20-2ubuntu4.1) ...
Processing triggers for install-info (6.8-4build1) ...
Processing triggers for linux-image-5.15.0-60-generic (5.15.0-60.66) ...
Running kernel seems to be up-to-date.
No services need to be restarted.
No containers need to be restarted.
No user sessions are running outdated binaries.
No VM guests are running outdated hypervisor (qemu) binaries on this host.
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'apt-get', 'clean'] with allowed return codes [0] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.103
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: SUCCESS: installing kernel
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: setting up swap
Not creating swapfile: suggested size was 0
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: SUCCESS: setting up swap
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: apply networking config
copying interfaces
no interfaces file to copy!
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: SUCCESS: apply networking config
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: writing etc/fstab
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: SUCCESS: writing etc/fstab
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: configuring multipath
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device? False
/dev/loop1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop5'] with allowed return codes [0] (capture=True)
/dev/loop5 is multipath device? False
/dev/loop5 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop5'] with allowed return codes [0] (capture=True)
/dev/loop5 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device? False
/dev/loop6 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop7'] with allowed return codes [0] (capture=True)
/dev/loop7 is multipath device? False
/dev/loop7 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop7'] with allowed return codes [0] (capture=True)
/dev/loop7 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop9'] with allowed return codes [0] (capture=True)
/dev/loop9 is multipath device? False
/dev/loop9 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop9'] with allowed return codes [0] (capture=True)
/dev/loop9 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop10'] with allowed return codes [0] (capture=True)
/dev/loop10 is multipath device? False
/dev/loop10 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop10'] with allowed return codes [0] (capture=True)
/dev/loop10 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop8'] with allowed return codes [0] (capture=True)
/dev/loop8 is multipath device? False
/dev/loop8 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop8'] with allowed return codes [0] (capture=True)
/dev/loop8 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device? False
/dev/loop1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device? False
/dev/loop6 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device? False
/dev/dm-1 is multipath device partition? False
Running command ['pvdisplay', '-C', '--separator', '=', '--noheadings', '-o', 'vg_name,pv_name'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda2'] with allowed return codes [0] (capture=True)
/dev/sda2 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device? False
/dev/sda1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? 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', '--nodeps'] 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/loop0'] 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/loop1'] 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/loop2'] 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/loop3'] 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/loop4'] 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/loop5'] 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/loop6'] 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/loop7'] 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/loop8'] 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/loop9'] 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/loop10'] 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/sda'] 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/sdb'] 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/sr0'] with allowed return codes [0] (capture=True)
Running command ['blockdev', '--rereadpt', '/dev/sdb'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.015
Running command ['blkid', '-o', 'full'] with allowed return codes [0] (capture=True)
legacy_detect_multipath found blkid info: {'/dev/loop1': {'TYPE': 'squashfs'}, '/dev/mapper/anima--vg-root': {'UUID': '3ca53f1d-b636-4181-b7f5-3c23b657851f', 'BLOCK_SIZE': '4096', 'TYPE': 'ext4'}, '/dev/loop8': {'TYPE': 'squashfs'}, '/dev/loop6': {'TYPE': 'squashfs'}, '/dev/loop4': {'TYPE': 'squashfs'}, '/dev/loop2': {'TYPE': 'squashfs'}, '/dev/loop0': {'BLOCK_SIZE': '2048', 'UUID': '2023-02-17-21-57-15-00', 'LABEL': 'Ubuntu-Server 22.04.2 LTS amd64', 'TYPE': 'iso9660', 'PTUUID': 'c3b09d5c-3f01-457b-ac5e-206e8818bfa0', 'PTTYPE': 'gpt'}, '/dev/mapper/anima--vg-swap': {'UUID': '88be7603-aa33-405e-8dbd-a25c00b3fdc1', 'TYPE': 'swap'}, '/dev/loop9': {'TYPE': 'squashfs'}, '/dev/loop7': {'TYPE': 'squashfs'}, '/dev/sda2': {'UUID': 'z7Ch8p-2pVc-YTPB-2tJX-6ZE4-oxFz-9dCgsb', 'TYPE': 'LVM2_member', 'PARTUUID': '2ff74d02-78bc-4723-b425-8df886515e2c'}, '/dev/sda1': {'UUID': '9b59c814-1660-44fa-9784-8c0c1fc58c24', 'BLOCK_SIZE': '4096', 'TYPE': 'ext4', 'PARTUUID': 'd5a30389-2c38-4d47-b753-8988486dcba9'}, '/dev/loop5': {'TYPE': 'squashfs'}, '/dev/loop3': {'TYPE': 'squashfs'}, '/dev/loop10': {'TYPE': 'squashfs'}}
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)
target_devs: ['/dev/dm-1']
Multipath detection found: False
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: SUCCESS: configuring multipath
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: updating packages on target system
system_upgrade disabled by config.
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: SUCCESS: updating packages on target system
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: configuring pollinate user-agent on target
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: SUCCESS: configuring pollinate user-agent on target
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: updating initramfs configuration
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--list'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.012
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Restoring update-initramfs in target for initrd updates.
del_file: removed /target/usr/sbin/update-initramfs
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--rename', '--remove', '/usr/sbin/update-initramfs'] with allowed return codes [0] (capture=False)
Removing '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.013
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--list'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.017
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic'] with allowed return codes [0] (capture=False)
update-initramfs: Generating /boot/initrd.img-5.15.0-60-generic
zstd: error 25 : Write error : No space left on device (cannot write compressed block)
E: mkinitramfs failure zstd -q -1 -T0 25
update-initramfs: failed for /boot/initrd.img-5.15.0-60-generic with 1.
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.020
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: FAIL: updating initramfs configuration
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks: FAIL: curtin command curthooks
Traceback (most recent call last):
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/main.py", line 202, in main
ret = args.func(args)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 1903, in curthooks
builtin_curthooks(cfg, target, state)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 1846, in builtin_curthooks
update_initramfs(target, all_kernels=True)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 877, in update_initramfs
in_chroot.subp(cmd)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 787, in subp
return subp(*args, **kwargs)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 275, in subp
return _subp(*args, **kwargs)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 139, in _subp
raise ProcessExecutionError(stdout=out, stderr=err,
curtin.util.ProcessExecutionError: Unexpected error while running command.
Command: ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic']
Exit code: 1
Reason: -
Stdout: ''
Stderr: ''
Unexpected error while running command.
Command: ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic']
Exit code: 1
Reason: -
Stdout: ''
Stderr: ''
curtin: Installation failed with exception: Unexpected error while running command.
Command: ['curtin', 'curthooks']
Exit code: 3
Reason: -
Stdout: 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 {'subiquity': ''} into new format: {'debconf_selections': {'subiquity': ''}}
curthooks handling apt to target /target with config {'debconf_selections': {'subiquity': ''}}
Running command ['unshare', '--help'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'lsb_release', '--all'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/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', '/target', 'debconf-set-selections'] with allowed return codes [0] (capture=True)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Diverting original update-initramfs in target.
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/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.016
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/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', '/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', '/target', 'dpkg-query', '--list'] with allowed return codes [0] (capture=True)
Curtin config dependencies requires additional packages: ['lvm2', 'lvm2', 'e2fsprogs']
Skipping install of {'vlan', 'bridge-utils', 'ifenslave'}. 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', '/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', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'apt-get', '--quiet', '--option=Acquire::Languages=none', '--option=Dir::Etc::sourcelist=/tmp/tmpdt_akq_r/sources.list', '--option=Dir::Etc::sourceparts=/tmp/tmpdt_akq_r/sources.list.d', 'update'] with allowed return codes [0] (capture=False)
Running in chroot, ignoring command 'start'
Get:1 file:/cdrom jammy InRelease
Ign:1 file:/cdrom jammy InRelease
Get:2 file:/cdrom jammy Release [1486 B]
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Get:2 file:/cdrom jammy Release [1486 B]
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Ign:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Ign:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Ign:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Ign:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Err:3 http://my.archive.ubuntu.com/ubuntu jammy InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:4 http://my.archive.ubuntu.com/ubuntu jammy-updates InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:5 http://my.archive.ubuntu.com/ubuntu jammy-backports InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Err:6 http://my.archive.ubuntu.com/ubuntu jammy-security InRelease
Temporary failure resolving 'my.archive.ubuntu.com'
Reading package lists...
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-updates/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-backports/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Failed to fetch http://my.archive.ubuntu.com/ubuntu/dists/jammy-security/InRelease Temporary failure resolving 'my.archive.ubuntu.com'
W: Some index files failed to download. They have been ignored, or old ones used instead.
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.023
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'eatmydata', 'apt-get', '--quiet', '--assume-yes', '--option=Dpkg::options::=--force-unsafe-io', '--option=Dpkg::Options::=--force-confold', 'install', '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 firmware-sof-signed grub-common grub-gfxpayload-lists
grub-pc grub-pc-bin grub2-common intel-microcode iucode-tool
libdbus-glib-1-2 libevdev2 libfreetype6 libimobiledevice6 libplist3
libupower-glib3 libusbmuxd6 linux-firmware linux-headers-5.15.0-60
linux-headers-5.15.0-60-generic linux-headers-generic
linux-image-5.15.0-60-generic linux-image-generic
linux-modules-5.15.0-60-generic linux-modules-extra-5.15.0-60-generic
os-prober thermald upower usbmuxd wireless-regdb
Suggested packages:
multiboot-doc grub-emu mtools xorriso desktop-base libusbmuxd-tools fdutils
linux-doc | linux-source-5.15.0 linux-tools
The following NEW packages will be installed:
amd64-microcode firmware-sof-signed grub-common grub-gfxpayload-lists
grub-pc grub-pc-bin grub2-common intel-microcode iucode-tool
libdbus-glib-1-2 libevdev2 libfreetype6 libimobiledevice6 libplist3
libupower-glib3 libusbmuxd6 linux-firmware linux-generic
linux-headers-5.15.0-60 linux-headers-5.15.0-60-generic
linux-headers-generic linux-image-5.15.0-60-generic linux-image-generic
linux-modules-5.15.0-60-generic linux-modules-extra-5.15.0-60-generic
os-prober thermald upower usbmuxd wireless-regdb
0 upgraded, 30 newly installed, 0 to remove and 0 not upgraded.
Need to get 0 B/370 MB of archives.
After this operation, 1554 MB of additional disk space will be used.
Get:1 file:/cdrom jammy/restricted amd64 firmware-sof-signed all 2.0-1ubuntu4.1 [820 kB]
Get:2 file:/cdrom jammy/main amd64 libfreetype6 amd64 2.11.1+dfsg-1ubuntu0.1 [389 kB]
Get:3 file:/cdrom jammy/main amd64 grub-common amd64 2.06-2ubuntu7.1 [2188 kB]
Get:4 file:/cdrom jammy/main amd64 grub2-common amd64 2.06-2ubuntu7.1 [651 kB]
Get:5 file:/cdrom jammy/main amd64 grub-pc-bin amd64 2.06-2ubuntu7.1 [1083 kB]
Get:6 file:/cdrom jammy/main amd64 grub-pc amd64 2.06-2ubuntu7.1 [132 kB]
Get:7 file:/cdrom jammy/main amd64 grub-gfxpayload-lists amd64 0.7 [3658 B]
Get:8 file:/cdrom jammy/main amd64 iucode-tool amd64 2.3.1-1build1 [46.9 kB]
Get:9 file:/cdrom jammy/main amd64 libdbus-glib-1-2 amd64 0.112-2build1 [65.4 kB]
Get:10 file:/cdrom jammy/main amd64 libplist3 amd64 2.2.0-6build2 [32.1 kB]
Get:11 file:/cdrom jammy/main amd64 libusbmuxd6 amd64 2.0.2-3build2 [20.4 kB]
Get:12 file:/cdrom jammy/main amd64 libimobiledevice6 amd64 1.3.0-6build3 [71.1 kB]
Get:13 file:/cdrom jammy/main amd64 libupower-glib3 amd64 0.99.17-1 [46.7 kB]
Get:14 file:/cdrom jammy/main amd64 linux-firmware all 20220329.git681281e4-0ubuntu3.10 [247 MB]
Get:15 file:/cdrom jammy/main amd64 linux-modules-5.15.0-60-generic amd64 5.15.0-60.66 [22.8 MB]
Get:16 file:/cdrom jammy/main amd64 linux-image-5.15.0-60-generic amd64 5.15.0-60.66 [11.4 MB]
Get:17 file:/cdrom jammy/main amd64 wireless-regdb all 2022.06.06-0ubuntu1~22.04.1 [10.3 kB]
Get:18 file:/cdrom jammy/main amd64 linux-modules-extra-5.15.0-60-generic amd64 5.15.0-60.66 [64.0 MB]
Get:19 file:/cdrom jammy/main amd64 intel-microcode amd64 3.20220809.0ubuntu0.22.04.1 [4039 kB]
Get:20 file:/cdrom jammy/main amd64 amd64-microcode amd64 3.20191218.1ubuntu2 [32.5 kB]
Get:21 file:/cdrom jammy/main amd64 linux-image-generic amd64 5.15.0.60.58 [2466 B]
Get:22 file:/cdrom jammy/main amd64 linux-headers-5.15.0-60 all 5.15.0-60.66 [12.3 MB]
Get:23 file:/cdrom jammy/main amd64 linux-headers-5.15.0-60-generic amd64 5.15.0-60.66 [2871 kB]
Get:24 file:/cdrom jammy/main amd64 linux-headers-generic amd64 5.15.0.60.58 [2316 B]
Get:25 file:/cdrom jammy/main amd64 linux-generic amd64 5.15.0.60.58 [1698 B]
Get:26 file:/cdrom jammy/main amd64 os-prober amd64 1.79ubuntu2 [19.3 kB]
Get:27 file:/cdrom jammy/main amd64 libevdev2 amd64 1.12.1+dfsg-1 [39.5 kB]
Get:28 file:/cdrom jammy/main amd64 thermald amd64 2.4.9-1ubuntu0.1 [212 kB]
Get:29 file:/cdrom jammy/main amd64 upower amd64 0.99.17-1 [86.7 kB]
Get:30 file:/cdrom jammy/main amd64 usbmuxd amd64 1.1.1-2build2 [42.8 kB]
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
dpkg-preconfigure: unable to re-open stdin:
E: Can not write log (Is /dev/pts mounted?) - posix_openpt (19: No such device)
Selecting previously unselected package firmware-sof-signed.
(Reading database ... 34175 files and directories currently installed.)
Preparing to unpack .../00-firmware-sof-signed_2.0-1ubuntu4.1_all.deb ...
Unpacking firmware-sof-signed (2.0-1ubuntu4.1) ...
Selecting previously unselected package libfreetype6:amd64.
Preparing to unpack .../01-libfreetype6_2.11.1+dfsg-1ubuntu0.1_amd64.deb ...
Unpacking libfreetype6:amd64 (2.11.1+dfsg-1ubuntu0.1) ...
Selecting previously unselected package grub-common.
Preparing to unpack .../02-grub-common_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-common (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub2-common.
Preparing to unpack .../03-grub2-common_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub2-common (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-pc-bin.
Preparing to unpack .../04-grub-pc-bin_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-pc-bin (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-pc.
Preparing to unpack .../05-grub-pc_2.06-2ubuntu7.1_amd64.deb ...
Unpacking grub-pc (2.06-2ubuntu7.1) ...
Selecting previously unselected package grub-gfxpayload-lists.
Preparing to unpack .../06-grub-gfxpayload-lists_0.7_amd64.deb ...
Unpacking grub-gfxpayload-lists (0.7) ...
Selecting previously unselected package iucode-tool.
Preparing to unpack .../07-iucode-tool_2.3.1-1build1_amd64.deb ...
Unpacking iucode-tool (2.3.1-1build1) ...
Selecting previously unselected package libdbus-glib-1-2:amd64.
Preparing to unpack .../08-libdbus-glib-1-2_0.112-2build1_amd64.deb ...
Unpacking libdbus-glib-1-2:amd64 (0.112-2build1) ...
Selecting previously unselected package libplist3:amd64.
Preparing to unpack .../09-libplist3_2.2.0-6build2_amd64.deb ...
Unpacking libplist3:amd64 (2.2.0-6build2) ...
Selecting previously unselected package libusbmuxd6:amd64.
Preparing to unpack .../10-libusbmuxd6_2.0.2-3build2_amd64.deb ...
Unpacking libusbmuxd6:amd64 (2.0.2-3build2) ...
Selecting previously unselected package libimobiledevice6:amd64.
Preparing to unpack .../11-libimobiledevice6_1.3.0-6build3_amd64.deb ...
Unpacking libimobiledevice6:amd64 (1.3.0-6build3) ...
Selecting previously unselected package libupower-glib3:amd64.
Preparing to unpack .../12-libupower-glib3_0.99.17-1_amd64.deb ...
Unpacking libupower-glib3:amd64 (0.99.17-1) ...
Selecting previously unselected package linux-firmware.
Preparing to unpack .../13-linux-firmware_20220329.git681281e4-0ubuntu3.10_all.deb ...
Unpacking linux-firmware (20220329.git681281e4-0ubuntu3.10) ...
Selecting previously unselected package linux-modules-5.15.0-60-generic.
Preparing to unpack .../14-linux-modules-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-modules-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package linux-image-5.15.0-60-generic.
Preparing to unpack .../15-linux-image-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-image-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package wireless-regdb.
Preparing to unpack .../16-wireless-regdb_2022.06.06-0ubuntu1~22.04.1_all.deb ...
Unpacking wireless-regdb (2022.06.06-0ubuntu1~22.04.1) ...
Selecting previously unselected package linux-modules-extra-5.15.0-60-generic.
Preparing to unpack .../17-linux-modules-extra-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-modules-extra-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package intel-microcode.
Preparing to unpack .../18-intel-microcode_3.20220809.0ubuntu0.22.04.1_amd64.deb ...
Unpacking intel-microcode (3.20220809.0ubuntu0.22.04.1) ...
Selecting previously unselected package amd64-microcode.
Preparing to unpack .../19-amd64-microcode_3.20191218.1ubuntu2_amd64.deb ...
Unpacking amd64-microcode (3.20191218.1ubuntu2) ...
Selecting previously unselected package linux-image-generic.
Preparing to unpack .../20-linux-image-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-image-generic (5.15.0.60.58) ...
Selecting previously unselected package linux-headers-5.15.0-60.
Preparing to unpack .../21-linux-headers-5.15.0-60_5.15.0-60.66_all.deb ...
Unpacking linux-headers-5.15.0-60 (5.15.0-60.66) ...
Selecting previously unselected package linux-headers-5.15.0-60-generic.
Preparing to unpack .../22-linux-headers-5.15.0-60-generic_5.15.0-60.66_amd64.deb ...
Unpacking linux-headers-5.15.0-60-generic (5.15.0-60.66) ...
Selecting previously unselected package linux-headers-generic.
Preparing to unpack .../23-linux-headers-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-headers-generic (5.15.0.60.58) ...
Selecting previously unselected package linux-generic.
Preparing to unpack .../24-linux-generic_5.15.0.60.58_amd64.deb ...
Unpacking linux-generic (5.15.0.60.58) ...
Selecting previously unselected package os-prober.
Preparing to unpack .../25-os-prober_1.79ubuntu2_amd64.deb ...
Unpacking os-prober (1.79ubuntu2) ...
Selecting previously unselected package libevdev2:amd64.
Preparing to unpack .../26-libevdev2_1.12.1+dfsg-1_amd64.deb ...
Unpacking libevdev2:amd64 (1.12.1+dfsg-1) ...
Selecting previously unselected package thermald.
Preparing to unpack .../27-thermald_2.4.9-1ubuntu0.1_amd64.deb ...
Unpacking thermald (2.4.9-1ubuntu0.1) ...
Selecting previously unselected package upower.
Preparing to unpack .../28-upower_0.99.17-1_amd64.deb ...
Unpacking upower (0.99.17-1) ...
Selecting previously unselected package usbmuxd.
Preparing to unpack .../29-usbmuxd_1.1.1-2build2_amd64.deb ...
Unpacking usbmuxd (1.1.1-2build2) ...
Setting up libplist3:amd64 (2.2.0-6build2) ...
Setting up iucode-tool (2.3.1-1build1) ...
Setting up linux-firmware (20220329.git681281e4-0ubuntu3.10) ...
Setting up intel-microcode (3.20220809.0ubuntu0.22.04.1) ...
intel-microcode: microcode will be updated at next boot
Setting up firmware-sof-signed (2.0-1ubuntu4.1) ...
Setting up wireless-regdb (2022.06.06-0ubuntu1~22.04.1) ...
Setting up amd64-microcode (3.20191218.1ubuntu2) ...
amd64-microcode: microcode will be updated at next boot
Setting up libfreetype6:amd64 (2.11.1+dfsg-1ubuntu0.1) ...
Setting up libdbus-glib-1-2:amd64 (0.112-2build1) ...
Setting up libusbmuxd6:amd64 (2.0.2-3build2) ...
Setting up libupower-glib3:amd64 (0.99.17-1) ...
Setting up libimobiledevice6:amd64 (1.3.0-6build3) ...
Setting up linux-headers-5.15.0-60 (5.15.0-60.66) ...
Setting up libevdev2:amd64 (1.12.1+dfsg-1) ...
Setting up upower (0.99.17-1) ...
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start upower.service'
Setting up linux-headers-5.15.0-60-generic (5.15.0-60.66) ...
Setting up linux-headers-generic (5.15.0.60.58) ...
Setting up grub-common (2.06-2ubuntu7.1) ...
Created symlink /etc/systemd/system/multi-user.target.wants/grub-common.service → /lib/systemd/system/grub-common.service.
Created symlink /etc/systemd/system/sleep.target.wants/grub-common.service → /lib/systemd/system/grub-common.service.
Created symlink /etc/systemd/system/multi-user.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/rescue.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/emergency.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Created symlink /etc/systemd/system/sleep.target.wants/grub-initrd-fallback.service → /lib/systemd/system/grub-initrd-fallback.service.
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start grub-initrd-fallback.service'
update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults
Running in chroot, ignoring command 'daemon-reload'
Running in chroot, ignoring request.
invoke-rc.d: policy-rc.d denied execution of start.
Setting up os-prober (1.79ubuntu2) ...
Setting up usbmuxd (1.1.1-2build2) ...
Warning: The home dir /var/lib/usbmux you specified can't be accessed: No such file or directory
Adding system user `usbmux' (UID 113) ...
Adding new user `usbmux' (UID 113) with group `plugdev' ...
ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored.
Not creating home directory `/var/lib/usbmux'.
Running in chroot, ignoring command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start usbmuxd.service'
Setting up thermald (2.4.9-1ubuntu0.1) ...
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 command 'daemon-reload'
/usr/sbin/policy-rc.d returned 101, not running 'start thermald.service'
Setting up grub2-common (2.06-2ubuntu7.1) ...
Setting up grub-pc-bin (2.06-2ubuntu7.1) ...
Setting up linux-image-5.15.0-60-generic (5.15.0-60.66) ...
I: /boot/vmlinuz.old is now a symlink to vmlinuz-5.15.0-60-generic
I: /boot/initrd.img.old is now a symlink to initrd.img-5.15.0-60-generic
I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-60-generic
I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-60-generic
Setting up grub-gfxpayload-lists (0.7) ...
Setting up linux-modules-5.15.0-60-generic (5.15.0-60.66) ...
Setting up linux-modules-extra-5.15.0-60-generic (5.15.0-60.66) ...
Setting up grub-pc (2.06-2ubuntu7.1) ...
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
Creating config file /etc/default/grub with new version
Setting up linux-image-generic (5.15.0.60.58) ...
Setting up linux-generic (5.15.0.60.58) ...
Processing triggers for libc-bin (2.35-0ubuntu3.1) ...
Processing triggers for man-db (2.10.2-1) ...
Processing triggers for dbus (1.12.20-2ubuntu4.1) ...
Processing triggers for install-info (6.8-4build1) ...
Processing triggers for linux-image-5.15.0-60-generic (5.15.0-60.66) ...
Running kernel seems to be up-to-date.
No services need to be restarted.
No containers need to be restarted.
No user sessions are running outdated binaries.
No VM guests are running outdated hypervisor (qemu) binaries on this host.
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'apt-get', 'clean'] with allowed return codes [0] (capture=False)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.103
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: SUCCESS: installing kernel
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: setting up swap
Not creating swapfile: suggested size was 0
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: SUCCESS: setting up swap
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: apply networking config
copying interfaces
no interfaces file to copy!
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: SUCCESS: apply networking config
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: writing etc/fstab
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: SUCCESS: writing etc/fstab
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: configuring multipath
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device? False
/dev/loop1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop5'] with allowed return codes [0] (capture=True)
/dev/loop5 is multipath device? False
/dev/loop5 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop5'] with allowed return codes [0] (capture=True)
/dev/loop5 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device? False
/dev/loop6 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop7'] with allowed return codes [0] (capture=True)
/dev/loop7 is multipath device? False
/dev/loop7 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop7'] with allowed return codes [0] (capture=True)
/dev/loop7 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop9'] with allowed return codes [0] (capture=True)
/dev/loop9 is multipath device? False
/dev/loop9 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop9'] with allowed return codes [0] (capture=True)
/dev/loop9 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop10'] with allowed return codes [0] (capture=True)
/dev/loop10 is multipath device? False
/dev/loop10 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop10'] with allowed return codes [0] (capture=True)
/dev/loop10 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop8'] with allowed return codes [0] (capture=True)
/dev/loop8 is multipath device? False
/dev/loop8 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop8'] with allowed return codes [0] (capture=True)
/dev/loop8 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device? False
/dev/loop1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop1'] with allowed return codes [0] (capture=True)
/dev/loop1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device? False
/dev/loop6 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop6'] with allowed return codes [0] (capture=True)
/dev/loop6 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device? False
/dev/dm-1 is multipath device partition? False
Running command ['pvdisplay', '-C', '--separator', '=', '--noheadings', '-o', 'vg_name,pv_name'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda2'] with allowed return codes [0] (capture=True)
/dev/sda2 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/dm-1'] with allowed return codes [0] (capture=True)
/dev/dm-1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device? False
/dev/sda1 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/sda1'] with allowed return codes [0] (capture=True)
/dev/sda1 is multipath device member? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device? False
/dev/loop0 is multipath device partition? False
Running command ['udevadm', 'info', '--query=property', '--export', '/dev/loop0'] with allowed return codes [0] (capture=True)
/dev/loop0 is multipath device member? 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', '--nodeps'] 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/loop0'] 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/loop1'] 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/loop2'] 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/loop3'] 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/loop4'] 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/loop5'] 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/loop6'] 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/loop7'] 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/loop8'] 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/loop9'] 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/loop10'] 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/sda'] 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/sdb'] 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/sr0'] with allowed return codes [0] (capture=True)
Running command ['blockdev', '--rereadpt', '/dev/sdb'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED udevadm_settle(): 0.015
Running command ['blkid', '-o', 'full'] with allowed return codes [0] (capture=True)
legacy_detect_multipath found blkid info: {'/dev/loop1': {'TYPE': 'squashfs'}, '/dev/mapper/anima--vg-root': {'UUID': '3ca53f1d-b636-4181-b7f5-3c23b657851f', 'BLOCK_SIZE': '4096', 'TYPE': 'ext4'}, '/dev/loop8': {'TYPE': 'squashfs'}, '/dev/loop6': {'TYPE': 'squashfs'}, '/dev/loop4': {'TYPE': 'squashfs'}, '/dev/loop2': {'TYPE': 'squashfs'}, '/dev/loop0': {'BLOCK_SIZE': '2048', 'UUID': '2023-02-17-21-57-15-00', 'LABEL': 'Ubuntu-Server 22.04.2 LTS amd64', 'TYPE': 'iso9660', 'PTUUID': 'c3b09d5c-3f01-457b-ac5e-206e8818bfa0', 'PTTYPE': 'gpt'}, '/dev/mapper/anima--vg-swap': {'UUID': '88be7603-aa33-405e-8dbd-a25c00b3fdc1', 'TYPE': 'swap'}, '/dev/loop9': {'TYPE': 'squashfs'}, '/dev/loop7': {'TYPE': 'squashfs'}, '/dev/sda2': {'UUID': 'z7Ch8p-2pVc-YTPB-2tJX-6ZE4-oxFz-9dCgsb', 'TYPE': 'LVM2_member', 'PARTUUID': '2ff74d02-78bc-4723-b425-8df886515e2c'}, '/dev/sda1': {'UUID': '9b59c814-1660-44fa-9784-8c0c1fc58c24', 'BLOCK_SIZE': '4096', 'TYPE': 'ext4', 'PARTUUID': 'd5a30389-2c38-4d47-b753-8988486dcba9'}, '/dev/loop5': {'TYPE': 'squashfs'}, '/dev/loop3': {'TYPE': 'squashfs'}, '/dev/loop10': {'TYPE': 'squashfs'}}
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)
target_devs: ['/dev/dm-1']
Multipath detection found: False
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: SUCCESS: configuring multipath
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: updating packages on target system
system_upgrade disabled by config.
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: SUCCESS: updating packages on target system
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: configuring pollinate user-agent on target
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: SUCCESS: configuring pollinate user-agent on target
start: cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: updating initramfs configuration
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--list'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.012
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Restoring update-initramfs in target for initrd updates.
del_file: removed /target/usr/sbin/update-initramfs
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--rename', '--remove', '/usr/sbin/update-initramfs'] with allowed return codes [0] (capture=False)
Removing '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.013
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'dpkg-divert', '--list'] with allowed return codes [0] (capture=True)
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.017
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/dev', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/proc', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/run', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--bind', '/sys', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic'] with allowed return codes [0] (capture=False)
update-initramfs: Generating /boot/initrd.img-5.15.0-60-generic
zstd: error 25 : Write error : No space left on device (cannot write compressed block)
E: mkinitramfs failure zstd -q -1 -T0 25
update-initramfs: failed for /boot/initrd.img-5.15.0-60-generic with 1.
Running command ['udevadm', 'settle'] with allowed return codes [0] (capture=False)
TIMED subp(['udevadm', 'settle']): 0.020
Running command ['mount', '--make-private', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/sys'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/run'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/proc'] with allowed return codes [0] (capture=False)
Running command ['mount', '--make-private', '/target/dev'] with allowed return codes [0] (capture=False)
Running command ['umount', '/target/dev'] with allowed return codes [0] (capture=False)
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: FAIL: updating initramfs configuration
finish: cmd-install/stage-curthooks/builtin/cmd-curthooks: FAIL: curtin command curthooks
Traceback (most recent call last):
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/main.py", line 202, in main
ret = args.func(args)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 1903, in curthooks
builtin_curthooks(cfg, target, state)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 1846, in builtin_curthooks
update_initramfs(target, all_kernels=True)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/commands/curthooks.py", line 877, in update_initramfs
in_chroot.subp(cmd)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 787, in subp
return subp(*args, **kwargs)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 275, in subp
return _subp(*args, **kwargs)
File "/snap/subiquity/4380/lib/python3.8/site-packages/curtin/util.py", line 139, in _subp
raise ProcessExecutionError(stdout=out, stderr=err,
curtin.util.ProcessExecutionError: Unexpected error while running command.
Command: ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic']
Exit code: 1
Reason: -
Stdout: ''
Stderr: ''
Unexpected error while running command.
Command: ['unshare', '--fork', '--pid', '--', 'chroot', '/target', 'update-initramfs', '-c', '-k', '5.15.0-60-generic']
Exit code: 1
Reason: -
Stdout: ''
Stderr: ''
Stderr: ''
Raw
subiquity-client-debug.log
2023-06-16 04:00:16,144 INFO subiquity:126 Starting Subiquity revision 4380
2023-06-16 04:00:16,145 INFO subiquity:127 Arguments passed: ['/snap/subiquity/4380/usr/bin/subiquity']
2023-06-16 04:00:16,145 DEBUG subiquitycore.screen:135 KDGKBTYPE returned b'\x02', is_linux_tty True
2023-06-16 04:00:16,146 DEBUG asyncio:59 Using selector: EpollSelector
Raw
subiquity-server-debug.log
2023-06-16 04:00:16,407 INFO subiquity:161 Starting Subiquity server revision 4380
2023-06-16 04:00:16,407 INFO subiquity:162 Arguments passed: ['/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/cmd/server.py']
2023-06-16 04:00:16,407 DEBUG subiquity:163 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=22.04/vmlinuz root=/dev/ram0 ramdisk_size=3000000 ip=dhcp url=192.168.128.10/ubuntu/22.04/ubuntu-22.04.2-live-server-amd64.iso autoinstall ds=nocloud-net;s=http://192.168.128.10/ubuntu/22.04/kickstart/ initrd=22.04/initrd BOOTIF=01-08-00-27-b4-9d-ae\n', _tokens={'autoinstall'}, _values={'BOOT_IMAGE': '22.04/vmlinuz', 'root': '/dev/ram0', 'ramdisk_size': '3000000', 'ip': 'dhcp', 'url': '192.168.128.10/ubuntu/22.04/ubuntu-22.04.2-live-server-amd64.iso', 'ds': 'nocloud-net;s=http://192.168.128.10/ubuntu/22.04/kickstart/', 'initrd': '22.04/initrd', 'BOOTIF': '01-08-00-27-b4-9d-ae'})
2023-06-16 04:00:16,408 DEBUG subiquity:164 Storage version: 1
2023-06-16 04:00:16,408 DEBUG asyncio:59 Using selector: EpollSelector
2023-06-16 04:00:16,409 DEBUG subiquitycore.prober:34 Prober() init finished, data:None
2023-06-16 04:00:16,445 DEBUG curtin:87 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
2023-06-16 04:00:16,457 DEBUG subiquitycore.netplan:109 config for enp0s3 = {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:16,457 DEBUG subiquitycore.netplan:109 config for zz-all-en = {'dhcp4': True, 'match': {'name': 'en*'}}
2023-06-16 04:00:16,457 DEBUG subiquitycore.netplan:109 config for zz-all-eth = {'dhcp4': True, 'match': {'name': 'eth*'}}
2023-06-16 04:00:16,472 DEBUG subiquitycore.utils:92 arun_command called: ['cloud-init', 'status', '--wait']
2023-06-16 04:00:17,160 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET:
2023-06-16 04:00:17,161 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init...
2023-06-16 04:00:17,161 INFO aiohttp.access:233 [16/Jun/2023:04:00:17 +0000] "GET /meta/status?cur=null HTTP/1.1" 200 419 "-" "Python/3.8 aiohttp/3.6.2"
2023-06-16 04:00:17,185 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET:
2023-06-16 04:00:18,030 DEBUG subiquitycore.utils:101 arun_command ['cloud-init', 'status', '--wait'] exited with code 0
2023-06-16 04:00:18,030 DEBUG subiquity.server.server:532 waited 1.5580697059631348s for cloud-init
2023-06-16 04:00:18,031 DEBUG subiquity.server.server:534 loading cloud config
2023-06-16 04:00:18,031 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg (quiet=False)
2023-06-16 04:00:18,031 DEBUG cloudinit.util:1539 Read 3487 bytes from /etc/cloud/cloud.cfg
2023-06-16 04:00:18,032 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 3487 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,047 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/enp0s3.cfg (quiet=False)
2023-06-16 04:00:18,047 DEBUG cloudinit.util:1539 Read 201 bytes from /etc/cloud/cloud.cfg.d/enp0s3.cfg
2023-06-16 04:00:18,047 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 201 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,049 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
2023-06-16 04:00:18,049 DEBUG cloudinit.util:1539 Read 320 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
2023-06-16 04:00:18,049 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 320 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,051 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/06_quiet.cfg (quiet=False)
2023-06-16 04:00:18,051 DEBUG cloudinit.util:1539 Read 112 bytes from /etc/cloud/cloud.cfg.d/06_quiet.cfg
2023-06-16 04:00:18,051 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 112 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,052 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2023-06-16 04:00:18,052 DEBUG cloudinit.util:1539 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
2023-06-16 04:00:18,053 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,060 DEBUG cloudinit.util:1528 Reading from /run/cloud-init/cloud.cfg (quiet=False)
2023-06-16 04:00:18,061 DEBUG cloudinit.util:1539 Read 35 bytes from /run/cloud-init/cloud.cfg
2023-06-16 04:00:18,061 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,062 DEBUG cloudinit.subp:245 Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
2023-06-16 04:00:18,083 DEBUG cloudinit.util:1528 Reading from /etc/os-release (quiet=False)
2023-06-16 04:00:18,083 DEBUG cloudinit.util:1539 Read 386 bytes from /etc/os-release
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1528 Reading from /proc/1/environ (quiet=False)
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1539 Read 520 bytes from /proc/1/environ
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1528 Reading from /proc/self/status (quiet=False)
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1539 Read 1371 bytes from /proc/self/status
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1528 Reading from /proc/cmdline (quiet=False)
2023-06-16 04:00:18,085 DEBUG cloudinit.util:1539 Read 262 bytes from /proc/cmdline
2023-06-16 04:00:18,085 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,086 DEBUG cloudinit.util:949 loaded blob returned None, returning default.
2023-06-16 04:00:18,087 DEBUG cloudinit.util:1528 Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2023-06-16 04:00:18,087 DEBUG cloudinit.util:1539 Read 3069 bytes from /var/lib/cloud/instance/cloud-config.txt
2023-06-16 04:00:18,087 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 3069 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,112 DEBUG cloudinit.util:1528 Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2023-06-16 04:00:18,112 DEBUG cloudinit.util:1539 Read 14152 bytes from /var/lib/cloud/instance/obj.pkl
2023-06-16 04:00:18,129 DEBUG cloudinit.util:1528 Reading from /run/cloud-init/.instance-id (quiet=False)
2023-06-16 04:00:18,129 DEBUG cloudinit.util:1539 Read 12 bytes from /run/cloud-init/.instance-id
2023-06-16 04:00:18,130 DEBUG cloudinit.stages:318 restored from cache with run check: DataSourceNoCloudNet [seed=cmdline,/var/lib/cloud/seed/nocloud,http://192.168.128.10/ubuntu/22.04/kickstart/][dsmode=net]
2023-06-16 04:00:18,130 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg (quiet=False)
2023-06-16 04:00:18,130 DEBUG cloudinit.util:1539 Read 3487 bytes from /etc/cloud/cloud.cfg
2023-06-16 04:00:18,130 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 3487 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,144 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/enp0s3.cfg (quiet=False)
2023-06-16 04:00:18,144 DEBUG cloudinit.util:1539 Read 201 bytes from /etc/cloud/cloud.cfg.d/enp0s3.cfg
2023-06-16 04:00:18,144 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 201 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,146 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
2023-06-16 04:00:18,146 DEBUG cloudinit.util:1539 Read 320 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
2023-06-16 04:00:18,146 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 320 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,148 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/06_quiet.cfg (quiet=False)
2023-06-16 04:00:18,148 DEBUG cloudinit.util:1539 Read 112 bytes from /etc/cloud/cloud.cfg.d/06_quiet.cfg
2023-06-16 04:00:18,148 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 112 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,149 DEBUG cloudinit.util:1528 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2023-06-16 04:00:18,149 DEBUG cloudinit.util:1539 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
2023-06-16 04:00:18,149 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,154 DEBUG cloudinit.util:1528 Reading from /run/cloud-init/cloud.cfg (quiet=False)
2023-06-16 04:00:18,154 DEBUG cloudinit.util:1539 Read 35 bytes from /run/cloud-init/cloud.cfg
2023-06-16 04:00:18,154 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,155 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,155 DEBUG cloudinit.util:949 loaded blob returned None, returning default.
2023-06-16 04:00:18,155 DEBUG cloudinit.util:1528 Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2023-06-16 04:00:18,156 DEBUG cloudinit.util:1539 Read 3069 bytes from /var/lib/cloud/instance/cloud-config.txt
2023-06-16 04:00:18,156 DEBUG cloudinit.util:941 Attempting to load yaml from string of length 3069 with allowed root types (<class 'dict'>,)
2023-06-16 04:00:18,175 DEBUG cloudinit.stages:136 Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2023-06-16 04:00:18,187 DEBUG subiquitycore.utils:64 run_command called: ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys']
2023-06-16 04:00:18,199 DEBUG subiquitycore.utils:77 run_command ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] exited with code 255
2023-06-16 04:00:18,199 DEBUG subiquitycore.ssh:53 ssh-keygen -lf /home/installer/.ssh/authorized_keys failed '/home/installer/.ssh/authorized_keys is not a public key file.\r\n'
2023-06-16 04:00:18,199 DEBUG subiquitycore.utils:64 run_command called: chpasswd
2023-06-16 04:00:18,247 DEBUG subiquitycore.utils:77 run_command chpasswd exited with code 0
2023-06-16 04:00:18,249 DEBUG subiquity.server.server:479 load_autoinstall_config only_early True file /autoinstall.yaml
2023-06-16 04:00:18,271 DEBUG curtin.reporting.start.subiquity/Reporting/load_autoinstall_data:45 start: subiquity/Reporting/load_autoinstall_data:
2023-06-16 04:00:18,284 DEBUG curtin.reporting.finish.subiquity/Reporting/load_autoinstall_data:45 finish: subiquity/Reporting/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,285 DEBUG root:37 start: subiquity/Error/load_autoinstall_data:
2023-06-16 04:00:18,288 DEBUG root:37 finish: subiquity/Error/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,288 INFO root:37 start: subiquity/core_validation:
2023-06-16 04:00:18,289 INFO root:37 finish: subiquity/core_validation: SUCCESS:
2023-06-16 04:00:18,289 DEBUG root:37 start: subiquity/Early/load_autoinstall_data:
2023-06-16 04:00:18,290 DEBUG root:37 finish: subiquity/Early/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,290 DEBUG subiquity.server.server:479 load_autoinstall_config only_early False file /autoinstall.yaml
2023-06-16 04:00:18,307 DEBUG root:37 start: subiquity/Early/load_autoinstall_data:
2023-06-16 04:00:18,308 DEBUG root:37 finish: subiquity/Early/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,308 DEBUG root:37 start: subiquity/Reporting/load_autoinstall_data:
2023-06-16 04:00:18,309 DEBUG root:37 finish: subiquity/Reporting/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,309 DEBUG root:37 start: subiquity/Error/load_autoinstall_data:
2023-06-16 04:00:18,310 DEBUG root:37 finish: subiquity/Error/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,310 DEBUG root:37 start: subiquity/Userdata/load_autoinstall_data:
2023-06-16 04:00:18,310 DEBUG root:37 finish: subiquity/Userdata/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,310 DEBUG root:37 start: subiquity/Package/load_autoinstall_data:
2023-06-16 04:00:18,311 DEBUG root:37 finish: subiquity/Package/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,311 DEBUG root:37 start: subiquity/Debconf/load_autoinstall_data:
2023-06-16 04:00:18,312 DEBUG root:37 finish: subiquity/Debconf/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,312 DEBUG root:37 start: subiquity/Locale/load_autoinstall_data:
2023-06-16 04:00:18,312 DEBUG root:37 finish: subiquity/Locale/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,313 DEBUG root:37 start: subiquity/Refresh/load_autoinstall_data:
2023-06-16 04:00:18,313 DEBUG root:37 finish: subiquity/Refresh/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,313 DEBUG root:37 start: subiquity/Kernel/load_autoinstall_data:
2023-06-16 04:00:18,313 DEBUG root:37 finish: subiquity/Kernel/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,313 DEBUG root:37 start: subiquity/Keyboard/load_autoinstall_data:
2023-06-16 04:00:18,314 DEBUG root:37 finish: subiquity/Keyboard/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,314 DEBUG root:37 start: subiquity/Zdev/load_autoinstall_data:
2023-06-16 04:00:18,315 DEBUG root:37 finish: subiquity/Zdev/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,315 DEBUG root:37 start: subiquity/Source/load_autoinstall_data:
2023-06-16 04:00:18,316 DEBUG root:37 finish: subiquity/Source/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,316 DEBUG root:37 start: subiquity/Network/load_autoinstall_data:
2023-06-16 04:00:18,318 DEBUG root:37 finish: subiquity/Network/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,318 DEBUG root:37 start: subiquity/UbuntuPro/load_autoinstall_data:
2023-06-16 04:00:18,318 DEBUG root:37 finish: subiquity/UbuntuPro/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,318 DEBUG root:37 start: subiquity/Proxy/load_autoinstall_data:
2023-06-16 04:00:18,319 DEBUG root:37 finish: subiquity/Proxy/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,319 DEBUG root:37 start: subiquity/Mirror/load_autoinstall_data:
2023-06-16 04:00:18,320 DEBUG root:37 finish: subiquity/Mirror/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,320 DEBUG root:37 start: subiquity/Filesystem/load_autoinstall_data:
2023-06-16 04:00:18,321 DEBUG subiquity.server.controllers.filesystem:115 load_autoinstall_data {'config': [{'grub_device': True, 'id': 'disk-sda', 'name': '', 'path': '/dev/sda', 'preserve': False, 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'}, {'device': 'disk-sda', 'flag': 'boot', 'grub_device': True, 'id': 'boot-partition', 'number': 1, 'preserve': False, 'size': '512MB', 'type': 'partition', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-0', 'preserve': False, 'type': 'format', 'volume': 'boot-partition'}, {'device': 'disk-sda', 'flag': '', 'grub_device': False, 'id': 'lvm-partition', 'number': 2, 'preserve': False, 'size': -1, 'type': 'partition', 'wipe': 'superblock'}, {'devices': ['lvm-partition'], 'id': 'anima-vg', 'name': 'anima-vg', 'preserve': False, 'type': 'lvm_volgroup'}, {'id': 'swap', 'name': 'swap', 'preserve': False, 'size': '16GB', 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'swap', 'id': 'format-1', 'preserve': False, 'type': 'format', 'volume': 'swap'}, {'device': 'format-1', 'id': 'mount-1', 'path': '', 'type': 'mount'}, {'id': 'root', 'name': 'root', 'preserve': False, 'size': -1, 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-2', 'preserve': False, 'type': 'format', 'volume': 'root'}, {'device': 'format-2', 'id': 'mount-2', 'path': '/', 'type': 'mount'}, {'device': 'format-0', 'id': 'mount-0', 'path': '/boot/efi', 'type': 'mount'}], 'swap': {'swap': 0}}
2023-06-16 04:00:18,321 DEBUG subiquity.server.controllers.filesystem:125 self.ai_data = {'config': [{'grub_device': True, 'id': 'disk-sda', 'name': '', 'path': '/dev/sda', 'preserve': False, 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'}, {'device': 'disk-sda', 'flag': 'boot', 'grub_device': True, 'id': 'boot-partition', 'number': 1, 'preserve': False, 'size': '512MB', 'type': 'partition', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-0', 'preserve': False, 'type': 'format', 'volume': 'boot-partition'}, {'device': 'disk-sda', 'flag': '', 'grub_device': False, 'id': 'lvm-partition', 'number': 2, 'preserve': False, 'size': -1, 'type': 'partition', 'wipe': 'superblock'}, {'devices': ['lvm-partition'], 'id': 'anima-vg', 'name': 'anima-vg', 'preserve': False, 'type': 'lvm_volgroup'}, {'id': 'swap', 'name': 'swap', 'preserve': False, 'size': '16GB', 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'swap', 'id': 'format-1', 'preserve': False, 'type': 'format', 'volume': 'swap'}, {'device': 'format-1', 'id': 'mount-1', 'path': '', 'type': 'mount'}, {'id': 'root', 'name': 'root', 'preserve': False, 'size': -1, 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-2', 'preserve': False, 'type': 'format', 'volume': 'root'}, {'device': 'format-2', 'id': 'mount-2', 'path': '/', 'type': 'mount'}, {'device': 'format-0', 'id': 'mount-0', 'path': '/boot/efi', 'type': 'mount'}], 'swap': {'swap': 0}}
2023-06-16 04:00:18,321 DEBUG root:37 finish: subiquity/Filesystem/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,321 DEBUG root:37 start: subiquity/Identity/load_autoinstall_data:
2023-06-16 04:00:18,322 DEBUG root:37 finish: subiquity/Identity/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,322 DEBUG root:37 start: subiquity/SSH/load_autoinstall_data:
2023-06-16 04:00:18,323 DEBUG root:37 finish: subiquity/SSH/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,323 DEBUG root:37 start: subiquity/SnapList/load_autoinstall_data:
2023-06-16 04:00:18,324 DEBUG root:37 finish: subiquity/SnapList/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,324 DEBUG root:37 start: subiquity/Drivers/load_autoinstall_data:
2023-06-16 04:00:18,325 DEBUG root:37 finish: subiquity/Drivers/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,325 DEBUG root:37 start: subiquity/TimeZone/load_autoinstall_data:
2023-06-16 04:00:18,357 DEBUG root:37 finish: subiquity/TimeZone/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,358 DEBUG root:37 start: subiquity/Install/load_autoinstall_data:
2023-06-16 04:00:18,358 DEBUG root:37 finish: subiquity/Install/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,358 DEBUG root:37 start: subiquity/Updates/load_autoinstall_data:
2023-06-16 04:00:18,359 DEBUG root:37 finish: subiquity/Updates/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,359 DEBUG root:37 start: subiquity/Late/load_autoinstall_data:
2023-06-16 04:00:18,361 DEBUG root:37 finish: subiquity/Late/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,361 DEBUG root:37 start: subiquity/Shutdown/load_autoinstall_data:
2023-06-16 04:00:18,361 DEBUG root:37 finish: subiquity/Shutdown/load_autoinstall_data: SUCCESS:
2023-06-16 04:00:18,364 DEBUG subiquitycore.core:120 starting controllers
2023-06-16 04:00:18,364 DEBUG subiquity.server.controllers.kernel:66 Using kernel linux-generic due to /run/kernel-meta-package
2023-06-16 04:00:18,371 DEBUG subiquity.models.source:87 loaded 2 sources from '/cdrom/casper/install-sources.yaml'
2023-06-16 04:00:18,378 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2023-06-16 04:00:18,378 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s3'}
2023-06-16 04:00:18,378 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2023-06-16 04:00:18,378 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 2, 'scope': 0, 'local': b'192.168.132.119/24'}
2023-06-16 04:00:18,378 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'}
2023-06-16 04:00:18,379 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::a00:27ff:feb4:9dae/64'}
2023-06-16 04:00:18,379 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2023-06-16 04:00:18,450 DEBUG subiquitycore.models.network:435 new_link 1 lo lo
2023-06-16 04:00:18,450 DEBUG subiquitycore.models.network:437 ignoring based on type
2023-06-16 04:00:18,450 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s3'}
2023-06-16 04:00:18,520 DEBUG subiquitycore.models.network:435 new_link 2 enp0s3 eth
2023-06-16 04:00:18,520 DEBUG subiquitycore.models.network:466 new_link 2 enp0s3 with config {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:18,520 DEBUG root:37 start: subiquity/Network/_send_update: NEW enp0s3
2023-06-16 04:00:18,521 DEBUG subiquity.server.controllers.network:354 dev_info enp0s3 {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:18,521 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: NEW enp0s3
2023-06-16 04:00:18,521 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2023-06-16 04:00:18,521 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 2, 'scope': 0, 'local': b'192.168.132.119/24'}
2023-06-16 04:00:18,522 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp0s3
2023-06-16 04:00:18,522 DEBUG subiquity.server.controllers.network:354 dev_info enp0s3 {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:18,522 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s3
2023-06-16 04:00:18,522 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'}
2023-06-16 04:00:18,522 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::a00:27ff:feb4:9dae/64'}
2023-06-16 04:00:18,522 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp0s3
2023-06-16 04:00:18,523 DEBUG subiquity.server.controllers.network:354 dev_info enp0s3 {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:18,523 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s3
2023-06-16 04:00:18,523 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2}
2023-06-16 04:00:18,523 DEBUG subiquitycore.controllers.network:94 default routes {2}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.128.10', 'ifindex': 2}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.132.0/24', 'ifindex': 2}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.132.1', 'ifindex': 2}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.132.119', 'ifindex': 2}
2023-06-16 04:00:18,524 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.132.255', 'ifindex': 2}
2023-06-16 04:00:18,525 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'::1', 'ifindex': 1}
2023-06-16 04:00:18,525 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2}
2023-06-16 04:00:18,525 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1}
2023-06-16 04:00:18,525 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::a00:27ff:feb4:9dae', 'ifindex': 2}
2023-06-16 04:00:18,525 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2}
2023-06-16 04:00:18,626 DEBUG subiquitycore.core:123 controllers started
2023-06-16 04:00:18,626 INFO root:37 start: subiquity/apply_autoinstall_config:
2023-06-16 04:00:18,626 DEBUG root:37 start: subiquity/Early/apply_autoinstall_config:
2023-06-16 04:00:18,626 DEBUG root:37 finish: subiquity/Early/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,627 DEBUG root:37 start: subiquity/Reporting/apply_autoinstall_config:
2023-06-16 04:00:18,627 DEBUG root:37 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,627 DEBUG root:37 start: subiquity/Error/apply_autoinstall_config:
2023-06-16 04:00:18,628 DEBUG root:37 finish: subiquity/Error/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,628 DEBUG root:37 start: subiquity/Userdata/apply_autoinstall_config:
2023-06-16 04:00:18,628 DEBUG root:37 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,629 DEBUG subiquity.models.subiquity:231 model userdata for postinstall stage is configured, to go {'snaplist', 'ubuntu_pro', 'packages', 'ssh', 'locale', 'drivers', 'network', 'identity'}
2023-06-16 04:00:18,629 DEBUG root:37 start: subiquity/Package/apply_autoinstall_config:
2023-06-16 04:00:18,629 DEBUG root:37 finish: subiquity/Package/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,629 DEBUG subiquity.models.subiquity:231 model packages for postinstall stage is configured, to go {'snaplist', 'ubuntu_pro', 'ssh', 'locale', 'drivers', 'network', 'identity'}
2023-06-16 04:00:18,629 DEBUG root:37 start: subiquity/Debconf/apply_autoinstall_config:
2023-06-16 04:00:18,629 DEBUG root:37 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,630 DEBUG subiquity.models.subiquity:231 model debconf_selections for install stage is configured, to go {'mirror', 'filesystem', 'source', 'kernel', 'proxy', 'network', 'keyboard'}
2023-06-16 04:00:18,630 DEBUG root:37 start: subiquity/Locale/apply_autoinstall_config:
2023-06-16 04:00:18,630 DEBUG root:37 finish: subiquity/Locale/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,630 DEBUG subiquity.models.subiquity:231 model locale for postinstall stage is configured, to go {'snaplist', 'ubuntu_pro', 'ssh', 'drivers', 'network', 'identity'}
2023-06-16 04:00:18,630 DEBUG root:37 start: subiquity/Refresh/apply_autoinstall_config:
2023-06-16 04:00:18,631 DEBUG root:37 finish: subiquity/Refresh/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,631 DEBUG root:37 start: subiquity/Kernel/apply_autoinstall_config:
2023-06-16 04:00:18,631 DEBUG root:37 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,632 DEBUG subiquity.models.subiquity:231 model kernel for install stage is configured, to go {'mirror', 'filesystem', 'source', 'proxy', 'network', 'keyboard'}
2023-06-16 04:00:18,632 DEBUG root:37 start: subiquity/Keyboard/apply_autoinstall_config:
2023-06-16 04:00:18,632 DEBUG root:37 finish: subiquity/Keyboard/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,632 DEBUG subiquity.models.subiquity:231 model keyboard for install stage is configured, to go {'mirror', 'filesystem', 'source', 'proxy', 'network'}
2023-06-16 04:00:18,632 DEBUG root:37 start: subiquity/Zdev/apply_autoinstall_config:
2023-06-16 04:00:18,632 DEBUG root:37 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:18,633 DEBUG curtin:87 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/ubuntu-server-minimal.squashfs', '/tmp/tmpi1cx9dfk/ubuntu-server-minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-16 04:00:18,640 DEBUG curtin:87 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/ubuntu-server-minimal.ubuntu-server.squashfs', '/tmp/tmpi1cx9dfk/ubuntu-server-minimal.ubuntu-server.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-16 04:00:18,648 DEBUG curtin:87 Running command ['mount', '-o', 'lowerdir=/tmp/tmpi1cx9dfk/ubuntu-server-minimal.ubuntu-server.squashfs.dir:/tmp/tmpi1cx9dfk/ubuntu-server-minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpi1cx9dfk/root.dir'] with allowed return codes [0] (capture=True)
2023-06-16 04:00:18,654 DEBUG subiquity.models.subiquity:231 model source for install stage is configured, to go {'proxy', 'mirror', 'network', 'filesystem'}
2023-06-16 04:00:18,655 DEBUG root:37 start: subiquity/Network/apply_autoinstall_config:
2023-06-16 04:00:18,655 DEBUG root:37 start: subiquity/Network/apply_autoinstall_config/wait_for_apply:
2023-06-16 04:00:18,656 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-16 04:00:18,657 DEBUG subiquity.models.subiquity:231 model locale for postinstall stage is configured, to go {'snaplist', 'ubuntu_pro', 'ssh', 'drivers', 'network', 'identity'}
2023-06-16 04:00:18,657 INFO root:37 start: subiquity/Network/apply_config: silent=False
2023-06-16 04:00:18,658 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp0s3
2023-06-16 04:00:18,658 DEBUG subiquity.server.controllers.network:354 dev_info enp0s3 {'critical': True, 'dhcp-identifier': 'mac', 'dhcp4': True, 'nameservers': {'addresses': ['192.168.128.10'], 'search': ['animapoint']}}
2023-06-16 04:00:18,658 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s3
2023-06-16 04:00:18,660 DEBUG subiquitycore.controllers.network:240 network config:
network:
ethernets:
enp1s0:
dhcp4: true
version: 2
2023-06-16 04:00:18,664 DEBUG subiquitycore.netplan:109 config for enp1s0 = {'dhcp4': True}
2023-06-16 04:00:18,664 DEBUG subiquitycore.utils:92 arun_command called: ['netplan', 'apply']
2023-06-16 04:00:18,676 DEBUG root:37 start: subiquity/Drivers/_list_drivers:
2023-06-16 04:00:18,676 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt:
2023-06-16 04:00:18,677 DEBUG root:37 start: subiquity/Install/install:
2023-06-16 04:00:18,678 INFO aiohttp.access:233 [16/Jun/2023:04:00:17 +0000] "GET /meta/status?cur=%22CLOUD_INIT_WAIT%22 HTTP/1.1" 200 412 "-" "Python/3.8 aiohttp/3.6.2"
2023-06-16 04:00:18,703 DEBUG root:37 start: subiquity/Filesystem/_probe:
2023-06-16 04:00:18,705 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2023-06-16 04:00:18,716 INFO root:37 start: subiquity/Meta/status_GET:
2023-06-16 04:00:18,785 DEBUG probert.filesystems:158 swap volume size not found. Neither ID_PART_ENTRY_SIZE nor attrs:size present
2023-06-16 04:00:18,794 DEBUG subiquitycore.utils:64 run_command called: ['udevadm', 'settle', '-t', '0']
2023-06-16 04:00:18,828 DEBUG subiquitycore.utils:77 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2023-06-16 04:00:18,829 DEBUG subiquity.server.controllers.filesystem:683 _udev_event change Device('/sys/devices/pci0000:00/0000:00:0d.0/ata3/host2/target2:0:0/2:0:0:0/block/sda/sda1')
2023-06-16 04:00:18,829 DEBUG subiquity.server.controllers.filesystem:687 Skipping run of Probert - probe run already active
2023-06-16 04:00:18,843 DEBUG subiquitycore.utils:64 run_command called: ['udevadm', 'settle', '-t', '0']
2023-06-16 04:00:18,880 DEBUG subiquitycore.utils:77 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2023-06-16 04:00:18,881 DEBUG subiquity.server.controllers.filesystem:683 _udev_event change Device('/sys/devices/virtual/block/dm-1')
2023-06-16 04:00:18,881 DEBUG subiquity.server.controllers.filesystem:687 Skipping run of Probert - probe run already active
2023-06-16 04:00:19,357 INFO probert.lvm:120 b' 2 logical volume(s) in volume group "anima-vg" now active\n'
2023-06-16 04:00:19,447 ERROR probert.lvm:225 Found duplicate volume group: anima-vg
2023-06-16 04:00:19,459 DEBUG probert.multipath:48 Extracted multipath maps fields: ['ok']
2023-06-16 04:00:19,460 DEBUG probert.multipath:52 Failed to parse multipath maps entry: ok: __new__() missing 2 required positional arguments: 'sysfs' and 'paths'
2023-06-16 04:00:19,467 DEBUG probert.multipath:48 Extracted multipath paths fields: ['sda', 'VBbfa36849-2c4eb02e', '[orphan]', '[undef]', 'ata-3.00', '[undef]', '[undef]', '[undef]']
2023-06-16 04:00:19,467 DEBUG probert.multipath:48 Extracted multipath paths fields: ['sdb', 'VBfae3babf-9290d236', '[orphan]', '[undef]', 'ata-4.00', '[undef]', '[undef]', '[undef]']
2023-06-16 04:00:19,468 DEBUG probert.dasd:134 Probing DASD devies
2023-06-16 04:00:19,468 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2023-06-16 04:00:19,471 DEBUG curtin:1303 Extracting storage config from probe data
2023-06-16 04:00:19,471 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2023-06-16 04:00:19,471 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2023-06-16 04:00:19,471 DEBUG curtin:73 /dev/sda is multipath device member? False
2023-06-16 04:00:19,471 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-16 04:00:19,471 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-16 04:00:19,471 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-16 04:00:19,472 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-16 04:00:19,491 DEBUG curtin:73 /dev/sda1 is multipath device member? False
2023-06-16 04:00:19,491 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-16 04:00:19,491 DEBUG curtin:61 /dev/sda1 is multipath device? False
2023-06-16 04:00:19,491 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-16 04:00:19,491 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-16 04:00:19,508 DEBUG curtin:73 /dev/sda2 is multipath device member? False
2023-06-16 04:00:19,508 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-16 04:00:19,508 DEBUG curtin:61 /dev/sda2 is multipath device? False
2023-06-16 04:00:19,508 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-16 04:00:19,508 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-16 04:00:19,524 DEBUG curtin:73 /dev/sdb is multipath device member? False
2023-06-16 04:00:19,525 DEBUG curtin:86 /dev/sdb is multipath device partition? False
2023-06-16 04:00:19,525 DEBUG curtin:61 /dev/sdb is multipath device? False
2023-06-16 04:00:19,525 DEBUG curtin:86 /dev/sdb is multipath device partition? False
2023-06-16 04:00:19,525 DEBUG curtin:61 /dev/sdb is multipath device? False
2023-06-16 04:00:19,541 DEBUG curtin:61 /dev/dm-0 is multipath device? False
2023-06-16 04:00:19,541 DEBUG curtin:86 /dev/dm-0 is multipath device partition? False
2023-06-16 04:00:19,541 DEBUG curtin:61 /dev/dm-1 is multipath device? False
2023-06-16 04:00:19,541 DEBUG curtin:86 /dev/dm-1 is multipath device partition? False
2023-06-16 04:00:19,542 DEBUG curtin:73 /dev/sda1 is multipath device member? False
2023-06-16 04:00:19,558 DEBUG curtin:73 /dev/dm-0 is multipath device member? False
2023-06-16 04:00:19,558 DEBUG curtin:73 /dev/dm-1 is multipath device member? False
2023-06-16 04:00:19,621 DEBUG curtin:1310 Sorting extracted configurations
2023-06-16 04:00:19,621 INFO curtin:1329 Validating extracted storage config components
2023-06-16 04:00:19,647 DEBUG curtin:1346 Extracted (unmerged) storage config:
storage:
- id: disk-sda
path: /dev/sda
ptable: gpt
serial: VBOX_HARDDISK_VBbfa36849-2c4eb02e
type: disk
- id: disk-sdb
path: /dev/sdb
serial: VBOX_HARDDISK_VBfae3babf-9290d236
type: disk
- device: disk-sda
flag: boot
id: partition-sda1
number: 1
offset: 1048576
partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
path: /dev/sda1
size: 536870912
type: partition
- device: disk-sda
flag: linux
id: partition-sda2
number: 2
offset: 537919488
partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
path: /dev/sda2
size: 20935868416
type: partition
- fstype: ext4
id: format-partition-sda1
type: format
uuid: d1b85c34-8dba-4813-882a-826c5a9c00b8
volume: partition-sda1
- fstype: ext4
id: format-lvm-partition-root
type: format
uuid: 438b809f-bf91-4aa6-94e3-e3f0b27f2625
volume: lvm-partition-root
- devices:
- partition-sda2
id: lvm-volgroup-anima-vg
name: anima-vg
type: lvm_volgroup
- id: lvm-partition-swap
name: swap
path: /dev/dm-0
size: 17179869184B
type: lvm_partition
volgroup: lvm-volgroup-anima-vg
- id: lvm-partition-root
name: root
path: /dev/dm-1
size: 3753902080B
type: lvm_partition
volgroup: lvm-volgroup-anima-vg
2023-06-16 04:00:19,647 DEBUG curtin:1350 Generating storage config dependencies
2023-06-16 04:00:19,647 DEBUG curtin:236 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'bcache', 'lvm_partition', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: format-lvm-partition-root:SourceType:format -> (DepId:lvm-partition-root DepType:lvm_partition) in SourceDeps:{'bcache', 'lvm_partition', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: lvm-partition-root:SourceType:lvm_partition -> (DepId:lvm-volgroup-anima-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: lvm-volgroup-anima-vg:SourceType:lvm_volgroup -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'bcache', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,648 DEBUG curtin:236 Validate: lvm-volgroup-anima-vg:SourceType:lvm_volgroup -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'bcache', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: lvm-partition-swap:SourceType:lvm_partition -> (DepId:lvm-volgroup-anima-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: lvm-volgroup-anima-vg:SourceType:lvm_volgroup -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'bcache', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: lvm-partition-root:SourceType:lvm_partition -> (DepId:lvm-volgroup-anima-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: lvm-volgroup-anima-vg:SourceType:lvm_volgroup -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'bcache', 'partition', 'dm_crypt', 'raid', 'disk'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:236 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'disk', 'bcache', 'partition'} ? result=True
2023-06-16 04:00:19,649 DEBUG curtin:1356 Merging storage config dependencies
2023-06-16 04:00:19,655 DEBUG curtin:1361 Merged storage config:
storage:
config:
- id: disk-sda
path: /dev/sda
ptable: gpt
serial: VBOX_HARDDISK_VBbfa36849-2c4eb02e
type: disk
- id: disk-sdb
path: /dev/sdb
serial: VBOX_HARDDISK_VBfae3babf-9290d236
type: disk
- device: disk-sda
flag: boot
id: partition-sda1
number: 1
offset: 1048576
partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
path: /dev/sda1
size: 536870912
type: partition
- device: disk-sda
flag: linux
id: partition-sda2
number: 2
offset: 537919488
partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
path: /dev/sda2
size: 20935868416
type: partition
- fstype: ext4
id: format-partition-sda1
type: format
uuid: d1b85c34-8dba-4813-882a-826c5a9c00b8
volume: partition-sda1
- devices:
- partition-sda2
id: lvm-volgroup-anima-vg
name: anima-vg
type: lvm_volgroup
- id: lvm-partition-root
name: root
path: /dev/dm-1
size: 3753902080B
type: lvm_partition
volgroup: lvm-volgroup-anima-vg
- id: lvm-partition-swap
name: swap
path: /dev/dm-0
size: 17179869184B
type: lvm_partition
volgroup: lvm-volgroup-anima-vg
- fstype: ext4
id: format-lvm-partition-root
type: format
uuid: 438b809f-bf91-4aa6-94e3-e3f0b27f2625
volume: lvm-partition-root
version: 2
2023-06-16 04:00:19,655 DEBUG subiquity.models.filesystem:1356 exclusions set()
2023-06-16 04:00:19,655 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2023-06-16 04:00:19,656 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS:
2023-06-16 04:00:19,691 DEBUG subiquitycore.utils:101 arun_command ['netplan', 'apply'] exited with code 0
2023-06-16 04:00:29,706 INFO root:37 finish: subiquity/Network/apply_config: SUCCESS: silent=False
2023-06-16 04:00:29,706 DEBUG root:37 finish: subiquity/Network/apply_autoinstall_config/wait_for_apply: SUCCESS:
2023-06-16 04:00:29,707 DEBUG subiquitycore.models.network:427 has_network True
2023-06-16 04:00:29,708 DEBUG root:37 finish: subiquity/Network/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,708 DEBUG subiquitycore.models.network:427 has_network True
2023-06-16 04:00:29,709 DEBUG subiquity.models.subiquity:231 model network for install stage is configured, to go {'proxy', 'mirror', 'filesystem'}
2023-06-16 04:00:29,709 DEBUG subiquity.models.subiquity:231 model network for postinstall stage is configured, to go {'snaplist', 'ubuntu_pro', 'ssh', 'drivers', 'identity'}
2023-06-16 04:00:29,709 DEBUG root:37 start: subiquity/UbuntuPro/apply_autoinstall_config:
2023-06-16 04:00:29,710 DEBUG root:37 finish: subiquity/UbuntuPro/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,710 DEBUG subiquity.models.subiquity:231 model ubuntu_pro for postinstall stage is configured, to go {'ssh', 'snaplist', 'drivers', 'identity'}
2023-06-16 04:00:29,710 DEBUG root:37 start: subiquity/Proxy/apply_autoinstall_config:
2023-06-16 04:00:29,711 DEBUG root:37 finish: subiquity/Proxy/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,711 DEBUG subiquity.models.subiquity:231 model proxy for install stage is configured, to go {'mirror', 'filesystem'}
2023-06-16 04:00:29,711 DEBUG root:37 start: subiquity/Mirror/apply_autoinstall_config:
2023-06-16 04:00:29,712 DEBUG root:37 finish: subiquity/Mirror/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,712 DEBUG subiquity.models.subiquity:231 model mirror for install stage is configured, to go {'filesystem'}
2023-06-16 04:00:29,712 DEBUG root:37 start: subiquity/Filesystem/apply_autoinstall_config:
2023-06-16 04:00:29,713 DEBUG root:37 start: subiquity/Filesystem/apply_autoinstall_config/convert_autoinstall_config:
2023-06-16 04:00:29,713 DEBUG subiquity.server.controllers.filesystem:633 self.ai_data = {'config': [{'grub_device': True, 'id': 'disk-sda', 'name': '', 'path': '/dev/sda', 'preserve': False, 'ptable': 'gpt', 'type': 'disk', 'wipe': 'superblock'}, {'device': 'disk-sda', 'flag': 'boot', 'grub_device': True, 'id': 'boot-partition', 'number': 1, 'preserve': False, 'size': '512MB', 'type': 'partition', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-0', 'preserve': False, 'type': 'format', 'volume': 'boot-partition'}, {'device': 'disk-sda', 'flag': '', 'grub_device': False, 'id': 'lvm-partition', 'number': 2, 'preserve': False, 'size': -1, 'type': 'partition', 'wipe': 'superblock'}, {'devices': ['lvm-partition'], 'id': 'anima-vg', 'name': 'anima-vg', 'preserve': False, 'type': 'lvm_volgroup'}, {'id': 'swap', 'name': 'swap', 'preserve': False, 'size': '16GB', 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'swap', 'id': 'format-1', 'preserve': False, 'type': 'format', 'volume': 'swap'}, {'device': 'format-1', 'id': 'mount-1', 'path': '', 'type': 'mount'}, {'id': 'root', 'name': 'root', 'preserve': False, 'size': -1, 'type': 'lvm_partition', 'volgroup': 'anima-vg', 'wipe': 'superblock'}, {'fstype': 'ext4', 'id': 'format-2', 'preserve': False, 'type': 'format', 'volume': 'root'}, {'device': 'format-2', 'id': 'mount-2', 'path': '/', 'type': 'mount'}, {'device': 'format-0', 'id': 'mount-0', 'path': '/boot/efi', 'type': 'mount'}], 'swap': {'swap': 0}}
2023-06-16 04:00:29,714 DEBUG subiquity.models.filesystem:1356 exclusions set()
2023-06-16 04:00:29,714 DEBUG root:37 finish: subiquity/Filesystem/apply_autoinstall_config/convert_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,714 DEBUG root:37 finish: subiquity/Filesystem/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,715 DEBUG subiquity.models.subiquity:231 model filesystem for install stage is configured, to go set()
2023-06-16 04:00:29,715 DEBUG root:37 start: subiquity/Identity/apply_autoinstall_config:
2023-06-16 04:00:29,715 DEBUG root:37 finish: subiquity/Identity/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,715 DEBUG subiquity.models.subiquity:231 model identity for postinstall stage is configured, to go {'ssh', 'snaplist', 'drivers'}
2023-06-16 04:00:29,716 DEBUG root:37 start: subiquity/SSH/apply_autoinstall_config:
2023-06-16 04:00:29,716 DEBUG root:37 finish: subiquity/SSH/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,716 DEBUG subiquity.models.subiquity:231 model ssh for postinstall stage is configured, to go {'snaplist', 'drivers'}
2023-06-16 04:00:29,716 DEBUG root:37 start: subiquity/SnapList/apply_autoinstall_config:
2023-06-16 04:00:29,717 DEBUG root:37 finish: subiquity/SnapList/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,717 DEBUG subiquity.models.subiquity:231 model snaplist for postinstall stage is configured, to go {'drivers'}
2023-06-16 04:00:29,717 DEBUG root:37 start: subiquity/Drivers/apply_autoinstall_config:
2023-06-16 04:00:29,717 DEBUG root:37 finish: subiquity/Drivers/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,717 DEBUG subiquity.models.subiquity:231 model drivers for postinstall stage is configured, to go set()
2023-06-16 04:00:29,718 DEBUG root:37 start: subiquity/TimeZone/apply_autoinstall_config:
2023-06-16 04:00:29,718 DEBUG root:37 finish: subiquity/TimeZone/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,718 DEBUG subiquity.server.server:471 apply_autoinstall_config: skipping Install as interactive
2023-06-16 04:00:29,718 DEBUG root:37 start: subiquity/Updates/apply_autoinstall_config:
2023-06-16 04:00:29,719 DEBUG root:37 finish: subiquity/Updates/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,719 DEBUG root:37 start: subiquity/Late/apply_autoinstall_config:
2023-06-16 04:00:29,719 DEBUG root:37 finish: subiquity/Late/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,719 DEBUG root:37 start: subiquity/Shutdown/apply_autoinstall_config:
2023-06-16 04:00:29,720 DEBUG root:37 finish: subiquity/Shutdown/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,720 INFO root:37 finish: subiquity/apply_autoinstall_config: SUCCESS:
2023-06-16 04:00:29,722 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-06-16 04:00:29,722 INFO aiohttp.access:233 [16/Jun/2023:04:00:18 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 423 "-" "Python/3.8 aiohttp/3.6.2"
2023-06-16 04:00:29,723 INFO root:37 start: subiquity/Install/install/configure_apt: configuring apt
2023-06-16 04:00:29,723 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'mount', '-o', 'lowerdir=/tmp/tmpi1cx9dfk/root.dir,upperdir=/tmp/tmp471ht0h_/upper,workdir=/tmp/tmp471ht0h_/work', '-t', 'overlay', 'overlay', '/tmp/tmp471ht0h_/mount']
2023-06-16 04:00:29,742 INFO root:37 start: subiquity/Meta/status_GET:
2023-06-16 04:00:29,743 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-16 04:00:29,744 INFO aiohttp.access:233 [16/Jun/2023:04:00:29 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 412 "-" "Python/3.8 aiohttp/3.6.2"
2023-06-16 04:00:29,745 INFO root:37 start: subiquity/Meta/status_GET:
2023-06-16 04:00:29,775 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.1"}}', '-c', '/var/log/installer/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmp471ht0h_/mount']
2023-06-16 04:00:30,849 DEBUG root:37 start: subiquity/Mirror/cmd-apt-config: curtin command apt-config
2023-06-16 04:00:35,599 DEBUG subiquity.server.curtin:121 waited 0.1 seconds for events to drain
2023-06-16 04:00:35,666 DEBUG root:37 finish: subiquity/Mirror/cmd-apt-config: SUCCESS: curtin command apt-config
2023-06-16 04:00:35,701 DEBUG subiquity.server.curtin:121 waited 0.2 seconds for events to drain
2023-06-16 04:00:35,702 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'mount', '-o', 'lowerdir=/tmp/tmp471ht0h_/upper:/tmp/tmpi1cx9dfk/root.dir,upperdir=/tmp/tmpt368at9e/upper,workdir=/tmp/tmpt368at9e/work', '-t', 'overlay', 'overlay', '/tmp/tmpt368at9e/mount']
2023-06-16 04:00:35,747 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmpt368at9e/mount/cdrom']
2023-06-16 04:00:35,779 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.2"}}', 'in-target', '-t', '/tmp/tmpt368at9e/mount', '--', 'apt-get', 'update']
2023-06-16 04:00:36,906 DEBUG root:37 start: subiquity/Install/install/configure_apt/cmd-in-target: curtin command in-target
2023-06-16 04:00:47,886 DEBUG subiquity.server.curtin:121 waited 0.1 seconds for events to drain
2023-06-16 04:00:47,988 DEBUG subiquity.server.curtin:121 waited 0.2 seconds for events to drain
2023-06-16 04:00:48,090 DEBUG subiquity.server.curtin:121 waited 0.30000000000000004 seconds for events to drain
2023-06-16 04:00:48,167 DEBUG root:37 finish: subiquity/Install/install/configure_apt/cmd-in-target: SUCCESS: curtin command in-target
2023-06-16 04:00:48,192 DEBUG subiquity.server.curtin:121 waited 0.4 seconds for events to drain
2023-06-16 04:00:48,193 INFO root:37 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt
2023-06-16 04:00:48,194 INFO root:37 start: subiquity/Install/install/curtin_install: installing system
2023-06-16 04:00:48,195 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step: executing curtin install initial step
2023-06-16 04:00:48,207 DEBUG root:37 finish: subiquity/Drivers/_list_drivers/wait_apt: SUCCESS:
2023-06-16 04:00:48,207 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'mount', '-o', 'lowerdir=/tmp/tmpi1cx9dfk/root.dir:/tmp/tmp471ht0h_/upper:/tmp/tmpt368at9e/upper,upperdir=/tmp/tmpg1zd0kdz/upper,workdir=/tmp/tmpg1zd0kdz/work', '-t', 'overlay', 'overlay', '/tmp/tmpg1zd0kdz/mount']
2023-06-16 04:00:48,213 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.3"}}', '-c', '/var/log/installer/curtin-install/subiquity-initial.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=[]']
2023-06-16 04:00:48,251 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'chroot', '/tmp/tmpg1zd0kdz/mount', 'sh', '-c', 'command -v ubuntu-drivers']
2023-06-16 04:00:48,275 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.4"}}', 'in-target', '-t', '/tmp/tmpg1zd0kdz/mount', '--', 'ubuntu-drivers', 'list', '--recommended', '--gpgpu']
2023-06-16 04:00:49,000 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step: SUCCESS: executing curtin install initial step
2023-06-16 04:00:49,000 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step: executing curtin install partitioning step
2023-06-16 04:00:49,001 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.mirror.MirrorModel object at 0x7faf3e25adc0>
2023-06-16 04:00:49,002 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x7faf3e25ac70>
2023-06-16 04:00:49,002 DEBUG subiquity.models.filesystem:1421 mountpoints {'': 'mount-1', '/': 'mount-2', '/boot/efi': 'mount-0'}
2023-06-16 04:00:49,003 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.source.SourceModel object at 0x7faf3e1bde20>
2023-06-16 04:00:49,004 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x7faf3e25abe0>
2023-06-16 04:00:49,004 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.kernel.KernelModel object at 0x7faf3e25abb0>
2023-06-16 04:00:49,004 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.proxy.ProxyModel object at 0x7faf3e1bdf70>
2023-06-16 04:00:49,004 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.network.NetworkModel object at 0x7faf3e1bdd00>
2023-06-16 04:00:49,006 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x7faf3e25ad00>
2023-06-16 04:00:49,020 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.5"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["partitioning"]']
2023-06-16 04:00:49,164 DEBUG root:37 start: subiquity/Drivers/_list_drivers/cmd-in-target: curtin command in-target
2023-06-16 04:00:50,165 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: curtin command install
2023-06-16 04:00:50,166 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning: configuring storage
2023-06-16 04:00:50,166 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin: running 'curtin block-meta simple'
2023-06-16 04:00:50,778 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta
2023-06-16 04:00:51,746 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: removing previous storage devices
2023-06-16 04:00:53,653 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: SUCCESS: removing previous storage devices
2023-06-16 04:00:53,654 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: configuring disk: disk-sda
2023-06-16 04:00:53,841 DEBUG subiquity.server.curtin:121 waited 0.1 seconds for events to drain
2023-06-16 04:00:53,914 DEBUG root:37 finish: subiquity/Drivers/_list_drivers/cmd-in-target: SUCCESS: curtin command in-target
2023-06-16 04:00:53,917 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: SUCCESS: configuring disk: disk-sda
2023-06-16 04:00:53,918 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: boot-partition
2023-06-16 04:00:53,942 DEBUG subiquity.server.curtin:121 waited 0.2 seconds for events to drain
2023-06-16 04:00:53,943 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', 'umount', '/tmp/tmpg1zd0kdz/mount']
2023-06-16 04:00:53,983 DEBUG subiquity.server.controllers.drivers:100 Available drivers to install: []
2023-06-16 04:00:53,984 DEBUG subiquity.models.subiquity:231 model drivers for postinstall stage is configured, to go set()
2023-06-16 04:00:53,984 DEBUG root:37 finish: subiquity/Drivers/_list_drivers: SUCCESS:
2023-06-16 04:00:55,759 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: boot-partition
2023-06-16 04:00:55,761 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-0
2023-06-16 04:00:56,423 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-0
2023-06-16 04:00:56,424 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: lvm-partition
2023-06-16 04:00:58,358 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: lvm-partition
2023-06-16 04:00:58,359 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_volgroup: anima-vg
2023-06-16 04:00:58,915 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_volgroup: anima-vg
2023-06-16 04:00:58,916 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: swap
2023-06-16 04:00:59,468 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: swap
2023-06-16 04:00:59,468 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-1
2023-06-16 04:00:59,727 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-1
2023-06-16 04:00:59,728 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-1
2023-06-16 04:00:59,728 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-1
2023-06-16 04:00:59,729 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: root
2023-06-16 04:01:00,673 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: root
2023-06-16 04:01:00,673 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-2
2023-06-16 04:01:01,419 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-2
2023-06-16 04:01:01,419 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-2
2023-06-16 04:01:01,420 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-2
2023-06-16 04:01:01,420 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-0
2023-06-16 04:01:01,963 DEBUG subiquity.server.curtin:121 waited 0.1 seconds for events to drain
2023-06-16 04:01:02,063 DEBUG subiquity.server.curtin:121 waited 0.2 seconds for events to drain
2023-06-16 04:01:02,164 DEBUG subiquity.server.curtin:121 waited 0.30000000000000004 seconds for events to drain
2023-06-16 04:01:02,168 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-0
2023-06-16 04:01:02,169 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning/builtin: SUCCESS: running 'curtin block-meta simple'
2023-06-16 04:01:02,170 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-partitioning: SUCCESS: configuring storage
2023-06-16 04:01:02,170 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: SUCCESS: curtin command install
2023-06-16 04:01:02,265 DEBUG subiquity.server.curtin:121 waited 0.4 seconds for events to drain
2023-06-16 04:01:02,266 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step: SUCCESS: executing curtin install partitioning step
2023-06-16 04:01:02,266 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step: executing curtin install extract step
2023-06-16 04:01:02,267 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.mirror.MirrorModel object at 0x7faf3e25adc0>
2023-06-16 04:01:02,267 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x7faf3e25ac70>
2023-06-16 04:01:02,268 DEBUG subiquity.models.filesystem:1421 mountpoints {'': 'mount-1', '/': 'mount-2', '/boot/efi': 'mount-0'}
2023-06-16 04:01:02,269 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.source.SourceModel object at 0x7faf3e1bde20>
2023-06-16 04:01:02,269 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x7faf3e25abe0>
2023-06-16 04:01:02,269 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.kernel.KernelModel object at 0x7faf3e25abb0>
2023-06-16 04:01:02,269 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.proxy.ProxyModel object at 0x7faf3e1bdf70>
2023-06-16 04:01:02,269 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.network.NetworkModel object at 0x7faf3e1bdd00>
2023-06-16 04:01:02,271 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x7faf3e25ad00>
2023-06-16 04:01:02,286 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-extract.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["extract"]']
2023-06-16 04:01:03,415 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: curtin command install
2023-06-16 04:01:03,415 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract: writing install sources to disk
2023-06-16 04:01:03,416 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract/builtin: running 'curtin extract'
2023-06-16 04:01:04,164 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract/builtin/cmd-extract: curtin command extract
2023-06-16 04:01:04,165 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract/builtin/cmd-extract/: acquiring and extracting image from cp:///tmp/tmpt368at9e/mount
2023-06-16 04:01:38,160 DEBUG subiquity.server.curtin:121 waited 0.1 seconds for events to drain
2023-06-16 04:01:38,261 DEBUG subiquity.server.curtin:121 waited 0.2 seconds for events to drain
2023-06-16 04:01:38,362 DEBUG subiquity.server.curtin:121 waited 0.30000000000000004 seconds for events to drain
2023-06-16 04:01:38,420 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract/builtin/cmd-extract/: SUCCESS: acquiring and extracting image from cp:///tmp/tmpt368at9e/mount
2023-06-16 04:01:38,421 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract/builtin: SUCCESS: running 'curtin extract'
2023-06-16 04:01:38,422 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-extract: SUCCESS: writing install sources to disk
2023-06-16 04:01:38,422 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: SUCCESS: curtin command install
2023-06-16 04:01:38,464 DEBUG subiquity.server.curtin:121 waited 0.4 seconds for events to drain
2023-06-16 04:01:38,464 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step: SUCCESS: executing curtin install extract step
2023-06-16 04:01:38,465 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step: executing curtin install curthooks step
2023-06-16 04:01:38,466 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.mirror.MirrorModel object at 0x7faf3e25adc0>
2023-06-16 04:01:38,466 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x7faf3e25ac70>
2023-06-16 04:01:38,466 DEBUG subiquity.models.filesystem:1421 mountpoints {'': 'mount-1', '/': 'mount-2', '/boot/efi': 'mount-0'}
2023-06-16 04:01:38,468 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.source.SourceModel object at 0x7faf3e1bde20>
2023-06-16 04:01:38,468 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x7faf3e25abe0>
2023-06-16 04:01:38,468 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.kernel.KernelModel object at 0x7faf3e25abb0>
2023-06-16 04:01:38,468 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.proxy.ProxyModel object at 0x7faf3e1bdf70>
2023-06-16 04:01:38,468 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.network.NetworkModel object at 0x7faf3e1bdd00>
2023-06-16 04:01:38,470 DEBUG subiquity.models.subiquity:436 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x7faf3e25ad00>
2023-06-16 04:01:38,486 DEBUG subiquitycore.utils:115 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']
2023-06-16 04:01:39,666 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: curtin command install
2023-06-16 04:01:39,667 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks: configuring installed system
2023-06-16 04:01:39,668 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/001-mount-cdrom: running 'mount --bind /cdrom /target/cdrom'
2023-06-16 04:01:39,668 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/001-mount-cdrom: SUCCESS: running 'mount --bind /cdrom /target/cdrom'
2023-06-16 04:01:39,669 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/002-setupcon-save-only: running 'curtin in-target -- setupcon --save-only'
2023-06-16 04:01:40,927 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/002-setupcon-save-only/cmd-in-target: curtin command in-target
2023-06-16 04:01:41,679 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/002-setupcon-save-only/cmd-in-target: SUCCESS: curtin command in-target
2023-06-16 04:01:41,680 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/002-setupcon-save-only: SUCCESS: running 'curtin in-target -- setupcon --save-only'
2023-06-16 04:01:41,680 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin: running 'curtin curthooks'
2023-06-16 04:01:42,453 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks: curtin command curthooks
2023-06-16 04:01:42,453 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: configuring apt configuring apt
2023-06-16 04:01:42,740 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: SUCCESS: configuring apt configuring apt
2023-06-16 04:01:42,740 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: installing missing packages
2023-06-16 04:01:42,740 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: SUCCESS: installing missing packages
2023-06-16 04:01:42,741 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: configuring iscsi service
2023-06-16 04:01:42,741 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: SUCCESS: configuring iscsi service
2023-06-16 04:01:42,742 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: configuring raid (mdadm) service
2023-06-16 04:01:42,742 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: SUCCESS: configuring raid (mdadm) service
2023-06-16 04:01:42,743 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: installing kernel
2023-06-16 04:02:28,137 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: SUCCESS: installing kernel
2023-06-16 04:02:28,137 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: setting up swap
2023-06-16 04:02:28,138 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: SUCCESS: setting up swap
2023-06-16 04:02:28,138 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: apply networking config
2023-06-16 04:02:28,138 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: SUCCESS: apply networking config
2023-06-16 04:02:28,139 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: writing etc/fstab
2023-06-16 04:02:28,139 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: SUCCESS: writing etc/fstab
2023-06-16 04:02:28,140 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: configuring multipath
2023-06-16 04:02:29,129 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: SUCCESS: configuring multipath
2023-06-16 04:02:29,130 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: updating packages on target system
2023-06-16 04:02:29,130 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: SUCCESS: updating packages on target system
2023-06-16 04:02:29,131 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: configuring pollinate user-agent on target
2023-06-16 04:02:29,131 DEBUG root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: SUCCESS: configuring pollinate user-agent on target
2023-06-16 04:02:29,132 DEBUG root:37 start: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: updating initramfs configuration
2023-06-16 04:03:04,442 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: FAIL: updating initramfs configuration
2023-06-16 04:03:04,443 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin/cmd-curthooks: FAIL: curtin command curthooks
2023-06-16 04:03:04,444 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks/builtin: FAIL: running 'curtin curthooks'
2023-06-16 04:03:04,444 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install/stage-curthooks: FAIL: configuring installed system
2023-06-16 04:03:05,965 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.
2023-06-16 04:03:05,965 ERROR root:37 finish: subiquity/Install/install/curtin_install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.
2023-06-16 04:03:05,966 DEBUG subiquity.common.errorreport:384 generating crash report
2023-06-16 04:03:05,984 INFO subiquity.common.errorreport:406 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1686888185.966253757.install_fail.crash
2023-06-16 04:03:05,984 ERROR root:37 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.
2023-06-16 04:03:05,985 INFO root:37 start: subiquity/ErrorReporter/1686888185.966253757.install_fail/add_info:
2023-06-16 04:03:05,985 ERROR subiquity.server.server:424 top level error
Traceback (most recent call last):
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 139, in _run
await Install.install_task
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 281, in install
await self.curtin_install(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 249, in curtin_install
await self.run_curtin_install_step(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 187, in run_curtin_install_step
return await run_curtin_command(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/curtin.py", line 203, in run_curtin_command
return await cmd.wait()
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/curtin.py", line 116, in wait
result = await self.runner.wait(self.proc)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/runner.py", line 84, in wait
raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.
2023-06-16 04:03:05,986 ERROR subiquity.server.server:424 top level error
Traceback (most recent call last):
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/shutdown.py", line 80, in _wait_install
await self.app.controllers.Install.install_task
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/cmdlist.py", line 139, in _run
await Install.install_task
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 281, in install
await self.curtin_install(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 249, in curtin_install
await self.run_curtin_install_step(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquitycore/context.py", line 148, in decorated_async
return await meth(self, **kw)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/controllers/install.py", line 187, in run_curtin_install_step
return await run_curtin_command(
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/curtin.py", line 203, in run_curtin_command
return await cmd.wait()
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/curtin.py", line 116, in wait
result = await self.runner.wait(self.proc)
File "/snap/subiquity/4380/lib/python3.8/site-packages/subiquity/server/runner.py", line 84, in wait
raise subprocess.CalledProcessError(proc.returncode, proc.args)
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2424', '--setenv', 'PATH=/snap/subiquity/4380/bin:/snap/subiquity/4380/usr/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin:/snap/subiquity/4380/bin:/snap/subiquity/4380/sbin', '--setenv', 'PYTHONPATH=:/snap/subiquity/4380/lib/python3.8/site-packages', '--setenv', 'PYTHON=/snap/subiquity/4380/usr/bin/python3.8', '--setenv', 'SNAP=/snap/subiquity/4380', '--', '/snap/subiquity/4380/usr/bin/python3.8', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2424.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-curthooks.conf', 'install', 'cp:///tmp/tmpt368at9e/mount', '--set', 'json:stages=["curthooks"]']' returned non-zero exit status 3.
2023-06-16 04:03:06,193 ERROR root:37 finish: subiquity/Install/install/curtin_install/run_curtin_install_step/cmd-install: FAIL: curtin command install
2023-06-16 04:03:06,691 INFO root:37 finish: subiquity/ErrorReporter/1686888185.966253757.install_fail/add_info: SUCCESS: written to /var/crash/1686888185.966253757.install_fail.crash
2023-06-16 04:03:06,694 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "", "error": {"state": "DONE", "base": "...
2023-06-16 04:03:06,695 INFO aiohttp.access:233 [16/Jun/2023:04:00:32 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 524 "-" "Python/3.8 aiohttp/3.6.2"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment