How to reduce LVM messages in /var/log/debug?

catkin · 02-21-2011, 02:03 AM

Hello

/var/log/debug gets lots of messages any time an LVM snapshot is setup (lvcreate --size ${size}k --snapshot --name "$LVM_snapshot_device" "$LVM_device"). Here is a recent sample.

Code:

Feb 19 22:40:10 CW8 lvm[2163]: Loading config file: /etc/lvm/lvm.conf
Feb 19 22:40:10 CW8 lvm[2163]: Opened /etc/lvm/lvm.conf RO
Feb 19 22:40:10 CW8 lvm[2163]: Closed /etc/lvm/lvm.conf
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/syslog to 1
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/level to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/verbose to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/indent to 1
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/abort_on_internal_errors to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/prefix to   
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/command_names to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/test to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting log/overwrite to 0
Feb 19 22:40:10 CW8 lvm[2163]: log/activation not found in config: defaulting to 0
Feb 19 22:40:10 CW8 lvm[2163]: Logging initialised at Sat Feb 19 22:40:10 2011
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/umask to 63
Feb 19 22:40:10 CW8 lvm[2163]: Setting devices/dir to /dev
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/proc to /proc
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/activation to 1
Feb 19 22:40:10 CW8 lvm[2163]: global/suffix not found in config: defaulting to 1
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/units to h
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/readahead to auto
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/udev_rules to 1
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/udev_sync to 1
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/missing_stripe_filler to error
Feb 19 22:40:10 CW8 lvm[2163]: Setting global/si_unit_consistency to 1
Feb 19 22:40:10 CW8 lvm[2163]: devices/preferred_names not found in config file: using built-in preferences
Feb 19 22:40:10 CW8 lvm[2163]: Matcher built with 3 dfa states
Feb 19 22:40:10 CW8 lvm[2163]: Setting devices/ignore_suspended_devices to 0
Feb 19 22:40:10 CW8 lvm[2163]: Setting devices/cache_dir to /etc/lvm/cache
Feb 19 22:40:10 CW8 lvm[2163]: Setting devices/write_cache_state to 1
Feb 19 22:40:10 CW8 lvm[2163]: Opened /etc/lvm/cache/.cache RO
Feb 19 22:40:10 CW8 lvm[2163]: /dev/CW8/home: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram11: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda11: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/mapper/CW8-var: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram10: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda10: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/mapper/CW8-home: Aliased to /dev/CW8/home in device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram12: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram6: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda6: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram13: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram14: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/CW8/var: Aliased to /dev/mapper/CW8-var in device cache (preferred name)
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram5: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda5: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sdb5: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/CW8/s_var: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram1: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda1: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sdb1: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/CW8/srv: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/mapper/CW8-opt: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram0: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/mapper/CW8-srv: Aliased to /dev/CW8/srv in device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/mapper/CW8-s_var: Aliased to /dev/CW8/s_var in device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram2: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda2: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/CW8/opt: Aliased to /dev/mapper/CW8-opt in device cache (preferred name)
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram8: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda8: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram9: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda9: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram15: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram3: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda3: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram4: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/ram7: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: /dev/sda7: Added to device cache
Feb 19 22:40:10 CW8 lvm[2163]: Loaded persistent filter cache from /etc/lvm/cache/.cache
Feb 19 22:40:10 CW8 lvm[2163]: Closed /etc/lvm/cache/.cache
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/reserved_stack to 256
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/reserved_memory to 8192
Feb 19 22:40:10 CW8 lvm[2163]: Setting activation/process_priority to -18
Feb 19 22:40:10 CW8 lvm[2163]: Initialised format: lvm1
Feb 19 22:40:10 CW8 lvm[2163]: Initialised format: pool
Feb 19 22:40:10 CW8 lvm[2163]: Initialised format: lvm2
Feb 19 22:40:10 CW8 lvm[2163]: global/format not found in config: defaulting to lvm2
Feb 19 22:40:10 CW8 lvm[2163]: lvmcache: initialised VG #orphans_lvm1
Feb 19 22:40:10 CW8 lvm[2163]: lvmcache: initialised VG #orphans_pool
Feb 19 22:40:10 CW8 lvm[2163]: lvmcache: initialised VG #orphans_lvm2
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: striped
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: zero
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: error
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: free
Feb 19 22:40:10 CW8 lvm[2163]: Setting dmeventd/snapshot_library to libdevmapper-event-lvm2snapshot.so
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: snapshot
Feb 19 22:40:10 CW8 lvm[2163]: Initialised segtype: mirror
Feb 19 22:40:10 CW8 lvm[2163]: Setting backup/retain_days to 30
Feb 19 22:40:10 CW8 lvm[2163]: Setting backup/retain_min to 10
Feb 19 22:40:10 CW8 lvm[2163]: Setting backup/archive_dir to /etc/lvm/archive
Feb 19 22:40:10 CW8 lvm[2163]: Setting backup/backup_dir to /etc/lvm/backup
Feb 19 22:40:10 CW8 lvm[2163]: Locking memory
Feb 19 22:40:10 CW8 lvm[2163]: memlock_count_daemon inc to 1
Feb 19 22:46:29 CW8 lvm[2163]: Unlocking memory
Feb 19 22:46:29 CW8 lvm[2163]: memlock_count_daemon dec to 0
Feb 19 22:46:29 CW8 lvm[2163]: Dumping persistent device cache to /etc/lvm/cache/.cache
Feb 19 22:46:29 CW8 lvm[2163]: Locking /etc/lvm/cache/.cache (F_WRLCK, 1)
Feb 19 22:46:29 CW8 lvm[2163]: Opened /etc/lvm/cache/.cache RO
Feb 19 22:46:29 CW8 lvm[2163]: Loaded persistent filter cache from /etc/lvm/cache/.cache
Feb 19 22:46:29 CW8 lvm[2163]: Unlocking fd 8
Feb 19 22:46:29 CW8 lvm[2163]: Closed /etc/lvm/cache/.cache
Feb 19 22:46:29 CW8 lvm[2163]: Wiping internal VG cache
Feb 19 22:53:05 CW8 lvm[2163]: Loading config file: /etc/lvm/lvm.conf
Feb 19 22:53:05 CW8 lvm[2163]: Opened /etc/lvm/lvm.conf RO
Feb 19 22:53:05 CW8 lvm[2163]: Closed /etc/lvm/lvm.conf
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/syslog to 1
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/level to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/verbose to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/indent to 1
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/abort_on_internal_errors to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/prefix to   
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/command_names to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/test to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting log/overwrite to 0
Feb 19 22:53:05 CW8 lvm[2163]: log/activation not found in config: defaulting to 0
Feb 19 22:53:05 CW8 lvm[2163]: Logging initialised at Sat Feb 19 22:53:05 2011
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/umask to 63
Feb 19 22:53:05 CW8 lvm[2163]: Setting devices/dir to /dev
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/proc to /proc
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/activation to 1
Feb 19 22:53:05 CW8 lvm[2163]: global/suffix not found in config: defaulting to 1
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/units to h
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/readahead to auto
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/udev_rules to 1
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/udev_sync to 1
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/missing_stripe_filler to error
Feb 19 22:53:05 CW8 lvm[2163]: Setting global/si_unit_consistency to 1
Feb 19 22:53:05 CW8 lvm[2163]: devices/preferred_names not found in config file: using built-in preferences
Feb 19 22:53:05 CW8 lvm[2163]: Matcher built with 3 dfa states
Feb 19 22:53:05 CW8 lvm[2163]: Setting devices/ignore_suspended_devices to 0
Feb 19 22:53:05 CW8 lvm[2163]: Setting devices/cache_dir to /etc/lvm/cache
Feb 19 22:53:05 CW8 lvm[2163]: Setting devices/write_cache_state to 1
Feb 19 22:53:05 CW8 lvm[2163]: Opened /etc/lvm/cache/.cache RO
Feb 19 22:53:05 CW8 lvm[2163]: /dev/CW8/home: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram11: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda11: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/mapper/CW8-var: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram10: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda10: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/mapper/CW8-home: Aliased to /dev/CW8/home in device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram12: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram6: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda6: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram13: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram14: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/CW8/var: Aliased to /dev/mapper/CW8-var in device cache (preferred name)
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram5: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda5: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sdb5: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/CW8/s_var: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram1: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda1: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sdb1: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/CW8/srv: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/mapper/CW8-opt: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram0: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/mapper/CW8-srv: Aliased to /dev/CW8/srv in device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/mapper/CW8-s_var: Aliased to /dev/CW8/s_var in device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram2: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda2: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/CW8/opt: Aliased to /dev/mapper/CW8-opt in device cache (preferred name)
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram8: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda8: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram9: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda9: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram15: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram3: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda3: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram4: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/ram7: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: /dev/sda7: Added to device cache
Feb 19 22:53:05 CW8 lvm[2163]: Loaded persistent filter cache from /etc/lvm/cache/.cache
Feb 19 22:53:05 CW8 lvm[2163]: Closed /etc/lvm/cache/.cache
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/reserved_stack to 256
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/reserved_memory to 8192
Feb 19 22:53:05 CW8 lvm[2163]: Setting activation/process_priority to -18
Feb 19 22:53:05 CW8 lvm[2163]: Initialised format: lvm1
Feb 19 22:53:05 CW8 lvm[2163]: Initialised format: pool
Feb 19 22:53:05 CW8 lvm[2163]: Initialised format: lvm2
Feb 19 22:53:05 CW8 lvm[2163]: global/format not found in config: defaulting to lvm2
Feb 19 22:53:05 CW8 lvm[2163]: lvmcache: initialised VG #orphans_lvm1
Feb 19 22:53:05 CW8 lvm[2163]: lvmcache: initialised VG #orphans_pool
Feb 19 22:53:05 CW8 lvm[2163]: lvmcache: initialised VG #orphans_lvm2
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: striped
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: zero
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: error
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: free
Feb 19 22:53:05 CW8 lvm[2163]: Setting dmeventd/snapshot_library to libdevmapper-event-lvm2snapshot.so
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: snapshot
Feb 19 22:53:05 CW8 lvm[2163]: Initialised segtype: mirror
Feb 19 22:53:05 CW8 lvm[2163]: Setting backup/retain_days to 30
Feb 19 22:53:05 CW8 lvm[2163]: Setting backup/retain_min to 10
Feb 19 22:53:05 CW8 lvm[2163]: Setting backup/archive_dir to /etc/lvm/archive
Feb 19 22:53:05 CW8 lvm[2163]: Setting backup/backup_dir to /etc/lvm/backup
Feb 19 22:53:05 CW8 lvm[2163]: Locking memory
Feb 19 22:53:05 CW8 lvm[2163]: memlock_count_daemon inc to 1
Feb 19 23:08:32 CW8 lvm[2163]: Unlocking memory
Feb 19 23:08:32 CW8 lvm[2163]: memlock_count_daemon dec to 0
Feb 19 23:08:32 CW8 lvm[2163]: Dumping persistent device cache to /etc/lvm/cache/.cache
Feb 19 23:08:32 CW8 lvm[2163]: Locking /etc/lvm/cache/.cache (F_WRLCK, 1)
Feb 19 23:08:32 CW8 lvm[2163]: Opened /etc/lvm/cache/.cache RO
Feb 19 23:08:32 CW8 lvm[2163]: Loaded persistent filter cache from /etc/lvm/cache/.cache
Feb 19 23:08:32 CW8 lvm[2163]: Unlocking fd 8
Feb 19 23:08:32 CW8 lvm[2163]: Closed /etc/lvm/cache/.cache
Feb 19 23:08:32 CW8 lvm[2163]: Wiping internal VG cache

