Re: r268621: panic: shadowed tmpfs v_object [with dump]

From: Bryan Drewery <bdrewery_at_FreeBSD.org>
Date: Tue, 22 Jul 2014 14:53:56 -0700
On 7/22/14, 2:26 PM, Bryan Drewery wrote:
> On 7/22/14, 2:07 PM, Bryan Drewery wrote:
>> Meant to send to current_at_, moving there.
>>
>> On 7/22/14, 2:07 PM, Bryan Drewery wrote:
>>> On r268621:
>>>
>>>> panic: shadowed tmpfs v_object 0xfffff807a7f96600
>>>> cpuid = 0
>>>> KDB: stack backtrace:
>>>> db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame
>>>> 0xfffffe1247d67390
>>>> kdb_backtrace() at kdb_backtrace+0x39/frame 0xfffffe1247d67440
>>>> vpanic() at vpanic+0x126/frame 0xfffffe1247d67480
>>>> kassert_panic() at kassert_panic+0x139/frame 0xfffffe1247d674f0
>>>> vm_object_deallocate() at vm_object_deallocate+0x236/frame
>>>> 0xfffffe1247d67550
>>>> tmpfs_free_node() at tmpfs_free_node+0x138/frame 0xfffffe1247d67580
>>>> tmpfs_reclaim() at tmpfs_reclaim+0x17d/frame 0xfffffe1247d675c0
>>>> VOP_RECLAIM_APV() at VOP_RECLAIM_APV+0xf7/frame 0xfffffe1247d675f0
>>>> vgonel() at vgonel+0x1a1/frame 0xfffffe1247d67660
>>>> vrecycle() at vrecycle+0x3e/frame 0xfffffe1247d67690
>>>> tmpfs_inactive() at tmpfs_inactive+0x4c/frame 0xfffffe1247d676b0
>>>> VOP_INACTIVE_APV() at VOP_INACTIVE_APV+0xf7/frame 0xfffffe1247d676e0
>>>> vinactive() at vinactive+0xc6/frame 0xfffffe1247d67730
>>>> vputx() at vputx+0x27a/frame 0xfffffe1247d67790
>>>> tmpfs_rename() at tmpfs_rename+0xf5/frame 0xfffffe1247d67860
>>>> VOP_RENAME_APV() at VOP_RENAME_APV+0xfc/frame 0xfffffe1247d67890
>>>> kern_renameat() at kern_renameat+0x3ef/frame 0xfffffe1247d67ae0
>>>> amd64_syscall() at amd64_syscall+0x25a/frame 0xfffffe1247d67bf0
>>>> Xfast_syscall() at Xfast_syscall+0xfb/frame 0xfffffe1247d67bf0
>>>> --- syscall (128, FreeBSD ELF64, sys_rename), rip = 0x80088b74a, rsp =
>>>> 0x7fffffffe238, rbp = 0x7fffffffe710 ---
>>>> Uptime: 6d4h0m3s
>>>>
>>>> Dump failed. Partition too small.
>>>
>>> Unfortunately I have no dump to debug.
>>>
>>
> Running poudriere again after boot hit the issue right away:
>
>
>> (kgdb) bt
>> #0  doadump (textdump=1) at pcpu.h:219
>> #1  0xffffffff809122a7 in kern_reboot (howto=260) at
>> /usr/src/sys/kern/kern_shutdown.c:445
>> #2  0xffffffff809127e5 in vpanic (fmt=<value optimized out>, ap=<value
>> optimized out>) at /usr/src/sys/kern/kern_shutdown.c:744
>> #3  0xffffffff80912679 in kassert_panic (fmt=<value optimized out>) at
>> /usr/src/sys/kern/kern_shutdown.c:632
>> #4  0xffffffff80ba7996 in vm_object_deallocate (object=<value
>> optimized out>) at /usr/src/sys/vm/vm_object.c:562
>> #5  0xffffffff820a75a8 in tmpfs_free_node (tmp=0xfffff800b5155980,
>> node=0xfffff802716ba740) at
>> /usr/src/sys/modules/tmpfs/../../fs/tmpfs/tmpfs_subr.c:335
>> #6  0xffffffff820a363d in tmpfs_reclaim (v=<value optimized out>) at
>> /usr/src/sys/modules/tmpfs/../../fs/tmpfs/tmpfs_vnops.c:1276
>> #7  0xffffffff80e48717 in VOP_RECLAIM_APV (vop=<value optimized out>,
>> a=<value optimized out>) at vnode_if.c:2017
>> #8  0xffffffff809c1381 in vgonel (vp=0xfffff802716b61d8) at
>> vnode_if.h:830
>> #9  0xffffffff809c18be in vrecycle (vp=0xfffff802716b61d8) at
>> /usr/src/sys/kern/vfs_subr.c:2655
>> #10 0xffffffff820a61cc in tmpfs_inactive (v=<value optimized out>) at
>> /usr/src/sys/modules/tmpfs/../../fs/tmpfs/tmpfs_vnops.c:1242
>> #11 0xffffffff80e485b7 in VOP_INACTIVE_APV (vop=<value optimized out>,
>> a=<value optimized out>) at vnode_if.c:1951
>> #12 0xffffffff809bfd36 in vinactive (vp=0xfffff802716b61d8,
>> td=0xfffff80187e29920) at vnode_if.h:807
>> #13 0xffffffff809c012a in vputx (vp=0xfffff802716b61d8, func=2) at
>> /usr/src/sys/kern/vfs_subr.c:2267
>> #14 0xffffffff820a47c5 in tmpfs_rename (v=<value optimized out>) at
>> /usr/src/sys/modules/tmpfs/../../fs/tmpfs/tmpfs_vnops.c:1023
>> #15 0xffffffff80e47d3c in VOP_RENAME_APV (vop=<value optimized out>,
>> a=<value optimized out>) at vnode_if.c:1544
>> #16 0xffffffff809cc77f in kern_renameat (td=<value optimized out>,
>> oldfd=<value optimized out>, old=<value optimized out>, newfd=<value
>> optimized out>, new=<value optimized out>,
>>     pathseg=<value optimized out>) at vnode_if.h:636
>> #17 0xffffffff80d280fa in amd64_syscall (td=0xfffff80187e29920,
>> traced=0) at subr_syscall.c:133
>> #18 0xffffffff80d0a64b in Xfast_syscall () at
>> /usr/src/sys/amd64/amd64/exception.S:407
>> (kgdb) p *(vm_object_t)0xfffff8027169f500
>> $1 = {lock = {lock_object = {lo_name = 0xffffffff80fe89f6 "vm object",
>> lo_flags = 90374144, lo_data = 0, lo_witness = 0xfffffe00006e7680},
>> rw_lock = 18446735284191271200}, object_list = {
>>     tqe_next = 0xfffff8027169f400, tqe_prev = 0xfffff8027169f620},
>> shadow_head = {lh_first = 0xfffff801b8489e00}, shadow_list = {le_next
>> = 0x0, le_prev = 0x0}, memq = {tqh_first = 0xfffff811d966bc08,
>>     tqh_last = 0xfffff811d966bc18}, rtree = {rt_root =
>> 18446735354278362121, rt_flags = 0 '\0'}, size = 1, generation = 1,
>> ref_count = 1, shadow_count = 1, memattr = 6 '\006', type = 1 '\001',
>>   flags = 528, pg_color = 0, paging_in_progress = 0,
>> resident_page_count = 1, backing_object = 0x0, backing_object_offset =
>> 0, pager_object_list = {tqe_next = 0x0, tqe_prev = 0x0}, rvq = {
>>     lh_first = 0x0}, cache = {rt_root = 0, rt_flags = 0 '\0'}, handle
>> = 0x0, un_pager = {vnp = {vnp_size = 0, writemappings = 0}, devp =
>> {devp_pglist = {tqh_first = 0x0, tqh_last = 0x0}, ops = 0x0,
>>       dev = 0x0}, sgp = {sgp_pglist = {tqh_first = 0x0, tqh_last =
>> 0x0}}, swp = {swp_tmpfs = 0x0, swp_bcount = 0}}, cred = 0x0, charge = 0}
>> (kgdb) frame 8
>> #8  0xffffffff809c1381 in vgonel (vp=0xfffff802716b61d8) at
>> vnode_if.h:830
>> 830             return (VOP_RECLAIM_APV(vp->v_op, &a));
>> (kgdb) p *vp
>> $2 = {v_tag = 0xffffffff820abf96 "tmpfs", v_op = 0xffffffff820ac938,
>> v_data = 0x0, v_mount = 0xfffff8004733a000, v_nmntvnodes = {tqe_next =
>> 0xfffff802716b6000, tqe_prev = 0xfffff802716b63d0}, v_un = {
>>     vu_mount = 0x0, vu_socket = 0x0, vu_cdev = 0x0, vu_fifoinfo =
>> 0x0}, v_hashlist = {le_next = 0x0, le_prev = 0x0}, v_cache_src =
>> {lh_first = 0x0}, v_cache_dst = {tqh_first = 0x0,
>>     tqh_last = 0xfffff802716b6228}, v_cache_dd = 0x0, v_lock =
>> {lock_object = {lo_name = 0xffffffff820abf96 "tmpfs", lo_flags =
>> 116588544, lo_data = 0, lo_witness = 0xfffffe0000711980},
>>     lk_lock = 18446735284191271200, lk_exslpfail = 0, lk_timo = 51,
>> lk_pri = 96}, v_interlock = {lock_object = {lo_name =
>> 0xffffffff80fafc26 "vnode interlock", lo_flags = 16973824, lo_data = 0,
>>       lo_witness = 0xfffffe00006e7500}, mtx_lock = 4}, v_vnlock =
>> 0xfffff802716b6240, v_actfreelist = {tqe_next = 0xfffff80271898588,
>> tqe_prev = 0xfffff8004733a078}, v_bufobj = {bo_lock = {
>>       lock_object = {lo_name = 0xffffffff80fb8084 "bufobj interlock",
>> lo_flags = 86179840, lo_data = 0, lo_witness = 0xfffffe00006ef380},
>> rw_lock = 1}, bo_ops = 0xffffffff814942a0, bo_object = 0x0,
>>     bo_synclist = {le_next = 0x0, le_prev = 0x0}, bo_private =
>> 0xfffff802716b61d8, __bo_vnode = 0xfffff802716b61d8, bo_clean = {bv_hd
>> = {tqh_first = 0x0, tqh_last = 0xfffff802716b62f8}, bv_root = {
>>         pt_root = 0}, bv_cnt = 0}, bo_dirty = {bv_hd = {tqh_first =
>> 0x0, tqh_last = 0xfffff802716b6318}, bv_root = {pt_root = 0}, bv_cnt =
>> 0}, bo_numoutput = 0, bo_flag = 0, bo_bsize = 4096},
>>   v_pollinfo = 0x0, v_label = 0x0, v_lockf = 0x0, v_rl = {rl_waiters =
>> {tqh_first = 0x0, tqh_last = 0xfffff802716b6360}, rl_currdep = 0x0},
>> v_cstart = 0, v_lasta = 0, v_lastw = 0, v_clen = 0,
>>   v_holdcnt = 2, v_usecount = 0, v_iflag = 2688, v_vflag = 0,
>> v_writecount = 0, v_hash = 40987489, v_type = VREG}
>> (kgdb) info locals
>> mp = (struct mount *) 0xfffff8004733a000
>> fromnd = {ni_dirp = 0x801006080 <Address 0x801006080 out of bounds>,
>> ni_segflg = UIO_USERSPACE, ni_rightsneeded = {cr_rights =
>> {144115188142965760, 288230376151711744}},
>>   ni_startdir = 0xfffff802716b63b0, ni_rootdir = 0xfffff8026b01a760,
>> ni_topdir = 0xfffff8026b01a760, ni_dirfd = -100, ni_strictrelative =
>> 0, ni_filecaps = {fc_rights = {cr_rights = {0, 0}},
>>     fc_ioctls = 0x0, fc_nioctls = -1, fc_fcntls = 0}, ni_vp =
>> 0xfffff80271898588, ni_dvp = 0xfffff802716b63b0, ni_pathlen = 1,
>> ni_next = 0xfffff80061ea501f "", ni_loopcnt = 0, ni_cnd = {cn_nameiop
>> = 2,
>>     cn_flags = 67148812, cn_thread = 0xfffff80187e29920, cn_cred =
>> 0xfffff80038911800, cn_lkflags = 524288, cn_pnbuf = 0xfffff80061ea5000
>> "/var/run/ld-elf.so.hints.HTjP6A",
>>     cn_nameptr = 0xfffff80061ea5009 "ld-elf.so.hints.HTjP6A",
>> cn_namelen = 22, cn_consume = 0}}
>> tond = {ni_dirp = 0x403e66 <Address 0x403e66 out of bounds>, ni_segflg
>> = UIO_USERSPACE, ni_rightsneeded = {cr_rights = {144115188080051200,
>> 288230376151711744}}, ni_startdir = 0xfffff802716b63b0,
>>   ni_rootdir = 0xfffff8026b01a760, ni_topdir = 0xfffff8026b01a760,
>> ni_dirfd = -100, ni_strictrelative = 0, ni_filecaps = {fc_rights =
>> {cr_rights = {0, 0}}, fc_ioctls = 0x0, fc_nioctls = -1,
>>     fc_fcntls = 0}, ni_vp = 0xfffff802716b61d8, ni_dvp =
>> 0xfffff802716b63b0, ni_pathlen = 1, ni_next = 0xfffff80038d69418 "",
>> ni_loopcnt = 0, ni_cnd = {cn_nameiop = 3, cn_flags = 134257708,
>>     cn_thread = 0xfffff80187e29920, cn_cred = 0xfffff80038911800,
>> cn_lkflags = 524288, cn_pnbuf = 0xfffff80038d69400
>> "/var/run/ld-elf.so.hints", cn_nameptr = 0xfffff80038d69409
>> "ld-elf.so.hints",
>>     cn_namelen = 15, cn_consume = 0}}
>> rights = {cr_rights = {144115188080051200, 288230376151711744}}
>> mp = (struct mount *) 0xfffff8004733a000
>> error = <value optimized out>
>> fvp = <value optimized out>
>> tvp = <value optimized out>
>> tdvp = <value optimized out>
>> (kgdb) p *mp
>> $9 = {mnt_mtx = {lock_object = {lo_name = 0xffffffff80f8fcec "struct
>> mount mtx", lo_flags = 16973824, lo_data = 0, lo_witness =
>> 0xfffffe00006e7a00}, mtx_lock = 4}, mnt_gen = 1, mnt_list = {
>>     tqe_next = 0xfffff80038fa9cc0, tqe_prev = 0xfffff80187b74ce8},
>> mnt_op = 0xffffffff820ace60, mnt_vfc = 0xffffffff820acf80,
>> mnt_vnodecovered = 0xfffff801b853e760, mnt_syncer = 0xfffff8026b01a588,
>>   mnt_ref = 13206, mnt_nvnodelist = {tqh_first = 0xfffff8026b01a760,
>> tqh_last = 0xfffff802718985a8}, mnt_nvnodelistsize = 13205,
>> mnt_activevnodelist = {tqh_first = 0xfffff802716b61d8,
>>     tqh_last = 0xfffff8026b01a648}, mnt_activevnodelistsize = 730,
>> mnt_writeopcount = 1, mnt_kern_flag = 0, mnt_flag = 4096, mnt_opt =
>> 0xfffff8000e59cc30, mnt_optnew = 0xfffff8001b9ea050,
>>   mnt_maxsymlinklen = 0, mnt_stat = {f_version = 537068824, f_type =
>> 135, f_flags = 4096, f_bsize = 4096, f_iosize = 4096, f_blocks =
>> 1835008, f_bfree = 1738991, f_bavail = 1738991, f_files = 25690112,
>>     f_ffree = 25676911, f_syncwrites = 0, f_asyncwrites = 0,
>> f_syncreads = 0, f_asyncreads = 0, f_spare = {0, 0, 0, 0, 0, 0, 0, 0,
>> 0, 0}, f_namemax = 255, f_owner = 0, f_fsid = {val = {-2029977843,
>>         135}}, f_charspare = '\0' <repeats 79 times>, f_fstypename =
>> "tmpfs\000\000\000\000\000\000\000\000\000\000", f_mntfromname =
>> "tmpfs", '\0' <repeats 82 times>,
>>     f_mntonname = "/poudriere/data/.m/exp-10amd64-commit-test/01",
>> '\0' <repeats 42 times>}, mnt_cred = 0xfffff80047478700, mnt_data =
>> 0xfffff800b5155980, mnt_time = 0, mnt_iosize_max = 65536,
>>   mnt_export = 0x0, mnt_label = 0x0, mnt_hashseed = 1147308587,
>> mnt_lockref = 0, mnt_secondary_writes = 0, mnt_secondary_accwrites =
>> 0, mnt_susp_owner = 0x0, mnt_gjprovider = 0x0, mnt_explock = {
>>     lock_object = {lo_name = 0xffffffff80f8fd0f "explock", lo_flags =
>> 108199936, lo_data = 0, lo_witness = 0xfffffe000070ef80}, lk_lock = 1,
>> lk_exslpfail = 0, lk_timo = 0, lk_pri = 96},
>>   mnt_upper_link = {tqe_next = 0x0, tqe_prev = 0x0}, mnt_uppers =
>> {tqh_first = 0x0, tqh_last = 0xfffff8004733a320}}
>

