[Samba] shadow_copy2 not working
Tom Jermy
tomjermy at gmail.com
Fri Mar 28 10:44:05 MDT 2014
Hi folks,
I've been trying to get shadow copy / previous versions working under
sernet-samba-ad-4.1.6-7.el6.x86_64. I cannot see where I am going wrong. I
have:
[global]
workgroup = COMPANY
realm = COMPANY.LOCAL
netbios name = DC
server role = active directory domain controller
server services = s3fs, rpc, nbt, wrepl, ldap, cldap, kdc, drepl,
winbind, ntp_signd, kcc, dnsupdate
idmap_ldb:use rfc2307 = yes
netbios aliases = files files.company.local sbs sbs.company.local
server3 server3.company.local
wide links = yes
unix extensions = no
log level=10
max log size = 50000
[...]
[TekShare]
path = /home/TekShare
read only = No
vfs objects = shadow_copy2
shadow:snapdir = TekShareSnap
shadow:basedir = /home/TekShare
The directory & file system looks like this:
[root at dc @GMT-2014.03.28-14.36.11]# cat /etc/fstab | grep lv_home
/dev/mapper/vg_dc-lv_home /home ext4 user_xattr,acl,barrier=1 1 1
[root at dc home]# pwd
/home
[root at dc home]# ll
total 80
drwxr-xr-x 4 root root 4096 Mar 26 05:46 backuplocations
drwxrwxrwx+ 2 root root 4096 Mar 28 12:03 bacs
drwxrwx---+ 52 root root 4096 Mar 17 09:17 Company
drwxrwx---+ 5 Dropbox root 4096 Mar 23 18:09 Dropbox
drwx------ 2 root root 16384 Mar 21 18:35 lost+found
drwxrwx---+ 9 root root 4096 Feb 4 14:38 TekShare
drwxrwx---+ 4 root root 4096 Mar 28 14:39 TekShareSnap
drwxrwx---+ 19 root root 4096 Feb 4 14:40 Users
[root at dc TekShareSnap]# cd TekShareSnap
[root at dc TekShareSnap]# ll
total 16
drwxrwx---+ 3 root root 4096 Mar 28 14:36 2014.03.28-14.36.11
drwxrwx---+ 2 root root 4096 Mar 28 14:36 dirvish
lrwxrwxrwx 1 root root 43 Mar 28 14:39 @GMT-2014.03.28-14.36.11 ->
/home/TekShareSnap/2014.03.28-14.36.11/tree
[root at dc TekShareSnap]# cd \@GMT-2014.03.28-14.36.11/
[root at dc @GMT-2014.03.28-14.36.11]# ll
total 56
drwxrwx---+ 7 root root 4096 Feb 4 14:38 Bacs
drwxrwx---+ 7 root root 4096 Feb 4 14:38 Causeway
-rwxrwx---+ 1 root root 196 Sep 7 2010 edgespro.bin
drwxrwx---+ 2 root root 4096 Aug 15 2011 Incoming Faxes
-rwxrwx---+ 1 root root 120 Jul 9 2010 ISYSERROR.LOG
drwxrwx---+ 17 root root 4096 Feb 4 14:38 Accounts Prints
drwxrwx---+ 15 root root 4096 Mar 28 10:54 Other
drwxrwx---+ 2 root root 4096 Feb 4 14:38 Product Info
You can find selected highlights of my level 10 log.smbd below, the full
log is attached. Perhaps the timestamps I've got below will help with
debugging?
Thanks in advance for help
Tom
[2014/03/28 15:41:16.130561, 10, pid=4037, effective(0, 0), real(0, 0)]
../source3/modules/vfs_shadow_copy2.c:1741(shadow_copy2_connect)
../source3/modules/vfs_shadow_copy2.c:1741: cnum[697285368],
connectpath[/home/TekShare]
[2014/03/28 15:41:16.130808, 10, pid=4037, effective(0, 0), real(0, 0)]
../source3/modules/vfs_shadow_copy2.c:1953(shadow_copy2_connect)
shadow_copy2_connect: configuration:
share root: '/home/TekShare'
basedir: '/home/TekShare'
mountpoint: '/home'
rel share root: '(null)'
snapdir: 'TekShareSnap'
snapshot base path: '/home/TekShareSnap'
format: '@GMT-%Y.%m.%d-%H.%M.%S'
use sscanf: no
snapdirs everywhere: no
cross mountpoints: no
fix inodes: no
sort order: desc
[...]
[2014/03/28 15:41:16.617435, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/modules/vfs_shadow_copy2.c:1223(shadow_copy2_snapshot_to_gmt)
shadow_copy2_snapshot_to_gmt: match @GMT-%Y.%m.%d-%H.%M.%S:
@GMT-2014.03.28-14.36.11
[2014/03/28 15:41:16.617513, 6, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/modules/vfs_shadow_copy2.c:1330(shadow_copy2_get_shadow_copy_data)
shadow_copy2_get_shadow_copy_data: @GMT-2014.03.28-14.36.11 ->
@GMT-2014.03.28-14.36.11
[...]
[2014/03/28 15:41:16.618044, 10, pid=4038, effective(3000019, 100),
real(3000019, 0), class=vfs]
../source3/modules/vfs_default.c:1176(vfswrap_fsctl)
FSCTL_GET_SHADOW_COPY_DATA: 1 volumes for path[Accounts Prints/Year End -
Payroll/Payroll Year End 11/P35 Monthly.pdf].
Label[0]: '@GMT-2014.03.28-14.36.11'
[...]
[2014/03/28 15:41:16.618137, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_ioctl.c:291(smbd_smb2_request_ioctl_done)
smbd_smb2_request_ioctl_done: smbd_smb2_ioctl_recv returned 68 status
NT_STATUS_OK
[2014/03/28 15:41:16.618180, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:2499(smbd_smb2_request_done_ex)
smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[48]
dyn[yes:68] at ../source3/smbd/smb2_ioctl.c:358
[2014/03/28 15:41:16.618220, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:874(smb2_set_operation_credit)
smb2_set_operation_credit: requested 1, charge 1, granted 1, current
possible/max 482/512, total granted/max/low/range 31/8192/13/31
[2014/03/28 15:41:16.618571, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)] ../source3/smbd/smb2_server.c:3241(smbd_smb2_io_handler)
smbd_smb2_request idx[1] of 9 vectors
[2014/03/28 15:41:16.618646, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:621(smb2_validate_sequence_number)
smb2_validate_sequence_number: clearing id 13 (position 13) from bitmap
[2014/03/28 15:41:16.618686, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:621(smb2_validate_sequence_number)
smb2_validate_sequence_number: clearing id 14 (position 14) from bitmap
[2014/03/28 15:41:16.618724, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:1878(smbd_smb2_request_dispatch)
smbd_smb2_request_dispatch: opcode[SMB2_OP_GETINFO] mid = 13
[2014/03/28 15:41:16.618764, 4, pid=4038, effective(3000019, 100),
real(3000019, 0)] ../source3/smbd/uid.c:384(change_to_user)
Skipping user change - already user
[2014/03/28 15:41:16.618811, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:1780(smbd_smb2_request_verify_creditcharge)
mid 13, CreditCharge: 1, NeededCharge: 1
[2014/03/28 15:41:16.618853, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)] ../source3/smbd/smb2_getinfo.c:271(smbd_smb2_getinfo_send)
smbd_smb2_getinfo_send: Accounts Prints/Year End - Payroll/Payroll Year
End 11/P35 Monthly.pdf - fnum 2471567668
[2014/03/28 15:41:16.618915, 3, pid=4038, effective(3000019, 100),
real(3000019, 0)] ../source3/smbd/trans2.c:3092(smbd_do_qfsinfo)
smbd_do_qfsinfo: level = 1001
[2014/03/28 15:41:16.618958, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/modules/vfs_shadow_copy2.c:242(shadow_copy2_strip_snapshot)
../source3/modules/vfs_shadow_copy2.c:242: enter path 'Accounts
Prints/Year End - Payroll/Payroll Year End 11/P35 Monthly.pdf'
[2014/03/28 15:41:16.619074, 5, pid=4038, effective(3000019, 100),
real(3000019, 0)] ../source3/smbd/trans2.c:3244(smbd_do_qfsinfo)
smbd_do_qfsinfo : SMB_QUERY_FS_VOLUME_INFO namelen = 8, vol=TekShare
serv=TekShare
[2014/03/28 15:41:16.619136, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:2499(smbd_smb2_request_done_ex)
smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[8]
dyn[yes:34] at ../source3/smbd/smb2_getinfo.c:200
[2014/03/28 15:41:16.619192, 10, pid=4038, effective(3000019, 100),
real(3000019, 0)]
../source3/smbd/smb2_server.c:2467(smbd_smb2_request_dispatch_immediate)
smbd_smb2_request_dispatch_immediate: idx[5] of 9 vectors
req->in.vector[0].iov_len = 0
req->in.vector[1].iov_len = 0
req->in.vector[2].iov_len = 64
req->in.vector[3].iov_len = 40
req->in.vector[4].iov_len = 8
req->in.vector[5].iov_len = 0
req->in.vector[6].iov_len = 64
req->in.vector[7].iov_len = 40
req->in.vector[8].iov_len = 1
req->out.vector[0].iov_len = 4
req->out.vector[1].iov_len = 0
req->out.vector[2].iov_len = 64
req->out.vector[3].iov_len = 8
req->out.vector[4].iov_len = 40
req->out.vector[5].iov_len = 0
req->out.vector[6].iov_len = 64
req->out.vector[7].iov_len = 8
req->out.vector[8].iov_len = 0
More information about the samba
mailing list