Met eth disconnect when recv udp package in hikey970


#1

hi,
I use hikey 970 to recv udp package from sensor, about 1MByte/s.
I find eth port will down 2-3 seconds sometime.
tried with debian and ubuntu images, same issue. even dont’s launch any program. just let sensor send package to the 970’s ip address.
when I use USB-ETH device, don’t meet this issue any more.
so I think it should be some issue of ETH or PCIE driver, Anyone can help?


#2

A few of questions:

  1. Which OS build are you using?
  2. Is the issue specific to your use-case, or does the network disconnection occurs during other workloads, say a large download using wget.
  3. Can you post the output of dmesg while the issue occurs?
  4. Can you also post the output of tcpdump -i <eth_interface_name> udp port <port number> -vv -X

Thanks


#3

hi,
@ric96

  1. I’ve tried ubuntu and debian from lemaker’s site and also tried debian from hiphope. same issue.
  2. when I download large file with wget(tcp), also met this issue. (I am using bmon to monitor package)
  3. below is the dmesg
    [ 1.513980] [cambricon_ipu_init]: platform device and driver register!
    [ 1.514096] [cambricon_ipu_probe]: Initializing IPU device!
    [ 1.514106] [cambricon_ipu_probe]:IPU FIFO_TaskElements init success, queue size: 4096
    [ 1.514157] [cambricon_ipu_probe]: platform_get_irq_byname:65
    [ 1.514186] ff400000.cambricon-ipu supply vipu not found, using dummy regulator
    [ 1.514226] [cambricon_ipu_probe]:Get regulator ip succuss! dev-num-resouce:3
    [ 1.514294] [ipu_get_feature_tree]: the platform is kirin970_cs
    [ 1.514406] [cambricon_ipu_probe]: IPU Require IRQ Succeeded
    [ 1.514697] [ipu_clock_init]: IPU_DEBUG vcodecbus_clk_ptr is fffffffffffffffe
    [ 1.514706] [ipu_clock_init]: get clk rate done, start clk rate:900000000, stop clk rate:332000000
    [ 1.514745] of_get_iova_info:start_addr 0x40000, size 0x80000000 align 0x40000
    [ 1.522016] [ipu_enable_iommu] success,ipu_smmu_domain=0xffff8001b7e31c30, dev->bus is 0xffff000009694880
    [ 1.522102] [cambricon_ipu_probe]: Succeeded to initialize ipu device.
    [ 1.522169] IFO:<493:VENC_DRV_ModInit>enter VENC_DRV_ModInit()

[ 1.530026] IFO:<429:VENC_DRV_Probe>omxvenc prepare to probe

[ 1.537278] IFO:<368:VENC_DRV_SetupCdev>enter VENC_DRV_SetupCdev()

[ 1.545259] IFO:<399:VENC_DRV_SetupCdev>exit VENC_DRV_SetupCdev()

[ 1.552947] in hisi_ion_enable_iommu start
[ 1.552949] ion domain already init return domain
[ 1.557701] IFO:<131:Venc_GetDtsConfigInfo>venc_clk_rate: highRate:554000000, normalRate:450000000, lowRate:238000000

[ 1.569885] ERR:<137:Venc_GetDtsConfigInfo>get venc qos mode failed set default

[ 1.578776] IFO:<143:Venc_GetDtsConfigInfo>SmmuPageBaseAddr is 0xffff000008e22c38

[ 1.582689] sd 0:0:0:3: [sdd] 15616000 4096-byte logical blocks: (64.0 GB/59.6 GiB)
[ 1.583050] sd 0:0:0:3: [sdd] Write Protect is off
[ 1.583053] sd 0:0:0:3: [sdd] Mode Sense: 00 32 00 10
[ 1.583160] sd 0:0:0:3: [sdd] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 1.585897] sdd: sdd1 sdd2 sdd3 sdd4 sdd5 sdd6 sdd7 sdd8 sdd9 sdd10 sdd11 sdd12
[ 1.587709] sd 0:0:0:3: [sdd] Attached SCSI disk
[ 1.587838] IFO:<458:VENC_DRV_Probe>omxvenc probe successfully

[ 1.595266] IFO:<429:VENC_DRV_Probe>omxvenc prepare to probe

[ 1.602492] IFO:<432:VENC_DRV_Probe>venc device detected already

[ 1.610078] IFO:<506:VENC_DRV_ModInit>success

[ 1.616001] IFO:<510:VENC_DRV_ModInit>exit VENC_DRV_ModInit()

