Skip to content

Node Exporter 1.5.0 causing "Error Information Log Entries" increase in NVMe SMART data #2643

Description

@got3nks
node_exporter, version 1.5.0 (branch: HEAD, revision: 1b48970ffcf5630534fb00bb0687d73c66d1c959)
  build user:       root@6e7732a7b81b
  build date:       20221129-18:59:09
  go version:       go1.19.3
  platform:         linux/amd64

Each iteration of Node Exporter is causing the Error Information Log Entries to increase by two in the NVMe's SMART data.

# smartctl -a /dev/nvme0n1
Critical Warning:                   0x00
Temperature:                        53 Celsius
Available Spare:                    100%
Available Spare Threshold:          10%
...
Power On Hours:                     4,252
Unsafe Shutdowns:                   9
Media and Data Integrity Errors:    0
Error Information Log Entries:      1,548,554
Warning  Comp. Temperature Time:    0
Critical Comp. Temperature Time:    0
...

I tried to experiment with the parameters a bit --collector.diskstats.device-exclude=nvme0 / --no-collector.nvme but I'm still noticing an increase of 2 at every iteration. As a confirmation, after stopping the Node Exporter process the Error Information Log Entries value stops growing.

# /usr/local/bin/node_exporter --log.level=debug
ts=2023-03-26T01:00:47.757Z caller=node_exporter.go:180 level=info msg="Starting node_exporter" version="(version=1.5.0, branch=HEAD, revision=1b48970ffcf5630534fb00bb0687d73c66d1c959)"
ts=2023-03-26T01:00:47.757Z caller=node_exporter.go:181 level=info msg="Build context" build_context="(go=go1.19.3, user=root@6e7732a7b81b, date=20221129-18:59:09)"
ts=2023-03-26T01:00:47.757Z caller=node_exporter.go:183 level=warn msg="Node Exporter is running as root user. This exporter is designed to run as unprivileged user, root is not required."
ts=2023-03-26T01:00:47.757Z caller=node_exporter.go:186 level=debug msg="Go MAXPROCS" procs=1
ts=2023-03-26T01:00:47.757Z caller=diskstats_common.go:111 level=info collector=diskstats msg="Parsed flag --collector.diskstats.device-exclude" flag=^(ram|loop|fd|(h|s|v|xv)d[a-z]|nvme\d+n\d+p)\d+$
ts=2023-03-26T01:00:47.757Z caller=filesystem_common.go:111 level=info collector=filesystem msg="Parsed flag --collector.filesystem.mount-points-exclude" flag=^/(dev|proc|run/credentials/.+|sys|var/lib/docker/.+|var/lib/containers/storage/.+)($|/)
ts=2023-03-26T01:00:47.757Z caller=filesystem_common.go:113 level=info collector=filesystem msg="Parsed flag --collector.filesystem.fs-types-exclude" flag=^(autofs|binfmt_misc|bpf|cgroup2?|configfs|debugfs|devpts|devtmpfs|fusectl|hugetlbfs|iso9660|mqueue|nsfs|overlay|proc|procfs|pstore|rpc_pipefs|securityfs|selinuxfs|squashfs|sysfs|tracefs)$
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:110 level=info msg="Enabled collectors"
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=arp
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=bcache
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=bonding
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=btrfs
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=conntrack
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=cpu
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=cpufreq
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=diskstats
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=dmi
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=edac
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=entropy
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=fibrechannel
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=filefd
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=filesystem
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=hwmon
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=infiniband
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=ipvs
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=loadavg
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=mdadm
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=meminfo
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=netclass
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=netdev
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=netstat
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=nfs
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=nfsd
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=nvme
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=os
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=powersupplyclass
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=pressure
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=rapl
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=schedstat
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=selinux
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=sockstat
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=softnet
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=stat
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=tapestats
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=textfile
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=thermal_zone
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=time
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=timex
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=udp_queues
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=uname
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=vmstat
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=xfs
ts=2023-03-26T01:00:47.758Z caller=node_exporter.go:117 level=info collector=zfs
ts=2023-03-26T01:00:47.758Z caller=tls_config.go:232 level=info msg="Listening on" address=[::]:9100
ts=2023-03-26T01:00:47.758Z caller=tls_config.go:235 level=info msg="TLS is disabled." http2=false address=[::]:9100
ts=2023-03-26T01:00:49.158Z caller=node_exporter.go:78 level=debug msg="collect query:" filters="unsupported value type"
ts=2023-03-26T01:00:49.159Z caller=collector.go:173 level=debug msg="collector succeeded" name=softnet duration_seconds=7.3325e-05
ts=2023-03-26T01:00:49.159Z caller=infiniband_linux.go:119 level=debug collector=infiniband msg="infiniband statistics not found, skipping"
ts=2023-03-26T01:00:49.159Z caller=collector.go:167 level=debug msg="collector returned no data" name=infiniband duration_seconds=4.0584e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.159Z caller=collector.go:173 level=debug msg="collector succeeded" name=stat duration_seconds=0.00023361
ts=2023-03-26T01:00:49.159Z caller=collector.go:173 level=debug msg="collector succeeded" name=uname duration_seconds=8.064e-06
ts=2023-03-26T01:00:49.159Z caller=collector.go:173 level=debug msg="collector succeeded" name=dmi duration_seconds=1.3722e-05
ts=2023-03-26T01:00:49.159Z caller=collector.go:173 level=debug msg="collector succeeded" name=schedstat duration_seconds=9.9374e-05
ts=2023-03-26T01:00:49.159Z caller=bonding_linux.go:64 level=debug collector=bonding msg="Not collecting bonding, file does not exist" file=/sys/class/net
ts=2023-03-26T01:00:49.159Z caller=collector.go:167 level=debug msg="collector returned no data" name=bonding duration_seconds=4.3633e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.159Z caller=nfs_linux.go:103 level=debug collector=nfs msg="Not collecting NFS metrics" err="open /proc/net/rpc/nfs: no such file or directory"
ts=2023-03-26T01:00:49.159Z caller=collector.go:167 level=debug msg="collector returned no data" name=nfs duration_seconds=3.6933e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.160Z caller=collector.go:173 level=debug msg="collector succeeded" name=cpu duration_seconds=0.000531816
ts=2023-03-26T01:00:49.160Z caller=collector.go:173 level=debug msg="collector succeeded" name=rapl duration_seconds=0.000214019
ts=2023-03-26T01:00:49.160Z caller=collector.go:173 level=debug msg="collector succeeded" name=edac duration_seconds=1.8176e-05
ts=2023-03-26T01:00:49.160Z caller=nfsd_linux.go:69 level=debug collector=nfsd msg="Not collecting NFSd metrics" err="open /proc/net/rpc/nfsd: no such file or directory"
ts=2023-03-26T01:00:49.160Z caller=collector.go:167 level=debug msg="collector returned no data" name=nfsd duration_seconds=2.1169e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.160Z caller=collector.go:173 level=debug msg="collector succeeded" name=vmstat duration_seconds=0.000133747
ts=2023-03-26T01:00:49.160Z caller=zfs_linux.go:53 level=debug collector=zfs msg="Cannot open file for reading" path=/proc/spl/kstat/zfs
ts=2023-03-26T01:00:49.160Z caller=zfs.go:73 level=debug collector=zfs err="ZFS / ZFS statistics are not available"
ts=2023-03-26T01:00:49.160Z caller=collector.go:167 level=debug msg="collector returned no data" name=zfs duration_seconds=2.2639e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.160Z caller=tapestats_linux.go:129 level=debug collector=tapestats msg="scsi_tape stats not found, skipping"
ts=2023-03-26T01:00:49.160Z caller=collector.go:167 level=debug msg="collector returned no data" name=tapestats duration_seconds=1.2659e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=nvme duration_seconds=5.7868e-05
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=mdadm duration_seconds=2.0072e-05
ts=2023-03-26T01:00:49.161Z caller=loadavg.go:55 level=debug collector=loadavg msg="return load" index=0 load=2.06
ts=2023-03-26T01:00:49.161Z caller=loadavg.go:55 level=debug collector=loadavg msg="return load" index=1 load=2.11
ts=2023-03-26T01:00:49.161Z caller=loadavg.go:55 level=debug collector=loadavg msg="return load" index=2 load=2.2
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=loadavg duration_seconds=3.9813e-05
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/proc
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/dev
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/dev/pts
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/kernel/security
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/dev/shm
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/fs/cgroup
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/fs/pstore
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/firmware/efi/efivars
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/fs/bpf
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/proc/sys/fs/binfmt_misc
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/dev/hugepages
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/dev/mqueue
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/kernel/debug
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/kernel/tracing
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/fs/fuse/connections
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/sys/kernel/config
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/run/credentials/systemd-sysusers.service
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=squashfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/proc/sys/fs/binfmt_misc
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=nsfs
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:55 level=debug collector=filesystem msg="Ignoring mount point" mountpoint=/var/lib/docker/overlay2/2af3311293529ff4a255aebc19bae9c373a3869d422d2913826d6951229f65c9/merged
ts=2023-03-26T01:00:49.161Z caller=filesystem_linux.go:59 level=debug collector=filesystem msg="Ignoring fs" type=nsfs
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=filesystem duration_seconds=0.000412723
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=powersupplyclass duration_seconds=0.000263269
ts=2023-03-26T01:00:49.161Z caller=collector.go:173 level=debug msg="collector succeeded" name=btrfs duration_seconds=9.1371e-05
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=udp_queues duration_seconds=0.000128837
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=sockstat duration_seconds=5.6732e-05
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=selinux duration_seconds=1.8721e-05
ts=2023-03-26T01:00:49.162Z caller=meminfo.go:54 level=debug collector=meminfo msg="Set node_mem" memInfo="unsupported value type"
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=meminfo duration_seconds=0.000133845
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=xfs duration_seconds=1.0969e-05
ts=2023-03-26T01:00:49.162Z caller=pressure_linux.go:92 level=debug collector=pressure msg="collecting statistics for resource" resource=cpu
ts=2023-03-26T01:00:49.162Z caller=pressure_linux.go:92 level=debug collector=pressure msg="collecting statistics for resource" resource=io
ts=2023-03-26T01:00:49.162Z caller=pressure_linux.go:92 level=debug collector=pressure msg="collecting statistics for resource" resource=memory
ts=2023-03-26T01:00:49.162Z caller=collector.go:173 level=debug msg="collector succeeded" name=pressure duration_seconds=7.917e-05
ts=2023-03-26T01:00:49.163Z caller=collector.go:173 level=debug msg="collector succeeded" name=netstat duration_seconds=0.000467337
ts=2023-03-26T01:00:49.163Z caller=fibrechannel_linux.go:113 level=debug collector=fibrechannel msg="fibrechannel statistics not found, skipping"
ts=2023-03-26T01:00:49.163Z caller=collector.go:167 level=debug msg="collector returned no data" name=fibrechannel duration_seconds=1.2075e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.164Z caller=conntrack_linux.go:157 level=debug collector=conntrack msg="conntrack probably not loaded"
ts=2023-03-26T01:00:49.164Z caller=collector.go:167 level=debug msg="collector returned no data" name=conntrack duration_seconds=3.4392e-05 err="collector returned no data"
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=timex duration_seconds=8.236e-06
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=arp duration_seconds=2.8395e-05
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=filefd duration_seconds=1.5349e-05
ts=2023-03-26T01:00:49.164Z caller=os_release.go:146 level=debug collector=os msg="file modification time has changed" file=//etc/os-release old_value=0001-01-01T00:00:00Z new_value=2022-07-28T17:29:51+02:00
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=os duration_seconds=5.743e-05
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=diskstats duration_seconds=0.000164119
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=textfile duration_seconds=4.504e-06
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=bcache duration_seconds=6.019e-06
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=entropy duration_seconds=2.5907e-05
ts=2023-03-26T01:00:49.164Z caller=time.go:73 level=debug collector=time msg="Return time" now=1.6797924491645694e+09
ts=2023-03-26T01:00:49.164Z caller=time.go:75 level=debug collector=time msg="Zone offset" offset=7200 time_zone=CEST
ts=2023-03-26T01:00:49.164Z caller=time_linux.go:38 level=debug collector=time msg="in Update" clocksources="[{0 [tsc hpet acpi_pm] tsc}]"
ts=2023-03-26T01:00:49.164Z caller=collector.go:173 level=debug msg="collector succeeded" name=time duration_seconds=6.055e-05
ts=2023-03-26T01:00:49.166Z caller=ipvs_linux.go:146 level=debug collector=ipvs msg="ipvs collector metrics are not available for this system"
ts=2023-03-26T01:00:49.166Z caller=collector.go:167 level=debug msg="collector returned no data" name=ipvs duration_seconds=0.003813326 err="collector returned no data"
ts=2023-03-26T01:00:49.167Z caller=collector.go:173 level=debug msg="collector succeeded" name=netdev duration_seconds=0.004513598
ts=2023-03-26T01:00:49.167Z caller=collector.go:173 level=debug msg="collector succeeded" name=netclass duration_seconds=0.004997598
ts=2023-03-26T01:00:49.168Z caller=collector.go:173 level=debug msg="collector succeeded" name=thermal_zone duration_seconds=0.004920199
ts=2023-03-26T01:00:49.171Z caller=collector.go:173 level=debug msg="collector succeeded" name=hwmon duration_seconds=0.009453131
ts=2023-03-26T01:00:49.187Z caller=collector.go:173 level=debug msg="collector succeeded" name=cpufreq duration_seconds=0.02797438

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions