raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
10.88k stars 4.89k forks source link

enc28j60 random disconnects #3702

Open ComexioDev opened 4 years ago

ComexioDev commented 4 years ago

Describe the bug I use a enc28j60 ethernet module with spi on my CM3+. Sometimes it randomly disconnects (about once every day) with following error (dmesg): eth0 (enc28j60): transmit queue 0 timed out

To reproduce

Expected behaviour

Actual behaviour

System `System Information

Raspberry Pi Compute Module 3 Plus Rev 1.0 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)"

Raspberry Pi reference 2020-02-13 Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 5f884374b6ac6e155330c58caa1fb7249b8badf1, stage4

Linux raspberrypi 5.4.47-v7+ #1322 SMP Wed Jun 17 17:52:39 BST 2020 armv7l GNU/Linux Revision : a02100 Serial : 000000001ef8fa95 Model : Raspberry Pi Compute Module 3 Plus Rev 1.0 Throttled flag : throttled=0x0 Camera : supported=0 detected=0

Videocore information

Jun 19 2020 15:44:36 Copyright (c) 2012 Broadcom version 31eda665dcc45e61650a2ef018ef2cf94091c46f (clean) (release) (start)

alloc failures: 0 compactions: 0 legacy block fails: 0

Filesystem information

Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf /dev/root 7189324 5617380 1230756 83% / devtmpfs 441236 0 441236 0% /dev tmpfs 474516 0 474516 0% /dev/shm tmpfs 474516 12352 462164 3% /run tmpfs 5120 4 5116 1% /run/lock tmpfs 474516 0 474516 0% /sys/fs/cgroup /dev/mmcblk0p1 258095 54429 203666 22% /boot tmpfs 94900 4 94896 1% /run/user/1000

Filename Type Size Used Priority /var/swap file 102396 0 -2

Package version information

raspberrypi-ui-mods: Installiert: 1.20200611 raspberrypi-sys-mods: Installiert: 20200514 openbox: Installiert: 3.6.1-8+rpt4 lxpanel: Installiert: 0.10.0-2+rpt11 pcmanfm: Installiert: 1.3.1-1+rpt22 rpd-plym-splash: Installiert: 0.23

Networking Information

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500 inet x.x.x.x netmask x.x.x.x broadcast x.x.x.x inet6 y::y.y.y.y prefixlen 64 scopeid 0x20 ether m.m.m.m txqueuelen 1000 (Ethernet) RX packets 93772 bytes 138396944 (131.9 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 30496 bytes 1923024 (1.8 MiB) TX errors 35988 dropped 0 overruns 0 carrier 0 collisions 0 device interrupt 166

lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536 inet x.x.x.x netmask x.x.x.x inet6 ::1 prefixlen 128 scopeid 0x10 loop txqueuelen 1000 (Lokale Schleife) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0

USB Information

/: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M | Port 1: Dev 2, If 0, Class=Human Interface Device, Driver=usbhid, 1.5M | Port 1: Dev 2, If 1, Class=Human Interface Device, Driver=usbhid, 1.5M

config.txt

aphy_params_current=819 arm_freq=1200 audio_pwm_mode=514 config_hdmi_boost=5 core_freq=400 desired_osc_freq=0x387520 disable_commandline_tags=2 disable_l2cache=1 display_default_lcd=1 display_hdmi_rotate=-1 display_lcd_rotate=-1 dphy_params_current=547 dpi_group=2 dpi_mode=87 dpi_output_format=0x6f007 enable_dpi_lcd=1 enable_tvout=1 enable_uart=1 force_pwm_open=1 framebuffer_aspect=-1 framebuffer_height=445 framebuffer_ignore_alpha=1 framebuffer_swap=1 framebuffer_width=800 gpu_freq=300 ignore_lcd=1 init_uart_clock=0x2dc6c00 over_voltage_avs=56250 pause_burst_frames=1 program_serial_random=1 sdram_freq=450 total_mem=1024 hdmi_force_cec_address:0=65535 hdmi_force_cec_address:1=65535 hdmi_force_hotplug:0=1 hdmi_pixel_freq_limit:0=0x9a7ec80 device_tree=- dpi_timings=800 0 40 48 88 480 0 13 3 32 0 0 0 60 0 32000000 6 overlay_prefix=overlays/ hdmi_cvt:0= hdmi_cvt:1= hdmi_edid_filename:0= hdmi_edid_filename:1= hdmi_timings:0= hdmi_timings:1=

cmdline.txt

coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=445 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:F8:FA:95 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyAMA0,115200 console=tty1 root=PARTUUID=ea7d04d6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles

raspi-gpio settings

BANK0 (GPIO 0 to 27): GPIO 0: level=0 fsel=6 alt=2 func=PCLK GPIO 1: level=1 fsel=6 alt=2 func=DE GPIO 2: level=1 fsel=6 alt=2 func=LCD_VSYNC GPIO 3: level=0 fsel=6 alt=2 func=LCD_HSYNC GPIO 4: level=0 fsel=6 alt=2 func=DPI_D0 GPIO 5: level=0 fsel=6 alt=2 func=DPI_D1 GPIO 6: level=0 fsel=6 alt=2 func=DPI_D2 GPIO 7: level=0 fsel=6 alt=2 func=DPI_D3 GPIO 8: level=0 fsel=6 alt=2 func=DPI_D4 GPIO 9: level=1 fsel=6 alt=2 func=DPI_D5 GPIO 10: level=0 fsel=6 alt=2 func=DPI_D6 GPIO 11: level=0 fsel=6 alt=2 func=DPI_D7 GPIO 12: level=1 fsel=6 alt=2 func=DPI_D8 GPIO 13: level=0 fsel=6 alt=2 func=DPI_D9 GPIO 14: level=1 fsel=6 alt=2 func=DPI_D10 GPIO 15: level=1 fsel=6 alt=2 func=DPI_D11 GPIO 16: level=0 fsel=6 alt=2 func=DPI_D12 GPIO 17: level=0 fsel=6 alt=2 func=DPI_D13 GPIO 18: level=0 fsel=6 alt=2 func=DPI_D14 GPIO 19: level=0 fsel=6 alt=2 func=DPI_D15 GPIO 20: level=0 fsel=6 alt=2 func=DPI_D16 GPIO 21: level=0 fsel=6 alt=2 func=DPI_D17 GPIO 22: level=1 fsel=6 alt=2 func=DPI_D18 GPIO 23: level=0 fsel=6 alt=2 func=DPI_D19 GPIO 24: level=0 fsel=6 alt=2 func=DPI_D20 GPIO 25: level=1 fsel=6 alt=2 func=DPI_D21 GPIO 26: level=1 fsel=6 alt=2 func=DPI_D22 GPIO 27: level=0 fsel=6 alt=2 func=DPI_D23 BANK1 (GPIO 28 to 45): GPIO 28: level=0 fsel=6 alt=2 func=PCM_CLK GPIO 29: level=0 fsel=6 alt=2 func=PCM_FS GPIO 30: level=0 fsel=6 alt=2 func=PCM_DIN GPIO 31: level=0 fsel=6 alt=2 func=PCM_DOUT GPIO 32: level=0 fsel=1 func=OUTPUT GPIO 33: level=0 fsel=0 func=INPUT GPIO 34: level=1 fsel=0 func=INPUT GPIO 35: level=1 fsel=0 func=INPUT GPIO 36: level=1 fsel=1 func=OUTPUT GPIO 37: level=0 fsel=4 alt=0 func=SPI0_MISO GPIO 38: level=0 fsel=4 alt=0 func=SPI0_MOSI GPIO 39: level=0 fsel=4 alt=0 func=SPI0_SCLK GPIO 40: level=0 fsel=4 alt=0 func=PWM0 GPIO 41: level=1 fsel=1 func=OUTPUT GPIO 42: level=1 fsel=0 func=INPUT GPIO 43: level=1 fsel=1 func=OUTPUT GPIO 44: level=1 fsel=5 alt=1 func=SDA0 GPIO 45: level=1 fsel=5 alt=1 func=SCL0 BANK2 (GPIO 46 to 53): GPIO 46: level=1 fsel=0 func=INPUT GPIO 47: level=1 fsel=1 func=OUTPUT GPIO 48: level=0 fsel=4 alt=0 func=SD0_CLK GPIO 49: level=1 fsel=4 alt=0 func=SD0_CMD GPIO 50: level=1 fsel=4 alt=0 func=SD0_DAT0 GPIO 51: level=1 fsel=4 alt=0 func=SD0_DAT1 GPIO 52: level=1 fsel=4 alt=0 func=SD0_DAT2 GPIO 53: level=1 fsel=4 alt=0 func=SD0_DAT3

vcdbg log messages

001972.084: brfs: File read: /mfs/sd/config.txt 001973.010: brfs: File read: 637 bytes 002358.929: HDMI:EDID error reading EDID block 0 attempt 0 002720.908: HDMI:EDID error reading EDID block 0 attempt 1 003082.886: HDMI:EDID error reading EDID block 0 attempt 2 003444.864: HDMI:EDID error reading EDID block 0 attempt 3 003806.842: HDMI:EDID error reading EDID block 0 attempt 4 004168.820: HDMI:EDID error reading EDID block 0 attempt 5 004530.798: HDMI:EDID error reading EDID block 0 attempt 6 004892.776: HDMI:EDID error reading EDID block 0 attempt 7 005254.754: HDMI:EDID error reading EDID block 0 attempt 8 005616.732: HDMI:EDID error reading EDID block 0 attempt 9 005617.752: HDMI:EDID giving up on reading EDID block 0 005619.112: brfs: File read: /mfs/sd/config.txt 005619.943: gpioman: gpioman_get_pin_num: pin DISPLAY_SDA not defined 005619.974: gpioman: gpioman_get_pin_num: pin LEDS_PWR_OK not defined 005631.827: brfs: File read: 637 bytes 005636.898: gpioman: gpioman_get_pin_num: pin FLASH_0_ENABLE not defined 005636.926: gpioman: gpioman_get_pin_num: pin FLASH_0_INDICATOR not defined 005636.974: gpioman: gpioman_get_pin_num: pin FLASH_0_ENABLE not defined 005637.001: gpioman: gpioman_get_pin_num: pin FLASH_0_INDICATOR not defined 005637.046: gpioman: gpioman_get_pin_num: pin BT_ON not defined 005637.067: gpioman: gpioman_get_pin_num: pin WL_ON not defined 005670.105: gpioman: gpioman_get_pin_num: pin LEDS_PWR_OK not defined 005670.165: *** Restart logging 006031.768: hdmi: HDMI:EDID error reading EDID block 0 attempt 0 006393.751: hdmi: HDMI:EDID error reading EDID block 0 attempt 1 006755.733: hdmi: HDMI:EDID error reading EDID block 0 attempt 2 007117.716: hdmi: HDMI:EDID error reading EDID block 0 attempt 3 007479.699: hdmi: HDMI:EDID error reading EDID block 0 attempt 4 007841.682: hdmi: HDMI:EDID error reading EDID block 0 attempt 5 008203.665: hdmi: HDMI:EDID error reading EDID block 0 attempt 6 008565.647: hdmi: HDMI:EDID error reading EDID block 0 attempt 7 008927.630: hdmi: HDMI:EDID error reading EDID block 0 attempt 8 009289.612: hdmi: HDMI:EDID error reading EDID block 0 attempt 9 009290.636: hdmi: HDMI:EDID giving up on reading EDID block 0 009651.688: hdmi: HDMI:EDID error reading EDID block 0 attempt 0 010013.668: hdmi: HDMI:EDID error reading EDID block 0 attempt 1 010375.648: hdmi: HDMI:EDID error reading EDID block 0 attempt 2 010737.628: hdmi: HDMI:EDID error reading EDID block 0 attempt 3 011099.608: hdmi: HDMI:EDID error reading EDID block 0 attempt 4 011461.588: hdmi: HDMI:EDID error reading EDID block 0 attempt 5 011823.568: hdmi: HDMI:EDID error reading EDID block 0 attempt 6 012185.548: hdmi: HDMI:EDID error reading EDID block 0 attempt 7 012547.528: hdmi: HDMI:EDID error reading EDID block 0 attempt 8 012909.508: hdmi: HDMI:EDID error reading EDID block 0 attempt 9 012910.529: hdmi: HDMI:EDID giving up on reading EDID block 0 012916.079: HDMI0: hdmi_pixel_encoding: 162000000

012917.309: gpioman: gpioman_get_pin_num: pin CAMERA_0_SDA_PIN not defined 012917.332: gpioman: gpioman_get_pin_num: pin CAMERA_0_SCL_PIN not defined 012917.356: gpioman: gpioman_get_pin_num: pin CAMERA_0_I2C_PORT not defined 012917.389: dtb_file 'bcm2710-rpi-cm3.dtb' 012923.426: brfs: File read: /mfs/sd/bcm2710-rpi-cm3.dtb 012923.449: Loading 'bcm2710-rpi-cm3.dtb' to 0x100 size 0x66c6 012960.753: brfs: File read: 26310 bytes 012971.617: brfs: File read: /mfs/sd/overlays/overlay_map.dtb 013147.090: brfs: File read: 1371 bytes 013148.330: brfs: File read: /mfs/sd/config.txt 013148.968: brfs: File read: 637 bytes 013150.942: brfs: File read: /mfs/sd/overlays/dpi24.dtbo 013181.640: Loaded overlay 'dpi24' 013181.865: dtparam: spi=on 013275.385: brfs: File read: 975 bytes 013281.726: brfs: File read: /mfs/sd/overlays/spi-gpio35-39-com.dtbo 013309.956: Loaded overlay 'spi-gpio35-39-com' 013348.058: brfs: File read: 666 bytes 013351.716: brfs: File read: /mfs/sd/overlays/enc28j60.dtbo 013375.833: Loaded overlay 'enc28j60' 013375.850: dtparam: int_pin=35 013376.747: dtparam: i2c_vc=on 013497.305: brfs: File read: 1403 bytes 013502.001: brfs: File read: /mfs/sd/overlays/i2c0.dtbo 013525.695: Loaded overlay 'i2c0' 013525.713: dtparam: pins_44_45=1 013560.681: brfs: File read: 1396 bytes 013567.998: brfs: File read: /mfs/sd/overlays/comexioTouch.dtbo 013585.144: Loaded overlay 'comexioTouch' 013585.198: dtparam: audio=on 013657.923: brfs: File read: 779 bytes 013663.191: brfs: File read: /mfs/sd/overlays/pwm.dtbo 013682.419: Loaded overlay 'pwm' 013682.435: dtparam: pin=40 013682.726: dtparam: func=4 013683.061: dtparam: i2s=on 013752.983: brfs: File read: 985 bytes 013756.462: brfs: File read: /mfs/sd/overlays/i2s-gpio28-31.dtbo 013771.842: Loaded overlay 'i2s-gpio28-31' 013803.889: brfs: File read: 307 bytes 013804.997: brfs: File read: /mfs/sd/cmdline.txt 013805.056: Read command line from file 'cmdline.txt': 013805.079: 'console=serial0,115200 console=tty1 root=PARTUUID=ea7d04d6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles' 015752.418: gpioman: gpioman_get_pin_num: pin WL_ON not defined 015768.438: brfs: File read: 166 bytes 016271.954: brfs: File read: /mfs/sd/kernel7.img 016271.977: Loading 'kernel7.img' to 0x8000 size 0x555b80 016272.007: Device tree loaded to 0x2eff9100 (size 0x6eee) 016272.903: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined 019133.278: vchiq_core: vchiq_init_state: slot_zero = 0xf7580000, is_master = 1 019137.410: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead 019142.592: TV service:host side not connected, dropping notification 0x00000002, 0x00000002, 0x00000004

dmesg log

[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 5.4.47-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1322 SMP Wed Jun 17 17:52:39 BST 2020 [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi Compute Module 3 Plus Rev 1.0 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool [ 0.000000] On node 0 totalpages: 242688 [ 0.000000] Normal zone: 1896 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 242688 pages, LIFO batch:63 [ 0.000000] percpu: Embedded 20 pages/cpu s49792 r8192 d23936 u81920 [ 0.000000] pcpu-alloc: s49792 r8192 d23936 u81920 alloc=20*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240792 [ 0.000000] Kernel command line: coherent_pool=1M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=445 bcm2708_fb.fbswap=1 smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyAMA0,115200 console=tty1 root=PARTUUID=ea7d04d6-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] Memory: 882476K/970752K available (8192K kernel code, 680K rwdata, 2532K rodata, 1024K init, 824K bss, 22740K reserved, 65536K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 27907 entries in 55 pages [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x328/0x4e8 with crng_init=0 [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000018] Switching to timer-based delay loop, resolution 52ns [ 0.000276] Console: colour dummy device 80x30 [ 0.000303] printk: console [tty1] enabled [ 0.000353] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) [ 0.000373] pid_max: default: 32768 minimum: 301 [ 0.000701] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.000722] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) [ 0.001887] CPU: Testing write buffer coherency: ok [ 0.002440] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.003375] Setting up static identity map for 0x100000 - 0x10003c [ 0.003569] rcu: Hierarchical SRCU implementation. [ 0.004249] smp: Bringing up secondary CPUs ... [ 0.005359] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.006615] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 0.007767] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.007919] smp: Brought up 1 node, 4 CPUs [ 0.007935] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.007944] CPU: All CPU(s) started in HYP mode. [ 0.007953] CPU: Virtualization extensions available. [ 0.008886] devtmpfs: initialized [ 0.025221] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 [ 0.025487] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.025514] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.028552] pinctrl core: initialized pinctrl subsystem [ 0.029672] NET: Registered protocol family 16 [ 0.034047] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.040298] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.040311] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.040537] Serial: AMBA PL011 UART driver [ 0.042462] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.060097] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-06-19 15:44, variant start [ 0.070111] raspberrypi-firmware soc:firmware: Firmware hash is 31eda665dcc45e61650a2ef018ef2cf94091c46f [ 0.120896] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.123035] SCSI subsystem initialized [ 0.123295] usbcore: registered new interface driver usbfs [ 0.123356] usbcore: registered new interface driver hub [ 0.123484] usbcore: registered new device driver usb [ 0.125585] clocksource: Switched to clocksource arch_sys_counter [ 1.283448] VFS: Disk quotas dquot_6.6.0 [ 1.283565] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 1.283745] FS-Cache: Loaded [ 1.284015] CacheFiles: Loaded [ 1.295168] thermal_sys: Registered thermal governor 'step_wise' [ 1.295559] NET: Registered protocol family 2 [ 1.296497] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) [ 1.296537] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) [ 1.296652] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 1.296841] TCP: Hash tables configured (established 8192 bind 8192) [ 1.297004] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.297056] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.297344] NET: Registered protocol family 1 [ 1.298118] RPC: Registered named UNIX socket transport module. [ 1.298129] RPC: Registered udp transport module. [ 1.298139] RPC: Registered tcp transport module. [ 1.298148] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.300012] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available [ 1.303245] Initialise system trusted keyrings [ 1.303484] workingset: timestamp_bits=30 max_order=18 bucket_order=0 [ 1.313901] FS-Cache: Netfs 'nfs' registered for caching [ 1.314543] NFS: Registering the id_resolver key type [ 1.314578] Key type id_resolver registered [ 1.314588] Key type id_legacy registered [ 1.314608] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.315863] Key type asymmetric registered [ 1.315875] Asymmetric key parser 'x509' registered [ 1.315925] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 1.315938] io scheduler mq-deadline registered [ 1.315949] io scheduler kyber registered [ 1.321528] bcm2708_fb soc:fb: FB found 1 display(s) [ 1.329596] Console: switching to colour frame buffer device 100x27 [ 1.335749] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x445 [ 1.340698] bcm2835-rng 3f104000.rng: hwrng registered [ 1.341230] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.341908] vc-sm: Videocore shared memory driver [ 1.342370] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 1.353834] brd: module loaded [ 1.365491] loop: module loaded [ 1.366875] Loading iSCSI transport class v2.0-870. [ 1.367763] libphy: Fixed MDIO Bus: probed [ 1.367870] usbcore: registered new interface driver lan78xx [ 1.367931] usbcore: registered new interface driver smsc95xx [ 1.367954] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.096156] Core Release: 2.80a [ 2.096170] Setting default values for core params [ 2.096204] Finished setting default values for core params [ 2.296597] Using Buffer DMA mode [ 2.296608] Periodic Transfer Interrupt Enhancement - disabled [ 2.296617] Multiprocessor Interrupt Enhancement - disabled [ 2.296628] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 2.296690] Dedicated Tx FIFOs mode [ 2.297310] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024 [ 2.297340] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 2.297352] dwc_otg: Microframe scheduler enabled [ 2.297418] WARN::hcd_init_fiq:457: FIQ on core 1 [ 2.297428] WARN::hcd_init_fiq:458: FIQ ASM at 806bc490 length 36 [ 2.297439] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000 [ 2.297459] dwc_otg 3f980000.usb: DWC OTG Controller [ 2.297496] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 2.297541] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 [ 2.297594] Init: Port Power? op_state=1 [ 2.297604] Init: Power Port (0) [ 2.297971] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 [ 2.297987] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.298001] usb usb1: Product: DWC OTG Controller [ 2.298015] usb usb1: Manufacturer: Linux 5.4.47-v7+ dwc_otg_hcd [ 2.298028] usb usb1: SerialNumber: 3f980000.usb [ 2.298755] hub 1-0:1.0: USB hub found [ 2.298818] hub 1-0:1.0: 1 port detected [ 2.299571] dwc_otg: FIQ enabled [ 2.299581] dwc_otg: NAK holdoff enabled [ 2.299591] dwc_otg: FIQ split-transaction FSM enabled [ 2.299605] Module dwc_common_port init [ 2.299925] usbcore: registered new interface driver usb-storage [ 2.300167] mousedev: PS/2 mouse device common for all mice [ 2.301632] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 2.303822] sdhci: Secure Digital Host Controller Interface driver [ 2.303831] sdhci: Copyright(c) Pierre Ossman [ 2.304483] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe [ 2.304736] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.305432] ledtrig-cpu: registered to indicate activity on CPUs [ 2.305529] hidraw: raw HID events driver (C) Jiri Kosina [ 2.305724] usbcore: registered new interface driver usbhid [ 2.305733] usbhid: USB HID core driver [ 2.306742] vchiq: vchiq_init_state: slot_zero = (ptrval)

[ 2.317274] [vc_sm_connected_init]: end - returning 0 [ 2.319030] Initializing XFRM netlink socket [ 2.319066] NET: Registered protocol family 17 [ 2.319203] Key type dns_resolver registered [ 2.320004] Registering SWP/SWPB emulation handler [ 2.320373] registered taskstats version 1 [ 2.320393] Loading compiled-in X.509 certificates [ 2.320860] Key type ._fscrypt registered [ 2.320871] Key type .fscrypt registered [ 2.331275] uart-pl011 3f201000.serial: cts_event_workaround enabled [ 2.331368] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 [ 2.331439] printk: console [ttyAMA0] enabled [ 2.333194] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 2.334631] sdhost: log_buf @ (ptrval) (f7507000) [ 2.382651] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 2.385217] of_cfs_init [ 2.385490] of_cfs_init: OK [ 2.386500] Waiting for root device PARTUUID=ea7d04d6-02... [ 2.477095] mmc0: new high speed MMC card at address 0001 [ 2.478061] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 2.478411] mmcblk0boot0: mmc0:0001 8GTF4R partition 1 4.00 MiB [ 2.478766] mmcblk0boot1: mmc0:0001 8GTF4R partition 2 4.00 MiB [ 2.479064] mmcblk0rpmb: mmc0:0001 8GTF4R partition 3 512 KiB, chardev (245:0) [ 2.481362] mmcblk0: p1 p2 [ 2.507763] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem [ 2.507780] EXT4-fs (mmcblk0p2): write access will be enabled during recovery [ 2.515737] Indeed it is in host mode hprt0 = 00041901 [ 2.674796] random: fast init done [ 2.725635] usb 1-1: new low-speed USB device number 2 using dwc_otg [ 2.726534] Indeed it is in host mode hprt0 = 00041901 [ 2.971459] usb 1-1: New USB device found, idVendor=046d, idProduct=c31c, bcdDevice=64.02 [ 2.971476] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 2.971490] usb 1-1: Product: USB Keyboard [ 2.971504] usb 1-1: Manufacturer: Logitech [ 2.979700] input: Logitech USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1:1.0/0003:046D:C31C.0001/input/input0 [ 3.046514] hid-generic 0003:046D:C31C.0001: input,hidraw0: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-3f980000.usb-1/input0 [ 3.056978] input: Logitech USB Keyboard Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1:1.1/0003:046D:C31C.0002/input/input1 [ 3.125894] input: Logitech USB Keyboard System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1:1.1/0003:046D:C31C.0002/input/input2 [ 3.126373] hid-generic 0003:046D:C31C.0002: input,hiddev96,hidraw1: USB HID v1.10 Device [Logitech USB Keyboard] on usb-3f980000.usb-1/input1 [ 3.343210] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs [ 3.348066] EXT4-fs (mmcblk0p2): 6 orphan inodes deleted [ 3.348079] EXT4-fs (mmcblk0p2): recovery complete [ 3.357927] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 3.358022] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 3.359246] devtmpfs: mounted [ 3.366109] Freeing unused kernel memory: 1024K [ 3.386013] Run /sbin/init as init process [ 3.936745] systemd[1]: System time before build time, advancing clock. [ 4.089490] NET: Registered protocol family 10 [ 4.090947] Segment Routing with IPv6 [ 4.122470] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) [ 4.123462] systemd[1]: Detected architecture arm. [ 4.199124] systemd[1]: Set hostname to . [ 4.358518] uart-pl011 3f201000.serial: no DMA platform data [ 5.116134] random: systemd: uninitialized urandom read (16 bytes read) [ 5.130069] random: systemd: uninitialized urandom read (16 bytes read) [ 5.133262] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 5.133617] random: systemd: uninitialized urandom read (16 bytes read) [ 5.134202] systemd[1]: Listening on udev Kernel Socket. [ 5.134979] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.135778] systemd[1]: Listening on udev Control Socket. [ 5.162415] systemd[1]: Condition check resulted in Journal Audit Socket being skipped. [ 5.164043] systemd[1]: Created slice system-getty.slice. [ 5.164523] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 5.368109] i2c /dev entries driver [ 5.854442] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null) [ 5.979273] systemd-journald[100]: Received request to flush runtime journal from PID 1 [ 6.562200] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 6.564970] bcm2835_vc_sm_cma_probe: Videocore shared memory driver

[ 6.570790] mc: Linux media interface: v0.10 [ 6.571871] [vc_sm_connected_init]: installed successfully [ 6.633687] videodev: Linux video capture interface: v2.00 [ 6.710406] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 6.734086] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 6.734975] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 6.738229] bcm2835_audio bcm2835_audio: card created with 8 channels [ 6.758959] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 6.759703] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 6.762450] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 6.788314] bcm2835-isp bcm2835-isp: bcm2835_isp_get_supported_fmts: port has more encoding than we provided space for. Some are dropped. [ 6.791841] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 6.797592] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 6.809676] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 6.821394] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 6.821435] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 6.821457] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 6.821526] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 6.821545] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 6.822035] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 6.833714] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 6.833780] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 6.849266] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 6.849361] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 6.860068] : bcm2835_codec_get_supported_fmts: port has more encoding than we provided space for. Some are dropped. [ 6.861123] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 6.861175] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 7.095720] i2c i2c-11: Added multiplexed i2c bus 0 [ 7.096240] i2c i2c-11: Added multiplexed i2c bus 10 [ 8.305677] enc28j60 spi0.0: Ethernet driver 1.02 loaded [ 8.755394] input: generic ft5x06 (79) as /devices/platform/soc/3f205000.i2c/i2c-11/i2c-0/0-0038/input/input3 [ 9.929606] random: crng init done [ 9.929630] random: 7 urandom warning(s) missed due to ratelimiting [ 9.999395] 8021q: 802.1Q VLAN Support v1.8 [ 10.314932] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 10.447899] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 10.506299] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS [ 10.622769] enc28j60 spi0.0 eth0: link down [ 10.622814] enc28j60 spi0.0 eth0: multicast mode [ 10.623032] enc28j60 spi0.0 eth0: multicast mode [ 10.663982] enc28j60 spi0.0 eth0: multicast mode [ 10.664228] enc28j60 spi0.0 eth0: multicast mode [ 10.763027] enc28j60 spi0.0 eth0: multicast mode [ 10.763471] enc28j60 spi0.0 eth0: multicast mode [ 11.578224] enc28j60 spi0.0 eth0: link up - Half duplex [ 11.578465] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 11.596351] enc28j60 spi0.0 eth0: multicast mode [ 11.596834] enc28j60 spi0.0 eth0: multicast mode [ 15.234169] fuse: init (API version 7.31) [ 17.260989] enc28j60 spi0.0 eth0: multicast mode [ 2536.102591] ------------[ cut here ]------------ [ 2536.102624] WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:448 dev_watchdog+0x310/0x314 [ 2536.102633] NETDEV WATCHDOG: eth0 (enc28j60): transmit queue 0 timed out [ 2536.102641] Modules linked in: fuse sha256_generic libsha256 cfg80211 rfkill 8021q garp stp llc edt_ft5x06 enc28j60 spidev joydev evdev raspberrypi_hwmon i2c_mux_pinctrl i2c_mux snd_soc_bcm2835_i2s pwm_bcm2835 i2c_bcm2835 spi_bcm2835 snd_soc_core snd_compress bcm2835_codec(C) bcm2835_v4l2(C) bcm2835_isp(C) v4l2_mem2mem bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc snd_bcm2835(C) videobuf2_memops videobuf2_v4l2 snd_pcm_dmaengine snd_pcm videobuf2_common snd_timer snd videodev mc vc_sm_cma(C) uio_pdrv_genirq uio fixed i2c_dev ip_tables x_tables ipv6 nf_defrag_ipv6 [ 2536.102814] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G C 5.4.47-v7+ #1322 [ 2536.102818] Hardware name: BCM2835 [ 2536.102822] Backtrace: [ 2536.102838] [<8010d458>] (dump_backtrace) from [<8010d750>] (show_stack+0x20/0x24) [ 2536.102847] r6:80d00000 r5:00000000 r4:80d93c74 r3:c8851a02 [ 2536.102859] [<8010d730>] (show_stack) from [<808b08c4>] (dump_stack+0xe0/0x124) [ 2536.102870] [<808b07e4>] (dump_stack) from [<8011fd24>] (warn+0xec/0x104) [ 2536.102880] r8:000001c0 r7:00000009 r6:80b0b594 r5:00000000 r4:80d01cec r3:c8851a02 [ 2536.102890] [<8011fc38>] (warn) from [<8011fdc0>] (warn_slowpath_fmt+0x84/0xc0) [ 2536.102900] r9:80b0b594 r8:000001c0 r7:807be45c r6:00000009 r5:80b0b5ac r4:80d04f48 [ 2536.102911] [<8011fd40>] (warn_slowpath_fmt) from [<807be45c>] (dev_watchdog+0x310/0x314) [ 2536.102921] r9:00036978 r8:b5ed4000 r7:00000000 r6:80d03d00 r5:b5ed42a8 r4:00000000 [ 2536.102936] [<807be14c>] (dev_watchdog) from [<8019b61c>] (call_timer_fn+0x40/0x180) [ 2536.102945] r8:807be14c r7:00000100 r6:80d00000 r5:b5ed42a8 r4:b6c12440 [ 2536.102958] [<8019b5dc>] (call_timer_fn) from [<8019c76c>] (run_timer_softirq+0x288/0x654) [ 2536.102968] r9:00000000 r8:b5ed42a8 r7:80d00000 r6:00036978 r5:80d01dc4 r4:b6c12440 [ 2536.102980] [<8019c4e4>] (run_timer_softirq) from [<8010249c>] (do_softirq+0x1a4/0x418) [ 2536.102990] r10:00000004 r9:00000082 r8:b649d000 r7:00000100 r6:80d00000 r5:00000001 [ 2536.102994] r4:80d03084 [ 2536.103006] [<801022f8>] (__do_softirq) from [<80125b78>] (irq_exit+0x100/0x110) [ 2536.103015] r10:b73ff840 r9:80d00000 r8:b649d000 r7:00000001 r6:00000000 r5:00000000 [ 2536.103020] r4:80ca12a4 [ 2536.103032] [<80125a78>] (irq_exit) from [<80180a50>] (handle_domain_irq+0x70/0xc4) [ 2536.103043] [<801809e0>] (handle_domain_irq) from [<80102228>] (bcm2836_arm_irqchip_handle_irq+0x60/0xa4) [ 2536.103053] r8:80d990bc r7:80d01f0c r6:ffffffff r5:60000013 r4:00000000 r3:80d01ed8 [ 2536.103063] [<801021c8>] (bcm2836_arm_irqchip_handle_irq) from [<80101a3c>] (irq_svc+0x5c/0x7c) [ 2536.103068] Exception stack(0x80d01ed8 to 0x80d01f20) [ 2536.103075] 1ec0: 80109b94 00000000 [ 2536.103084] 1ee0: 60000093 8011a2e0 80d04f68 80d00000 80d04fb0 00000001 80d990bc 80c67a40 [ 2536.103094] 1f00: b73ff840 80d01f34 80d0535c 80d01f28 00000000 80109b98 60000013 ffffffff [ 2536.103100] r4:80109b98 r3:c8851a02 [ 2536.103113] [<80109b64>] (arch_cpu_idle) from [<808d1224>] (default_idle_call+0x34/0x48) [ 2536.103123] [<808d11f0>] (default_idle_call) from [<80153418>] (do_idle+0xec/0x170) [ 2536.103132] [<8015332c>] (do_idle) from [<80153778>] (cpu_startup_entry+0x28/0x2c) [ 2536.103142] r8:00000000 r7:80c67a40 r6:80daa200 r5:80daa258 r4:000000ce r3:80d01f50 [ 2536.103154] [<80153750>] (cpu_startup_entry) from [<808cab48>] (rest_init+0xbc/0xc0) [ 2536.103169] [<808caa8c>] (rest_init) from [<80c00bd8>] (arch_call_rest_init+0x18/0x1c) [ 2536.103175] r4:80d04f48 r3:80c96260 [ 2536.103187] [<80c00bc0>] (arch_call_rest_init) from [<80c01090>] (start_kernel+0x4b4/0x4e8) [ 2536.103198] [<80c00bdc>] (start_kernel) from [<00000000>] (0x0) [ 2536.103204] ---[ end trace 694e100ebaed3721 ]--- [ 2536.137096] enc28j60 spi0.0 eth0: link down [ 2536.174263] enc28j60 spi0.0 eth0: multicast mode [ 2536.174817] enc28j60 spi0.0 eth0: multicast mode [ 2536.199726] enc28j60 spi0.0 eth0: link up - Half duplex [ 2536.223212] enc28j60 spi0.0 eth0: multicast mode [ 2536.370228] enc28j60 spi0.0 eth0: multicast mode [ 2536.370387] enc28j60 spi0.0 eth0: multicast mode [ 2542.255617] enc28j60 spi0.0 eth0: multicast mode `