[ 1.623559] in hisi_ion_enable_iommu start
[ 1.623560] ion domain already init return domain
[ 1.628324] Already probe omxvdec
[ 1.628996] hisi_i2s e804f800.hisi_i2s: Registered as hisi_i2s
[ 1.629117] u32 classifier
[ 1.629118] Actions configured
[ 1.629122] Netfilter messages via NETLINK v0.30.
[ 1.629333] nf_conntrack version 0.5.0 (65536 buckets, 262144 max)
[ 1.629432] ctnetlink v0.93: registering with nfnetlink.
[ 1.629621] xt_time: kernel timezone is -0000
[ 1.629721] ip_tables: © 2000-2006 Netfilter Core Team
[ 1.629775] arp_tables: arp_tables: © 2002 David S. Miller
[ 1.629800] Initializing XFRM netlink socket
[ 1.629945] NET: Registered protocol family 10
[ 1.630292] mip6: Mobile IPv6
[ 1.630300] ip6_tables: © 2000-2006 Netfilter Core Team
[ 1.630861] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 1.631306] NET: Registered protocol family 17
[ 1.631321] NET: Registered protocol family 15
[ 1.631324] can: controller area network core (rev 20120528 abi 9)
[ 1.631363] NET: Registered protocol family 29
[ 1.631373] can: raw protocol (rev 20120528)
[ 1.631377] can: broadcast manager protocol (rev 20161123 t)
[ 1.631383] can: netlink gateway (rev 20130117) max_hops=1
[ 1.631795] Bluetooth: RFCOMM TTY layer initialized
[ 1.631802] Bluetooth: RFCOMM socket layer initialized
[ 1.631815] Bluetooth: RFCOMM ver 1.11
[ 1.631823] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 1.631829] Bluetooth: HIDP socket layer initialized
[ 1.632061] 9pnet: Installing 9P2000 support
[ 1.632411] Registered cp15_barrier emulation handler
[ 1.632627] Registered setend emulation handler
[ 1.632811] registered taskstats version 1
[ 1.640229] hisi_i2s e804f800.hisi_i2s: Failed to get DMA channel capabilities, falling back to period counting: -6
[ 1.640233] asoc-simple-card soc:sound: adv7511 <-> e804f800.hisi_i2s mapping ok
[ 1.640719] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 1.640728] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
[ 1.640893] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220ffe5 hci version 0x110 quirks 0x00010010
[ 1.640914] xhci-hcd xhci-hcd.1.auto: irq 354, io mem 0xff100000
[ 1.640982] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 1.640984] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.640985] usb usb1: Product: xHCI Host Controller
[ 1.640987] usb usb1: Manufacturer: Linux 4.9.78-147538-g244928755bbe xhci-hcd
[ 1.640988] usb usb1: SerialNumber: xhci-hcd.1.auto
[ 1.641275] hub 1-0:1.0: USB hub found
[ 1.641285] hub 1-0:1.0: 1 port detected
[ 1.641358] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[ 1.641363] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
[ 1.641366] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.1 Enhanced SuperSpeed
[ 1.641389] usb usb2: We don’t know the algorithms for LPM for this host, disabling LPM.
[ 1.641415] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[ 1.641416] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.641418] usb usb2: Product: xHCI Host Controller
[ 1.641419] usb usb2: Manufacturer: Linux 4.9.78-147538-g244928755bbe xhci-hcd
[ 1.641420] usb usb2: SerialNumber: xhci-hcd.1.auto
[ 1.641589] hub 2-0:1.0: USB hub found
[ 1.641597] hub 2-0:1.0: 1 port detected
[ 1.642204] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 1.642320] hctosys: unable to open rtc device (rtc0)
[ 1.642322] [ISP] ov5640_main_module_init: enter!
[ 1.642366] [ISP] ov5640_main_probe: enter!
[ 1.734854] wlcore: wl18xx HW: 183x or 180x, PG 2.2 (ROM 0x11)
[ 1.740616] wlcore: loaded
[ 1.892943] [ISP] isp_i2c_read_clear_intrbits: ABRT_SOURCE 0x1000001
[ 1.899343] [ISP] isp_i2c_xfer: I2C abort
[ 1.903371] [ISP] isp_i2c_write_reg: write reg fail
[ 1.908359] [ISP] ov5640_main_power: could not set init registers
[ 1.914481] [ISP] isp_i2c_read_reg: read[0x300a]
[ 1.922122] [ISP] isp_i2c_read_clear_intrbits: ABRT_SOURCE 0x1000001
[ 1.928486] [ISP] isp_i2c_xfer: I2C abort
[ 1.932508] [ISP] isp_i2c_read_reg: read reg fail
[ 1.937039] [ISP] ov5640_main_get_chip_id: get chip id high[255] fail
[ 1.943510] [ISP] ov5640_main_probe: chip id is 0x0
[ 1.948393] [ISP] ov5640_main_probe: chip id is 0x0, probe failed
[ 1.954513] hisi-ov5640_main: probe of e8400000.isp:hisi,ov5640_main failed with error -1
[ 1.954584] [ISP] ov5640_main_module_init: exit!
[ 1.954587] [ISP] ov5640_sub_module_init: enter!
[ 1.954660] [ISP] ov5640_sub_probe: enter!
[ 1.966198] usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
[ 1.986989] usb 2-1: New USB device found, idVendor=0451, idProduct=8140
[ 1.986991] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 1.993968] hub 2-1:1.0: USB hub found
[ 1.994239] hub 2-1:1.0: 4 ports detected
[ 2.086140] [drm] client change to HDMI
[ 2.086141] [drm] hotplug_event!!!
[ 2.086895] [drm] connected : regmap_read val = 0xf0
[ 2.086896] [drm] hdmi connector status = 1
[ 2.105917] usb 1-1: new high-speed USB device number 2 using xhci-hcd
[ 2.196935] [ISP] isp_i2c_read_clear_intrbits: ABRT_SOURCE 0x1000001
[ 2.203348] [ISP] isp_i2c_xfer: I2C abort
[ 2.207397] [ISP] isp_i2c_write_reg: write reg fail
[ 2.212469] [ISP] ov5640_sub_power: could not set init registers
[ 2.218500] [ISP] isp_i2c_read_reg: read[0x300a]
[ 2.226148] [ISP] isp_i2c_read_clear_intrbits: ABRT_SOURCE 0x1000001
[ 2.232543] [ISP] isp_i2c_xfer: I2C abort
[ 2.236592] [ISP] isp_i2c_read_reg: read reg fail
[ 2.241127] [ISP] ov5640_sub_get_chip_id: get chip id high[255] fail
[ 2.247512] [ISP] ov5640_sub_probe: chip id is 0x0, probe failed
[ 2.253541] hisi-ov5640_sub: probe of e8400000.isp:hisi,ov5640_sub failed with error -1
[ 2.253609] [ISP] ov5640_sub_module_init: exit!
[ 2.253821] clk: Not disabling unused clocks
[ 2.253840] wlan-en-regulator: disabling
[ 2.253864] <[hisi_ip_to_atf_is_enabled]:regulator_id=0>
[ 2.259205] <[hisi_ip_to_atf_is_enabled]:regulator_id=1>
[ 2.264565] <[hisi_ip_to_atf_is_enabled]:regulator_id=8>
[ 2.269902] <[hisi_ip_to_atf_is_enabled]:regulator_id=2>
[ 2.275261] <[hisi_ip_to_atf_is_enabled]:regulator_id=3>
[ 2.280605] <[hisi_ip_to_atf_is_enabled]:regulator_id=4>
[ 2.285944] <[hisi_ip_to_atf_is_enabled]:regulator_id=5>
[ 2.291275] <[hisi_ip_to_atf_is_enabled]:regulator_id=6>
[ 2.296607] usb 1-1: New USB device found, idVendor=0451, idProduct=8142
[ 2.296610] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=1
[ 2.296612] usb 1-1: SerialNumber: F107106914FF
[ 2.296673] <[hisi_ip_to_atf_is_enabled]:regulator_id=7>
[ 2.302069] ALSA device list:
[ 2.302071] #0: hikey-hdmi
[ 2.302167] uart-pl011 fff32000.serial: no DMA platform data
[ 2.305247] Checking mode 1920x1080@60 clock: 148500…
[ 2.305249] OK
[ 2.305251] Checking mode 1920x1080@50 clock: 148500…
[ 2.305252] OK
[ 2.305253] Checking mode 1280x720@60 clock: 74250…
[ 2.305254] OK
[ 2.305255] Checking mode 1280x720@50 clock: 74250…
[ 2.305256] OK
[ 2.305257] Checking mode 800x600@60 clock: 40000…
[ 2.305258] OK
[ 2.305259] Checking mode 1152x864@75 clock: 108000…
[ 2.305259] BAD
[ 2.305260] Checking mode 1280x1024@60 clock: 108000…
[ 2.305261] BAD
[ 2.305262] Checking mode 640x480@75 clock: 31500…
[ 2.305263] BAD
[ 2.305264] Checking mode 640x480@60 clock: 25175…
[ 2.305264] BAD
[ 2.305265] Checking mode 720x400@70 clock: 28320…
[ 2.305265] BAD
[ 2.305266] Checking mode 1280x1024@75 clock: 135000…
[ 2.305267] BAD
[ 2.305268] Checking mode 1024x768@75 clock: 78750…
[ 2.305268] BAD
[ 2.305269] Checking mode 1024x768@60 clock: 65000…
[ 2.305270] BAD
[ 2.305270] Checking mode 800x600@75 clock: 49500…
[ 2.305271] BAD
[ 2.305272] Checking mode 720x480@60 clock: 27000…
[ 2.305272] BAD
[ 2.305273] Checking mode 720x576@50 clock: 27000…
[ 2.305274] BAD
[ 2.305275] Checking mode 1920x1080@60 clock: 148352…
[ 2.305275] BAD
[ 2.305276] Checking mode 1280x720@60 clock: 74176…
[ 2.305277] BAD
[ 2.305278] Checking mode 640x480@60 clock: 25200…
[ 2.305278] BAD
[ 2.305279] Checking mode 720x480@60 clock: 27027…
[ 2.305280] BAD
[ 2.305384] [drm] +.
[ 2.305384] [drm] -.
[ 2.305473] [drm] dss_pri_clk:[300000000]->[300000000].
[ 2.305518] [drm] dss_mmbuf_clk:[238000000]->[237142858].
[ 2.305552] [drm] mode->clock(org) = 148500
[ 2.305554] [drm] HDMI real need clock = 144000000
[ 2.305557] [drm] postdiv1=0x1, POSTDIV2=0x1
[ 2.305601] [drm] dss_pxl0_clk [144000000]->[144000000]
[ 2.305667] [drm] isr_s1 = 0x10000!
[ 2.305672] [drm] isr_s2 = 0x11!
[ 2.313499] hub 1-1:1.0: USB hub found
[ 2.313546] hub 1-1:1.0: 4 ports detected
[ 2.348819] EXT4-fs (sdd12): couldn’t mount as ext3 due to feature incompatibilities
[ 2.359783] EXT4-fs (sdd12): mounted filesystem with ordered data mode. Opts: (null)
[ 2.359798] VFS: Mounted root (ext4 filesystem) on device 8:60.
[ 2.360750] devtmpfs: mounted
[ 2.361774] Freeing unused kernel memory: 4160K
[ 2.365976] usb 2-1.2: new SuperSpeed USB device number 3 using xhci-hcd
[ 2.384334] [drm] Expected : lane_clock = 864 M
[ 2.384501] [drm] burst_mode = 0x0 (DSI_NON_BURST_SYNC_PULSES => 0)
[ 2.384505] [drm] hsa_time=33, hbp_time=111, hline_time=1650
[ 2.384506] [drm] lane_byte_clk=108000000, pixel_clk=144000000
[ 2.386340] usb 2-1.2: New USB device found, idVendor=0424, idProduct=5734
[ 2.386342] usb 2-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=0
[ 2.386345] usb 2-1.2: Product: USB5734
[ 2.386347] usb 2-1.2: Manufacturer: Microchip Tech
[ 2.393519] hub 2-1.2:1.0: USB hub found
[ 2.393568] hub 2-1.2:1.0: 4 ports detected
[ 2.399875] systemd[1]: System time before build time, advancing clock.
[ 2.405938] (stk) :ldisc installation timeout
[ 2.410130] (stk) :ldisc_install = 0
[ 2.431068] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN)
[ 2.431267] systemd[1]: Detected architecture arm64.
[ 2.431472] systemd[1]: Set hostname to .
[ 2.490925] systemd[1]: Reached target Encrypted Volumes.
[ 2.491012] systemd[1]: Reached target Remote File Systems (Pre).
[ 2.491082] systemd[1]: Listening on Journal Socket (/dev/log).
[ 2.491095] systemd[1]: Reached target Remote File Systems.
[ 2.491139] systemd[1]: Listening on udev Kernel Socket.
[ 2.491152] systemd[1]: Reached target Swap.
[ 2.491198] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 2.565190] systemd-journald[1933]: Received request to flush runtime journal from PID 1
[ 2.575572] hisi_thermal fff30000.tsensor: THERMAL ALARM: 65600 > 65000
[ 2.633956] usb 1-1.2: new high-speed USB device number 3 using xhci-hcd
[ 2.735930] usb 1-1.2: New USB device found, idVendor=0424, idProduct=2734
[ 2.735939] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 2.735941] usb 1-1.2: Product: USB2734
[ 2.735942] usb 1-1.2: Manufacturer: Microchip Tech
[ 2.761731] hub 1-1.2:1.0: USB hub found
[ 2.761786] hub 1-1.2:1.0: 5 ports detected
[ 2.782286] r8168 0000:06:00.0 enp6s0: renamed from eth0
[ 2.805617] [drm] bl_level is 255
[ 2.805634] [drm] bl_level=255.
[ 2.993957] [drm] +.
[ 2.993971] [drm] dss_pwm_clk clk_enable successed, ret=0!
[ 3.010779] hisi_thermal fff30000.tsensor: THERMAL ALARM stopped: 61064 < 65000
[ 3.201960] [drm] bl_level is 255
[ 3.201966] [drm] bl_level=255.
[ 3.216964] [drm] hotplug_event!!!
[ 3.217255] vgaarb: this pci device is not a vga device
[ 3.425011] random: crng init done
[ 3.430132] (stk) : timed out waiting for ldisc to be un-installed
[ 3.459949] [drm] connected : regmap_read val = 0xf0
[ 3.459960] [drm] hdmi connector status = 1
[ 3.469223] Checking mode 1920x1080@60 clock: 148500…
[ 3.469227] OK
[ 3.469229] Checking mode 1920x1080@50 clock: 148500…
[ 3.469231] OK
[ 3.469232] Checking mode 1280x720@60 clock: 74250…
[ 3.469233] OK
[ 3.469235] Checking mode 1280x720@50 clock: 74250…
[ 3.469237] OK
[ 3.469239] Checking mode 800x600@60 clock: 40000…
[ 3.469240] OK
[ 3.469242] Checking mode 2048x1080@60 clock: 147180…
[ 3.469243] BAD
[ 3.469245] Checking mode 2048x1080@24 clock: 58230…
[ 3.469246] BAD
[ 3.469247] Checking mode 1152x864@75 clock: 108000…
[ 3.469249] BAD
[ 3.469251] Checking mode 1600x1200@60 clock: 162000…
[ 3.469252] BAD
[ 3.469254] Checking mode 1280x1024@60 clock: 108000…
[ 3.469255] BAD
[ 3.469257] Checking mode 640x480@75 clock: 31500…
[ 3.469258] BAD
[ 3.469259] Checking mode 640x480@60 clock: 25175…
[ 3.469260] BAD
[ 3.469262] Checking mode 720x400@70 clock: 28320…
[ 3.469264] BAD
[ 3.469266] Checking mode 1280x1024@75 clock: 135000…
[ 3.469267] BAD
[ 3.469269] Checking mode 1024x768@75 clock: 78750…
[ 3.469270] BAD
[ 3.469271] Checking mode 1024x768@60 clock: 65000…
[ 3.469272] BAD
[ 3.469274] Checking mode 800x600@75 clock: 49500…
[ 3.469276] BAD
[ 3.469278] Checking mode 720x480@60 clock: 27000…
[ 3.469279] BAD
[ 3.469281] Checking mode 720x576@50 clock: 27000…
[ 3.469282] BAD
[ 3.469283] Checking mode 1920x1080@60 clock: 148352…
[ 3.469285] BAD
[ 3.469286] Checking mode 1280x720@60 clock: 74176…
[ 3.469288] BAD
[ 3.469290] Checking mode 640x480@60 clock: 25200…
[ 3.469291] BAD
[ 3.469293] Checking mode 720x480@60 clock: 27027…
[ 3.469294] BAD
[ 3.472607] [drm] connected : regmap_read val = 0xf0
[ 3.472609] [drm] hdmi connector status = 1
[ 3.473720] Checking mode 1920x1080@60 clock: 148500…
[ 3.473723] OK
[ 3.473725] Checking mode 1920x1080@50 clock: 148500…
[ 3.473726] OK
[ 3.473728] Checking mode 1280x720@60 clock: 74250…
[ 3.473729] OK
[ 3.473731] Checking mode 1280x720@50 clock: 74250…
[ 3.473732] OK
[ 3.473734] Checking mode 800x600@60 clock: 40000…
[ 3.473735] OK
[ 3.473738] Checking mode 2048x1080@60 clock: 147180…
[ 3.473739] BAD
[ 3.473741] Checking mode 2048x1080@24 clock: 58230…
[ 3.473742] BAD
[ 3.473743] Checking mode 1152x864@75 clock: 108000…
[ 3.473744] BAD
[ 3.473746] Checking mode 1600x1200@60 clock: 162000…
[ 3.473748] BAD
[ 3.473750] Checking mode 1280x1024@60 clock: 108000…
[ 3.473751] BAD
[ 3.473753] Checking mode 640x480@75 clock: 31500…
[ 3.473754] BAD
[ 3.473755] Checking mode 640x480@60 clock: 25175…
[ 3.473757] BAD
[ 3.473758] Checking mode 720x400@70 clock: 28320…
[ 3.473759] BAD
[ 3.473761] Checking mode 1280x1024@75 clock: 135000…
[ 3.473763] BAD
[ 3.473765] Checking mode 1024x768@75 clock: 78750…
[ 3.473766] BAD
[ 3.473768] Checking mode 1024x768@60 clock: 65000…
[ 3.473769] BAD
[ 3.473770] Checking mode 800x600@75 clock: 49500…
[ 3.473771] BAD
[ 3.473773] Checking mode 720x480@60 clock: 27000…
[ 3.473775] BAD
[ 3.473777] Checking mode 720x576@50 clock: 27000…
[ 3.473778] BAD
[ 3.473780] Checking mode 1920x1080@60 clock: 148352…
[ 3.473781] BAD
[ 3.473782] Checking mode 1280x720@60 clock: 74176…
[ 3.473784] BAD
[ 3.473785] Checking mode 640x480@60 clock: 25200…
[ 3.473786] BAD
[ 3.473789] Checking mode 720x480@60 clock: 27027…
[ 3.473790] BAD
[ 3.480128] vgaarb: this pci device is not a vga device
[ 3.518115] usb 1-1.2.1: new low-speed USB device number 4 using xhci-hcd
[ 3.543163] (stk) :ldisc_install = 1
[ 3.625408] usb 1-1.2.1: New USB device found, idVendor=046d, idProduct=c31c
[ 3.625416] usb 1-1.2.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.625418] usb 1-1.2.1: Product: USB Keyboard
[ 3.625419] usb 1-1.2.1: Manufacturer: Logitech
[ 3.662380] input: Logitech USB Keyboard as /devices/platform/soc/soc:hisi_dwc3/ff100000.dwc3/xhci-hcd.1.auto/usb1/1-1/1-1.2/1-1.2.1/1-1.2.1:1.0/0003:046D:C31C.0001/input/input1
[ 3.726857] hid-generic 0003:046D:C31C.0001: input,hidraw0: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-xhci-hcd.1.auto-1.2.1/input0
[ 3.733907] input: Logitech USB Keyboard as /devices/platform/soc/soc:hisi_dwc3/ff100000.dwc3/xhci-hcd.1.auto/usb1/1-1/1-1.2/1-1.2.1/1-1.2.1:1.1/0003:046D:C31C.0002/input/input2
[ 3.794504] hid-generic 0003:046D:C31C.0002: input,hidraw1: USB HID v1.10 Device [Logitech USB Keyboard] on usb-xhci-hcd.1.auto-1.2.1/input1
[ 3.877943] usb 1-1.2.3: new low-speed USB device number 5 using xhci-hcd
[ 3.983833] usb 1-1.2.3: New USB device found, idVendor=046d, idProduct=c077
[ 3.983839] usb 1-1.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.983841] usb 1-1.2.3: Product: USB Optical Mouse
[ 3.983842] usb 1-1.2.3: Manufacturer: Logitech
[ 4.012410] input: Logitech USB Optical Mouse as /devices/platform/soc/soc:hisi_dwc3/ff100000.dwc3/xhci-hcd.1.auto/usb1/1-1/1-1.2/1-1.2.3/1-1.2.3:1.0/0003:046D:C077.0003/input/input3
[ 4.012896] hid-generic 0003:046D:C077.0003: input,hidraw2: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-xhci-hcd.1.auto-1.2.3/input0
[ 4.094151] usb 1-1.2.5: new high-speed USB device number 6 using xhci-hcd
[ 4.199745] usb 1-1.2.5: New USB device found, idVendor=0424, idProduct=274c
[ 4.199752] usb 1-1.2.5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 4.199753] usb 1-1.2.5: Product: Hub Controller
[ 4.199755] usb 1-1.2.5: Manufacturer: Microchip Tech
[ 4.203928] hid-generic 0003:0424:274C.0004: hiddev0,hidraw3: USB HID v1.10 Device [Microchip Tech Hub Controller] on usb-xhci-hcd.1.auto-1.2.5/input1
[ 4.549964] (stk) :ldisc installation timeout
[ 4.554185] (stk) :ldisc_install = 0
[ 5.573970] (stk) : timed out waiting for ldisc to be un-installed
[ 5.685723] (stk) :ldisc_install = 1
[ 6.694229] (stk) :ldisc installation timeout
[ 6.697131] (stk) :ldisc_install = 0
[ 7.718184] (stk) : timed out waiting for ldisc to be un-installed
[ 7.830052] (stk) :ldisc_install = 1
[ 8.523316] hisi_thermal fff30000.tsensor: THERMAL ALARM: 66032 > 65000
[ 8.837941] (stk) :ldisc installation timeout
[ 8.842149] (stk) :ldisc_install = 0
[ 9.095390] hisi_thermal fff30000.tsensor: THERMAL ALARM stopped: 60848 < 65000
[ 9.862120] (stk) : timed out waiting for ldisc to be un-installed
[ 9.975157] (stk) :ldisc_install = 1
[ 10.982192] (stk) :ldisc installation timeout
[ 10.985090] (stk) :ldisc_install = 0
[ 12.006672] (stk) : timed out waiting for ldisc to be un-installed
[ 12.118417] (stk) :ldisc_install = 1
[ 13.126668] (stk) :ldisc installation timeout
[ 13.129554] (stk) :ldisc_install = 0
[ 14.149934] (stk) : timed out waiting for ldisc to be un-installed
[ 14.156688] Bluetooth: st_register failed -22
[ 14.161316] (stc): chnl_id list empty :4
[ 14.161326] (stk) : st_kim_start
[ 14.266346] (stk) :ldisc_install = 1
[ 15.270727] (stk) :ldisc installation timeout
[ 15.275150] (stk) :ldisc_install = 0
[ 16.294031] (stk) : timed out waiting for ldisc to be un-installed
[ 16.407281] (stk) :ldisc_install = 1
[ 17.414721] (stk) :ldisc installation timeout
[ 17.419140] (stk) :ldisc_install = 0
[ 18.438734] (stk) : timed out waiting for ldisc to be un-installed
[ 18.552016] (stk) :ldisc_install = 1
[ 19.557970] (stk) :ldisc installation timeout
[ 19.562385] (stk) :ldisc_install = 0
[ 20.582721] (stk) : timed out waiting for ldisc to be un-installed
[ 20.696013] (stk) :ldisc_install = 1
[ 21.701972] (stk) :ldisc installation timeout
[ 21.706382] (stk) :ldisc_install = 0
[ 22.725979] (stk) : timed out waiting for ldisc to be un-installed
[ 22.839232] (stk) :ldisc_install = 1
[ 23.846711] (stk) :ldisc installation timeout
[ 23.851144] (stk) :ldisc_install = 0
[ 24.870732] (stk) : timed out waiting for ldisc to be un-installed
[ 24.984789] (stk) :ldisc_install = 1
[ 25.989981] (stk) :ldisc installation timeout
[ 25.994400] (stk) :ldisc_install = 0
[ 27.013951] (stk) : timed out waiting for ldisc to be un-installed
[ 27.022192] Bluetooth: st_register failed -22
[ 27.997101] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 28.124185] mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0)
[ 28.161191] mmc_host mmc1: Bus speed (slot 0) = 25000000Hz (slot req 25000000Hz, actual 25000000HZ div = 0)
[ 28.574673] wlcore: PHY firmware version: Rev 8.2.0.0.236
[ 28.689403] wlcore: firmware booted (Rev 8.9.0.0.69)
[ 28.723032] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 28.769242] IPv6: ADDRCONF(NETDEV_UP): enp6s0: link is not ready
[ 28.772607] enp6s0: 0xffff000009ae2000, 10:c1:72:fc:e4:57, IRQ 327
[ 28.843686] IPv6: ADDRCONF(NETDEV_UP): enp6s0: link is not ready
[ 28.980344] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 31.925193] r8168: enp6s0: link up
[ 31.925382] IPv6: ADDRCONF(NETDEV_CHANGE): enp6s0: link becomes ready
[ 185.989069] nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead.
[ 242.690556] INFO: task mailboxNormal:1807 blocked for more than 120 seconds.
[ 242.697686] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 242.704733] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 242.712684] mailboxNormal D 0 1807 2 0x00000000
[ 242.712702] Call trace:
[ 242.712737] [] __switch_to+0x8c/0xb0
[ 242.712758] [] __schedule+0x28c/0x68c
[ 242.712769] [] schedule+0x3c/0xa0
[ 242.712787] [] mailbox_receive_task+0x84/0xe8
[ 242.712798] [] kthread+0xf0/0x104
[ 242.712808] [] ret_from_fork+0x10/0x20
[ 242.712818] INFO: task mailboxHigh:1808 blocked for more than 120 seconds.
[ 242.719828] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 242.726828] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 242.734795] mailboxHigh D 0 1808 2 0x00000000
[ 242.734813] Call trace:
[ 242.734838] [] __switch_to+0x8c/0xb0
[ 242.734865] [] __schedule+0x28c/0x68c
[ 242.734875] [] schedule+0x3c/0xa0
[ 242.734892] [] mailbox_receive_task+0x84/0xe8
[ 242.734902] [] kthread+0xf0/0x104
[ 242.734912] [] ret_from_fork+0x10/0x20
[ 363.533597] INFO: task mailboxNormal:1807 blocked for more than 120 seconds.
[ 363.540720] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 363.547781] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 363.555753] mailboxNormal D 0 1807 2 0x00000000
[ 363.555770] Call trace:
[ 363.555803] [] __switch_to+0x8c/0xb0
[ 363.555823] [] __schedule+0x28c/0x68c
[ 363.555833] [] schedule+0x3c/0xa0
[ 363.555850] [] mailbox_receive_task+0x84/0xe8
[ 363.555862] [] kthread+0xf0/0x104
[ 363.555872] [] ret_from_fork+0x10/0x20
[ 363.555882] INFO: task mailboxHigh:1808 blocked for more than 120 seconds.
[ 363.562888] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 363.569889] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 363.577837] mailboxHigh D 0 1808 2 0x00000000
[ 363.577849] Call trace:
[ 363.577871] [] __switch_to+0x8c/0xb0
[ 363.577884] [] __schedule+0x28c/0x68c
[ 363.577893] [] schedule+0x3c/0xa0
[ 363.577904] [] mailbox_receive_task+0x84/0xe8
[ 363.577913] [] kthread+0xf0/0x104
[ 363.577923] [] ret_from_fork+0x10/0x20
[ 484.373912] INFO: task mailboxNormal:1807 blocked for more than 120 seconds.
[ 484.381047] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 484.388145] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 484.396107] mailboxNormal D 0 1807 2 0x00000000
[ 484.396125] Call trace:
[ 484.396160] [] __switch_to+0x8c/0xb0
[ 484.396180] [] __schedule+0x28c/0x68c
[ 484.396190] [] schedule+0x3c/0xa0
[ 484.396207] [] mailbox_receive_task+0x84/0xe8
[ 484.396219] [] kthread+0xf0/0x104
[ 484.396229] [] ret_from_fork+0x10/0x20
[ 484.396239] INFO: task mailboxHigh:1808 blocked for more than 120 seconds.
[ 484.403250] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 484.410237] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 484.418195] mailboxHigh D 0 1808 2 0x00000000
[ 484.418209] Call trace:
[ 484.418227] [] __switch_to+0x8c/0xb0
[ 484.418241] [] __schedule+0x28c/0x68c
[ 484.418251] [] schedule+0x3c/0xa0
[ 484.418263] [] mailbox_receive_task+0x84/0xe8
[ 484.418273] [] kthread+0xf0/0x104
[ 484.418283] [] ret_from_fork+0x10/0x20
[ 522.455782] r8168: enp6s0: link down
[ 526.598229] r8168: enp6s0: link up


