You are not logged in.

#1 2014-04-15 10:50:24

funkypotatoe
Member
Registered: 2012-03-23
Posts: 47

Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

Hi. Second day my laptop runs at 100% cpu usage and i can't understand why. Reboot, process killing doesn't help.

top - 11:36:39 up  2:50,  2 users,  load average: 2.75, 3.23, 3.49
Tasks: 140 total,   2 running, 138 sleeping,   0 stopped,   0 zombie
%Cpu(s): 36.9 us, 19.7 sy,  1.2 ni, 40.1 id,  1.7 wa,  0.0 hi,  0.4 si,  0.0 st
KiB Mem:   3633664 total,  3442056 used,   191608 free,    22956 buffers
KiB Swap:  1539072 total,    67536 used,  1471536 free.  2449928 cached Mem
PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND                                                            
9535 root      20   0   66168   4316   2720 R  89.9  0.1   8:02.91 syslog-ng                                                          
8677 root      20   0  131716  64312  61964 S  33.7  1.8   7:16.38 systemd-journal                                                    

Journalctl and Syslog always on top

iotop:

[4;2H(B[m8677 be/4 root[4;24H0.00 B/s   3.20 M/s  0.00 %  0.00 % systemd-journald

3.2M/sec!

journalctl -r:

Apr 15 11:46:51 laptop kernel: usb 4-1: BOGUS urb xfer, pipe 1 != type 3
Apr 15 11:46:51 laptop kernel: WARNING: CPU: 1 PID: 10706 at drivers/usb/core/urb.c:450 usb_submit_urb+0x1fd/0x5c0 [usbcore]()
Apr 15 11:46:51 laptop kernel: ------------[ cut here ]------------
Apr 15 11:46:51 laptop kernel: ---[ end trace 15c765949b58e17b ]---
Apr 15 11:46:51 laptop kernel:  [<ffffffff810870b0>] ? kthread_create_on_node+0x180/0x180
Apr 15 11:46:51 laptop kernel:  [<ffffffff814e2ebc>] ret_from_fork+0x7c/0xb0
Apr 15 11:46:51 laptop kernel:  [<ffffffff810870b0>] ? kthread_create_on_node+0x180/0x180
Apr 15 11:46:51 laptop kernel:  [<ffffffff81087182>] kthread+0xd2/0xf0
Apr 15 11:46:51 laptop kernel:  [<ffffffff81080a90>] ? manage_workers.isra.23+0x2a0/0x2a0
Apr 15 11:46:51 laptop kernel:  [<ffffffff81080bb1>] worker_thread+0x121/0x3a0
Apr 15 11:46:51 laptop kernel:  [<ffffffff810801f7>] process_one_work+0x167/0x420
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c2cc74>] ath9k_htc_ani_work+0x174/0x1a0 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0bcbce9>] ath9k_hw_ani_monitor+0x29/0x200 [ath9k_hw]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0ba8cd3>] ath_hw_cycle_counters_update+0xd3/0x130 [ath]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c2db2f>] ath9k_regwrite+0x5f/0x120 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c2815c>] ath9k_wmi_cmd+0x11c/0x1d0 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c25435>] htc_send_epid+0x15/0x20 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c2505e>] htc_issue_send.constprop.1+0x5e/0x70 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffff814d87a5>] ? wait_for_common+0x135/0x180
Apr 15 11:46:51 laptop kernel:  [<ffffffffa0c27879>] hif_usb_send+0xe9/0x360 [ath9k_htc]
Apr 15 11:46:51 laptop kernel:  [<ffffffffa00cad2d>] usb_submit_urb+0x1fd/0x5c0 [usbcore]
Apr 15 11:46:51 laptop kernel:  [<ffffffff81064f8c>] warn_slowpath_fmt+0x4c/0x50
Apr 15 11:46:51 laptop kernel:  [<ffffffff81064f1d>] warn_slowpath_common+0x7d/0xa0
Apr 15 11:46:51 laptop kernel:  [<ffffffff814d555c>] dump_stack+0x4d/0x6f
Apr 15 11:46:51 laptop kernel: Call Trace:
Apr 15 11:46:51 laptop kernel:  0000000000000002 0000000000000001 00000000000000d0 ffff88009cf3bc20
Apr 15 11:46:51 laptop kernel:  ffff88009cf3bbc0 ffffffff81064f1d ffff880060159540 ffff88008f4b5000
Apr 15 11:46:51 laptop kernel:  0000000000000009 ffff88009cf3bb88 ffffffff814d555c ffff88009cf3bbd0
Apr 15 11:46:51 laptop kernel: Workqueue: phy5 ath9k_htc_ani_work [ath9k_htc]
Apr 15 11:46:51 laptop kernel: Hardware name: Hewlett-Packard HP 655 Notebook PC/1885, BIOS F.13 09/07/2012
Apr 15 11:46:51 laptop kernel: CPU: 1 PID: 10706 Comm: kworker/u8:0 Tainted: G        W    3.14.0-5-ARCH #1
Apr 15 11:46:51 laptop kernel:  ttm battery drm_kms_helper thermal soundcore wmi drm hwmon i2c_algo_bit video i2c_piix4 shpchp i2c_core
Apr 15 11:46:51 laptop kernel: Modules linked in: rndis_host cdc_ether usbnet ctr ccm ath9k_htc ath9k_common ath9k_hw ath fuse ipt_MASQ

