From owner-freebsd-arm@freebsd.org Fri Aug 28 01:18:57 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 5C1D19C3F49 for ; Fri, 28 Aug 2015 01:18:57 +0000 (UTC) (envelope-from fbsd@www.zefox.net) Received: from www.zefox.net (www.zefox.net [69.239.235.194]) (using TLSv1 with cipher DHE-RSA-CAMELLIA256-SHA (256/256 bits)) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 1B8A9E0C for ; Fri, 28 Aug 2015 01:18:56 +0000 (UTC) (envelope-from fbsd@www.zefox.net) Received: from www.zefox.net (localhost [127.0.0.1]) by www.zefox.net (8.14.9/8.14.5) with ESMTP id t7S1IoCA002425; Thu, 27 Aug 2015 18:18:50 -0700 (PDT) (envelope-from fbsd@www.zefox.net) Received: (from fbsd@localhost) by www.zefox.net (8.14.9/8.14.5/Submit) id t7S1Ioat002424; Thu, 27 Aug 2015 18:18:50 -0700 (PDT) (envelope-from fbsd) Date: Thu, 27 Aug 2015 18:18:50 -0700 From: bob prohaska To: freebsd-arm@freebsd.org Subject: Crash during buildworld on RPI2 Message-ID: <20150828011850.GI53136@www.zefox.net> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.4.2.3i 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: Fri, 28 Aug 2015 01:18:57 -0000 Here's another crash, this time during build world. The kernel reports FreeBSD www.zefox.org 11.0-CURRENT FreeBSD 11.0-CURRENT #44 r287137M: Wed Aug 26 00:42:10 PDT 2015 bob@www.zefox.org:/usr/obj/usr/src/sys/RPI2 arm but user land is a day or two newer, I hope that does not obscure matters too much. A world and kernel build/install cycle completed unexpectedly, despite hours of smsc0 warnings. Thinking that prolonged run time might encourage a crash, rather than reboot, /usr/src was updated and the compilation cycle started again. In hindsight the update was probably an error on my part. sysctl hw.sdhci.debug=1 and sysctl hw.sdhci.debug=1 were both set and acknowledged by sysctl -a, but I could not find any extra debugging output on the console or in /var/log/debug.log. smsc0: warning: MII is busy smsc0: warning: Failed to read register 0x114 smsc0: warning: MII read timeout smsc0: warning: Failed to read register 0x114 smsc0: warning: MII is busy smsc0: warning: Failed to write register 0x114 smsc0: warning: Failed to write register 0x114 smsc0: warning: Failed to read register 0x114 smsc0: warning: MII is busy panic: initiate_write_inodeblock_ufs2: already started cpuid = 0 KDB: enter: panic [ thread pid 21 tid 100063 ] Stopped at $d.7: ldrb r15, [r15, r15, ror r15]! db> bt Tracing pid 21 tid 100063 td 0xc414c6c0 db_trace_self() at db_trace_self pc = 0xc054396c lr = 0xc0140fe8 (db_stack_trace+0x108) sp = 0xd77fd8b0 fp = 0xd77fd8c8 r10 = 0xc07885f8 db_stack_trace() at db_stack_trace+0x108 pc = 0xc0140fe8 lr = 0xc0140a34 (db_command+0x388) sp = 0xd77fd8d0 fp = 0xd77fd970 r4 = 0x00000000 r5 = 0x00000000 r6 = 0x00000000 r10 = 0xc07885f8 db_command() at db_command+0x388 pc = 0xc0140a34 lr = 0xc014069c (db_command_loop+0x74) sp = 0xd77fd978 fp = 0xd77fd988 r4 = 0xc05aa8d4 r5 = 0xc05cb943 r6 = 0xc07885e4 r7 = 0xd77fdb58 r8 = 0xc077d620 r9 = 0xc0693da4 r10 = 0xc077d624 db_command_loop() at db_command_loop+0x74 pc = 0xc014069c lr = 0xc01431cc (db_trap+0x108) sp = 0xd77fd990 fp = 0xd77fdaa8 r4 = 0x00000000 r5 = 0xc07885f0 r6 = 0xc077d648 r10 = 0xc077d624 db_trap() at db_trap+0x108 pc = 0xc01431cc lr = 0xc02eb678 (kdb_trap+0x184) sp = 0xd77fdab0 fp = 0xd77fdad8 r4 = 0x00000000 r5 = 0x00000001 r6 = 0xc077d648 r7 = 0xd77fdb58 kdb_trap() at kdb_trap+0x184 pc = 0xc02eb678 lr = 0xc055ba1c (undefinedinstruction+0x344) sp = 0xd77fdae0 fp = 0xd77fdb50 r4 = 0x00000000 r5 = 0x00000000 r6 = 0xc055b628 r7 = 0xe7ffffff r8 = 0xc414c6c0 r9 = 0xc02eadd0 r10 = 0xd77fdb58 undefinedinstruction() at undefinedinstruction+0x344 pc = 0xc055ba1c lr = 0xc0544ff4 (exception_exit) sp = 0xd77fdb58 fp = 0xd77fdbf0 r4 = 0xc05cb998 r5 = 0x00000001 r6 = 0xc076e0e0 r7 = 0xc076e278 r8 = 0xd77fdc34 r9 = 0xc078a3a0 r10 = 0xc414c6c0 exception_exit() at exception_exit pc = 0xc0544ff4 lr = 0xc02eadc0 (kdb_enter+0x48) sp = 0xd77fdbe8 fp = 0xd77fdbf0 r0 = 0xc077d634 r1 = 0x00000000 r2 = 0xd77fdb1c r3 = 0xc05cfa5d r4 = 0xc05cb998 r5 = 0x00000001 r6 = 0xc076e0e0 r7 = 0xc076e278 r8 = 0xd77fdc34 r9 = 0xc078a3a0 r10 = 0xc414c6c0 r12 = 0xc06aeb08 $a.8() at $a.8 pc = 0xc02eadd4 lr = 0xc02adf60 (vpanic+0x164) sp = 0xd77fdbf8 fp = 0xd77fdc18 r4 = 0x00000100 r10 = 0xc414c6c0 vpanic() at vpanic+0x164 pc = 0x sp = 0xd77fdc20 fp = 0xd77fdc28 r4 = 0xd751dca0 r5 = 0xc05f14f0 r6 = 0xc41ced00 r7 = 0xa0020024 r8 = 0xc442e100 r9 = 0xc078aa6c r10 = 0xd751dca0 kproc_shutdown() at kproc_shutdown pc = 0xc02adfac lr = 0xc04d6310 ($a.5+0x12e8) sp = 0xd77fdc30 fp = 0xd77fdc98 r4 = 0xd751dca0 r5 = 0xd77fdc34 $a.5() at $a.5+0x12e8 pc = 0xc04d6310 lr = 0xc04f4dc0 (ffs_geom_strategy+0x174) sp = 0xd77fdca0 fp = 0xd77fdcb0 r4 = 0xd751dca0 r5 = 0xc42a83d4 r6 = 0xc42a8360 r7 = 0xa0020024 r8 = 0x00000000 r9 = 0xc078aa6c r10 = 0xd751dca0 ffs_geom_strategy() at ffs_geom_strategy+0x174 pc = 0xc04f4dc0 lr = 0xc0339268 (bufwrite+0x1d4) sp = 0xd77fdcb8 fp = 0xd77fdcf0 r4 = 0xd751dca0 r5 = 0x00000004 r6 = 0x00006e00 r7 = 0xa0020024 bufwrite() at bufwrite+0x1d4 pc = 0xc0339268 lr = 0xc033c4f4 (vfs_bio_awrite+0x1f8) sp = 0xd77fdcf8 fp = 0xd77fdd40 r4 = 0xd751dca0 r5 = 0x00008000 r6 = 0x00000000 r7 = 0xc05d9e33 r8 = 0x00000000 r9 = 0xd751dcf0 r10 = 0xd751dca0 vfs_bio_awrite() at vfs_bio_awrite+0x1f8 pc = 0xc033c4f4 lr = 0xc0347ad8 (vop_stdfsync+0x198) sp = 0xd77fdd48 fp = 0xd77fdd80 r4 = 0xc42a8360 r5 = 0xc42a83e4 r6 = 0x00000000 r7 = 0xc05d9e33 r8 = 0x00000000 r9 = 0xd751dcf0 r10 = 0xd751dca0 vop_stdfsync() at vop_stdfsync+0x198 pc = 0xc0347ad8 lr = 0xc01dbbd0 (devfs_fsync+0x2c) sp = 0xd77fdd88 fp = 0xd77fdda0 r4 = 0xd77fde04 r5 = 0xd77fde04 r6 = 0x00000000 r7 = 0xc0781548 r8 = 0xc42a8360 r9 = 0xc42a83d4 r10 = 0xc3a57800 devfs_fsync() at devfs_fsync+0x2c pc = 0xc01dbbd0 lr = 0xc0580f50 (VOP_FSYNC_APV+0x12c) sp = 0xd77fdda8 fp = 0xd77fddc0 r4 = 0xd77fde04 r5 = 0xc06afac8 r6 = 0x00000000 r10 = 0xc3a57800 VOP_FSYNC_APV() at VOP_FSYNC_APV+0x12c pc = 0xc0580f50 lr = 0xc035bde4 (sched_sync+0x3f8) sp = 0xd77fddc8 fp = 0xd77fde30 r4 = 0xd77fde04 r5 = 0xc05dae2c r6 = 0x00000003 r10 = 0xc3a57800 sched_sync() at sched_sync+0x3f8 pc = 0xc035bde4 lr = 0xc027b0f4 (fork_exit+0xa0) sp = 0xd77fde38 fp = 0xd77fde50 r4 = 0xc414c6c0 r5 = 0xc4156700 r6 = 0xc035b9ec r7 = 0x00000000 r8 = 0xd77fde58 r9 = 0xc0789b40 r10 = 0xc077b8c0 fork_exit() at fork_exit+0xa0 pc = 0xc027b0f4 lr = 0xc0544f84 (swi_exit) sp = 0xd77fde58 fp = 0x00000000 r4 = 0xc035b9ec r5 = 0x00000000 r6 = 0xc395b330 r7 = 0x7fff1d8b r8 = 0xc078a920 r10 = 0xc077b8c0 swi_exit() at swi_exit pc = 0xc0544f84 lr = 0xc0544f84 (swi_exit) sp = 0xd77fde58 fp = 0x00000000 db> As before, the machine rebooted and has so far completed world and kernel builds. Installworld is running now. The extra debugging is off courtesy of my inattention. thanks for reading and any guidance, bob prohaska