How to stop hub_port_status failed messages flooding syslog?

zimon · 04-14-2012, 11:38 AM

I have this troublesome active 7-port USB-hub, which sometimes goes defunctioning when some usb-device is uncleanly detached.

When it does, I switch it off and connect again to an another port, it usually works and all the usb-devices work again.

But for example now currently, I then connected the hub to the different usb port (2), and the port it were (1) keeps outputting error messages although nothing is connected to that port anymore:

How to stop it whining without rebooting the machine?

So I guess, how to reset the whole usb-system on the machine without rebooting?

System: Fedora 16
Kernel: 3.2.10-3.fc16.x86_64

Quote:

Apr 14 16:56:59 localhost kernel: [1538461.100071] generic-usb 0003:046D:C521.0004: can't reset device, 0000:00:1a.0-1.5.1/input0, status -71
Apr 14 16:56:59 localhost kernel: [1538461.156414] usb 1-1.5: clear tt 6 (90c2) error -71
Apr 14 16:56:59 localhost kernel: [1538461.189894] usb 1-1.5: clear tt 1 (0090) error -71
Apr 14 16:56:59 localhost kernel: [1538461.232085] usb 1-1.5: USB disconnect, device number 5
Apr 14 16:56:59 localhost kernel: [1538461.232089] usb 1-1.5.1: USB disconnect, device number 9
Apr 14 16:56:59 localhost kernel: [1538461.259104] generic-usb 0003:046D:C521.0005: can't reset device, 0000:00:1a.0-1.5.1/input1, status -71
Apr 14 16:56:59 localhost kernel: [1538461.259113] usb 1-1.5: clear tt 6 (90c2) error -71
Apr 14 16:56:59 localhost kernel: [1538461.259118] usb 1-1.5: clear tt 1 (0090) error -19
Apr 14 16:56:59 localhost kernel: [1538461.343904] generic-usb 0003:046D:C521.0004: can't reset device, 0000:00:1a.0-1.5.1/input0, status -71
Apr 14 16:56:59 localhost kernel: [1538461.343915] usb 1-1.5: clear tt 1 (0090) error -19
Apr 14 16:56:59 localhost kernel: [1538461.343919] usb 1-1.5: clear tt 6 (90c2) error -19
Apr 14 16:56:59 localhost kernel: [1538461.385126] usb 1-1.5.2: USB disconnect, device number 10
Apr 14 16:56:59 localhost kernel: [1538461.399859] usb 1-1.5: clear tt 6 (90c2) error -19
Apr 14 16:56:59 localhost kernel: [1538461.408060] usb 1-1.5.3: USB disconnect, device number 11
.......
Apr 14 18:27:06 localhost kernel: [1543864.939728] hub 1-1:1.0: cannot disable port 5 (err = -110)
Apr 14 18:27:07 localhost kernel: [1543865.949047] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:08 localhost kernel: [1543866.958440] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:09 localhost kernel: [1543867.967845] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:10 localhost kernel: [1543868.977136] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:11 localhost kernel: [1543869.986561] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:11 localhost kernel: [1543869.986564] hub 1-1:1.0: Cannot enable port 5. Maybe the USB cable is bad?
Apr 14 18:27:12 localhost kernel: [1543870.995823] hub 1-1:1.0: cannot disable port 5 (err = -110)
Apr 14 18:27:13 localhost kernel: [1543872.005144] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:14 localhost kernel: [1543873.014520] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:15 localhost kernel: [1543874.023853] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:16 localhost kernel: [1543875.033223] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:17 localhost kernel: [1543876.042566] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:17 localhost kernel: [1543876.042572] hub 1-1:1.0: Cannot enable port 5. Maybe the USB cable is bad?
Apr 14 18:27:18 localhost kernel: [1543877.051914] hub 1-1:1.0: cannot disable port 5 (err = -110)
Apr 14 18:27:19 localhost kernel: [1543878.061260] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:21 localhost kernel: [1543879.070608] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:22 localhost kernel: [1543880.079969] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:23 localhost kernel: [1543881.089311] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:24 localhost kernel: [1543882.098635] hub 1-1:1.0: cannot reset port 5 (err = -110)
Apr 14 18:27:24 localhost kernel: [1543882.098638] hub 1-1:1.0: Cannot enable port 5. Maybe the USB cable is bad?
Apr 14 18:27:25 localhost kernel: [1543883.107997] hub 1-1:1.0: cannot disable port 5 (err = -110)
Apr 14 18:27:25 localhost kernel: [1543883.108007] hub 1-1:1.0: unable to enumerate USB device on port 5
Apr 14 18:27:26 localhost kernel: [1543884.117349] hub 1-1:1.0: cannot disable port 5 (err = -110)
Apr 14 18:27:31 localhost kernel: [1543889.164079] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:00:38 localhost kernel: [1545874.932718] hub 1-1:1.0: hub_port_status failed (err = -110)