(repeats as long as i can see with ~20 same messages/sec)

UPD:
uname:

Linux laptop 3.14.0-5-ARCH #1 SMP PREEMPT Fri Apr 11 21:02:33 CEST 2014 x86_64 GNU/Linux

UPD2:
lsmod:

Module                  Size  Used by
rndis_host              7794  0 
cdc_ether               7404  1 rndis_host
usbnet                 26866  2 rndis_host,cdc_ether
ctr                     3831  1 
ccm                     7894  1 
ath9k_htc              58293  0 
ath9k_common            1874  1 ath9k_htc
ath9k_hw              391248  2 ath9k_common,ath9k_htc
ath                    19291  3 ath9k_common,ath9k_htc,ath9k_hw
fuse                   77228  3 
ipt_MASQUERADE          2154  6 
iptable_nat             3390  1 
nf_nat_ipv4             3568  1 iptable_nat
nf_nat                 12717  3 ipt_MASQUERADE,nf_nat_ipv4,iptable_nat
nf_conntrack_ipv4       9122  3 
nf_defrag_ipv4          1467  1 nf_conntrack_ipv4
xt_conntrack            3233  2 
nf_conntrack           73799  6 ipt_MASQUERADE,nf_nat,nf_nat_ipv4,xt_conntrack,iptable_nat,nf_conntrack_ipv4
ipt_REJECT              2441  4 
xt_CHECKSUM             1231  2 
iptable_mangle          1584  1 
xt_tcpudp               3143  12 
tun                    20203  1 
bridge                 97831  0 
stp                     1621  1 bridge
llc                     3601  2 stp,bridge
ip6table_filter         1428  0 
ip6_tables             17120  1 ip6table_filter
iptable_filter          1488  1 
ip_tables              17379  3 iptable_filter,iptable_mangle,iptable_nat
x_tables               17191  10 ip6table_filter,xt_CHECKSUM,ip_tables,xt_tcpudp,ipt_MASQUERADE,xt_conntrack,iptable_filter,ipt_REJECT,iptable_mangle,ip6_tables
snd_hda_codec_realtek    46566  1 
snd_hda_codec_generic    53211  1 snd_hda_codec_realtek
snd_hda_codec_hdmi     36588  1 
uvcvideo               72804  0 
videobuf2_vmalloc       3304  1 uvcvideo
videobuf2_memops        2335  1 videobuf2_vmalloc
videobuf2_core         29255  1 uvcvideo
videodev              122955  2 uvcvideo,videobuf2_core
media                  12103  2 uvcvideo,videodev
joydev                  9631  0 
mousedev               10464  0 
arc4                    2000  4 
rt2800pci               5657  0 
rt2800mmio              7174  1 rt2800pci
rt2800lib              73627  2 rt2800pci,rt2800mmio
rt2x00pci               2592  1 rt2800pci
hp_wmi                  7595  0 
sparse_keymap           3146  1 hp_wmi
rt2x00mmio              2897  2 rt2800pci,rt2800mmio
rt2x00lib              37575  5 rt2x00pci,rt2800lib,rt2800pci,rt2800mmio,rt2x00mmio
kvm_amd                52447  0 
led_class               3547  2 rt2x00lib,ath9k_htc
kvm                   404172  1 kvm_amd
mac80211              490609  4 rt2x00lib,rt2x00pci,rt2800lib,ath9k_htc
microcode              16837  0 
cfg80211              431038  4 ath,mac80211,rt2x00lib,ath9k_htc
snd_hda_intel          37704  7 
psmouse                88854  0 
evdev                  10629  13 
mac_hid                 3305  0 
pcspkr                  2027  0 
eeprom_93cx6            2185  1 rt2800pci
snd_hda_codec          99871  4 snd_hda_codec_realtek,snd_hda_codec_hdmi,snd_hda_codec_generic,snd_hda_intel
serio_raw               4977  0 
snd_hwdep               6332  1 snd_hda_codec
radeon               1312880  4 
snd_pcm                79815  4 snd_hda_codec_hdmi,snd_hda_codec,snd_hda_intel
crc_ccitt               1363  1 rt2800lib
rfkill                 15619  4 cfg80211,hp_wmi
snd_timer              18718  1 snd_pcm
k10temp                 3082  0 
snd                    59286  21 snd_hda_codec_realtek,snd_hwdep,snd_timer,snd_hda_codec_hdmi,snd_pcm,snd_hda_codec_generic,snd_hda_codec,snd_hda_intel
r8169                  58423  0 
rtsx_pci_ms             7418  0 
memstick                7504  1 rtsx_pci_ms
mii                     4027  2 r8169,usbnet
ttm                    64361  1 radeon
battery                 7533  0 
drm_kms_helper         35540  1 radeon
thermal                 8556  0 
soundcore               5418  1 snd
wmi                     8251  1 hp_wmi
drm                   237667  6 ttm,drm_kms_helper,radeon
hwmon                   3057  2 k10temp,radeon
i2c_algo_bit            5391  1 radeon
video                  11705  0 
i2c_piix4              10616  0 
shpchp                 25393  0 
i2c_core               24824  6 drm,i2c_piix4,drm_kms_helper,i2c_algo_bit,radeon,videodev
hp_wireless             1803  0 
ac                      3334  0 
button                  4477  0 
acpi_cpufreq            9818  0 
processor              24385  3 acpi_cpufreq
ext4                  481838  3 
crc16                   1359  1 ext4
mbcache                 6074  1 ext4
jbd2                   82704  1 ext4
sd_mod                 36882  5 
sr_mod                 14930  0 
cdrom                  34880  1 sr_mod
crc_t10dif              1039  1 sd_mod
crct10dif_common        1372  1 crc_t10dif
rtsx_pci_sdmmc         13911  0 
mmc_core               95529  1 rtsx_pci_sdmmc
atkbd                  16806  0 
libps2                  4187  2 atkbd,psmouse
ohci_pci                4000  0 
ohci_hcd               35204  1 ohci_pci
ehci_pci                3928  0 
ehci_hcd               64491  1 ehci_pci
rtsx_pci               32799  2 rtsx_pci_ms,rtsx_pci_sdmmc
usbcore               182430  9 uvcvideo,rndis_host,ohci_hcd,ohci_pci,ehci_hcd,ehci_pci,usbnet,ath9k_htc,cdc_ether
ahci                   23627  4 
usb_common              1648  1 usbcore
libahci                21484  1 ahci
libata                172264  2 ahci,libahci
scsi_mod              134432  3 libata,sd_mod,sr_mod
i8042                  12950  1 libps2
serio                  10721  6 serio_raw,atkbd,i8042,psmouse