Shadowed object:

> (kgdb) p *$1->shadow_head->lh_first
> $3 = {lock = {lock_object = {lo_name = 0xffffffff80fe89f6 "vm object", lo_flags = 90374144, lo_data = 0, lo_witness = 0xfffffe00006e7680}, rw_lock = 1}, object_list = {tqe_next = 0xfffff801b8b3ae00,
>     tqe_prev = 0xfffff802717bb120}, shadow_head = {lh_first = 0x0}, shadow_list = {le_next = 0x0, le_prev = 0xfffff8027169f530}, memq = {tqh_first = 0xfffff811da2c75f8, tqh_last = 0xfffff811da2c7608},
>   rtree = {rt_root = 18446735354291320313, rt_flags = 0 '\0'}, size = 1, generation = 1, ref_count = 1, shadow_count = 0, memattr = 6 '\006', type = 0 '\0', flags = 12288, pg_color = 1598,
>   paging_in_progress = 0, resident_page_count = 1, backing_object = 0xfffff8027169f500, backing_object_offset = 0, pager_object_list = {tqe_next = 0x0, tqe_prev = 0x0}, rvq = {lh_first = 0x0}, cache = {
>     rt_root = 0, rt_flags = 0 '\0'}, handle = 0x0, un_pager = {vnp = {vnp_size = 0, writemappings = 0}, devp = {devp_pglist = {tqh_first = 0x0, tqh_last = 0x0}, ops = 0x0, dev = 0x0}, sgp = {
>       sgp_pglist = {tqh_first = 0x0, tqh_last = 0x0}}, swp = {swp_tmpfs = 0x0, swp_bcount = 0}}, cred = 0xfffff80038911800, charge = 4096}



-- 
Regards,
Bryan Drewery
Received on Tue Jul 22 2014 - 19:54:00 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:40:51 UTC