collectl doesn't return correct values for network on ens3 interface

Bug #1709589 reported by Sebastian
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
collectl (Ubuntu)
New
Undecided
Unassigned

Bug Description

Below you can find the output of the collectl command when requesting information about the ens3 interface

root@ubuntu-1604:~# /usr/bin/collectl -sN
waiting for 1 second sample...
Bogus data record skipped for NET:ens3: data on 20170809 at 08:45:13

# NETWORK STATISTICS (/sec)
#Num Name KBIn PktIn SizeIn MultI CmpI ErrsI KBOut PktOut SizeO CmpO ErrsO
   0 lo 0 2 84 0 0 0 0 2 84 0 0
   1 ens3 0 0 0 0 0 0 0 0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:45:14
   0 lo 0 2 84 0 0 0 0 2 84 0 0
   1 ens3 0 0 0 0 0 0 0 0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:45:15
   0 lo 0 2 84 0 0 0 0 2 84 0 0
   1 ens3 0 0 0 0 0 0 0 0 0 0 0

Another example:

root@ubuntu-1604:~# /usr/bin/collectl -sn
waiting for 1 second sample...
Bogus data record skipped for NET:ens3: data on 20170809 at 08:46:42
#<----------Network---------->
# KBIn PktIn KBOut PktOut
      0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:46:43
      0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:46:44
      0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:46:45
      0 0 0 0
Bogus data record skipped for NET:ens3: data on 20170809 at 08:46:46
      0 0 0 0

I debugged a bit the problem and this is what I found:
problem seems to be somewhere here:
/usr/share/collectl/formatit.ph

~approx line 4100 on condition

    if ($DefNetSpeed>0 && $intFirstSeen && ($netRxKB[$netIndex]>$NetMaxTraffic[$netIndex] || netTxKB[$netIndex]>$NetMaxTraffic[$netIndex]))

The $NetMaxTraffic[$netIndex] seems to be -250 in my case because the value of $speed is -1.

The speed value seems to be taken from here in my case:
cat /sys/devices/pci0000:00/0000:00:03.0/virtio0/net/ens3/speed
-1

For lo interface I recive the following:
cat /sys/devices/virtual/net/lo/speed
cat: lo/speed: Invalid argument

The same 'Invalid argument' seems to be generated for ubuntu 14.04 instances.

Here is more info when collectl was started with the debug flag:
root@ubuntu-1604:~# /usr/bin/collectl -sn --debug 16989
Config File Search Path: /usr/bin/collectl.conf;/etc/collectl.conf
Reading Config File: /etc/collectl.conf
09:02:24 Couldn't find 'resize' so assuming terminal height of 24
BinDir: /usr/bin ReqDir: /usr/share/collectl
Set Output -- Subsys: n Verbose: 0 SameCols: 1
DskFilt - Ignore: Keep:
NetFilt - Ignore: Keep:
RawDsk - Ignore: Keep:
RawNet - Ignore: Keep:
RawFlag: 0 PlotFlag: 0 Repeat: 22 Log2Flag: 0 Export:
From: 0 000000 Thru: 0 235959
09:02:25 V4.0.4-1 Beginning execution on prod-idx-solr-01...
initRecord() - Subsys: n
09:02:25 initDisk initialized 2 disks
set netSpeeds{ens3}=>-1<
set netSpeeds{lo}=>??<
SetFlags: n
RecFlags: 1 0
initFormat()
initLustre() -- Type: o From: 0 Number: 0
initLustre() -- Type: m From: 0 Number:
initLustre() -- Type: c From: 0 Number: 0
initLustre() -- Type: c2 From: 0 Number: 0
waiting for 1 second sample...
Lustre Check Intervals: 1
>>> 1502269346.002 <<<
Net lo: 175833908 2093286 0 0 0 0 0 0 175833908 2093286 0 0 0 0 0 0
Net ens3: 71737046351 7211733 0 5 0 0 0 0 2086213559 6001251 0 0 0 0 0 0
>>> 1502269347.001 <<<
Net lo: 175834076 2093288 0 0 0 0 0 0 175834076 2093288 0 0 0 0 0 0
Net ens3: 71737049651 7211763 0 5 0 0 0 0 2086223834 6001270 0 0 0 0 0 0
09:02:27 Bogus data record skipped for NET:ens3: data on 20170809 at 09:02:26
09:02:27 Network speed threshhold: -250 Bogus Value(s) - TX: 10KB RX: 3KB
#<----------Network---------->
# KBIn PktIn KBOut PktOut
      0 0 0 0