#4

[ 878.957150] device enp6s0 entered promiscuous mode
[ 878.976675] device enp6s0 left promiscuous mode
[ 891.161981] device enp6s0 entered promiscuous mode
[ 891.185136] device enp6s0 left promiscuous mode
[ 900.870224] device enp6s0 entered promiscuous mode
[ 907.100388] hisi_thermal fff30000.tsensor: THERMAL ALARM: 65384 > 65000
[ 907.347861] hisi_thermal fff30000.tsensor: THERMAL ALARM stopped: 61064 < 65000
[ 907.353809] device enp6s0 left promiscuous mode
[ 1209.624987] r8168: enp6s0: link down
[ 1213.767827] r8168: enp6s0: link up
[ 1389.268118] [drm] +.
[ 1389.268392] [drm] dss_pxl0_clk:[238000000]->[237142858].
[ 1389.268395] [drm] +.
[ 1389.268604] [drm] dss_mmbuf_clk:[208000000]->[207500000].
[ 1389.270249] [drm] dss_pri_clk:[277000000]->[276666667].
[ 1389.270334] [drm:dss_disable_vblank] ERROR power is down! vblank disable fail
[ 1389.277836] [drm] +.
[ 1389.277841] [drm] -.
[ 1389.284558] [drm] dss_pri_clk:[300000000]->[300000000].
[ 1389.284779] [drm] dss_mmbuf_clk:[238000000]->[237142858].
[ 1389.295494] vgaarb: this pci device is not a vga device
[ 1434.952980] r8168: enp6s0: link down
[ 1439.095772] r8168: enp6s0: link up
[ 1689.321071] vgaarb: this pci device is not a vga device


