port-evbarm/48342: rpi kernel without uaudio panics at boot

To: port-evbarm-maintainer%netbsd.org@localhost,gnats-admin%netbsd.org@localhost,netbsd-bugs%netbsd.org@localhost
Subject: port-evbarm/48342: rpi kernel without uaudio panics at boot
From: Takahiro HAYASHI <t-hash%abox3.so-net.ne.jp@localhost>
Date: Fri, 25 Oct 2013 17:35:01 +0000 (UTC)
>Number:         48342
>Category:       port-evbarm
>Synopsis:       rpi kernel without uaudio panics at boot
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    port-evbarm-maintainer
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Fri Oct 25 17:35:00 +0000 2013
>Originator:     Takahiro HAYASHI
>Release:        NetBSD 6.99.24
>Organization:
>Environment:
System: NetBSD rpi 6.99.24 NetBSD 6.99.24 (MYRPI) #0: Fri Oct 25 18:17:28 JST 
2013 root@halt:/usr/build2/obj.evbarm/sys/arch/evbarm/compile/MYRPI evbarm
Architecture: arm
Machine: evbarm
>Description:
        Removing both uaudio* at {uhub?,usbifif?} from generic
        kernel for Raspberry Pi evbarm/conf/RPI causes kernel panic.
        This can be suppressed by adding aurateconv to files.bcm2835.

        Here is console log when "uaudio* at uhub?" disabled,
        with AUDIO_DEBUG, AUCONV_DEBUG.

Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
    2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013
    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 6.99.24 (MYRPI) #0: Fri Oct 25 18:17:28 JST 2013
        root@halt:/usr/build2/obj.evbarm/sys/arch/evbarm/compile/MYRPI
total memory = 448 MB
avail memory = 434 MB
sysctl_createv: sysctl_create(machine_arch) returned 17
timecounter: Timecounters tick every 10.000 msec
mainbus0 (root)
cpu0 at mainbus0 core 0: 700 MHz ARM1176JZ-S r0p7 (ARM11J V6ZK core)
cpu0: DC enabled IC enabled WB enabled LABT
cpu0: isar: [0]=0x140011 [1]=0x12002111 [2]=0x11231121 [3]=0x1102131, 
[4]=0x1141, [5]=0
cpu0: mmfr: [0]=0x1130003 [1]=0x10030302 [2]=0x1222100 [3]=0
cpu0: pfr: [0]=0x111 [1]=0x11
cpu0: 16KB/32B 4-way L1 Instruction cache
cpu0: 16KB/32B 4-way write-back-locking-C L1 Data cache
vfp0 at cpu0: VFP11
vfp0: mvfr: [0]=0x11111111 [1]=0
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 obio0
genfb0: framebuffer at 0x1c006000, size 1280x720, depth 32, stride 5120
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
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:0x0031be07:0x0d5>
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 xx:xx:xx:xx:xx:xx
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!
audioprobe: type=0 sa=0xcab19e84 hw=0xc02eed20
audio0 at vcaudio0: half duplex, playback, capture, independent
audiosetinfo sc=0xc1bc9008 ai=0xcab19a00
audiosetinfo() Setting record params: enc=1 1ch 8/8bit 8000Hz
audiosetinfo() Setting play params: enc=1 1ch 8/8bit 8000Hz
auconv_set_converter: ENTER rateconv=1
[ 0]: mode=PLAY|RECORD enc=slinear_le 16/16bit 2ch channel_mask=STEREO 
8000Hz-48000Hz
auconv_exact_match: ENTER: mode=0x1 target:enc=mulaw 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: mulaw
auconv_exact_match: format[0] normalized: slinear_le
auconv_set_converter: encoding emulation
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_be 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_be
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_le 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_be 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_be
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_le 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_set_converter: LEAVE with -1 (bottom)
audiosetinfo: hw->set_params() failed with 22
audio_initbufs: mode=0x0
audio_init_ringbuffer: MI blksize=32
audio_init_ringbuffer: final blksize=4096
audio_init_ringbuffer: MI blksize=32
audio_init_ringbuffer: final blksize=4096

uvm_fault(0xc042f820, 0, 1) -> e
Fatal kernel mode data abort: 'Translation Fault (S)'
trapframe: 0xcab19370
FSR=00000005, FAR=00000008, spsr=20000153
r0 =00000001, r1 =c1a7c000, r2 =00000000, r3 =c001c238
r4 =c1bc9008, r5 =c1bc9008, r6 =00000000, r7 =cab19748
r8 =c035282c, r9 =00000001, r10=00000016, r11=cab193dc
r12=cab19398, ssp=cab193c0, slr=c0011668, pc =c0012ba8

