From owner-freebsd-arm@freebsd.org Thu Jul 16 07:33:34 2015 Return-Path: Delivered-To: freebsd-arm@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id 009C79A3D29 for ; Thu, 16 Jul 2015 07:33:34 +0000 (UTC) (envelope-from tuexen@fh-muenster.de) Received: from mail-n.franken.de (drew.ipv6.franken.de [IPv6:2001:638:a02:a001:20e:cff:fe4a:feaa]) (using TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits)) (Client CN "mail-n.franken.de", Issuer "Thawte DV SSL CA" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 8A71A1C9E for ; Thu, 16 Jul 2015 07:33:33 +0000 (UTC) (envelope-from tuexen@fh-muenster.de) Received: from [192.168.1.200] (p4FE31A5B.dip0.t-ipconnect.de [79.227.26.91]) (Authenticated sender: macmic) by mail-n.franken.de (Postfix) with ESMTP id 8D8D81C0B462F; Thu, 16 Jul 2015 09:33:29 +0200 (CEST) Content-Type: text/plain; charset=utf-8 Mime-Version: 1.0 (Mac OS X Mail 8.2 \(2102\)) Subject: Re: SDHC errors during boot on RPi2 (11.0-CURRENT) From: Michael Tuexen In-Reply-To: Date: Thu, 16 Jul 2015 09:33:28 +0200 Cc: Dan Raymond , freebsd-arm@freebsd.org Content-Transfer-Encoding: quoted-printable Message-Id: <1B7FD2F7-60D4-44AD-A2EA-5AA1159BAB4B@fh-muenster.de> References: <55A5A3F4.7040105@foxvalley.net> To: Warner Losh X-Mailer: Apple Mail (2.2102) X-BeenThere: freebsd-arm@freebsd.org X-Mailman-Version: 2.1.20 Precedence: list List-Id: "Porting FreeBSD to ARM processors." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 16 Jul 2015 07:33:34 -0000 > On 16 Jul 2015, at 05:17, Warner Losh wrote: >=20 >=20 >> On Jul 14, 2015, at 6:06 PM, Dan Raymond = wrote: >>=20 >> Is anyone else getting these errors during every boot? I'm running = r285346. >=20 > I=E2=80=99ve been running on the RPi2 for some time and have never = seen this. I haven=E2=80=99t > rebuild in the past few weeks though. >=20 > Does this happen on either SD cards? Or other versions of the kernel? I'm observing this for quite a while, reproducible exactly once during every boot on a RPI2. Here is the what I see: > uname -a FreeBSD rpi2 11.0-CURRENT FreeBSD 11.0-CURRENT #28 r285595M: Wed Jul 15 = 11:26:48 CEST 2015 = tuexen@rpi2:/usr/home/tuexen/head/sys/arm/compile/RPI2 arm > dmesg KDB: debugger backends: ddb KDB: current backend: ddb Copyright (c) 1992-2015 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights = reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 11.0-CURRENT #28 r285595M: Wed Jul 15 11:26:48 CEST 2015 tuexen@rpi2:/usr/home/tuexen/head/sys/arm/compile/RPI2 arm FreeBSD clang version 3.6.0 (tags/RELEASE_360/final 230434) 20150225 VT: init without driver. sema_sysinit CPU: Cortex A7 rev 5 (Cortex-A core) Supported features: ARM_ISA THUMB2 JAZELLE THUMBEE ARMv4 Security_Ext WB disabled EABT branch prediction enabled LoUU:2 LoC:3 LoUIS:2=20 Cache level 1:=20 32KB/64B 4-way data cache WB Read-Alloc Write-Alloc 32KB/32B 2-way instruction cache Read-Alloc Cache level 2:=20 512KB/64B 8-way unified cache WB Read-Alloc Write-Alloc real memory =3D 989851648 (943 MB) avail memory =3D 958423040 (914 MB) FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs random: entropy device external interface kbd0 at kbdmux0 ofwbus0: simplebus0: mem 0x3f000000-0x3fffffff = on ofwbus0 bcm28360: generic_timer0: irq 72,73,75,74 on ofwbus0 Timecounter "ARM MPCore Timecounter" frequency 19200000 Hz quality 1000 Event timer "ARM MPCore Eventtimer" frequency 19200000 Hz quality 1000 intc0: mem 0xb200-0xb3ff on simplebus0 bcmwd0: mem 0x10001c-0x100027 on simplebus0 gpio0: mem 0x200000-0x2000af irq = 57,59,58,60 on simplebus0 gpio0: read-only pins: 46,48-53. gpio0: reserved pins: 48-53. gpiobus0: on gpio0 gpioled0: at pin(s) 35 on gpiobus0 gpioled1: at pin(s) 47 on gpiobus0 gpioc0: on gpio0 iichb0: mem 0x205000-0x20501f irq 61 on = simplebus0 iicbus0: on iichb0 iic0: on iicbus0 iichb1: mem 0x804000-0x80401f irq 61 on = simplebus0 iicbus1: on iichb1 iic1: on iicbus1 spi0: mem 0x204000-0x20401f irq 62 on = simplebus0 spibus0: on spi0 bcm_dma0: mem 0x7000-0x7fff,0xe05000-0xe05fff = irq 24,25,26,27,28,29,30,31,32,33,34,35,36 on simplebus0 mbox0: mem 0xb880-0xb8bf irq 1 on simplebus0 sdhci_bcm0: mem 0x300000-0x3000ff irq = 70 on simplebus0 mmc0: on sdhci_bcm0 uart0: mem 0x201000-0x201fff irq 65 on = simplebus0 uart0: console (115200,n,8,1) vchiq0: mem 0xb800-0xb84f irq 2 on simplebus0 vchiq0: [GIANT-LOCKED] vchiq: local ver 6 (min 3), remote ver 6. pcm0: on vchiq0 bcm283x_dwcotg0: mem = 0x980000-0x99ffff irq 17 on simplebus0 usbus0 on bcm283x_dwcotg0 cpulist0: on ofwbus0 cpu0: on cpulist0 bcm2835_cpufreq0: on cpu0 cpu1: on cpulist0 cpu2: on cpulist0 cpu3: on cpulist0 fb0: on ofwbus0 fbd0 on fb0 VT: initialize with new VT driver "fb". fb0: 656x416(656x416@0,0) 24bpp fb0: fbswap: 1, pitch 1968, base 0x3daac000, screen_size 818688 cryptosoft0: Timecounters tick every 10.000 msec IPsec: Initialized Security Association Processing. usbus0: 480Mbps High Speed USB v2.0 ugen0.1: at usbus0 uhub0: on usbus0 sdhci_bcm0-slot0: Got data interrupt 0x00000002, but there is no active = command. sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D REGISTER = DUMP =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Sys addr: 0x00000000 | Version: 0x00009902 sdhci_bcm0-slot0: Blk size: 0x00000040 | Blk cnt: 0x00000001 sdhci_bcm0-slot0: Argument: 0xaaaa0000 | Trn mode: 0x0000071b sdhci_bcm0-slot0: Present: 0x01ff0000 | Host ctl: 0x00000001 sdhci_bcm0-slot0: Power: 0x0000000f | Blk gap: 0x00000000 sdhci_bcm0-slot0: Wake-up: 0x00000000 | Clock: 0x00003947 sdhci_bcm0-slot0: Timeout: 0x0000000e | Int stat: 0x00000000 sdhci_bcm0-slot0: Int enab: 0x01ff00fb | Sig enab: 0x01ff00fb sdhci_bcm0-slot0: AC12 err: 0x00000000 | Slot int: 0x00000000 sdhci_bcm0-slot0: Caps: 0x00000000 | Max curr: 0x00000001 sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Got data interrupt 0x00000002, but there is no active = command. sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D REGISTER = DUMP =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Sys addr: 0x00000000 | Version: 0x00009902 sdhci_bcm0-slot0: Blk size: 0x00000040 | Blk cnt: 0x00000001 sdhci_bcm0-slot0: Argument: 0xaaaa0000 | Trn mode: 0x0000071b sdhci_bcm0-slot0: Present: 0x01ff0000 | Host ctl: 0x00000001 sdhci_bcm0-slot0: Power: 0x0000000f | Blk gap: 0x00000000 sdhci_bcm0-slot0: Wake-up: 0x00000000 | Clock: 0x00003947 sdhci_bcm0-slot0: Timeout: 0x0000000e | Int stat: 0x00000000 sdhci_bcm0-slot0: Int enab: 0x01ff00fb | Sig enab: 0x01ff00fb sdhci_bcm0-slot0: AC12 err: 0x00000000 | Slot int: 0x00000000 sdhci_bcm0-slot0: Caps: 0x00000000 | Max curr: 0x00000001 sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Got data interrupt 0x00000002, but there is no active = command. sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D REGISTER = DUMP =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Sys addr: 0x00000000 | Version: 0x00009902 sdhci_bcm0-slot0: Blk size: 0x00000040 | Blk cnt: 0x00000001 sdhci_bcm0-slot0: Argument: 0xaaaa0000 | Trn mode: 0x0000071b sdhci_bcm0-slot0: Present: 0x01ff0000 | Host ctl: 0x00000001 sdhci_bcm0-slot0: Power: 0x0000000f | Blk gap: 0x00000000 sdhci_bcm0-slot0: Wake-up: 0x00000000 | Clock: 0x00003947 sdhci_bcm0-slot0: Timeout: 0x0000000e | Int stat: 0x00000000 sdhci_bcm0-slot0: Int enab: 0x01ff00fb | Sig enab: 0x01ff00fb sdhci_bcm0-slot0: AC12 err: 0x00000000 | Slot int: 0x00000000 sdhci_bcm0-slot0: Caps: 0x00000000 | Max curr: 0x00000001 sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Got data interrupt 0x00000002, but there is no active = command. sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D REGISTER = DUMP =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D sdhci_bcm0-slot0: Sys addr: 0x00000000 | Version: 0x00009902 sdhci_bcm0-slot0: Blk size: 0x00000040 | Blk cnt: 0x00000001 sdhci_bcm0-slot0: Argument: 0xaaaa0000 | Trn mode: 0x0000071b sdhci_bcm0-slot0: Present: 0x01ff0000 | Host ctl: 0x00000001 sdhci_bcm0-slot0: Power: 0x0000000f | Blk gap: 0x00000000 sdhci_bcm0-slot0: Wake-up: 0x00000000 | Clock: 0x00003947 sdhci_bcm0-slot0: Timeout: 0x0000000e | Int stat: 0x00000000 sdhci_bcm0-slot0: Int enab: 0x01ff00fb | Sig enab: 0x01ff00fb sdhci_bcm0-slot0: AC12 err: 0x00000000 | Slot int: 0x00000000 sdhci_bcm0-slot0: Caps: 0x00000000 | Max curr: 0x00000001 sdhci_bcm0-slot0: =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D= =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D uhub0: 1 port with 1 removable, self powered mmc0: CMD7 failed, RESULT: 1 mmcsd0: 32GB at mmc0 = 41.6MHz/4bit/65535-block bcm2835_cpufreq0: ARM 600MHz, Core 250MHz, SDRAM 400MHz, Turbo OFF Release APs Root mount waiting for: usbus0 ugen0.2: at usbus0 uhub1: = on usbus0 uhub1: MTT enabled Root mount waiting for: usbus0 uhub1: 5 ports with 4 removable, self powered Root mount waiting for: usbus0 ugen0.3: at usbus0 smsc0: on usbus0 Trying to mount root from ufs:/dev/mmcsd0s2a [rw,noatime]... warning: no time-of-day clock registered, system time will not be set = accurately smsc0: chip 0xec00, rev. 0002 miibus0: on smsc0 ukphy0: PHY 1 on miibus0 ukphy0: none, 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto ue0: on smsc0 ue0: Ethernet address: b8:27:eb:81:ba:40 random: unblocking device. smsc0: chip 0xec00, rev. 0002 ue0: link state changed to DOWN ue0: link state changed to UP >=20 Best regards Michael >=20 > Warner >=20