#5

Thanks for the logs. I ran some tests using iperf in udp mode as well just to get some synthetic stress test results.

I only got this:

[Feb11 16:16] r8168: enp6s0: link down
[  +4.143793] r8168: enp6s0: link up

when pushing iperf to 800Mbps using iperf -c 192.168.100.25 -d -u -b 800m -t 120, which also resulted in packet loss showing up in the iperf output
0.0-122.5 sec 10.9 GBytes 766 Mbits/sec 0.054 ms 134503/8116294 (1.7%)

However when running iperf in default 1Mbps mode iperf -c 192.168.100.25 -d -u -t 120 I don’t see any packet loss
[ 3] 0.0-120.0 sec 15.0 MBytes 1.05 Mbits/sec 0.015 ms 0/10701 (0%)

I tried the same scenario with WiFi, with the same results except the link didn’t go down.

I also saw a lot of call trace similar to your output:

[ +23.211981] INFO: task mailboxNormal:1795 blocked for more than 120 seconds.
[  +0.007085]       Tainted: G S              4.9.78-147538-g244928755bbe #1
[  +0.008549] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  +0.009379] mailboxNormal   D    0  1795      2 0x00000000
[  +0.000010] Call trace:
[  +0.000021] [<ffff000008086188>] __switch_to+0x8c/0xb0
[  +0.000011] [<ffff000008c9b5c4>] __schedule+0x28c/0x68c
[  +0.000004] [<ffff000008c9ba00>] schedule+0x3c/0xa0
[  +0.000010] [<ffff000008a13b24>] mailbox_receive_task+0x84/0xe8
[  +0.000005] [<ffff0000080e828c>] kthread+0xf0/0x104
[  +0.000004] [<ffff000008083a30>] ret_from_fork+0x10/0x20
[  +0.000004] INFO: task mailboxHigh:1796 blocked for more than 120 seconds.
[  +0.006929]       Tainted: G S              4.9.78-147538-g244928755bbe #1
[  +0.006888] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  +0.007836] mailboxHigh     D    0  1796      2 0x00000000
[  +0.000004] Call trace:
[  +0.000009] [<ffff000008086188>] __switch_to+0x8c/0xb0
[  +0.000007] [<ffff000008c9b5c4>] __schedule+0x28c/0x68c
[  +0.000002] [<ffff000008c9ba00>] schedule+0x3c/0xa0
[  +0.000007] [<ffff000008a13b24>] mailbox_receive_task+0x84/0xe8
[  +0.000002] [<ffff0000080e828c>] kthread+0xf0/0x104
[  +0.000002] [<ffff000008083a30>] ret_from_fork+0x10/0x20