>>> 1502269348.001 <<<
Net lo: 175834244 2093290 0 0 0 0 0 0 175834244 2093290 0 0 0 0 0 0
Net ens3: 71737052969 7211801 0 5 0 0 0 0 2086234953 6001300 0 0 0 0 0 0
09:02:28 Bogus data record skipped for NET:ens3: data on 20170809 at 09:02:27
09:02:28 Network speed threshhold: -250 Bogus Value(s) - TX: 10KB RX: 3KB
      0 0 0 0
>>> 1502269349.001 <<<
Net lo: 175834412 2093292 0 0 0 0 0 0 175834412 2093292 0 0 0 0 0 0
Net ens3: 71737055047 7211823 0 5 0 0 0 0 2086243613 6001319 0 0 0 0 0 0
09:02:29 Bogus data record skipped for NET:ens3: data on 20170809 at 09:02:28
09:02:29 Network speed threshhold: -250 Bogus Value(s) - TX: 8KB RX: 2KB
      0 0 0 0
>>> 1502269350.001 <<<
Net lo: 175834580 2093294 0 0 0 0 0 0 175834580 2093294 0 0 0 0 0 0
Net ens3: 71737057941 7211855 0 5 0 0 0 0 2086253980 6001344 0 0 0 0 0 0
09:02:30 Bogus data record skipped for NET:ens3: data on 20170809 at 09:02:29
09:02:30 Network speed threshhold: -250 Bogus Value(s) - TX: 10KB RX: 2KB
      0 0 0 0
Ouch!
>>> 1502269350.559 <<<
09:02:30 Terminating...

Info about the system:

== ApportVersion =================================
2.20.1-0ubuntu2.10

== Architecture =================================
amd64

== Date =================================
Wed Aug 9 09:06:59 2017

== Dependencies =================================
gcc-6-base 6.0.1-0ubuntu1
libc6 2.23-0ubuntu9
libgcc1 1:6.0.1-0ubuntu1
libkmod2 22-1ubuntu5
libpci3 1:3.3.1-1.1ubuntu1.1
libudev1 229-4ubuntu19
pciutils 1:3.3.1-1.1ubuntu1.1
zlib1g 1:1.2.8.dfsg-2ubuntu4.1

== DistroRelease =================================
Ubuntu 16.04

== Ec2AMI =================================
ami-0000000a

== Ec2AMIManifest =================================
FIXME

== Ec2AvailabilityZone =================================
node-1

== Ec2InstanceType =================================
cloud-4c.24gb.10gb

== Ec2Kernel =================================
unavailable

== Ec2Ramdisk =================================
unavailable