Logs see above

Additional context I tested it with the current stable kernel and i testet it with the new kernel. Both have the same Problem. While i was searching i found a forum post https://www.raspberrypi.org/forums/viewtopic.php?t=232969. It doesn't contain any answer and no link to any bugreport.

I hope this is the right way of reporting this and i am sorry for any inconvenience. Feel free to correct me or ask for any additional information.

pelwell commented 4 years ago

I have been unable to reproduce the "transmit queue 0 timed out" failure, which makes it hard to diagnose.

ComexioDev commented 4 years ago

Can i give you any additional information to help you reproduce?

pelwell commented 4 years ago

That's so hard to say - you have given a fairly full, clear report. Perhaps you could say something about the other hardware attached?

ComexioDev commented 4 years ago

/boot/config:

Display

hdmi_force_hotplug=1 dtoverlay=dpi24 overscan_left=0 overscan_right=0 overscan_top=0 overscan_bottom=0 framebuffer_width=800 framebuffer_height=445 framebuffer_aspect=-1 enable_dpi_lcd=1 display_default_lcd=1 dpi_group=2 dpi_mode=87 dpi_output_format=0x6f007 dpi_timings=800 0 40 48 88 480 0 13 3 32 0 0 0 60 0 32000000 6

Ethernet

dtparam=spi=on dtoverlay=spi-gpio35-39-com dtoverlay=enc28j60,int_pin=35

