Comment 0 for bug 1832472

Revision history for this message
Ernst Ahlers (eactde) wrote :

Upon plugging in a USB adapter for NBase-T ethernet with an Realtek chip RTL8156 the kernel module cdc_ncm starts flooding /var/log/syslog with unneccessary status info.

The status lines either say that there's no ethernet link or show the interface as connected and state the link speed.

The relevant parts from /var/log/syslog after attaching the adapter without ethernet link read like this:

[...]
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.398485] usb 2-3: new SuperSpeed Gen 1 USB device number 5 using xhci_hcd
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.419463] usb 2-3: New USB device found, idVendor=0bda, idProduct=8156, bcdDevice=30.00
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.419469] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=6
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.419472] usb 2-3: Product: USB 10/100/1G/2.5G LAN
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.419475] usb 2-3: Manufacturer: Realtek
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.419477] usb 2-3: SerialNumber: 000000001
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.447769] cdc_ncm 2-3:2.0: MAC-Address: 00:13:3b:68:00:e9
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.447775] cdc_ncm 2-3:2.0: setting rx_max = 16384
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.447929] cdc_ncm 2-3:2.0: setting tx_max = 16384
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.448901] cdc_ncm 2-3:2.0 usb0: register 'cdc_ncm' at usb-0000:00:14.0-3, CDC NCM, 00:13:3b:68:00:e9
Jun 12 10:48:58 resdz-v13 NetworkManager[916]: <info> [1560329338.1872] manager: (usb0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/8)
Jun 12 10:48:58 resdz-v13 mtp-probe: checking bus 2, device 5: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-3"
Jun 12 10:48:58 resdz-v13 mtp-probe: bus: 2, device: 5 was not an MTP device
Jun 12 10:48:58 resdz-v13 systemd-udevd[4299]: Using default interface naming scheme 'v240'.
Jun 12 10:48:58 resdz-v13 systemd-udevd[4299]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.466476] cdc_ncm 2-3:2.0 enx00133b6800e9: renamed from usb0
Jun 12 10:48:58 resdz-v13 NetworkManager[916]: <info> [1560329338.2315] device (usb0): interface index 7 renamed iface from 'usb0' to 'enx00133b6800e9'
Jun 12 10:48:58 resdz-v13 systemd-udevd[4299]: Process 'vlan-network-interface UDEV' failed with exit code 1.
Jun 12 10:48:58 resdz-v13 NetworkManager[916]: <info> [1560329338.2431] device (enx00133b6800e9): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jun 12 10:48:58 resdz-v13 charon: 12[KNL] interface enx00133b6800e9 activated
Jun 12 10:48:58 resdz-v13 mtp-probe: checking bus 2, device 5: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-3"
Jun 12 10:48:58 resdz-v13 mtp-probe: bus: 2, device: 5 was not an MTP device
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.524052] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: disconnected
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.556109] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: disconnected
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.588054] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: disconnected
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.620383] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: disconnected
Jun 12 10:48:58 resdz-v13 kernel: [ 4240.652415] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: disconnected
[...]

Upon plugging in the ethernet cable I see
[...]
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.372273] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.404278] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
[...]
and Network Manager starts its work.

After the interface is up the flooding continues:
[...]
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.564056] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.596051] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 whoopsie[1530]: [10:49:01] The default IPv4 route is: /org/freedesktop/NetworkManager/ActiveConnection/4
Jun 12 10:49:01 resdz-v13 whoopsie[1530]: [10:49:01] Not a paid data plan: /org/freedesktop/NetworkManager/ActiveConnection/4
Jun 12 10:49:01 resdz-v13 whoopsie[1530]: [10:49:01] Found usable connection: /org/freedesktop/NetworkManager/ActiveConnection/4
Jun 12 10:49:01 resdz-v13 whoopsie[1530]: [10:49:01] online
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.628055] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.660060] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.692055] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.724054] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.756060] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.788053] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.820056] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 nm-dispatcher: req:2 'connectivity-change': start running ordered scripts...
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.852060] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.884064] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.916054] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.948055] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4243.980049] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
Jun 12 10:49:01 resdz-v13 kernel: [ 4244.012056] cdc_ncm 2-3:2.0 enx00133b6800e9: 1000 mbit/s downlink 1000 mbit/s uplink
Jun 12 10:49:01 resdz-v13 kernel: [ 4244.044053] cdc_ncm 2-3:2.0 enx00133b6800e9: network connection: connected
[...]

Expected behavior is showing the status lines only once after link status changes.

Kernel version is: 5.0.0-16-generic, OS: Kubuntu 19.04

Module info for cdc_ncm:

filename: /lib/modules/5.0.0-16-generic/kernel/drivers/net/usb/cdc_ncm.ko
license: Dual BSD/GPL
description: USB CDC NCM host driver
author: Hans Petter Selasky
srcversion: 9CC08FECEC9EC4AF1DBA207
alias: usb:v*p*d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v1546p1010d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v1519p0443d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v12D1p*d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v0930p*d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v413Cp*d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v413Cp81BCd*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v413Cp81BBd*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v1BC7p0036d*dc*dsc*dp*ic02isc0Dip00in*
alias: usb:v0BDBp*d*dc*dsc*dp*ic02isc0Dip00in*
depends: usbnet
retpoline: Y
intree: Y
name: cdc_ncm
vermagic: 5.0.0-16-generic SMP mod_unload
sig_id: PKCS#7
signer:
sig_key:
sig_hashalgo: md4
signature: 30:82:02:A5:06:09:2A:86:48:86:F7:0D:01:07:02:A0:82:02:96:30:
  82:02:92:02:01:01:31:0D:30:0B:06:09:60:86:48:01:65:03:04:02:
  03:30:0B:06:09:2A:86:48:86:F7:0D:01:07:01:31:82:02:6F:30:82:
  02:6B:02:01:01:30:46:30:2E:31:2C:30:2A:06:03:55:04:03:0C:23:
  42:75:69:6C:64:20:74:69:6D:65:20:61:75:74:6F:67:65:6E:65:72:
  61:74:65:64:20:6B:65:72:6E:65:6C:20:6B:65:79:02:14:6C:D7:37:
  E7:FA:BA:B9:CE:27:58:03:41:8C:55:76:76:23:DB:D6:27:30:0B:06:
  09:60:86:48:01:65:03:04:02:03:30:0D:06:09:2A:86:48:86:F7:0D:
  01:01:01:05:00:04:82:02:00:4D:14:7A:17:B0:09:83:C0:B7:24:84:
  3C:33:F0:C6:CA:70:11:69:13:F5:CC:57:11:1F:DA:AD:05:5A:A5:44:
  7F:A1:3C:9E:C1:A0:F5:0F:D1:1E:29:A9:A3:5C:61:24:0E:59:EA:83:
  C5:B2:1D:A5:2A:FD:22:E0:B3:22:0F:CC:62:D8:EB:DE:51:22:FB:D4:
  19:78:FA:36:65:BE:81:58:F2:D8:F8:4C:76:07:6A:4F:99:01:DC:DD:
  73:10:3C:D4:60:0A:CE:1E:E3:30:58:83:1F:1E:A1:ED:FF:01:D2:8F:
  74:01:74:3E:A7:56:DC:E8:3C:56:BA:DB:2A:EE:69:B0:16:88:46:41:
  2F:18:19:01:46:F0:A9:22:C9:48:C7:DA:DB:43:67:8A:97:9E:CB:AB:
  63:36:9E:31:61:34:5A:D3:77:76:01:19:A0:08:B3:C0:83:B0:53:FE:
  F3:92:7C:38:ED:41:A3:FB:84:29:86:04:B0:08:50:B1:C8:28:49:0D:
  9C:22:30:43:62:7F:BC:F2:88:08:09:50:0F:FE:09:AD:E4:22:FA:3B:
  B6:5F:95:7E:48:E0:0D:62:D2:F0:35:6C:21:BD:8B:54:9A:5A:32:CE:
  0D:71:F5:80:1B:52:3D:1F:45:4D:B2:10:6C:7B:49:E0:3D:D7:5A:22:
  94:A1:8B:99:8E:09:90:A7:41:43:FF:FC:DC:FE:21:D3:90:87:F9:34:
  C3:7C:2D:46:A9:3F:20:36:D3:99:1F:D5:34:5C:E6:F2:D5:B2:8D:09:
  15:ED:D1:E7:ED:DE:49:4C:B4:A9:FD:5E:35:47:86:75:A7:64:BB:1F:
  1C:85:58:8C:CF:C0:F7:52:DB:79:3F:76:22:6D:13:ED:5B:99:E1:5A:
  D2:01:1D:54:80:20:78:D8:33:B2:61:15:3A:44:BC:0D:C5:2C:7D:32:
  C6:26:CE:39:46:DA:64:9D:83:95:A3:01:82:D3:87:7F:48:DC:CA:40:
  F5:41:DC:BA:0F:96:C0:88:97:DD:5B:B4:80:0C:33:B9:CE:C0:97:D0:
  C4:94:B2:24:5E:D3:9C:0D:1E:99:27:13:51:9D:8A:1D:F7:04:9C:7C:
  11:DD:78:7A:29:DE:06:1A:61:C4:D7:4B:49:C7:4C:EE:0D:6A:15:47:
  AF:E7:31:4B:1E:C8:A0:B5:A2:24:EB:3F:37:2A:98:93:A0:D5:CE:A3:
  EE:78:38:FC:11:7D:4B:8C:2E:FF:9C:95:0E:CE:03:2A:73:A3:A1:FC:
  48:71:97:3B:C3:09:F3:D5:AD:99:AB:8E:51:18:DC:6B:1A:85:DA:E3:
  40:4B:D2:E8:E4:AF:82:1E:F5:19:D2:ED:35:D5:1A:83:D7:2D:20:4A:
  1C
parm: prefer_mbim:Prefer MBIM setting on dual NCM/MBIM functions (bool)