And these INFO: task mailboxNormal:1795 blocked for more than 120 seconds. messages do appear during network activity both on LAN and WiFi.

I’ll have to look a bit more into this.


#6

I would recommend updating the WL18xx driver.
We have specific WL1837MOD.
Updated to FW 8.9.0.0.79

Just replace the wl18xx-fw-4.bin.
The modules are in the kernel and I’m pretty sure you would only replace the .bin file to upgrade.

Running release
hikey970# strings /lib/firmware/ti-connectivity/wl18xx-fw-4.bin | grep Rev
FRev 8.9.0.0.76
FRev 8.2.0.0.240

I read that at one point in time the firmware identified some of the packets as an Attack so im not sure if that is related. Anyway easy enough to replace the wl18xx-fw-4.bin and try.

When i was building the driver I also found that some recommended flags were not set in the Kernel .Config.
You can see here for that info.
http://processors.wiki.ti.com/index.php/WL18xx_Platform_Integration_Guide

I would check the following .config settings.
CONFIG_NL80211_TESTMODE=y
CONFIG_WIRELESS_EXT=y
CONFIG_KEYS=y
CONFIG_CRYPTO_MICHAEL_MIC=y
CONFIG_NF_CONNTRACK_PROC_COMPAT=y
CONFIG_BRIDGE_NETFILTER=y
CONFIG_STP=y
CONFIG_BRIDGE=y
CONFIG_LLC=y
CONFIG_AVERAGE=y