......lots of similar lines removed

Apr 14 19:15:10 localhost kernel: [1546746.267189] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:15 localhost kernel: [1546751.313903] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:20 localhost kernel: [1546756.360669] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:25 localhost kernel: [1546761.407402] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:30 localhost kernel: [1546766.454143] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:35 localhost kernel: [1546771.500873] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:40 localhost kernel: [1546776.547610] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:45 localhost kernel: [1546781.594345] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:50 localhost kernel: [1546786.641050] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:15:55 localhost kernel: [1546791.687781] hub 1-1:1.0: hub_port_status failed (err = -110)

....lots of similar lines removed

Apr 14 19:19:22 localhost kernel: [1546998.604988] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:27 localhost kernel: [1547003.651693] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:32 localhost kernel: [1547008.698454] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:37 localhost kernel: [1547013.745192] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:42 localhost kernel: [1547018.791905] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:47 localhost kernel: [1547023.838667] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:52 localhost kernel: [1547028.885378] hub 1-1:1.0: hub_port_status failed (err = -110)
Apr 14 19:19:57 localhost kernel: [1547033.932101] hub 1-1:1.0: hub_port_status failed (err = -110)

....would go forever if I do not reboot the machine.

Quote:

$ lsusb
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 004 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 006 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 002 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 001 Device 003: ID 046d:08b2 Logitech, Inc. QuickCam Pro 4000
Bus 001 Device 072: ID 05e3:0608 Genesys Logic, Inc. USB-2.0 4-Port HUB
Bus 002 Device 005: ID 1a40:0101 TERMINUS TECHNOLOGY INC. USB-2.0 4-Port HUB
Bus 001 Device 006: ID 1a40:0101 TERMINUS TECHNOLOGY INC. USB-2.0 4-Port HUB
Bus 002 Device 003: ID 0b05:179c ASUSTek Computer, Inc.
Bus 001 Device 073: ID 0603:00f2 Novatek Microelectronics Corp.
Bus 001 Device 074: ID 046d:c05a Logitech, Inc. Optical Mouse M90
Bus 002 Device 006: ID 050d:0237 Belkin Components F5U237 USB 2.0 7-Port Hub
Bus 002 Device 007: ID 046d:c521 Logitech, Inc. Cordless Mouse Receiver
Bus 002 Device 008: ID 046d:08b2 Logitech, Inc. QuickCam Pro 4000
Bus 002 Device 009: ID 2040:5200 Hauppauge
Bus 002 Device 010: ID 0a12:0001 Cambridge Silicon Radio, Ltd Bluetooth Dongle (HCI mode)
Bus 002 Device 004: ID 04fc:0c05 Sunplus Technology Co., Ltd

Code:

$ lsmod
Module                  Size  Used by
vhost_net              33675  0 
macvtap                18183  1 vhost_net
macvlan                18569  1 macvtap
tun                    22768  1 vhost_net
virtio_net             22936  0 
kvm                   401307  0 
w83627ehf              38880  0 
hwmon_vid              12688  1 w83627ehf
coretemp               13401  0 
nls_utf8               12557  1 
bridge                 90784  0 
stp                    12823  1 bridge
llc                    14090  2 bridge,stp
usb_storage            52101  0 
tcp_lp                 12663  0 
fuse                   77591  11 
lockd                  84578  0 
rfcomm                 68661  10 
bnep                   19570  2 
ip6t_REJECT            12939  2 
nf_conntrack_ipv6      14290  2 
nf_defrag_ipv6         18139  1 nf_conntrack_ipv6
ip6table_filter        12815  1 
ip6_tables             26976  1 ip6table_filter
nf_conntrack_ipv4      14622  2 
nf_defrag_ipv4         12673  1 nf_conntrack_ipv4
xt_state               12578  4 
nf_conntrack           82331  3 nf_conntrack_ipv6,nf_conntrack_ipv4,xt_state
vfat                   17411  2 
fat                    60264  1 vfat
nvidia              12123658  40 
snd_hda_codec_hdmi     36277  4 
snd_hda_codec_realtek   231982  1 
snd_hda_intel          33276  1 
snd_usb_audio         125353  1 
snd_hda_codec         114652  3 snd_hda_codec_hdmi,snd_hda_codec_realtek,snd_hda_intel
snd_seq                64807  0 
snd_pcm                97170  4 snd_hda_codec_hdmi,snd_hda_intel,snd_usb_audio,snd_hda_codec
snd_timer              28815  2 snd_seq,snd_pcm
snd_hwdep              17611  2 snd_usb_audio,snd_hda_codec
snd_usbmidi_lib        24763  1 snd_usb_audio
snd_rawmidi            29530  1 snd_usbmidi_lib
snd_seq_device         14129  2 snd_seq,snd_rawmidi
snd                    74425  16 snd_hda_codec_hdmi,snd_hda_codec_realtek,snd_hda_intel,snd_usb_audio,snd_hda_codec,snd_seq,snd_pcm,snd_timer,snd_hwdep,snd_usbmidi_lib,snd_rawmidi,snd_seq_device
soundcore              14484  1 snd
iTCO_wdt               17948  0 
iTCO_vendor_support    13419  1 iTCO_wdt
shpchp                 37162  0 
eeepc_wmi              12826  0 
asus_wmi               23919  1 eeepc_wmi
rc_dib0700_rc5         12508  0 
btusb                  23904  4 
bluetooth             244750  37 rfcomm,bnep,btusb
snd_page_alloc         18101  2 snd_hda_intel,snd_pcm
sparse_keymap          13526  1 asus_wmi
rfkill                 21410  4 asus_wmi,bluetooth
ir_lirc_codec          13021  0 
lirc_dev               19504  1 ir_lirc_codec
ir_mce_kbd_decoder     13248  0 
ir_sony_decoder        12713  0 
ir_jvc_decoder         12751  0 
ir_rc6_decoder         12833  0 
ir_rc5_decoder         12710  0 
dvb_usb_dib0700       122479  5 
dib7000p               37514  3 dvb_usb_dib0700
ir_nec_decoder         12915  0 
dib0090                32396  1 dvb_usb_dib0700
dib7000m               22704  1 dvb_usb_dib0700
dib0070                17953  3 dvb_usb_dib0700
dvb_usb                23802  1 dvb_usb_dib0700
dib8000                41914  1 dvb_usb_dib0700
dib9000                41317  1 dvb_usb_dib0700
dvb_core              107356  4 dib7000p,dvb_usb,dib8000,dib9000
dib3000mc              18737  1 dvb_usb_dib0700
rc_core                26635  11 rc_dib0700_rc5,ir_lirc_codec,ir_mce_kbd_decoder,ir_sony_decoder,ir_jvc_decoder,ir_rc6_decoder,ir_rc5_decoder,dvb_usb_dib0700,ir_nec_decoder,dvb_usb
dibx000_common         18375  6 dvb_usb_dib0700,dib7000p,dib7000m,dib8000,dib9000,dib3000mc
joydev                 17412  0 
pwc                    83194  1 
videobuf2_core         31894  1 pwc
videodev               97776  2 pwc
media                  20408  1 videodev
r8169                  60789  0 
v4l2_compat_ioctl32    16726  1 videodev
videobuf2_vmalloc      12779  1 pwc
videobuf2_memops       13262  1 videobuf2_vmalloc
mii                    13527  1 r8169
serio_raw              13371  0 
i2c_i801               17765  0 
microcode              23240  0 
e1000e                198082  0 
i2c_core               37955  13 nvidia,dvb_usb_dib0700,dib7000p,dib0090,dib7000m,dib0070,dvb_usb,dib8000,dib9000,dib3000mc,dibx000_common,videodev,i2c_i801
sunrpc                234999  2 lockd
uinput                 17606  0 
xts                    12719  8 
gf128mul               14332  1 xts
dm_crypt               22882  1 
mxm_wmi                12823  0 
firewire_ohci          40326  0 
firewire_core          61393  1 firewire_ohci
crc_itu_t              12579  1 firewire_core
wmi                    18697  2 asus_wmi,mxm_wmi

