NetBSD Problem Report #49148

From www@NetBSD.org  Sun Aug 24 23:26:39 2014
Return-Path: <www@NetBSD.org>
Received: from mail.netbsd.org (mail.netbsd.org [149.20.53.66])
	(using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits))
	(Client CN "mail.netbsd.org", Issuer "Postmaster NetBSD.org" (verified OK))
	by mollari.NetBSD.org (Postfix) with ESMTPS id 1A95AAF0CB
	for <gnats-bugs@gnats.NetBSD.org>; Sun, 24 Aug 2014 23:26:39 +0000 (UTC)
Message-Id: <20140824232636.92526AF123@mollari.NetBSD.org>
Date: Sun, 24 Aug 2014 23:26:36 +0000 (UTC)
From: riz@NetBSD.org
Reply-To: riz@NetBSD.org
To: gnats-bugs@NetBSD.org
Subject: usmsc0: warning: Failed to write register 0x14 on Raspberry Pi
X-Send-Pr-Version: www-1.0

>Number:         49148
>Category:       kern
>Synopsis:       usmsc0: warning: Failed to write register 0x14 on Raspberry Pi
>Confidential:   no
>Severity:       serious
>Priority:       high
>Responsible:    kern-bug-people
>State:          closed
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Sun Aug 24 23:30:00 +0000 2014
>Closed-Date:    Wed Jun 20 01:22:04 +0000 2018
>Last-Modified:  Wed Jun 20 01:22:04 +0000 2018
>Originator:     Jeff Rizzo
>Release:        NetBSD 7.99.1 (RPI.201408231230Z) #0: Sat Aug 23 13:49:19 UTC 2014         builds@b47.netbsd.org:/home/builds/ab/HEAD/evbarm-earmv6hf/201408231230Z-obj/home/source/ab/HEAD/src/sys/arch/evbarm/compile/RPI
>Organization:
>Environment:
NetBSD 7.99.1 (RPI.201408231230Z) #0: Sat Aug 23 13:49:19 UTC 2014
        builds@b47.netbsd.org:/home/builds/ab/HEAD/evbarm-earmv6hf/201408231230Z-obj/home/source/ab/HEAD/src/sys/arch/evbarm/compile/RPI
>Description:
When doing a fair amount of NFS traffic, usmsc0 keeps locking up:

usmsc0: warning: Failed to write register 0x14
usmsc0: warning: Failed to read register 0x14
usmsc0: warning: timed-out waiting for reset to complete
usmsc0: error: smsc_chip_init failed (err=15)
ukphy0: detached
usmsc0: detached
usmsc0: at uhub1 port 1 (addr 3) disconnected
uhub1: detached
uhub1: at uhub0 port 1 (addr 2) disconnected

I was having trouble getting into DDB, but that's not a problem any more.  It's locked up 5 or 6 times today.

Here's the boot messages if it's helpful:


NetBSD/evbarm (rpi) booting ...
[ Kernel symbol table missing! ]
Loaded initial symtab at 0xc0491ecc, strtab at 0xc04d86fc, # entries 17129
pmap_postinit: Allocated 35 static L1 descriptor tables
Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
    2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014
    The NetBSD Foundation, Inc.  All rights reserved.
Copyright (c) 1982, 1986, 1989, 1991, 1993
    The Regents of the University of California.  All rights reserved.

NetBSD 7.99.1 (RPI.201408231230Z) #0: Sat Aug 23 13:49:19 UTC 2014
        builds@b47.netbsd.org:/home/builds/ab/HEAD/evbarm-earmv6hf/201408231230Z-obj/home/source/ab/HEAD/src/sys/arch/evbarm/compile/RPI
