/snap/subiquity/2393/usr/bin/subiquity-server:KeyError:main:wrapper:log_time:block_meta:meta_custom:mount_handler:mount_apply:fstab_line_for_data:get_volume_spec

[errors.ubuntu.com bug bridge]

The Ubuntu Error Tracker has been receiving reports about a problem regarding subiquity. This problem was most recently seen with package version 2393, the problem page at https://errors.ubuntu.com/problem/8727f4e3d81a8dc07846951867577d1bd38ccc52 contains more details, including versions of packages affected, stacktrace or traceback, and individual crash reports.
If you do not have access to the Ubuntu Error Tracker and are a software developer, you can request it at http://forms.canonical.com/reports/.

[Michael Hudson-Doyle (mwhudson)]

The curtin log tail is this:

Running command ['lsblk', '--noheadings', '--bytes', '--pairs', '--output=ALIGNMENT,DISC-ALN,DISC-GRAN,DISC-MAX,DISC-ZERO,FSTYPE,GROUP,KNAME,LABEL,LOG-SEC,MAJ:MIN,MIN-IO,MODE,MODEL,MOUNTPOINT,NAME,OPT-IO,OWNER,PHY-SEC,RM,RO,ROTA,RQ-SIZE,SIZE,STATE,TYPE,UUID', '/dev/sda2'] with allowed return codes [0] (capture=True)
volspec: path=/dev/sda2 type=part
An error occured handling 'mount-0': KeyError - 'DEVLINKS'
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: configuring mount: mount-0
TIMED BLOCK_META: 25.187
finish: cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: curtin command block-meta
Traceback (most recent call last):
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/main.py", line 202, in main
ret = args.func(args)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/log.py", line 97, in wrapper
return log_time("TIMED %s: " % msg, func, *args, **kwargs)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/log.py", line 79, in log_time
return func(*args, **kwargs)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 111, in block_meta
return meta_custom(args)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 1961, in meta_custom
handler(command, storage_config_dict)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 1248, in mount_handler
target=state.get('target'), fstab=state.get('fstab'))
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 1227, in mount_apply
util.write_file(fstab, fstab_line_for_data(fdata), omode="a")
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 1154, in fstab_line_for_data
spec = get_volume_spec(fdata.device)
File "/snap/subiquity/2393/lib/python3.6/site-packages/curtin/commands/block_meta.py", line 1082, in get_volume_spec
LOG.debug('info[DEVLINKS] = %s', info['DEVLINKS'])
KeyError: 'DEVLINKS'
'DEVLINKS'

which is strange -- maybe we are racing with udev and reading the partition's udev data while it is still being constructed somehow?

[Michael Hudson-Doyle (mwhudson)]

FWIW, this only seems to happen on hirsute.

[Michael Hudson-Doyle (mwhudson)]

This is still only happening on hirsute, albeit not very frequently. Always on real hw.