unSpawn · 04-15-2012, 08:05 AM

Quote:

Originally Posted by zimon

I have this troublesome active 7-port USB-hub, which sometimes goes defunctioning when some usb-device is uncleanly detached.

Neither hub or kernel are to blame for coping with PEBCAK as they do.

Never attribute to malice that which is adequately explained by user stupidity.
(Hanlon's Razor)

Quote:

Originally Posted by zimon

How to stop it

Fedora 16 comes with Rsyslog which can filter out messages. See for example http://www.linuxquestions.org/questi...7/#post4568766

zimon · 04-16-2012, 08:11 AM

This happens also sometimes because some usb-device connected to that usb-hub fails (for example DVB-T dongle). If I disconnect the dongle and connect it again, I'd except it to work, but it hangs like that starting to flood system log.

Yes, I know I can filter it out not ending to /var/log/messages with rsyslog, but still I think there is something broken when dmesg sees those messages every 5 seconds until I reboot the machine.

Just wondering is there anything to be done to end that condition without rebooting.

unSpawn · 04-17-2012, 05:11 PM

Usually the USB subsystem will settle eventually when it figures out the device is gone. I don't know of any way to make these messages stop.

zimon · 05-05-2012, 12:57 PM

Well, they never stop if they start.

BTW, to be found with search engines:

How to safely remove USB device from the Linux system.

To prevent this happening with some devices, I need to manually detach the USB device in the terminal window like this:

Code:

# lsusb | grep GEMBIRD
Bus 002 Device 018: ID 1908:0222 GEMBIRD 
# usb_modeswitch -v 0x1908 -p 0x0222

Looking for default devices ...
   found matching product ID
   adding device
 Found device in default mode, class or configuration (1)
Accessing device 018 on bus 002 ...
Getting the current device configuration ...
 OK, got current device configuration (1)
Using first interface: 0x00
Using endpoints 0x01 (out) and 0x81 (in)
Inquiring device details; driver will be detached ...
Looking for active driver ...
 OK, driver found; name unknown, limitation of libusb1
 OK, driver "unkown" detached
....

edit:
maybe --detach-only option will be needed if the device is something else.

unSpawn · 05-05-2012, 01:17 PM

Thanks for posting your solution. I never heard of 'usb_modeswitch': "USB Modeswitch brings up your datacard into operational mode. When plugged in they identify themselves as cdrom and present some non-Linux compatible installation files. This tool deactivates this cdrom-devices and enables the real communication device. It supports most devices built and sold by Huawei, T-Mobile, Vodafone, Option, ZTE, Novatel."