oplock errors/copying files between ntfs and samba shares - more info

John Reid john_reid at uow.edu.au
Sun Jan 17 08:30:56 GMT 1999


Hi all,

some more info from log files and directory listings, hope it helps

contents of log.mopoke:

[1999/01/17 17:34:37, 2] libsmb/namequery.c:name_query(300)
  Got a positive name query response from 130.130.120.10 (
130.130.123.180 130.130.120.10 )
[1999/01/17 17:34:37, 2] lib/access.c:check_access(249)
  Allowed connection from geo13.sci.uow.edu.au (130.130.120.83)
[1999/01/17 17:34:37, 2] lib/access.c:check_access(249)
  Allowed connection from geo13.sci.uow.edu.au (130.130.120.83)
[1999/01/17 17:34:37, 1] smbd/service.c:make_connection(488)
  mopoke (130.130.120.83) connect to service smbtest as user smbtest
(uid=2000, gid=1) (pid 10200)


*****could this possibly be the root of my problems?****************
[1999/01/17 17:34:38, 0] smbd/nttrans.c:call_nt_transact_ioctl(1658)
  call_nt_transact_ioctl: Currently not implemented.

*****copy files from ntfs partition on,local hard drive***********
[1999/01/17 17:45:29, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/ntfsd/cultural.shp read=No write=Yes
(numopen=1)
[1999/01/17 17:45:30, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/ntfsd/cultural.dbf read=No write=Yes
(numopen=2)
[1999/01/17 17:45:30, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/ntfsd/cultural.shx read=No write=Yes
(numopen=3)

*****attempt to get file listing by arcview open file dialog
box*********
[1999/01/17 17:47:09, 2] smbd/oplock.c:oplock_break(725)
  oplock_break resend
[1999/01/17 17:47:19, 2] smbd/oplock.c:oplock_break(725)
  oplock_break resend
[1999/01/17 17:47:37, 2] smbd/oplock.c:oplock_break(725)
  oplock_break resend
[1999/01/17 17:47:47, 0] smbd/oplock.c:oplock_break(742)
  oplock_break: receive_smb timed out after 30 seconds.
  oplock_break failed for file log2/ntfsd/cultural.shp (dev = 1980007,
inode = 226832).
[1999/01/17 17:47:47, 0] smbd/oplock.c:oplock_break(812)
  oplock_break: client failure in break - shutting down this smbd.
[1999/01/17 17:47:47, 2] smbd/server.c:exit_server(406)
  Closing connections
[1999/01/17 17:47:47, 1] smbd/service.c:close_cnum(514)
  mopoke (0.0.0.0) closed connection to service smbtest
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226834
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226834
[1999/01/17 17:47:47, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/ntfsd/cultural.shx (numopen=2) 
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226833
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226833
[1999/01/17 17:47:47, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/ntfsd/cultural.dbf (numopen=1) 
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226832
[1999/01/17 17:47:47, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226832
[1999/01/17 17:47:47, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/ntfsd/cultural.shp (numopen=0) 
[1999/01/17 17:47:47, 2] libsmb/namequery.c:name_query(300)
  Got a positive name query response from 130.130.120.10 (
130.130.123.180 130.130.120.10 )
[1999/01/17 17:47:47, 2] lib/access.c:check_access(249)
  Allowed connection from geo13.sci.uow.edu.au (130.130.120.83)
[1999/01/17 17:47:47, 1] smbd/service.c:make_connection(488)
  mopoke (130.130.120.83) connect to service smbtest as user smbtest
(uid=2000, gid=1) (pid 10210)

********copy files from fat partition****************
[1999/01/17 17:49:08, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.shp read=No write=Yes
(numopen=1)
[1999/01/17 17:49:08, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226835
[1999/01/17 17:49:08, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226835
[1999/01/17 17:49:08, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.shp (numopen=0) 
[1999/01/17 17:49:08, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.dbf read=No write=Yes
(numopen=1)
[1999/01/17 17:49:09, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226836
[1999/01/17 17:49:09, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226836
[1999/01/17 17:49:09, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.dbf (numopen=0) 
[1999/01/17 17:49:09, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.shx read=No write=Yes
(numopen=1)
[1999/01/17 17:49:09, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226837
[1999/01/17 17:49:09, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226837
[1999/01/17 17:49:09, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.shx (numopen=0) 

******arcview open file dialog box successfully gets directory listing
of files originating on fat partition*********

[1999/01/17 17:49:34, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.shp read=Yes write=No
(numopen=1)
[1999/01/17 17:49:34, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.shx read=Yes write=No
(numopen=2)
[1999/01/17 17:49:38, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.dbf read=Yes write=No
(numopen=3)


*****exiting arcview ????????????************
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226835
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226835
[1999/01/17 17:50:22, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.shp (numopen=2) 
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226837
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226837
[1999/01/17 17:50:22, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.shx (numopen=1) 
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226836
[1999/01/17 17:50:22, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226836
[1999/01/17 17:50:22, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.dbf (numopen=0) 
[1999/01/17 17:50:28, 2] smbd/open.c:open_file(569)
  smbtest opened file log2/fat/cultural.dbf read=Yes write=No
(numopen=1)
[1999/01/17 17:50:37, 2] locking/locking_shm.c:shm_del_share_mode(354)
  del_share_modes Deleting share mode entry dev=1980007 ino=226836
[1999/01/17 17:50:37, 2] locking/locking_shm.c:shm_del_share_mode(375)
  del_share_modes num entries = 0, deleting share_mode dev=1980007
ino=226836
[1999/01/17 17:50:37, 2] smbd/close.c:close_file(165)
  smbtest closed file log2/fat/cultural.dbf (numopen=0) 
[1999/01/17 17:50:37, 1] smbd/service.c:close_cnum(514)
  mopoke (130.130.120.83) closed connection to service smbtest
[1999/01/17 17:50:37, 2] smbd/server.c:exit_server(406)
  Closing connections

contents of log.smb:

[1999/01/17 17:34:11, 1] smbd/server.c:main(614)
  smbd version 2.0.0 started.
  Copyright Andrew Tridgell 1992-1998
[1999/01/17 17:34:11, 2] param/loadparm.c:do_section(2182)
  Processing section "[homes]"
[1999/01/17 17:34:11, 2] param/loadparm.c:do_section(2182)
  Processing section "[pcrdist]"
[1999/01/17 17:34:11, 2] param/loadparm.c:do_section(2182)
  Processing section "[homesdir]"
[1999/01/17 17:34:11, 2] lib/interface.c:interpret_interfaces(213)
  Added interface ip=130.130.120.15 bcast=130.130.120.255
nmask=255.255.255.0
[1999/01/17 17:34:11, 1] smbd/files.c:file_init(219)
  file_init: Information only: requested 10000 open files, 1014 are
available.
[1999/01/17 17:34:11, 2] smbd/server.c:open_sockets(183)
  waiting for a connection
[1999/01/17 17:34:37, 2] smbd/server.c:main(702)
  Changed root to /
[1999/01/17 17:34:37, 2] lib/access.c:check_access(249)
  Allowed connection from geo13.sci.uow.edu.au (130.130.120.83)
[1999/01/17 17:34:37, 2] smbd/reply.c:reply_special(95)
  netbios connect: name1=GEO-PS2          name2=MOPOKE         

***********attempted read of arcview files originating from ntfs
partition???**************

[1999/01/17 17:47:47, 2] smbd/server.c:main(702)
  Changed root to /
[1999/01/17 17:47:47, 2] lib/access.c:check_access(249)
  Allowed connection from geo13.sci.uow.edu.au (130.130.120.83)
[1999/01/17 17:47:47, 2] smbd/reply.c:reply_special(95)
  netbios connect: name1=GEO-PS2          name2=MOPOKE         
[1999/01/17 17:50:45, 2] smbd/server.c:exit_server(406)
  Closing connections


contents of log2 (directory listing from Solaris):

fat:
total 892
drwxr-xr-x   2 smbtest  other        512 Jan 17 17:49 .
drwxr-xr-x   5 smbtest  other        512 Jan 17 18:13 ..
-rw-------   1 smbtest  other     218458 May 14  1998 cultural.dbf
-rw-------   1 smbtest  other     201060 May 14  1998 cultural.shp
-rw-------   1 smbtest  other      11812 May 14  1998 cultural.shx

ntfsd:
total 812
drwxr-xr-x   2 smbtest  other        512 Jan 17 17:45 .
drwxr-xr-x   5 smbtest  other        512 Jan 17 18:13 ..
-rw-------   1 smbtest  other     218458 Jan 17 17:45 cultural.dbf
-rw-------   1 smbtest  other     201060 Jan 17 17:45 cultural.shp
-rw-------   1 smbtest  other      11812 Jan 17 17:45 cultural.shx

ntfss:
total 4
drwxr-xr-x   2 smbtest  other        512 Jan 17 17:27 .
drwxr-xr-x   5 smbtest  other        512 Jan 17 18:13 ..

results of "du -k *" in directory log2 (Interesting!!!! - according to
ls -al should be the same):  
445	fat
1	filecmp.txt
1	filelist.txt
405	ntfsd
1	ntfss

results of "cmp fat/cultural.shp ntfsd/cultural.shp":
fat/cultural.shp ntfsd/cultural.shp differ: char 184325, line 496

these logs were produced running samba 2.0.0 without disk quota support
enabled.  any ideas? 

cheers,
John
 
--------------------------------------------------------------------
john reid                                e-mail john_reid at uow.edu.au
technical officer                              room G02, building 41
school of geosciences                         phone +61 02 4221 3963
university of wollongong                        fax +61 02 4221 4250
        
computers can figure out all kinds of problems, except the things in
the world that just don't add up

apply standard disclaimers as desired...
---------------------------------------------------------------------


More information about the samba-ntdom mailing list