total memory = 192 MB
avail memory = 183 MB
sysctl_createv: sysctl_create(machine_arch) returned 17
kern.module.path=/stand/evbarm/7.99.1/modules
mainbus0 (root)
cpu0 at mainbus0 core 0: 700 MHz ARM1176JZ-S r0p7 (ARM11J V6ZK core)
cpu0: DC enabled IC enabled WB enabled LABT
cpu0: 16KB/32B 4-way L1 VIPT Instruction cache
cpu0: 16KB/32B 4-way write-back-locking-C L1 VIPT Data cache
vfp0 at cpu0: VFP11, rounding, exceptions
obio0 at mainbus0
bcmicu0 at obio0
bcmmbox0 at obio0: VC mailbox
vcmbox0 at bcmmbox0
bcmtmr0 at obio0 intr 3: VC System Timer
vchiq0 at obio0 intr 66: BCM2835 VCHIQ
bcmpm0 at obio0: Power management, Reset and Watchdog controller
bcmrng0 at obio0: RNG
plcom0 at obio0 intr 57
plcom0: txfifo disabled
plcom0: console
genfb0 at obio0no data for est. mode 640x480x67

wsdisplay0 at genfb0 kbdmux 1
sdhc0 at obio0 intr 62: SDHC controller
sdhc0: interrupting on intr 62
sdhc0: SD Host Specification 3.0, rev.153
sdmmc0 at sdhc0 slot 0
dwctwo0 at obio0 intr 9: USB controller
bcmspi0 at obio0 intr 54: SPI
spi0 at bcmspi0: SPI bus
bsciic0 at obio0 intr 53: BSC0
iic0 at bsciic0: I2C bus
bsciic1 at obio0 intr 53: BSC1
iic1 at bsciic1: I2C bus
bcmgpio0 at obio0: GPIO [0...31]
gpio0 at bcmgpio0: 32 pins
bcmgpio1 at obio0: GPIO [32...53]
gpio1 at bcmgpio1: 22 pins
usb0 at dwctwo0: USB revision 2.0
WARNING: module error: vfs load failed for `usbverbose', error 45
uhub0 at usb0WARNING: module error: vfs load failed for `usbverbose', error 45
: vendor 0x0000 DWC2 root hub, class 9/0, rev 2.00/1.00, addr 1
ld0 at sdmmc0: <0x03:0x5344:SU08G:0x80:0xb14f1de0:0x0bc>
ld0: 7580 MB, 3850 cyl, 64 head, 63 sec, 512 bytes/sect x 15523840 sectors
ld0: 4-bit width, bus clock 50.000 MHz
WARNING: module error: vfs load failed for `usbverbose', error 45
WARNING: module error: vfs load failed for `usbverbose', error 45
uhub1 at uhub0 port 1WARNING: module error: vfs load failed for `usbverbose', error 45
WARNING: module error: vfs load failed for `usbverbose', error 45
: vendor 0x0424 product 0x9512, class 9/0, rev 2.00/2.00, addr 2
uhub1: multiple transaction translators
WARNING: module error: vfs load failed for `usbverbose', error 45
WARNING: module error: vfs load failed for `usbverbose', error 45
usmsc0 at uhub1 port 1
WARNING: module error: vfs load failed for `usbverbose', error 45
WARNING: module error: vfs load failed for `usbverbose', error 45
usmsc0: vendor 0x0424 product 0xec00, rev 2.00/2.00, addr 3
usmsc0:  Ethernet address b8:27:eb:48:2c:d7
ukphy0 at usmsc0 phy 1WARNING: module error: vfs load failed for `miiverbose', error 45
: OUI 0x00800f, model 0x000c, rev. 3
ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
boot device: ld0
root on ld0a dumps on ld0b
mountroot: trying nfs...
mountroot: trying msdos...
mountroot: trying ext2fs...
mountroot: trying ffs...
root file system type: ffs
vchiq: local ver 6 (min 3), remote ver 6.
vcaudio0 at vchiq0: AUDS
WARNING: no TOD clock present
WARNING: using filesystem time
WARNING: CHECK AND RESET THE DATE!
audio0 at vcaudio0: half duplex, playback, capture, independent
init: copying out path `/sbin/init' 11
Sun Aug 24 12:24:44 PDT 2014
>How-To-Repeat:
Boot latest kernel on raspberry pi, build perl over NFS.
>Fix:

>Release-Note:

>Audit-Trail:
From: Jeff Rizzo <riz@NetBSD.org>
To: gnats-bugs@NetBSD.org
Cc: 
Subject: Re: kern/49148: usmsc0: warning: Failed to write register 0x14 on
 Raspberry Pi
