Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: STDERR=
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: [16879]: EC= 0 for "/usr/sbin/lvm fullreport --nosuffix --unbuffered --units b -a --configreport pv -o pv_name,pv_uuid,pv_fmt,pv_size,pv_free,pv_used,dev_size,pv_mda_size,pv_mda_free,pv_ba_start,pv_ba_size,pe_start,pv_pe_count,pv_pe_alloc_count,pv_attr,pv_tags,vg_name,vg_uuid,pv_missing --configreport vg -o vg_name,vg_uuid,vg_fmt,vg_size,vg_free,vg_sysid,vg_extent_size,vg_extent_count,vg_free_count,vg_profile,max_lv,max_pv,pv_count,lv_count,snap_count,vg_seqno,vg_mda_count,vg_mda_free,vg_mda_size,vg_mda_used_count,vg_attr,vg_tags --configreport lv -o lv_uuid,lv_name,lv_path,lv_size,vg_name,pool_lv_uuid,pool_lv,origin_uuid,origin,data_percent,lv_attr,lv_tags,vg_uuid,lv_active,data_lv,metadata_lv,lv_parent,lv_role,lv_layout,snap_percent,metadata_percent,copy_percent,sync_percent,lv_metadata_size,move_pv,move_pv_uuid,vdo_operating_mode,vdo_compression_state,vdo_index_state,vdo_used_size,vdo_saving_percent --configreport seg -o seg_pe_ranges,segtype,lv_uuid,vdo_compression,vdo_deduplication,vdo_use_metadata_hints,vdo_minimum_io_size,vdo_block_map_cache_size,vdo_block_map_era_length,vdo_use_sparse_index,vdo_index_memory_size,vdo_slab_size,vdo_ack_threads,vdo_bio_threads,vdo_bio_rotation,vdo_cpu_threads,vdo_hash_zone_threads,vdo_logical_threads,vdo_physical_threads,vdo_max_discard,vdo_write_policy,vdo_header_size --configreport pvseg -o pvseg_start,pvseg_size,segtype,pv_uuid,lv_uuid,pv_name --config global/notify_dbus=0 log/command_log_selection="log_context!=""
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: STARTED: Tue Jan 20 10:06:11 2026, ENDED: Tue Jan 20 10:06:11 2026, DURATION: 0.039975
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: STDOUT={'report': [{'vg': [{'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'vg_fmt': 'lvm2', 'vg_size': '4000795590656', 'vg_free': '3999184977920', 'vg_sysid': '', 'vg_extent_size': '4194304', 'vg_extent_count': '953864', 'vg_free_count': '953480', 'vg_profile': '', 'max_lv': '0', 'max_pv': '0', 'pv_count': '2', 'lv_count': '2', 'snap_count': '0', 'vg_seqno': '3', 'vg_mda_count': '2', 'vg_mda_free': '519168', 'vg_mda_size': '1044480', 'vg_mda_used_count': '2', 'vg_attr': 'wz--n-', 'vg_tags': ''}], 'pv': [{'pv_name': '/dev/sdb', 'pv_uuid': 'rD2ccW-ul8S-2My3-g1z5-1iJv-Mzl7-cGeZcP', 'pv_fmt': 'lvm2', 'pv_size': '2000397795328', 'pv_free': '1999324053504', 'pv_used': '1073741824', 'dev_size': '2000398934016', 'pv_mda_size': '1044480', 'pv_mda_free': '519168', 'pv_ba_start': '0', 'pv_ba_size': '0', 'pe_start': '1048576', 'pv_pe_count': '476932', 'pv_pe_alloc_count': '256', 'pv_attr': 'a--', 'pv_tags': '', 'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'pv_missing': '', 'pvseg_start': ['0', '256'], 'pvseg_size': ['256', '476676'], 'segtype': ['linear', 'free']}, {'pv_name': '/dev/sda', 'pv_uuid': 'mpjeRw-VELN-15i9-rosv-bDOQ-ub9f-dnOL35', 'pv_fmt': 'lvm2', 'pv_size': '2000397795328', 'pv_free': '1999860924416', 'pv_used': '536870912', 'dev_size': '2000398934016', 'pv_mda_size': '1044480', 'pv_mda_free': '519168', 'pv_ba_start': '0', 'pv_ba_size': '0', 'pe_start': '1048576', 'pv_pe_count': '476932', 'pv_pe_alloc_count': '128', 'pv_attr': 'a--', 'pv_tags': '', 'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'pv_missing': '', 'pvseg_start': ['0', '128'], 'pvseg_size': ['128', '476804'], 'segtype': ['linear', 'free']}], 'lv': [{'lv_uuid': 'uFDacO-uoxA-5bkd-uneV-WzNS-4CCH-nHljdl', 'lv_name': 'data_lv', 'lv_path': '/dev/test_vg_wc/data_lv', 'lv_size': '1073741824', 'vg_name': 'test_vg_wc', 'pool_lv_uuid': '', 'pool_lv': '', 'origin_uuid': '', 'origin': '', 'data_percent': '', 'lv_attr': '-wi-------', 'lv_tags': '', 'vg_uuid': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'lv_active': '', 'data_lv': '', 'metadata_lv': '', 'lv_parent': '', 'lv_role': 'public', 'lv_layout': 'linear', 'snap_percent': '', 'metadata_percent': '', 'copy_percent': '', 'sync_percent': '', 'lv_metadata_size': '', 'move_pv': '', 'move_pv_uuid': '', 'vdo_operating_mode': '', 'vdo_compression_state': '', 'vdo_index_state': '', 'vdo_used_size': '', 'vdo_saving_percent': '', 'seg_pe_ranges': ['/dev/sdb:0-255'], 'segtype': ['linear'], 'vdo_compression': '', 'vdo_deduplication': '', 'vdo_use_metadata_hints': '', 'vdo_minimum_io_size': '', 'vdo_block_map_cache_size': '', 'vdo_block_map_era_length': '', 'vdo_use_sparse_index': '', 'vdo_index_memory_size': '', 'vdo_slab_size': '', 'vdo_ack_threads': '', 'vdo_bio_threads': '', 'vdo_bio_rotation': '', 'vdo_cpu_threads': '', 'vdo_hash_zone_threads': '', 'vdo_logical_threads': '', 'vdo_physical_threads': '', 'vdo_max_discard': '', 'vdo_write_policy': '', 'vdo_header_size': ''}, {'lv_uuid': 'QY2NZp-FXTV-dGT5-oQn0-YGXW-i4f3-rN2vVQ', 'lv_name': 'cache_lv', 'lv_path': '/dev/test_vg_wc/cache_lv', 'lv_size': '536870912', 'vg_name': 'test_vg_wc', 'pool_lv_uuid': '', 'pool_lv': '', 'origin_uuid': '', 'origin': '', 'data_percent': '', 'lv_attr': '-wi-a-----', 'lv_tags': '', 'vg_uuid': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'lv_active': 'active', 'data_lv': '', 'metadata_lv': '', 'lv_parent': '', 'lv_role': 'public', 'lv_layout': 'linear', 'snap_percent': '', 'metadata_percent': '', 'copy_percent': '', 'sync_percent': '', 'lv_metadata_size': '', 'move_pv': '', 'move_pv_uuid': '', 'vdo_operating_mode': '', 'vdo_compression_state': '', 'vdo_index_state': '', 'vdo_used_size': '', 'vdo_saving_percent': '', 'seg_pe_ranges': ['/dev/sda:0-127'], 'segtype': ['linear'], 'vdo_compression': '', 'vdo_deduplication': '', 'vdo_use_metadata_hints': '', 'vdo_minimum_io_size': '', 'vdo_block_map_cache_size': '', 'vdo_block_map_era_length': '', 'vdo_use_sparse_index': '', 'vdo_index_memory_size': '', 'vdo_slab_size': '', 'vdo_ack_threads': '', 'vdo_bio_threads': '', 'vdo_bio_rotation': '', 'vdo_cpu_threads': '', 'vdo_hash_zone_threads': '', 'vdo_logical_threads': '', 'vdo_physical_threads': '', 'vdo_max_discard': '', 'vdo_write_policy': '', 'vdo_header_size': ''}], 'pvseg': [{'pvseg_start': '0', 'pvseg_size': '256', 'segtype': 'linear', 'pv_uuid': 'rD2ccW-ul8S-2My3-g1z5-1iJv-Mzl7-cGeZcP', 'lv_uuid': 'uFDacO-uoxA-5bkd-uneV-WzNS-4CCH-nHljdl', 'pv_name': '/dev/sdb'}, {'pvseg_start': '256', 'pvseg_size': '476676', 'segtype': 'free', 'pv_uuid': 'rD2ccW-ul8S-2My3-g1z5-1iJv-Mzl7-cGeZcP', 'lv_uuid': '', 'pv_name': '/dev/sdb'}, {'pvseg_start': '0', 'pvseg_size': '128', 'segtype': 'linear', 'pv_uuid': 'mpjeRw-VELN-15i9-rosv-bDOQ-ub9f-dnOL35', 'lv_uuid': 'QY2NZp-FXTV-dGT5-oQn0-YGXW-i4f3-rN2vVQ', 'pv_name': '/dev/sda'}, {'pvseg_start': '128', 'pvseg_size': '476804', 'segtype': 'free', 'pv_uuid': 'mpjeRw-VELN-15i9-rosv-bDOQ-ub9f-dnOL35', 'lv_uuid': '', 'pv_name': '/dev/sda'}], 'seg': [{'seg_pe_ranges': '/dev/sdb:0-255', 'segtype': 'linear', 'lv_uuid': 'uFDacO-uoxA-5bkd-uneV-WzNS-4CCH-nHljdl', 'vdo_compression': '', 'vdo_deduplication': '', 'vdo_use_metadata_hints': '', 'vdo_minimum_io_size': '', 'vdo_block_map_cache_size': '', 'vdo_block_map_era_length': '', 'vdo_use_sparse_index': '', 'vdo_index_memory_size': '', 'vdo_slab_size': '', 'vdo_ack_threads': '', 'vdo_bio_threads': '', 'vdo_bio_rotation': '', 'vdo_cpu_threads': '', 'vdo_hash_zone_threads': '', 'vdo_logical_threads': '', 'vdo_physical_threads': '', 'vdo_max_discard': '', 'vdo_write_policy': '', 'vdo_header_size': ''}, {'seg_pe_ranges': '/dev/sda:0-127', 'segtype': 'linear', 'lv_uuid': 'QY2NZp-FXTV-dGT5-oQn0-YGXW-i4f3-rN2vVQ', 'vdo_compression': '', 'vdo_deduplication': '', 'vdo_use_metadata_hints': '', 'vdo_minimum_io_size': '', 'vdo_block_map_cache_size': '', 'vdo_block_map_era_length': '', 'vdo_use_sparse_index': '', 'vdo_index_memory_size': '', 'vdo_slab_size': '', 'vdo_ack_threads': '', 'vdo_bio_threads': '', 'vdo_bio_rotation': '', 'vdo_cpu_threads': '', 'vdo_hash_zone_threads': '', 'vdo_logical_threads': '', 'vdo_physical_threads': '', 'vdo_max_discard': '', 'vdo_write_policy': '', 'vdo_header_size': ''}]}], 'log': [{'log_seq_num': '1', 'log_type': 'status', 'log_context': 'processing', 'log_object_type': 'pv', 'log_object_name': '/dev/sdb', 'log_object_id': 'rD2ccW-ul8S-2My3-g1z5-1iJv-Mzl7-cGeZcP', 'log_object_group': 'test_vg_wc', 'log_object_group_id': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'log_message': 'success', 'log_errno': '0', 'log_ret_code': '1'}, {'log_seq_num': '2', 'log_type': 'status', 'log_context': 'processing', 'log_object_type': 'pv', 'log_object_name': '/dev/sda', 'log_object_id': 'mpjeRw-VELN-15i9-rosv-bDOQ-ub9f-dnOL35', 'log_object_group': 'test_vg_wc', 'log_object_group_id': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'log_message': 'success', 'log_errno': '0', 'log_ret_code': '1'}, {'log_seq_num': '3', 'log_type': 'status', 'log_context': 'processing', 'log_object_type': 'lv', 'log_object_name': 'data_lv', 'log_object_id': 'uFDacO-uoxA-5bkd-uneV-WzNS-4CCH-nHljdl', 'log_object_group': 'test_vg_wc', 'log_object_group_id': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'log_message': 'success', 'log_errno': '0', 'log_ret_code': '1'}, {'log_seq_num': '4', 'log_type': 'status', 'log_context': 'processing', 'log_object_type': 'lv', 'log_object_name': 'cache_lv', 'log_object_id': 'QY2NZp-FXTV-dGT5-oQn0-YGXW-i4f3-rN2vVQ', 'log_object_group': 'test_vg_wc', 'log_object_group_id': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'log_message': 'success', 'log_errno': '0', 'log_ret_code': '1'}, {'log_seq_num': '5', 'log_type': 'status', 'log_context': 'processing', 'log_object_type': 'pv', 'log_object_name': '/dev/sdb', 'log_object_id': 'rD2ccW-ul8S-2My3-g1z5-1iJv-Mzl7-cGeZcP', 'log_object_group': 'test_vg_wc', 'log_object_group_id': 'HsgLWw-2NTq-es7X-JX3Y-dZhj-hHNE-TonMHB', 'log_message': 'success', 'log_errno': '0', 'log_ret_code': '1'}, {'log_seq_num': '6', 'log_type': 'status', 'log_context': 'processing', 'log_object_type':
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: STDERR=
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: [16879]: LVM dbus flight recorder END
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: [16879]: While processing <function Manager._pv_create at 0x7fdf6bb5cc20>: we encountered
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: Traceback (most recent call last):
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:  File "/usr/lib/python3.12/site-packages/lvmdbusd/request.py", line 72, in run_cmd
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:    result = self.method(*self.arguments)
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:  File "/usr/lib/python3.12/site-packages/lvmdbusd/manager.py", line 47, in _pv_create
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:    pv = cfg.om.get_object_path_by_uuid_lvm_id(device, device)
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:  File "/usr/lib/python3.12/site-packages/lvmdbusd/objectmanager.py", line 287, in get_object_path_by_uuid_lvm_id
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:    assert lvm_id
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]:           ^^^^^^
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: AssertionError
Jan 20 10:06:26 storageqe-69 lvmdbusd[16874]: [16879]: Error returned to client:
Jan 20 10:06:26 storageqe-69 root[86118]: [test_bd_lvm_parameter_validation.py:81:teardown_method] lsblk
Jan 20 10:06:26 storageqe-69 rsyslogd[3491]: message too long (12391) with configured size 8096, begin of message is: STDOUT={'report': [{'vg': [{'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7 [v8.2510.0-5.el10 try https://www.rsyslog.com/e/2445 ]
Jan 20 10:06:26 storageqe-69 rsyslogd[3491]: message too long (9628) with configured size 8096, begin of message is: STDOUT={'report': [{'vg': [{'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7 [v8.2510.0-5.el10 try https://www.rsyslog.com/e/2445 ]
Jan 20 10:06:26 storageqe-69 rsyslogd[3491]: message too long (9634) with configured size 8096, begin of message is: STDOUT={'report': [{'vg': [{'vg_name': 'test_vg_wc', 'vg_uuid': 'HsgLWw-2NTq-es7 [v8.2510.0-5.el10 try https://www.rsyslog.com/e/2445 ]
INFO: #### after available_disks ['/dev/sdb', '/dev/sda'] #### 
INFO: #### END test_lvm_pvcreate_invalid_device_params #### 
INFO: #### will start test_lvm_pvinfo_invalid_params #####
INFO: #### before available_disks ['/dev/sdb', '/dev/sda'] #### 
Jan 20 10:06:31 storageqe-69 root[86128]: [conftest.py:620:post_test_health_checks] lsblk 

tests/bd/test_bd_lvm_enhanced.py::TestSharedVGLocking::test_vg_locking_operations SKIPPED
tests/bd/test_bd_lvm_parameter_validation.py::TestBDLVMParameterValidation::test_lvm_pvcreate_invalid_device_params 
[INFO] Background services: CPU stress (PID: 4444, ~20% load)
[2026-01-20 10:06:21] [1;32m INFO: INFO: #### will start test_lvm_pvcreate_invalid_device_params ##### [0m
[2026-01-20 10:06:21] [1;32m INFO: INFO: #### before available_disks ['/dev/sdb', '/dev/sda'] ####  [0m
INFO: [2026-01-20 10:06:21] Running: 'lsblk'...
NAME   MAJ:MIN RM  SIZE RO TYPE MOUNTPOINTS
sda      8:0    0  1.8T  0 disk 
sdb      8:16   0  1.8T  0 disk 
sdc      8:32   0  1.8T  0 disk 
sdc1   8:33   0    1M  0 part 
sdc2   8:34   0    1G  0 part /boot
sdc3   8:35   0  7.8G  0 part [SWAP]
sdc4   8:36   0  1.8T  0 part /
sr0     11:0    1 1024M  0 rom

INFO: Starting test: test_lvm_pvcreate_invalid_device_params
[2026-01-20 10:06:26] [1;32m INFO: Testing lvm_pvcreate with invalid device parameters [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected empty string: Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected None: TypeError [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test\x00null': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test;rm -rf /tmp/test': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test`id`': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test$(id)': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test && echo pwned': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected '/dev/test|cat /etc/hostname': Error [0m
[2026-01-20 10:06:26] [1;32m INFO: Correctly rejected 'xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx': Error [0m
PASSEDINFO: [2026-01-20 10:06:26] Running: 'lsblk'...
NAME   MAJ:MIN RM  SIZE RO TYPE MOUNTPOINTS
sda      8:0    0  1.8T  0 disk 
sdb      8:16   0  1.8T  0 disk 
sdc      8:32   0  1.8T  0 disk 
sdc1   8:33   0    1M  0 part 
sdc2   8:34   0    1G  0 part /boot
sdc3   8:35   0  7.8G  0 part [SWAP]
sdc4   8:36   0  1.8T  0 part /
sr0     11:0    1 1024M  0 rom
[2026-01-20 10:06:26] [1;32m INFO: Cleaning up devices: [] [0m
[2026-01-20 10:06:26] [1;32m INFO: Reset LVM global config [0m
[2026-01-20 10:06:26] [1;32m INFO: Reset LVM global devices filter [0m
INFO: [2026-01-20 10:06:31] Running: 'lsblk'...
NAME   MAJ:MIN RM  SIZE RO TYPE MOUNTPOINTS
sda      8:0    0  1.8T  0 disk 
sdb      8:16   0  1.8T  0 disk 
sdc      8:32   0  1.8T  0 disk 
sdc1   8:33   0    1M  0 part 
sdc2   8:34   0    1G  0 part /boot
sdc3   8:35   0  7.8G  0 part [SWAP]
sdc4   8:36   0  1.8T  0 part /
sr0     11:0    1 1024M  0 rom
[2026-01-20 10:06:31] [1;32m INFO: INFO: #### after available_disks ['/dev/sdb', '/dev/sda'] ####  [0m
[2026-01-20 10:06:31] [1;32m INFO: INFO: #### END test_lvm_pvcreate_invalid_device_params ####  [0m