Touchscreen

dtparam=i2c_vc=on dtoverlay=i2c0,pins_44_45=1 dtoverlay=touchScreen

As you can see, i use spi and i²c.

The enc28j60 int_pin is wired to pin 35, so i had to modify the gpio35-39 overlay to take pin 40 instead of pin 35.

pelwell commented 4 years ago

The touchScreen overlay isn't a standard one - what's in it, or at least how is the touchscreen device attached?

ComexioDev commented 4 years ago

Its a Focaltech touchscreen with the following overlay, connected with i²c on GPIO 44-45:

/dts-v1/;
/plugin/;
/ {
        compatible = "brcm,bcm2835", "brcm,bcm2708", "brcm,bcm2709";
        fragment@0 {
                target = <&i2c0>;
                __overlay__ {
            #address-cells = <1>;
                        #size-cells = <0>;

                         polytouch: edt-ft5x06@38 {
                                compatible = "edt,edt-ft5406", "edt,edt-ft5x06";
                                reg = <0x38>;
                                pinctrl-names = "default";
                                interrupt-parent = <&gpio>;
                                interrupts = <42 2>; /* rising edge */
                                touchscreen-size-y = <800>;
                                touchscreen-size-x = <480>;
                                touchscreen-swapped-x-y;
                                reset-gpios = <&gpio 43 1>; /* active low */
                        };
                };
        };
};
FD- commented 3 years ago

