scrub (checkup), mounts of snapshots, recycling of vnodes

Moderators: jhartley, MSR734, nola

scrub (checkup), mounts of snapshots, recycling of vnodes

Post by grahamperrin » Fri Nov 16, 2012 3:44 am

From HardwareGrowler, just one notification – 

2012-11-16 06-55 screenshot.png
2012-11-16 06-55 screenshot.png (16.8 KiB) Viewed 30 times


– in parallel –

Code: Select all
2012-11-16.06:45:53 [internal pool import txg:2361152] pool spa 28; zfs spa 28; zpl 5; uts macbookpro08-centrim.home@1B4C77AE-B80A-59F9-B5CB-7A86B7437D40 12.2.0 Darwin Kernel Version 12.2.0: Sat Aug 25 00:48:52 PDT 2012; root:xnu-2050.18.24~1/RELEASE_X86_64 MacBookPro5,2
2012-11-16.06:46:13 [internal pool scrub txg:2361158] func=1 mintxg=0 maxtxg=2361158
2012-11-16.06:46:21 zpool scrub zhandy


– and (beginning a little earlier, including the disconnection then reconnection of an external hard disk and its cache vdev):

Code: Select all
2012-11-16 06:45:36.000 kernel[0]: ZFSLabelScheme:willTerminate: this 0xffffff803e76fb00 provider 0xffffff804083f200 'zfs vdev for 'zhandy''
2012-11-16 06:45:36.000 kernel[0]: ZFSLabelScheme:stop: 0xffffff803e76fb00 goodbye 'zfs vdev for 'zhandy''
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-151427 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-061544 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-220908 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-215119 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-133503 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-200909 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-103613 in avl, but vnode 0 was recycled
2012-11-16 06:45:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-143259 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-124533 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-133259 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-094705 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-194830 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-161546 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-213449 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: ZFSLabelScheme:willTerminate: this 0xffffff803e76fb00 provider 0xffffff803e7b5400 '%noformat%'
2012-11-16 06:45:37.000 kernel[0]: ZFSLabelScheme:stop: 0xffffff803e76fb00 goodbye '%noformat%'
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-082843 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-043448 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-084731 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-033448 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-20-221319 in avl, but vnode 0 was recycled
2012-11-16 06:45:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-064731 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-111338 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-073613 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-013449 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-121930 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-124704 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-051909 in avl, but vnode 0 was recycled
2012-11-16 06:45:38.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-142042 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-201531 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-154703 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-153259 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-05-182842 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-205119 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-181950 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-042845 in avl, but vnode 0 was recycled
2012-11-16 06:45:39.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-071544 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-141427 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-111543 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-131427 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-221540 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-100920 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-122842 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-062844 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-195120 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-091544 in avl, but vnode 0 was recycled
2012-11-16 06:45:40.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-015647 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-164703 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-225647 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-163259 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-19-151147 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-161541 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-181541 in avl, but vnode 0 was recycled
2012-11-16 06:45:41.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-001539 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-121543 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-190909 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-211540 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-110919 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-081011 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-065316 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-030031 in avl, but vnode 0 was recycled
2012-11-16 06:45:42.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-052844 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-06-160845 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-223449 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: ZFSLabelScheme:probe: label '???', vdev 4693709588154435632
2012-11-16 06:45:43.000 kernel[0]: ZFSLabelScheme:hasCriticalMass: 0xffffff803e7ad700 no top level vdevs!
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-193450 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-31-165618 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-044732 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-075234 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-081910 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-173450 in avl, but vnode 0 was recycled
2012-11-16 06:45:43.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-220008 in avl, but vnode 0 was recycled
2012-11-16 06:45:44.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-171749 in avl, but vnode 0 was recycled
2012-11-16 06:45:44.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-25-224433 in avl, but vnode 0 was recycled
2012-11-16 06:45:44.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-003448 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-142909 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-024731 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-094730 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-114704 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-061909 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-053447 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-191531 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-051544 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-180909 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-201540 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-120920 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-181532 in avl, but vnode 0 was recycled
2012-11-16 06:45:45.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-104730 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-191541 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-210908 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-171541 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-235647 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-053614 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-02-215522 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-034731 in avl, but vnode 0 was recycled
2012-11-16 06:45:46.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-104704 in avl, but vnode 0 was recycled
2012-11-16 06:45:47.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-174041 in avl, but vnode 0 was recycled
2012-11-16 06:45:47.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-103110 in avl, but vnode 0 was recycled
2012-11-16 06:45:47.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-071910 in avl, but vnode 0 was recycled
2012-11-16 06:45:47.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-054732 in avl, but vnode 0 was recycled
2012-11-16 06:45:48.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-065234 in avl, but vnode 0 was recycled
2012-11-16 06:45:48.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-233449 in avl, but vnode 0 was recycled
2012-11-16 06:45:48.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-041055 in avl, but vnode 0 was recycled
2012-11-16 06:45:48.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-183450 in avl, but vnode 0 was recycled
2012-11-16 06:45:50.000 kernel[0]: ZFSLabelScheme:probe: label 'zhandy', vdev 10616857169251329946
2012-11-16 06:45:50.000 kernel[0]: ZFSLabelScheme:start: 'zhandy' critical mass with 2 vdev(s) (importing)
2012-11-16 06:45:50.000 kernel[0]: zfsx_kev_importpool:'zhandy' (4688397874579579662)
2012-11-16 06:45:51.000 kernel[0]: zfsx_vdm_open: 'zhandy' disk5s2
2012-11-16 06:45:51.000 kernel[0]: zfsx_vdm_open: 'zhandy' disk4s2
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_close: 'disk4s2'
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_open: 'zhandy' disk4s2
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_close: 'disk4s2'
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_open: 'zhandy' disk4s2
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_close: 'disk4s2'
2012-11-16 06:45:53.000 kernel[0]: zfsx_vdm_open: 'zhandy' disk4s2
2012-11-16 06:45:53.000 kernel[0]: zfsx_mount: '/Volumes/zhandy'
2012-11-16 06:45:54.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine'
2012-11-16 06:46:05.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:47:01.159 sudo[24135]:    gjp22 : TTY=ttys000 ; PWD=/private/var/tmp ; USER=root ; COMMAND=/usr/local/bin/zdb history zhandy
2012-11-16 06:47:12.597 sudo[24142]:    gjp22 : TTY=ttys000 ; PWD=/private/var/tmp ; USER=root ; COMMAND=/usr/local/bin/zdb -h zhandy
2012-11-16 06:49:06.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:49:32.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:52:32.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:52:57.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:55:57.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:56:56.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:59:56.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:01:20.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:03:11.553 sudo[26607]:    gjp22 : TTY=ttys000 ; PWD=/Volumes/gjp22 ; USER=root ; COMMAND=/usr/local/bin/zdb -hvvvvv zhandy
2012-11-16 07:04:20.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:04:46.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:07:46.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:08:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:11:20.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:11:51.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:14:51.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:15:16.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-113844 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-032403 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-224204 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-022403 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-012403 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-214203 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-23-141640 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-133844 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-23-151639 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-24-012156 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-18-215455 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-204203 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-24-002156 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-06-28-084144 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-123844 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-134704 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-144704 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-074731 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-063613 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-22-230716 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-023448 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-184829 in avl, but vnode 0 was recycled
2012-11-16 07:15:30.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-092843 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-203449 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-070637 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-090920 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-26-225142 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-081543 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-04-172528 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-112843 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-063447 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-005647 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-041545 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-151542 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-072844 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-185120 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-101543 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-121427 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-231540 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-132842 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-130919 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-151427 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-061544 in avl, but vnode 0 was recycled
2012-11-16 07:15:31.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-220908 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-215119 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-133503 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-200909 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-103613 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-143259 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-124533 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-133259 in avl, but vnode 0 was recycled
2012-11-16 07:15:32.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-094705 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-194830 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-161546 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-213449 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-082843 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-043448 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-084731 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-033448 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-20-221319 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-064731 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-111338 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-073613 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-013449 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-121930 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-124704 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-051909 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-142042 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-201531 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-154703 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-153259 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-05-182842 in avl, but vnode 0 was recycled
2012-11-16 07:15:33.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-205119 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-181950 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-042845 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-071544 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-141427 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-111543 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-131427 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-221540 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-100920 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-122842 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-062844 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-195120 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-091544 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-015647 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-164703 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-225647 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-163259 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-19-151147 in avl, but vnode 0 was recycled
2012-11-16 07:15:34.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-161541 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-181541 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-001539 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-121543 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-190909 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-211540 in avl, but vnode 0 was recycled
2012-11-16 07:15:35.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-110919 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-081011 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-065316 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-030031 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-052844 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-06-160845 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-223449 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-193450 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-31-165618 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-044732 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-075234 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-081910 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-173450 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-220008 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-171749 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-25-224433 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-003448 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-142909 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-024731 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-094730 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-114704 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-061909 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-053447 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-191531 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-051544 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-180909 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-201540 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-120920 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-181532 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-104730 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-191541 in avl, but vnode 0 was recycled
2012-11-16 07:15:36.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-210908 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-11-171541 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-235647 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-09-053614 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-02-215522 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-034731 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-104704 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-174041 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-10-103110 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-12-071910 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-054732 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-08-065234 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-233449 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-15-041055 in avl, but vnode 0 was recycled
2012-11-16 07:15:37.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-14-183450 in avl, but vnode 0 was recycled
2012-11-16 07:18:16.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:19:03.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:22:03.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:23:04.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:26:04.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:26:29.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:29:29.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:29:52.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:32:53.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:33:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:36:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:36:43.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:39:43.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:40:07.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:43:07.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:43:31.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:46:31.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:46:55.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:49:55.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:50:20.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:53:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:53:45.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:56:45.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:57:11.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:00:11.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:00:35.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:03:35.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:04:04.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:07:04.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:07:28.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:10:28.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:10:53.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:13:53.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:14:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-113844 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-032403 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-224204 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-022403 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-10-24-012403 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-214203 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-23-141640 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-133844 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-23-151639 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-24-012156 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-18-215455 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-19-204203 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-24-002156 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-06-28-084144 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-09-30-123844 in avl, but vnode 0 was recycled
2012-11-16 08:15:54.000 kernel[0]: zfsctl_snapdir_lookup: found 2012-11-13-134704 in avl, but vnode 0 was recycled
2012-11-16 08:17:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:17:45.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:20:45.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:21:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:24:09.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:24:40.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:27:40.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:28:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:31:09.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:31:35.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:34:35.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:34:58.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:37:58.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:38:24.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:41:24.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:41:49.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:44:49.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:45:15.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:48:15.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:48:42.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:51:25.000 kernel[0]: still waiting on znode 17359341 (0xffffff819af8ae20) REG ZIL
2012-11-16 08:51:26.000 kernel[0]: still waiting on znode 17359341 (0xffffff819af8ae20) REG ZIL
2012-11-16 08:51:28.000 kernel[0]: still waiting on znode 17359341 (0xffffff819af8ae20) REG ZIL
2012-11-16 08:51:42.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:52:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'