I don't know when these started appearing. /etc/lvm/lvm.conf is as-installed so should not be the cause. In the kernel configuration "Device Drivers" -> "Multiple devices driver support (RAID and LVM)", "Device mapper debugging support" is cleared so should not be the cause. Which leaves me with no idea what may be causing it

The distro is Slackware64 13.1.

Best

Charles

cin_ · 02-21-2011, 02:30 AM

From the lvm() man page...

Quote:

# man lvm
...
Options
The following options are available for many of the commands. They are implemented generically and documented here rather than repeated on individual manual pages.

-h | --help -- Display the help text.
--version -- Display version information.
-v | --verbose -- Set verbose level.
Repeat from 1 to 3 times to increase the detail of messages sent to stdout and stderr. Overrides config file setting.
-d | --debug -- Set debug level.
Repeat from 1 to 6 times to increase the detail of messages sent to the log file and/or syslog (if configured). Overrides config file setting.
--quiet -- Suppress output and log messages.
Overrides -d and -v.
...
#

I believe lvcreate() is one of these `many of the commands`.

catkin · 02-21-2011, 03:00 AM

Thanks cin_

"gramm'err"

I am confident that my script is not using lvcreate's -d or --debug options and am reluctant to use --quiet in case it suppresses error messages.

cin_ · 02-22-2011, 03:57 AM

I saw from your post that your command was --debug'less, but I was suggesting you try adding it, at variable levels, 1-6, to see what kind of message volume you receive from each.

I'm glad the `gramm'err` pun'age garnered enough regard as to require the teeth to come out. I am always reluctant to use puns. I feel they are jokes that create a certain level of exclusion. If you are unfamiliar with a language then the joke is lost on you. But this one seemed to be tech'based enough to allow to overcome the pun's inherent exclusivity.

catkin · 02-22-2011, 04:20 AM

Quote:

Originally Posted by cin_

I saw from your post that your command was --debug'less, but I was suggesting you try adding it, at variable levels, 1-6, to see what kind of message volume you receive from each.

OK. Will try it.

catkin · 07-27-2011, 01:46 PM

Same output with three debugs ...