Stopped in pid 0.36 (system) at netbsd:audio_calcwater+0x24:    ldr     r1, [r2,
 #0x008]
db> bt
0xcab193dc: netbsd:audio_calcwater+0xc
0xcab199f8: netbsd:audiosetinfo+0x364
0xcab19ab0: netbsd:audio_set_defaults+0xc4
0xcab19e38: netbsd:audioattach+0x234
0xcab19e64: netbsd:config_attach_loc+0x19c
0xcab19e7c: netbsd:config_found+0x2c
0xcab19ea0: netbsd:audio_attach_mi+0x3c
0xcab19eb8: netbsd:vcaudio_rescan+0x50
0xcab19f2c: netbsd:vcaudio_attach+0x28c
0xcab19f58: netbsd:config_attach_loc+0x19c
0xcab19f70: netbsd:config_found_ia+0x2c
0xcab19f94: netbsd:vchiq_defer+0x64
0xcab19fac: netbsd:config_mountroot_thread+0x48
db> 

        This log is when "uaudio* at uhub?" enabled, with AUDIO_DEBUG,
        AUCONV_DEBUG.

Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
    2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013
    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 6.99.24 (MYRPI) #0: Fri Oct 25 18:00:59 JST 2013
        root@halt:/usr/build2/obj.evbarm/sys/arch/evbarm/compile/MYRPI
total memory = 448 MB
avail memory = 434 MB
sysctl_createv: sysctl_create(machine_arch) returned 17
timecounter: Timecounters tick every 10.000 msec
mainbus0 (root)
cpu0 at mainbus0 core 0: 700 MHz ARM1176JZ-S r0p7 (ARM11J V6ZK core)
cpu0: DC enabled IC enabled WB enabled LABT
cpu0: isar: [0]=0x140011 [1]=0x12002111 [2]=0x11231121 [3]=0x1102131, 
[4]=0x1141, [5]=0
cpu0: mmfr: [0]=0x1130003 [1]=0x10030302 [2]=0x1222100 [3]=0
cpu0: pfr: [0]=0x111 [1]=0x11
cpu0: 16KB/32B 4-way L1 Instruction cache
cpu0: 16KB/32B 4-way write-back-locking-C L1 Data cache
vfp0 at cpu0: VFP11
vfp0: mvfr: [0]=0x11111111 [1]=0
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 obio0
genfb0: framebuffer at 0x1c006000, size 1280x720, depth 32, stride 5120
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
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:0x0031be07:0x0d5>
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 xx:xx:xx:xx:xx:xx
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!
audioprobe: type=0 sa=0xcab18e84 hw=0xc02f8ae0
audio0 at vcaudio0: half duplex, playback, capture, independent
audiosetinfo sc=0xc1bc8008 ai=0xcab18a00
audiosetinfo() Setting record params: enc=1 1ch 8/8bit 8000Hz
audiosetinfo() Setting play params: enc=1 1ch 8/8bit 8000Hz
auconv_set_converter: ENTER rateconv=1
[ 0]: mode=PLAY|RECORD enc=slinear_le 16/16bit 2ch channel_mask=STEREO 
8000Hz-48000Hz
auconv_exact_match: ENTER: mode=0x1 target:enc=mulaw 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: mulaw
auconv_exact_match: format[0] normalized: slinear_le
auconv_set_converter: native with aurateconv
auconv_set_converter: encoding emulation
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_be 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_be
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_le 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_be 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_be
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=ulinear_le 8/8bit 1ch 8000Hz
auconv_exact_match: target normalized: ulinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_set_converter: encoding emulation with aurateconv
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 16/16bit 1ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_exact_match: ENTER: mode=0x1 target:enc=slinear_le 16/16bit 2ch 8000Hz
auconv_exact_match: target normalized: slinear_le
auconv_exact_match: format[0] normalized: slinear_le
auconv_set_converter: LEAVE with 0 (rateconv2)
audio_setup_pfilters: HW-buffer=0xc1bc8270 pustream=0xc1bc80d0
[0] enc=1 1ch 8/8bit 8000Hz
[1] enc=6 1ch 16/16bit 8000Hz
[HW] enc=6 2ch 16/16bit 8000Hz
audio_setup_rfilters: HW-buffer=0xc1bc82c8 pustream=0xc1bc82c8
[HW] enc=1 1ch 8/8bit 8000Hz
audiosetinfo: filter setup is completed.
audio_calc_blksize: record blksize=400
audio_calc_blksize: play blksize=1600
audio_initbufs: mode=0x0
audio_init_ringbuffer: MI blksize=400
audio_init_ringbuffer: final blksize=4096
audio_init_ringbuffer: MI blksize=1600
audio_init_ringbuffer: final blksize=4096
audio_calcwater: plow=49152 phigh=65536 rlow=0 rhigh=28672
audio_attach: inputs ports=0x0, input master=-1, output ports=0x0, output 
master=2
init: copying out path `/sbin/init' 11

>How-To-Repeat:
        add following 3 lines to the bottom of evbarm/conf/RPI
no uaudio* at uhub?
no uaudio* at usbifif?
no audio* at uaudio?
        Then build kernel and run it.

>Fix:
        This can be suppressed by adding "aurateconv" to "device vcaudio"
        in files.bcm2835.

        To avoid this problem, add either "uaudio* at uhub?" or
        "uaudio* at usbifif?" to your kernel config.
        "audio* at uaudio?" is not needed to avoid problem.

--
t-hash
Prev by Date: Re: misc/48338 (netbsd-iscsi-20080207/src/util.c:1165: possible bad if test ?)
Next by Date: kern/48343: patch for re(4) to change link address of 8111E_VL
Previous by Thread: Re: misc/48338 (netbsd-iscsi-20080207/src/util.c:1165: possible bad if test ?)
Next by Thread: kern/48343: patch for re(4) to change link address of 8111E_VL
Indexes:
Home | Main Index | Thread Index | Old Index