Last edited by funkypotatoe (2014-04-15 10:53:09)

Offline

#2 2014-04-15 11:45:54

lucke
Member
From: Poland
Registered: 2004-11-30
Posts: 4,018

Re: Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

What does "lsusb" show?

Offline

#3 2014-04-15 12:18:17

funkypotatoe
Member
Registered: 2012-03-23
Posts: 47

Re: Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

lucke wrote:

What does "lsusb" show?

Sorry, i forgot to say that i have usb attached wifi dongle.

Bus 002 Device 004: ID 0cf3:9271 Atheros Communications, Inc. AR9271 802.11n
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 002: ID 04f2:b34f Chicony Electronics Co., Ltd 
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub

I probably know what you thinking about. I deatached it, problem still persists, and it's Atheros TL-WN722N. BUT i had problems with it all the time, every day before i had random disconnects with dmesg saying:

[11922.027165] wlp0s22f2u1: RX AssocResp from 20:e5:2a:fe:ce:09 (capab=0x411 status=0 aid=3)
[11922.034459] wlp0s22f2u1: associated
[11922.034559] IPv6: ADDRCONF(NETDEV_CHANGE): wlp0s22f2u1: link becomes ready
[11922.076478] wlp0s22f2u1: deauthenticating from 20:e5:2a:fe:ce:09 by local choice (reason=2)
[11922.189525] wlp0s22f2u1: authenticate with 20:e5:2a:fe:ce:09
[11922.393783] wlp0s22f2u1: send auth to 20:e5:2a:fe:ce:09 (try 1/3)
[11922.397306] wlp0s22f2u1: authenticated
[11922.403329] wlp0s22f2u1: associate with 20:e5:2a:fe:ce:09 (try 1/3)
[11922.407834] wlp0s22f2u1: RX AssocResp from 20:e5:2a:fe:ce:09 (capab=0x411 status=0 aid=3)
[11922.416881] wlp0s22f2u1: associated