I'm seeing the same problem.

Similar to @ComexioDev I also have additional devices attached to the Pi: I have 3 devices on the I2C bus and am using both SPI interfaces (one to an STM32 and one to the ENC28J60). All of them are running off the Pi's 3v3 rail, so maybe high strain on that rail could play a role?

I'm on a Pi 3A+ using a 4.19.97 kernel (newest Raspbian Lite image when obtained from the official website back in March).

bhjelstrom commented 2 years ago

Still an issue on 5.15.32: [ 2891.055731] WARNING: CPU: 0 PID: 0 at net/sched/sch_generic.c:477 dev_watchdog+0x2fc/0x300 [ 2891.068407] NETDEV WATCHDOG: eth0 (enc28j60): transmit queue 0 timed out [ 2891.076666] Modules linked in: sha256_generic cfg80211 rfkill enc28j60 8021q spidev garp stp llc bcm2835_codec(C) bcm2835_isp(C) v4l2_mem2mem bcm2835_v4l2(C) bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common raspberrypi_hwmon dwc2 videodev roles vc_sm_cma(C) mc spi_bcm2835 uio_pdrv_genirq uio fixed sch_fq_codel drm fuse drm_panel_orientation_quirks backlight ipv6 [ 2891.123514] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G C 5.15.32-v7 #1 [ 2891.135528] Hardware name: BCM2835 [ 2891.140376] Backtrace: [ 2891.144244] [<80a53438>] (dump_backtrace) from [<80a5367c>] (show_stack+0x20/0x24) [ 2891.154744] r7:000001dd r6:00000000 r5:80d1933c r4:60000113 [ 2891.161903] [<80a5365c>] (show_stack) from [<80a57c80>] (dump_stack_lvl+0x70/0x94) [ 2891.172520] [<80a57c10>] (dump_stack_lvl) from [<80a57cbc>] (dump_stack+0x18/0x1c) [ 2891.183254] r7:000001dd r6:80927194 r5:00000009 r4:80d980b4 [ 2891.190520] [<80a57ca4>] (dump_stack) from [<8011fcfc>] (warn+0xfc/0x114) [ 2891.199104] [<8011fc00>] (warn) from [<80a53c70>] (warn_slowpath_fmt+0xa8/0xe8) [ 2891.209726] r7:80927194 r6:000001dd r5:80d980b4 r4:80d98078 [ 2891.216956] [<80a53bcc>] (warn_slowpath_fmt) from [<80927194>] (dev_watchdog+0x2fc/0x300) [ 2891.228230] r8:00000000 r7:82599000 r6:81003d00 r5:825992e8 r4:00000000 [ 2891.236498] [<80926e98>] (dev_watchdog) from [<801aa3b8>] (call_timer_fn+0x40/0x18c) [ 2891.247330] r9:81000000 r8:0003f420 r7:00000100 r6:80926e98 r5:825992e8 r4:825992e8 [ 2891.258226] [<801aa378>] (call_timer_fn) from [<801ab8c4>] (run_timer_softirq+0x630/0x6b4) [ 2891.269852] r8:0003f420 r7:00000000 r6:81138ce4 r5:825992e8 r4:81001dc4 [ 2891.278248] [<801ab294>] (run_timer_softirq) from [<80101498>] (do_softirq+0x190/0x440) [ 2891.289805] r10:81000000 r9:00000282 r8:81137da0 r7:00000100 r6:00000001 r5:00000002 [ 2891.300998] r4:81003084 [ 2891.305129] [<80101308>] (__do_softirq) from [<80126ad8>] (irq_exit+0xd8/0x120) [ 2891.314130] r10:80f54a60 r9:81000000 r8:81137da0 r7:00000001 r6:00000000 r5:00000000 [ 2891.325238] r4:ffffe000 [ 2891.329350] [<80126a00>] (irq_exit) from [<80188a80>] (handle_domain_irq+0x70/0x88) [ 2891.340226] r5:00000000 r4:80f9210c [ 2891.345369] [<80188a10>] (handle_domain_irq) from [<80101268>] (bcm2836_arm_irqchip_handle_irq+0x5c/0x60) [ 2891.358115] r7:81001f04 r6:ffffffff r5:60000013 r4:80108974 [ 2891.365352] [<8010120c>] (bcm2836_arm_irqchip_handle_irq) from [<80100aec>] (irq_svc+0x4c/0x64) [ 2891.377351] Exception stack(0x81001ed0 to 0x81001f18) [ 2891.383943] 1ec0: 00b1a34c 00000000 00000001 80119700 [ 2891.395124] 1ee0: 81000000 00000000 81005058 810050a8 81137da0 80d0f7bc 80f54a60 81001f2c [ 2891.406289] 1f00: 81001f30 81001f20 80108970 80108974 60000013 ffffffff [ 2891.414421] [<8010892c>] (arch_cpu_idle) from [<80a63ea4>] (default_idle_call+0x40/0xd8) [ 2891.425525] [<80a63e64>] (default_idle_call) from [<8015baf0>] (do_idle+0x10c/0x16c) [ 2891.436369] [<8015b9e4>] (do_idle) from [<8015be84>] (cpu_startup_entry+0x28/0x2c) [ 2891.447126] r9:81150000 r8:00000000 r7:ffffffff r6:81005000 r5:80f54a60 r4:000000e4 [ 2891.458102] [<8015be5c>] (cpu_startup_entry) from [<80a5b694>] (rest_init+0xc8/0xd0) [ 2891.469196] [<80a5b5cc>] (rest_init) from [<80f00c24>] (arch_call_rest_init+0x18/0x1c) [ 2891.480500] r5:80f54a60 r4:81150068 [ 2891.485727] [<80f00c0c>] (arch_call_rest_init) from [<80f0137c>] (start_kernel+0x6c4/0x710) [ 2891.497423] [<80f00cb8>] (start_kernel) from [<00000000>] (0x0) [ 2891.505070] ---[ end trace 6103e215c512cb35 ]--- [ 2891.540906] enc28j60 spi0.0 eth0: link down [ 2891.550357] enc28j60 spi0.0 eth0: link down [ 2891.611752] enc28j60 spi0.0 eth0: link up - Half duplex [ 2891.636598] enc28j60 spi0.0 eth0: multicast mode [ 2897.335100] enc28j60 spi0.0 eth0: multicast mode