… et cetera.

I assume that each snapshot may be mounted then unmounted, repeatedly, for as long as is necessary during a scrub. A filtered view of the same messages:

Code: Select all
2012-11-16 06:46:05.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:49:06.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:49:32.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:52:32.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:52:57.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:55:57.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 06:56:56.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 06:59:56.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:01:20.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:04:20.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:04:46.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:07:46.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:08:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:11:20.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:11:51.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:14:51.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:15:16.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:18:16.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:19:03.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:22:03.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:23:04.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:26:04.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:26:29.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:29:29.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:29:52.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:32:53.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:33:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:36:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:36:43.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:39:43.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:40:07.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:43:07.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:43:31.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:46:31.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:46:55.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:49:55.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:50:20.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:53:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:53:45.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 07:56:45.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 07:57:11.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:00:11.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:00:35.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:03:35.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:04:04.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:07:04.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:07:28.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:10:28.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:10:53.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:13:53.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:14:19.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:17:19.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:17:45.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:20:45.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:21:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:24:09.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:24:40.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:27:40.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:28:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:31:09.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:31:35.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:34:35.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:34:58.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:37:58.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:38:24.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:41:24.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:41:49.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:44:49.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:45:15.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:48:15.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:48:42.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:51:42.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)
2012-11-16 08:52:09.000 kernel[0]: zfsx_mount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148'
2012-11-16 08:55:09.000 kernel[0]: zfsx_unmount: '/Volumes/zhandy/Pocket Time Machine/.zfs/snapshot/2012-09-17-195148' (zfs_delegate)


Maybe I'll see, later, more notifications from HardwareGrowler – one per snapshot.

Recycling of vnodes

Do those messages from kernel suggest further investigation?

In my case snapshots are thinned automatically by a script (Local/Remote Backups Time Machine style, automatic scrub).
grahamperrin Offline

User avatar
 
Posts: 1596
Joined: Fri Sep 14, 2012 10:21 pm
Location: Brighton and Hove, United Kingdom

Return to General Discussion

Who is online

Users browsing this forum: ilovezfs and 3 guests

cron