#7

@ric96
@BitCountry907

my issue only happens on ETH port

below is my dmesg after upgrade wifi firmware.
seems eth would stop working about 4s sometime.

[ 28.006135] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 28.136173] mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0)
[ 28.174702] mmc_host mmc1: Bus speed (slot 0) = 25000000Hz (slot req 25000000Hz, actual 25000000HZ div = 0)
[ 28.588443] wlcore: PHY firmware version: Rev 8.2.0.0.242
[ 28.703148] wlcore: firmware booted (Rev 8.9.0.0.79)
[ 28.735978] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 28.792478] IPv6: ADDRCONF(NETDEV_UP): enp6s0: link is not ready
[ 28.794648] enp6s0: 0xffff000009ae2000, 10:c1:72:fc:e4:57, IRQ 327
[ 28.866979] IPv6: ADDRCONF(NETDEV_UP): enp6s0: link is not ready
[ 29.012058] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 31.956427] r8168: enp6s0: link up
[ 31.956560] IPv6: ADDRCONF(NETDEV_CHANGE): enp6s0: link becomes ready
[ 44.311368] nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead.
[ 210.134796] r8168: enp6s0: link down
[ 214.276497] r8168: enp6s0: link up
[ 242.678285] INFO: task mailboxNormal:1797 blocked for more than 120 seconds.
[ 242.685413] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 242.692437] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 242.700787] mailboxNormal D 0 1797 2 0x00000000
[ 242.700806] Call trace:
[ 242.700840] [] __switch_to+0x8c/0xb0
[ 242.700860] [] __schedule+0x28c/0x68c
[ 242.700870] [] schedule+0x3c/0xa0
[ 242.700887] [] mailbox_receive_task+0x84/0xe8
[ 242.700894] [] kthread+0xf0/0x104
[ 242.700901] [] ret_from_fork+0x10/0x20
[ 242.700908] INFO: task mailboxHigh:1798 blocked for more than 120 seconds.
[ 242.707830] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 242.715112] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 242.723012] mailboxHigh D 0 1798 2 0x00000000
[ 242.723021] Call trace:
[ 242.723032] [] __switch_to+0x8c/0xb0
[ 242.723041] [] __schedule+0x28c/0x68c
[ 242.723046] [] schedule+0x3c/0xa0
[ 242.723055] [] mailbox_receive_task+0x84/0xe8
[ 242.723061] [] kthread+0xf0/0x104
[ 242.723066] [] ret_from_fork+0x10/0x20
[ 363.489232] INFO: task mailboxNormal:1797 blocked for more than 120 seconds.
[ 363.496363] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 363.503391] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 363.511365] mailboxNormal D 0 1797 2 0x00000000
[ 363.511383] Call trace:
[ 363.511415] [] __switch_to+0x8c/0xb0
[ 363.511437] [] __schedule+0x28c/0x68c
[ 363.511447] [] schedule+0x3c/0xa0
[ 363.511465] [] mailbox_receive_task+0x84/0xe8
[ 363.511477] [] kthread+0xf0/0x104
[ 363.511488] [] ret_from_fork+0x10/0x20
[ 363.511501] INFO: task mailboxHigh:1798 blocked for more than 120 seconds.
[ 363.518481] Tainted: G S 4.9.78-147538-g244928755bbe #1
[ 363.525473] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 363.533404] mailboxHigh D 0 1798 2 0x00000000
[ 363.533419] Call trace:
[ 363.533445] [] __switch_to+0x8c/0xb0
[ 363.533463] [] __schedule+0x28c/0x68c
[ 363.533473] [] schedule+0x3c/0xa0
[ 363.533488] [] mailbox_receive_task+0x84/0xe8
[ 363.533498] [] kthread+0xf0/0x104
[ 363.533508] [] ret_from_fork+0x10/0x20