few more messages from journactl:

Apr 15 13:14:14 laptop NetworkManager[250]: <warn> nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted
Apr 15 13:10:14 laptop NetworkManager[250]: <warn> nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted
Apr 15 12:52:14 laptop NetworkManager[250]: <warn> nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted
Apr 15 12:46:14 laptop NetworkManager[250]: <warn> nl_recvmsgs() error: (-33) Dump inconsistency detected, interrupted
(...)
Apr 15 11:59:49 laptop NetworkManager[250]: <info> Removing DNS information from /usr/bin/resolvconf
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> (13) failed to find interface name for index
Apr 15 11:59:49 laptop NetworkManager[250]: nm_system_iface_flush_routes: assertion 'iface != NULL' failed
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> (13) failed to find interface name for index
Apr 15 11:59:49 laptop NetworkManager[250]: <info> (wlp0s22f0u1): bringing up device.
Apr 15 11:59:49 laptop NetworkManager[250]: <error> [1397559589.459084] [nm-system.c:768] nm_system_iface_get_flags(): (unknown): faile
Apr 15 11:59:49 laptop NetworkManager[250]: (nm-system.c:766):nm_system_iface_get_flags: runtime check failed: (iface != NULL)
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> (13) failed to find interface name for index
Apr 15 11:59:49 laptop NetworkManager[250]: <error> [1397559589.456017] [nm-system.c:768] nm_system_iface_get_flags(): (unknown): faile
Apr 15 11:59:49 laptop NetworkManager[250]: (nm-system.c:766):nm_system_iface_get_flags: runtime check failed: (iface != NULL)
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> (13) failed to find interface name for index
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> sysctl: failed to open '/proc/sys/net/ipv6/conf/wlp0s22f0u1/use_tempaddr': (2) No su
Apr 15 11:59:49 laptop NetworkManager[250]: <warn> sysctl: failed to open '/proc/sys/net/ipv6/conf/wlp0s22f0u1/accept_ra': (2) No such 
Apr 15 11:59:48 laptop NetworkManager[250]: <info> (wlp0s22f0u1): canceled DHCP transaction, DHCP client pid 3128
Apr 15 11:59:48 laptop dhcpcd[3128]: exited
Apr 15 11:59:48 laptop NetworkManager[250]: <info> (wlp0s22f0u1): deactivating device (reason 'removed') [36]
Apr 15 11:59:48 laptop NetworkManager[250]: <info> (wlp0s22f0u1): device state change: activated -> unmanaged (reason 'removed') [100 1
Apr 15 11:59:47 laptop dhcpcd[3128]: wlp0s22f0u1: removing interface
Apr 15 11:59:47 laptop kernel: usb 4-1: ath9k_htc: USB layer deinitialized
Apr 15 11:59:47 laptop NetworkManager[250]: <warn> Connection disconnected (reason -3)
Apr 15 11:59:47 laptop kernel: ath: phy5: Failed to wakeup in 500us
Apr 15 11:59:47 laptop kernel: ath: phy5: Failed to wakeup in 500us
Apr 15 11:59:47 laptop kernel: ath: phy5: Unable to set channel
Apr 15 11:59:47 laptop kernel: ath: phy5: Unable to reset channel (2462 Mhz) reset status -22
Apr 15 11:59:47 laptop kernel: ath: phy5: Chip reset failed
Apr 15 11:59:47 laptop kernel: wlp0s22f0u1: deauthenticating from 20:e5:2a:fe:ce:09 by local choice (reason=3)
Apr 15 11:59:47 laptop kernel: usb 4-1: USB disconnect, device number 2
Apr 15 11:59:47 laptop kernel: hub 4-0:1.0: port 1 disabled by hub (EMI?), re-enabling...

Last edited by funkypotatoe (2014-04-15 12:24:35)

Offline

#4 2014-04-15 12:31:51

lucke
Member
From: Poland
Registered: 2004-11-30
Posts: 4,018

Re: Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

Do you get that problem if you boot with that dongle unattached?

Offline

#5 2014-04-15 13:00:10

funkypotatoe
Member
Registered: 2012-03-23
Posts: 47

Re: Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

well... looks like no

Offline

#6 2014-04-15 13:11:39

lucke
Member
From: Poland
Registered: 2004-11-30
Posts: 4,018

Re: Please help me understand what is wrong (Syslog/Journalctl/Ath9k/etc.)

That dongle might be faulty. You could try using it on another PC.

In case it isn't the dongle's fault: you could check in pacman.log what you updated before it started happening. You could see what happens on linux-lts. You could try without NetworkManager.

Offline

Board footer

Powered by FluxBB