Subject: Re: r8169 link up but no traffic, and watchdog error

From: Martin Kjær Jørgensen <[email-masked]>
Date: Fri, 18 Aug 2023 13:49:27 +0200

On Wed, Aug 09 2023, Jakub Kicinski <[email-masked]> wrote:

>
> There were some fix in r8169 for power management changes recently.
> Could you try the latest stable kernel? 6.4.9 ?
>

I have just upgraded to latest Debian testing kernel (6.4.0-3-amd64 #1 SMP
PREEMPT_DYNAMIC Debian 6.4.11-1) but it doesn't seem to make much
difference. I can trigger the same issue again, and get similar kernel error
as before:

[1]:

[   99.274963] vdrbr0: port 2(enp9s0) entered disabled state
[  105.064083] r8169 0000:08:00.0 enp8s0: Link is Up - 1Gbps/Full - flow control rx/tx
[  105.064138] vdrbr0: port 1(enp8s0) entered blocking state
[  105.064145] vdrbr0: port 1(enp8s0) entered forwarding state
[  105.172598] r8169 0000:03:00.0 enp3s0: Link is Up - 1Gbps/Full - flow control rx/tx
[  115.666147] ------------[ cut here ]------------
[  115.666166] NETDEV WATCHDOG: enp3s0 (r8169): transmit queue 0 timed out 8312 ms
[  115.666191] WARNING: CPU: 2 PID: 0 at net/sched/sch_generic.c:525 dev_watchdog+0x232/0x240
[  115.666208] Modules linked in: snd_seq_dummy snd_hrtimer snd_seq nf_conntrack_netlink xt_addrtype br_netfilter xt_policy jitterentropy_rng drbg ansi_cprng authenc echainiv esp4 xfrm_interface xfrm6_tunnel tunnel6 tunnel4 xfrm_user xfrm_algo twofish_generic twofish_avx_x86_64 twofish_x86_64_3way twofish_x86_64 twofish_common serpent_avx_x86_64 serpent_sse2_x86_64 serpent_generic blowfish_generic blowfish_x86_64 blowfish_common xt_CHECKSUM cast5_avx_x86_64 cast5_generic cast_common xt_MASQUERADE ctr xt_conntrack ecb cmac ipt_REJECT des_generic nf_reject_ipv4 libdes nls_utf8 algif_skcipher xt_tcpudp camellia_generic cifs nft_compat cifs_arc4 cifs_md4 dns_resolver fscache netfs camellia_x86_64 xcbc md4 algif_hash af_alg nvme_fabrics nft_fib_ipv6 nft_nat nft_fib_ipv4 nft_fib overlay sunrpc binfmt_misc nls_ascii nls_cp437 vfat fat bridge stp llc cfg80211 rfkill intel_rapl_msr intel_rapl_common x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel snd_usb_audio kvm uvcvideo snd_usbmidi_lib snd_hwdep videobuf2_vmalloc uvc
[  115.666513]  snd_rawmidi videobuf2_memops videobuf2_v4l2 snd_seq_device snd_pcm irqbypass videodev rtsx_usb_ms iTCO_wdt think_lmi intel_pmc_bxt memstick mei_hdcp mei_wdt mei_pxp snd_timer intel_wmi_thunderbolt intel_cstate iTCO_vendor_support wmi_bmof firmware_attributes_class intel_uncore ftdi_sio mei_me watchdog snd videobuf2_common ee1004 usbserial mei joydev mc soundcore int3400_thermal acpi_thermal_rel intel_pmc_core acpi_pad nft_masq button acpi_tad evdev nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 nf_tables msr parport_pc ppdev nfnetlink lp parport fuse loop efi_pstore configfs ip_tables x_tables autofs4 btrfs blake2b_generic hid_logitech_hidpp hid_logitech_dj rtsx_usb_sdmmc mmc_core rtsx_usb hid_jabra hid_generic dm_crypt dm_mod efivarfs raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear md_mod usbhid hid ext4 crc16 mbcache jbd2 crc32c_generic i915 i2c_algo_bit drm_buddy drm_display_helper drm_kms_helper crc32_pclmul
[  115.666915]  crc32c_intel cec nvme ghash_clmulni_intel rc_core nvme_core sha512_ssse3 ahci sha512_generic ttm libahci xhci_pci t10_pi libata xhci_hcd drm crc64_rocksoft_generic r8169 aesni_intel realtek e1000e scsi_mod crc64_rocksoft usbcore mdio_devres crc_t10dif libphy crypto_simd cryptd crct10dif_generic i2c_i801 crct10dif_pclmul crc64 i2c_smbus crct10dif_common scsi_common usb_common fan video wmi
[  115.667077] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 6.4.0-3-amd64 #1  Debian 6.4.11-1
[  115.667087] Hardware name: LENOVO 30E30051UK/1052, BIOS S0AKT3AA 04/25/2023
[  115.667091] RIP: 0010:dev_watchdog+0x232/0x240
[  115.667101] Code: ff ff ff 48 89 df c6 05 35 fc 04 01 01 e8 46 3a fa ff 45 89 f8 44 89 f1 48 89 de 48 89 c2 48 c7 c7 80 99 8f 9f e8 9e 0f 70 ff <0f> 0b e9 2d ff ff ff 0f 1f 80 00 00 00 00 90 90 90 90 90 90 90 90
[  115.667107] RSP: 0018:ffffb7c70026ce70 EFLAGS: 00010286
[  115.667117] RAX: 0000000000000000 RBX: ffff9f540b878000 RCX: 0000000000000000
[  115.667123] RDX: 0000000000000104 RSI: 00000000000000f6 RDI: 00000000ffffffff
[  115.667129] RBP: ffff9f540b8784c8 R08: 0000000000000000 R09: ffffb7c70026cd00
[  115.667134] R10: 0000000000000003 R11: ffffffff9fed26a8 R12: ffff9f540b867600
[  115.667139] R13: ffff9f540b87841c R14: 0000000000000000 R15: 0000000000002078
[  115.667144] FS:  0000000000000000(0000) GS:ffff9f6335680000(0000) knlGS:0000000000000000
[  115.667150] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  115.667155] CR2: 0000559cac67feb0 CR3: 00000008c6220001 CR4: 0000000000770ee0
[  115.667161] PKRU: 55555554
[  115.667165] Call Trace:
[  115.667171]  <IRQ>
[  115.667176]  ? dev_watchdog+0x232/0x240
[  115.667183]  ? __warn+0x81/0x130
[  115.667198]  ? dev_watchdog+0x232/0x240
[  115.667205]  ? report_bug+0x191/0x1c0
[  115.667216]  ? native_apic_msr_write+0x2b/0x40
[  115.667228]  ? handle_bug+0x3c/0x80
[  115.667237]  ? exc_invalid_op+0x17/0x70
[  115.667245]  ? asm_exc_invalid_op+0x1a/0x20
[  115.667258]  ? dev_watchdog+0x232/0x240
[  115.667266]  ? __pfx_dev_watchdog+0x10/0x10
[  115.667273]  call_timer_fn+0x24/0x130
[  115.667285]  ? __pfx_dev_watchdog+0x10/0x10
[  115.667291]  __run_timers+0x222/0x2c0
[  115.667303]  run_timer_softirq+0x2f/0x50
[  115.667313]  __do_softirq+0xf1/0x301
[  115.667322]  __irq_exit_rcu+0xb5/0x130
[  115.667333]  sysvec_apic_timer_interrupt+0xa2/0xd0
[  115.667341]  </IRQ>
[  115.667345]  <TASK>
[  115.667349]  asm_sysvec_apic_timer_interrupt+0x1a/0x20
[  115.667359] RIP: 0010:cpuidle_enter_state+0xcc/0x440
[  115.667368] Code: 1a eb 58 ff e8 b5 f0 ff ff 8b 53 04 49 89 c5 0f 1f 44 00 00 31 ff e8 83 f6 57 ff 45 84 ff 0f 85 56 02 00 00 fb 0f 1f 44 00 00 <45> 85 f6 0f 88 85 01 00 00 49 63 d6 48 8d 04 52 48 8d 04 82 49 8d
[  115.667374] RSP: 0018:ffffb7c700193e90 EFLAGS: 00000246
[  115.667382] RAX: ffff9f6335680000 RBX: ffffd7c6ffaa8e00 RCX: 0000000000000000
[  115.667387] RDX: 0000000000000002 RSI: ffffffff9f840bf4 RDI: ffffffff9f82d4df
[  115.667392] RBP: 0000000000000003 R08: 0000000000000000 R09: 0000000033483483
[  115.667396] R10: ffff9f63356b1d84 R11: 0000000000004abf R12: ffffffff9ff98300
[  115.667401] R13: 0000001aee3cf035 R14: 0000000000000003 R15: 0000000000000000
[  115.667410]  cpuidle_enter+0x2d/0x40
[  115.667423]  do_idle+0x217/0x270
[  115.667433]  cpu_startup_entry+0x1d/0x20
[  115.667441]  start_secondary+0x134/0x160
[  115.667453]  secondary_startup_64_no_verify+0x10b/0x10b
[  115.667467]  </TASK>
[  115.667472] ---[ end trace 0000000000000000 ]---
[  129.905036] r8169 0000:03:00.0 enp3s0: Link is Down
[  132.696851] r8169 0000:03:00.0 enp3s0: Link is Up - 1Gbps/Full - flow control rx/tx
[  136.710137] r8169 0000:03:00.0 enp3s0: Link is Down
[  164.035337] r8169 0000:03:00.0 enp3s0: Link is Up - 1Gbps/Full - flow control rx/tx
[  165.922474] r8169 0000:03:00.0 enp3s0: Link is Down
[  166.252291] r8169 0000:08:00.0 enp8s0: Link is Down
[  166.928980] vdrbr0: port 1(enp8s0) entered disabled state