Date: Mon, 25 Aug 2014 09:15:51 -0700

 This is a multi-part message in MIME format.
 --------------040507000500010609020905
 Content-Type: text/plain; charset=windows-1252; format=flowed
 Content-Transfer-Encoding: 7bit

 I wasn't sure what was "interesting".  Here's a ps/l and the backtrace 
 of a couple LWPs.  This is very reproducible right now.



 --------------040507000500010609020905
 Content-Type: text/plain; charset=UTF-8;
  name="rpi.txt"
 Content-Transfer-Encoding: 7bit
 Content-Disposition: attachment;
  filename="rpi.txt"

 usmsc0: warning: Failed to write register 0x14
 usmsc0: warning: Failed to read register 0x14
 usmsc0: warning: timed-out waiting for reset to complete
 usmsc0: error: smsc_chip_init failed (err=15)
 ukphy0: detached
 usmsc0: detached
 usmsc0: at uhub1 port 1 (addr 3) disconnected
 uhub1: detached
 uhub1: at uhub0 port 1 (addr 2) disconnected
 ^AddStopped in pid 0.2 (system) at  netbsd:cpu_Debugger+0x4:        bx      r14
 db> ps/l
 PID    LID S CPU     FLAGS       STRUCT LWP *               NAME WAIT
 6699     1 3   0        80           c6e5abc0               ntpd nanoslp
 1708     1 3   0        80           cb79c080               ntpd pause
 10922    1 3   0        80           c6e5b400                 as pipe_rd
 11813    1 3   0         0           c6e5b6c0                cc1 nfscn2
 9181     1 3   0        80           c6e5ae80                gcc wait
 7495     1 3   0        80           c9cac0a0                 sh wait
 11418    1 3   0        80           c9cac360                 sh wait
 7079     1 3   0        80           c9cac8e0               make wait
 26756    1 3   0        80           c6e5b140                 sh wait
 28401    1 3   0        80           cbd28e20               make wait
 10069    1 3   0        80           c9cacba0                 sh wait
 7931     1 3   0        80           c6e5b980               make wait
 12165    1 3   0        80           c9cac620                 sh wait
 6204     1 3   0        80           cb79d3c0                 sh wait
 23411    1 3   0        80           cbd290e0               make wait
 10846    1 3   0        80           c6e5bc40                 sh wait
 131      1 3   0        80           cbd285e0                 sh wait
 9417     1 3   0        80           c9cace60               make wait
 25234    1 3   0        80           cbd293a0                 sh wait
 7387     1 3   0        80           cbd288a0                 sh wait
 1337     1 3   0        80           cbd28320               make wait
 15123    1 3   0        80           cb79c8c0                ksh pause
 20982    1 3   0        80           cbd28b60                 sh wait
 21283    1 3   0        80           cb79dc00                 sh wait
 18212    1 3   0        80           cbd28060                 sh wait
 16882    1 3   0        80           cb79c340             pickup kqueue
 2391     1 3   0        80           cbd29660              login wait
 2030     1 3   0        80           c9cad6a0               cron nanoslp
 2015     1 3   0        80           c9cad120              inetd kqueue
 2046     1 3   0        80           c9cad960               qmgr kqueue
 2170     1 3   0        80           c9cad3e0             master kqueue
 2036     1 3   0        80           c9cadc20               sshd select
 985      1 3   0        80           cb79d680            syslogd kqueue
 829      1 3   0        80           cb79d940             dhcpcd select
 1        1 3   0        80           cbdbf380               init wait
 0       50 3   0       200           cb79c600              nfsio nfsiod
 0       49 3   0       200           cb79cb80              nfsio nfsiod
 0       48 3   0       200           cb79ce40              nfsio nfsiod
 0       47 3   0       200           cb79d100              nfsio nfsiod
 0       46 3   0       200           cbede2e0            physiod physiod
 0       45 3   0       280           cbd29920          VCHIQka-0 lnxcmplt
 0       44 3   0       200           cbd29be0           aiodoned aiodoned
 0       43 3   0       200           cbdbe040            ioflush syncer
 0       42 3   0       200           cbdbf900           pgdaemon pgdaemon
 0       41 3   0       200           cbdbe300          vcaudiowq vcaudiowq
 0       40 3   0       280           cbdbe5c0           VCHIQs-0 semacv
 0       39 3   0       280           cbede020           VCHIQr-0 semacv
 0       38 3   0       280           cbdbfbc0            VCHIQ-0 semacv
 0       35 3   0       200           cbdbf640               usb0 usbevt
 0       33 3   0       200           cbdbf0c0              unpgc unpgc
 0       32 3   0       200           cbdbee00        vmem_rehash vmem_rehash
 0       31 3   0       200           cbdbe880             sdmmc0 mmctaskq
 0       30 3   0       200           cbdbeb40            vcmbox0 vcmbox0
 0       21 3   0       200           cbede5a0         usbtask-dr usbtsk
 0       20 3   0       200           cbede860         usbtask-hc usbtsk
 0       19 3   0       200           cbedeb20               dwc2 dwc2
 0       18 3   0       200           cbedede0               iic1 iicintr
 0       17 3   0       200           cbedf0a0               iic0 iicintr
 0       16 3   0       200           cbedf360             sysmon smtaskq
 0       15 3   0       200           cbedf620         pmfsuspend pmfsuspend
 0       14 3   0       200           cbedf8e0           pmfevent pmfevent
 0       13 3   0       200           cbedfba0         sopendfree sopendfr
 0       12 3   0       200           cbf4e000           nfssilly nfssilly
 0       11 3   0       200           cbf4e2c0            cachegc cachegc
 0       10 3   0       200           cbf4e580              vrele vrele
 0        9 3   0       200           cbf4e840             vdrain vdrain
 0        8 3   0       200           cbf4eb00          modunload mod_unld
 0        7 3   0       200           cbf4edc0            xcall/0 xcall
 0        6 1   0       200           cbf4f080          softser/0
 0        5 1   0       200           cbf4f340          softclk/0
 0        4 1   0       200           cbf4f600          softbio/0
 0        3 1   0       200           cbf4f8c0          softnet/0
 0    >   2 7   0       201           cbf4fb80             idle/0
 0        1 3   0       200           c048a240            swapper uvm
 db> bt/a c6e5b6c0
 trace: pid 11813 lid 1 at 0xc97578ec
 0xc97578ec: netbsd:mi_switch+0xc
 0xc975791c: netbsd:sleepq_block+0x158
 0xc975795c: netbsd:kpause+0x118
 0xc975797c: netbsd:nfs_reconnect+0x44
 0xc9757aa4: netbsd:nfs_request+0xe00
 0xc9757b2c: netbsd:nfs_readrpc+0x20c
 0xc9757bdc: netbsd:nfs_doio+0x648
 0xc9757bfc: netbsd:VOP_STRATEGY+0x40
 0xc9757d4c: netbsd:genfs_getpages+0x1224
 0xc9757dcc: netbsd:nfs_getpages+0xe8
 0xc9757e1c: netbsd:VOP_GETPAGES+0x70
 0xc9757f44: netbsd:uvm_fault_internal+0xf80
 0xc9757fac: netbsd:prefetch_abort_handler+0x194
 db> bt/a cbf4e000
 trace: pid 0 lid 12 at 0xcbee9f1c
 0xcbee9f1c: netbsd:mi_switch+0xc
 0xcbee9f4c: netbsd:sleepq_block+0xa4
 0xcbee9f7c: netbsd:cv_wait+0xec
 0xcbee9fac: netbsd:workqueue_worker+0x50
 db>

 --------------040507000500010609020905--

State-Changed-From-To: open->feedback
State-Changed-By: skrll@NetBSD.org
State-Changed-When: Tue, 01 Nov 2016 16:49:02 +0000
State-Changed-Why:
Is this still a problem with HEAD?


State-Changed-From-To: feedback->closed
State-Changed-By: maya@NetBSD.org
State-Changed-When: Wed, 20 Jun 2018 01:22:04 +0000
State-Changed-Why:
feedback timeout. assuming fixed because I have used usmsc with heavy nfs traffic.


>Unformatted:

NetBSD Home
NetBSD PR Database Search

(Contact us) $NetBSD: query-full-pr,v 1.43 2018/01/16 07:36:43 maya Exp $
$NetBSD: gnats_config.sh,v 1.9 2014/08/02 14:16:04 spz Exp $
Copyright © 1994-2017 The NetBSD Foundation, Inc. ALL RIGHTS RESERVED.