== JournalErrors =================================
-- Logs begin at Mon 2017-08-07 10:48:34 UTC, end at Wed 2017-08-09 09:06:56 UTC. --
Aug 07 10:48:34 hostname kernel: ACPI: RSDP 0x00000000000F6590 000014 (v00 BOCHS )
Aug 07 10:48:34 hostname kernel: ACPI: RSDT 0x00000000BFFE1499 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
Aug 07 10:48:34 hostname kernel: ACPI: FACP 0x00000000BFFE0914 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
Aug 07 10:48:34 hostname kernel: ACPI: DSDT 0x00000000BFFDFD40 000BD4 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
Aug 07 10:48:34 hostname kernel: ACPI: FACS 0x00000000BFFDFD00 000040
Aug 07 10:48:34 hostname kernel: ACPI: SSDT 0x00000000BFFE0988 000A81 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
Aug 07 10:48:34 hostname kernel: ACPI: APIC 0x00000000BFFE1409 000090 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
Aug 07 10:48:34 hostname kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded
Aug 07 10:48:34 hostname kernel: #2
Aug 07 10:48:34 hostname kernel: #3
Aug 07 10:48:34 hostname kernel: PCCT header not found.
Aug 07 10:48:34 hostname kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
Aug 07 10:48:34 hostname kernel: ACPI: Enabled 16 GPEs in block 00 to 0F
Aug 07 10:48:34 hostname kernel: ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
Aug 07 10:48:34 hostname kernel: ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
Aug 07 10:48:34 hostname kernel: ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
Aug 07 10:48:34 hostname kernel: ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
Aug 07 10:48:34 hostname systemd-sysv-generator[601]: stat() failed on /etc/init.d/solr, ignoring: No such file or directory
Aug 07 10:48:35 hostname systemd-tmpfiles[909]: [/usr/lib/tmpfiles.d/var.conf:14] Duplicate line for path "/var/log", ignoring.
Aug 07 10:48:37 hostname kernel: cgroup: new mount options do not match the existing superblock, will be ignored
Aug 07 10:48:37 hostname iscsid[1323]: iSCSI logger with pid=1325 started!
Aug 07 10:48:37 hostname iscsid[1325]: iSCSI daemon with pid=1326 started!
Aug 07 11:03:41 hostname systemd-tmpfiles[2838]: [/usr/lib/tmpfiles.d/var.conf:14] Duplicate line for path "/var/log", ignoring.
Aug 08 11:04:23 hostname systemd-tmpfiles[32491]: [/usr/lib/tmpfiles.d/var.conf:14] Duplicate line for path "/var/log", ignoring.
Aug 09 07:38:54 hostname iscsid[1325]: iscsid shutting down.
Aug 09 07:38:54 hostname iscsid[24414]: iSCSI logger with pid=24416 started!
Aug 09 07:38:55 hostname iscsid[24416]: iSCSI daemon with pid=24417 started!
Aug 09 07:38:55 hostname kernel: audit_printk_skb: 12 callbacks suppressed

== Package =================================
collectl 4.0.4-1 [modified: usr/bin/collectl usr/share/collectl/formatit.ph]

== PackageArchitecture =================================
all

== ProblemType =================================
Bug

== ProcCpuinfoMinimal =================================
processor : 3
vendor_id : GenuineIntel
cpu family : 6
model : 42
model name : Intel Xeon E312xx (Sandy Bridge)
stepping : 1
microcode : 0x1
cpu MHz : 2599.998
cache size : 4096 KB
physical id : 3
siblings : 1
core id : 0
cpu cores : 1
apicid : 3
initial apicid : 3
fpu : yes
fpu_exception : yes
cpuid level : 13
wp : yes
flags : fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx rdtscp lm constant_tsc rep_good nopl eagerfpu pni pclmulqdq ssse3 cx16 sse4_1 sse4_2 x2apic popcnt tsc_deadline_timer aes xsave avx hypervisor lahf_lm xsaveopt arat
bugs :
bogomips : 5199.99
clflush size : 64
cache_alignment : 64
address sizes : 40 bits physical, 48 bits virtual
power management:

== ProcEnviron =================================
LC_TIME=en_US.UTF-8
LC_MONETARY=en_US.UTF-8
TERM=xterm-256color
PATH=(custom, no user)
LC_ADDRESS=en_US.UTF-8
XDG_RUNTIME_DIR=<set>
LC_TELEPHONE=en_US.UTF-8
LANG=en_US.UTF-8
SHELL=/bin/bash
LC_NAME=en_US.UTF-8
LC_MEASUREMENT=en_US.UTF-8
LC_IDENTIFICATION=en_US.UTF-8
LC_NUMERIC=en_US.UTF-8
LC_PAPER=en_US.UTF-8

== ProcVersionSignature =================================
Ubuntu 4.4.0-89.112-generic 4.4.76

== SourcePackage =================================
collectl

== Tags =================================
 xenial ec2-images

== Uname =================================
Linux 4.4.0-89-generic x86_64

== UpgradeStatus =================================
No upgrade log present (probably fresh install)

Do you think that it is possible to add another way to make the collectl script functional even if this type of negative values are found in the /sys/class/net/*/speed path?

tags: added: collectl ens3 speed virtio xenial
description: updated
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.