image

BushyTaa commented 2 years ago

I was also seeing the same issue with a Zero 2 W. The timeouts were occuring when enc28j60's interrupt was triggered off by a program using switching the same pin low (GPIO 25 in my case).

gerwsx commented 5 months ago

I tried both enc28j60 and w5500 on a Raspberry Zero 2W with latest Raspberry Pi OS (bookworm). Both seem to show this issue (latest updates applied, 6.1.0-rpi7-rpi-v8 #1 SMP PREEMPT Debian 1:6.1.63-1+rpt1 (2023-11-24) aarch64 GNU/Linux). I can easily reproduce it by sending pings in a timely manner.

mtr -i 0.5 -s 1400 -n -o "LSRD NBAW X" hostonmynetwork

When executing with lower/higher intervals(-i) the issue is harder to reproduce or doesn't happen at all.

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        ether xx:xx:xx:xx:xx:xx  txqueuelen 1000  (Ethernet)
        RX packets 706626  bytes 461926722 (440.5 MiB)
        RX errors 0  dropped 147456 overruns 0  frame 0
        TX packets 261872  bytes 358885678 (342.2 MiB)
        TX errors 2  dropped 0 overruns 0  carrier 0  collisions 0

[56819.328223] NETDEV WATCHDOG: eth0 (w5100): transmit queue 0 timed out
[56819.328334] WARNING: CPU: 1 PID: 0 at net/sched/sch_generic.c:525 dev_watchdog+0x278/0x280
[56819.328372] Modules linked in: sctp ip6_udp_tunnel udp_tunnel rfkill snd_bcm2835(C) binfmt_misc snd_pcm bcm2835_v4l2(C) bcm2835_isp(C) videobuf2_vmalloc raspberrypi_hwmon bcm2835_codec(C) bcm2835_mmal_vchiq(C) snd_timer v4l2_mem2mem videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 videobuf2_common videodev snd mc vc_sm_cma(C) raspberrypi_gpiomem uio_pdrv_genirq uio i2c_dev drm fuse dm_mod drm_panel_orientation_quirks backlight ip_tables x_tables ipv6 w5100_spi w5100 spidev spi_bcm2835
[56819.328626] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G         C         6.1.0-rpi7-rpi-v8 #1  Debian 1:6.1.63-1+rpt1
[56819.328644] Hardware name: Raspberry Pi Zero 2 W Rev 1.0 (DT)
[56819.328653] pstate: 60000005 (nZCv daif -PAN -UAO -TCO -DIT -SSBS BTYPE=--)
[56819.328669] pc : dev_watchdog+0x278/0x280
[56819.328684] lr : dev_watchdog+0x278/0x280
[56819.328698] sp : ffffffc00800bdc0
[56819.328705] x29: ffffffc00800bdc0 x28: ffffffe222c612c4 x27: ffffffc00800bee0
[56819.328732] x26: ffffffe223345008 x25: 0000000000000000 x24: ffffffe22366d898
[56819.328758] x23: ffffffe223666000 x22: 0000000000000000 x21: ffffff80027ea3dc
[56819.328784] x20: ffffff80027ea000 x19: ffffff80027ea488 x18: ffffffffffffffff
[56819.328809] x17: ffffff9df3864000 x16: ffffffc008008000 x15: ffffffc00800b9b0
[56819.328835] x14: ffffffe223894a05 x13: 74756f2064656d69 x12: 7420302065756575
[56819.328860] x11: 712074696d736e61 x10: ffffffe2236e47e8 x9 : ffffffe22230eb68
[56819.328885] x8 : 00000000ffffefff x7 : ffffffe2236e47e8 x6 : 80000000fffff000
[56819.328910] x5 : ffffff8016baba10 x4 : 0000000000000040 x3 : 0000000000000001
[56819.328934] x2 : 0000000000000000 x1 : 0000000000000000 x0 : ffffff8001e60000
[56819.328959] Call trace:
[56819.328966]  dev_watchdog+0x278/0x280
[56819.328982]  call_timer_fn+0x3c/0x1cc
[56819.329001]  __run_timers+0x258/0x314
[56819.329018]  run_timer_softirq+0x28/0x4c
[56819.329034]  __do_softirq+0x170/0x468
[56819.329049]  ____do_softirq+0x18/0x24
[56819.329065]  call_on_irq_stack+0x24/0x54
[56819.329082]  do_softirq_own_stack+0x24/0x3c
[56819.329098]  __irq_exit_rcu+0xb8/0xe0
[56819.329119]  irq_exit_rcu+0x18/0x40
[56819.329138]  el1_interrupt+0x38/0x70
[56819.329157]  el1h_64_irq_handler+0x18/0x2c
[56819.329175]  el1h_64_irq+0x64/0x68
[56819.329187]  arch_cpu_idle+0x18/0x2c
[56819.329206]  default_idle_call+0x6c/0x1a8
[56819.329222]  do_idle+0x260/0x2a0
[56819.329237]  cpu_startup_entry+0x3c/0x44
[56819.329251]  secondary_start_kernel+0x130/0x154
[56819.329272]  __secondary_switched+0xb0/0xb4
[56819.329294] ---[ end trace 0000000000000000 ]---