[Samba] Show stopper - 2nd cry for help

Sean Noonan snoonan at snoonan.com
Sun Oct 6 17:22:01 GMT 2002


<Long Post, sorry!>

Folks, I have a really nasty problem preventing me from migrating a bunch of
NetWare 5 servers to Samba--attempts to copy large (> 2MB+) files to any
samba share results in an error.  To clarify what I mean by "error":

1. I attempt to copy, using explorer, a 2MB+ file to any samba share.
2. A 30 second or so pause occurs (copying this file to any other local
server, including a different samba server, completes in a couple of
seconds)
3. A message box pops up on my screen.  The title of the message box is
"Error Copying File or Folder".  The contents of the message box is "Cannot
copy <filename>:  The specified network name is no longer available".
4. I click "OK" to acknowledge the message.
5. Despite the error message, the file _appears_ to have actually copied
successfully, that is it appears in the target explorer window and has the
correct size.
6. Error messages are generated in the samba logs, mostly regarding oplocks.
7. Running smbstatus on the target server shows the file that was copied is
locked:

Samba version 2.2.6pre2
Service      uid      gid      pid     machine
----------------------------------------------
rootpart     root     wheel    12099   sean     (192.168.100.103) Sun Oct  6
09:40:51 2002
rootpart     root     wheel     9981   sean     (192.168.100.103) Sat Oct  5
21:50:27 2002

Locked files:
Pid    DenyMode   Access      R/W        Oplock           Name
--------------------------------------------------------------
12099  DENY_NONE  0x20189     RDONLY     EXCLUSIVE+BATCH
//tmp/Jennifer-Watkins97Data.mdb   Sun Oct  6 09:40:51 2002

Here's some other observations/facts which may be useful in helping me:

1. All clients are Windows 2000 Professional + SP3 + all hot fixes.
2. The Samba target server giving me error is running samba 2.2.6pre2 on
FreeBSD 4.6.2-RELEASE.
3. I have another server running samba 2.2.6pre2 on FreeBSD 4.6.2-RELEASE,
too.  It's acting as the PDC and WINS server.  File copies to _it_ work just
great!  The smb.conf files vary only slightly between the two machines.  The
differences in config files are due to two reasons.  The first is that the
second server is acting as a PDC and WINS server.  The second is that the
first machine, the one that's giving me trouble, is a multi-homed gateway.
So, I'm specifying the "interfaces" parameter and forcing the NetBIOS name
to match it's internal, RFC1918, dns name.  Those are the only differences.
4. The server that's giving me trouble is named IMAIL.
5. The server that works just fine, the PDC and WINS server, is named MOLD.
6. The NT Domain on MOLD is called WATDOM.
7. IMAIL is a member of WATDOM.
8. The W2K workstation trying to perform the file copies is called SEAN.
9. All machines are on one local subnet, using switched 100Mbps Ethernet.
Very fast, no network or PC hardware problems.
10. In addition to using exploder to try and copy files, I've tried copying
using a DOS Prompt.  Pretty much the same thing happens except for the GUI
popup.  Instead of the GUI popup error message, xcopy or copy returns "File
creation error - The specified network name is no longer available".
11. I played with disabling oplocks, level 2 oplocks, kernel oplocks, etc.
No love.
12.  I have "log level = 3" in my smb.conf file.  This file copy procedure
seems to modify three log files:  /var/log/messages, /var/log/log.smbd and
/var/log/log.sean.  Below are  relevant snippets of those logs:

/var/log/messages

Oct  5 21:50:59 mail smbd[9981]: [2002/10/05 21:50:59, 0]
smbd/oplock.c:request_oplock_break(1008)
Oct  5 21:50:59 mail smbd[9981]:   request_oplock_break: no response
received to oplock break request to pid 9330 on port 3824 for dev = 27405,
inode = 36, file_id = 9
Oct  5 21:50:59 mail smbd[9981]: [2002/10/05 21:50:59, 0]
smbd/open.c:open_mode_check(631)
Oct  5 21:50:59 mail smbd[9981]:   open_mode_check: exlusive oplock left by
process 9330 after break ! For file tmp/Jennifer-Watkins97Data.mdb, dev =
27405, inode = 36. Deleting it to continue...
Oct  5 21:50:59 mail smbd[9981]: [2002/10/05 21:50:59, 0]
smbd/open.c:open_mode_check(635)
Oct  5 21:50:59 mail smbd[9981]:   open_mode_check: Existent process 9330
left active oplock.

/var/log/log.smbd
[2002/10/05 21:50:26, 3] smbd/oplock.c:init_oplocks(1211)
  open_oplock_ipc: opening loopback UDP socket.
[2002/10/05 21:50:26, 3] lib/util_sock.c:open_socket_in(813)
  bind succeeded on port 0
[2002/10/05 21:50:26, 3] smbd/oplock.c:init_oplocks(1242)
  open_oplock ipc: pid = 9981, global_oplock_port = 4269
[2002/10/05 21:50:26, 3] lib/access.c:check_access(318)
  check_access: no hostnames in host allow/deny list.
[2002/10/05 21:50:26, 2] lib/access.c:check_access(329)
  Allowed connection from  (192.168.100.103)
[2002/10/05 21:50:26, 3] smbd/process.c:process_smb(878)
  Transaction 0 of length 72
[2002/10/05 21:50:26, 2] smbd/reply.c:reply_special(92)
  netbios connect: name1=IMAIL            name2=SEAN
[2002/10/05 21:50:26, 2] smbd/reply.c:reply_special(111)
  netbios connect: local=imail remote=sean

/var/log/log.sean

[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:49:34, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 564 of length 89
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2findfirst(900)
  call_trans2findfirst: dirtype = 22, maxentries = 1366,
close_after_first=1, close_if_end = 1 requires_resume_key = 1 level = 260,
max_data_bytes = 16384
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp]
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp]
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [./]
[2002/10/05 21:50:07, 3] smbd/dir.c:dptr_create(488)
  creating new dirptr 256 for path ./, expect_close = 1
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 565 of length 83
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp]
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp]
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2qfilepathinfo(1730)
  call_trans2qfilepathinfo tmp level=257 call=5 total_data=0
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 566 of length 87
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2findfirst(900)
  call_trans2findfirst: dirtype = 22, maxentries = 1366,
close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 260,
max_data_bytes = 16384
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/*]
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [*]
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [./]
[2002/10/05 21:50:07, 3] smbd/dir.c:dptr_create(488)
  creating new dirptr 256 for path ./, expect_close = 1
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 567 of length 74
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2qfsinfo(1352)
  call_trans2qfsinfo: level = 258
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 568 of length 74
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2qfsinfo(1352)
  call_trans2qfsinfo: level = 261
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 569 of length 74
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2qfsinfo(1352)
  call_trans2qfsinfo: level = 261
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 570 of length 119
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp/Jennifer-Watkins97Data.mdb]
[2002/10/05 21:50:07, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(tmp/Jennifer-Watkins97Data.mdb) returning 0764
[2002/10/05 21:50:07, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp/Jennifer-Watkins97Data.mdb]
[2002/10/05 21:50:07, 2] smbd/open.c:open_file(233)
  noonans opened file tmp/Jennifer-Watkins97Data.mdb read=No write=Yes
(numopen=2)
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 571 of length 88
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2setfilepathinfo(2414)
  call_trans2setfilepathinfo(8) tmp/Jennifer-Watkins97Data.mdb
info_level=260 totdata=8
[2002/10/05 21:50:07, 3] smbd/process.c:process_smb(878)
  Transaction 572 of length 120
[2002/10/05 21:50:07, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9330)
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:07, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:07, 3] smbd/trans2.c:call_trans2setfilepathinfo(2414)
  call_trans2setfilepathinfo(8) tmp/Jennifer-Watkins97Data.mdb
info_level=257 totdata=40
[2002/10/05 21:50:26, 3] smbd/process.c:process_smb(878)
  Transaction 1 of length 137
[2002/10/05 21:50:26, 3] smbd/process.c:switch_message(685)
  switch message SMBnegprot (pid 9981)
[2002/10/05 21:50:26, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [PC NETWORK PROGRAM 1.0]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [LANMAN1.0]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [Windows for Workgroups 3.1a]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [LM1.2X002]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [LANMAN2.1]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(342)
  Requested protocol [NT LM 0.12]
[2002/10/05 21:50:26, 3] smbd/negprot.c:reply_negprot(426)
  Selected protocol NT LM 0.12
[2002/10/05 21:50:27, 3] smbd/process.c:process_smb(878)
  Transaction 2 of length 200
[2002/10/05 21:50:27, 3] smbd/process.c:switch_message(685)
  switch message SMBsesssetupX (pid 9981)
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/reply.c:reply_sesssetup_and_X(858)
  Domain=[WATKINS]  NativeOS=[Windows 2000 2195] NativeLanMan=[Windows 2000
5.0]
[2002/10/05 21:50:27, 3] smbd/reply.c:reply_sesssetup_and_X(868)
  sesssetupX:name=[noonans]
[2002/10/05 21:50:27, 3] libsmb/namequery.c:resolve_lmhosts(768)
  resolve_lmhosts: Attempting lmhosts lookup for name MOLD<0x20>
[2002/10/05 21:50:27, 3] libsmb/namequery.c:resolve_hosts(808)
  resolve_hosts: Attempting host lookup for name MOLD<0x20>
[2002/10/05 21:50:27, 3] lib/util_sock.c:open_socket_out(845)
  Connecting to 192.168.100.3 at port 445
[2002/10/05 21:50:27, 2] lib/util_sock.c:open_socket_out(874)
  error connecting to 192.168.100.3:445 (Invalid argument)
[2002/10/05 21:50:27, 3] lib/util_sock.c:open_socket_out(845)
  Connecting to 192.168.100.3 at port 139
[2002/10/05 21:50:27, 3] param/loadparm.c:lp_add_home(1970)
  adding home directory noonans at /home/noonans
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:push_sec_ctx(297)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2002/10/05 21:50:27, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:get_current_groups(172)
  get_current_groups: user is in 3 groups: 1001, 1001, 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:get_current_groups(172)
  get_current_groups: user is in 3 groups: 1001, 1001, 0
[2002/10/05 21:50:27, 3] smbd/password.c:register_vuid(336)
  uid 1001 registered to name noonans
[2002/10/05 21:50:27, 3] smbd/password.c:register_vuid(338)
  Clearing default real name
[2002/10/05 21:50:27, 3] smbd/password.c:register_vuid(340)
  User name: noonans	Real name: User & Sean Noonan
[2002/10/05 21:50:27, 3] smbd/process.c:chain_reply(1023)
  Chained message
[2002/10/05 21:50:27, 3] smbd/process.c:switch_message(685)
  switch message SMBtconX (pid 9981)
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] lib/access.c:check_access(318)
  check_access: no hostnames in host allow/deny list.
[2002/10/05 21:50:27, 2] lib/access.c:check_access(329)
  Allowed connection from  (192.168.100.103)
[2002/10/05 21:50:27, 3] smbd/password.c:authorise_login(736)
  authorise_login: ACCEPTED: validated uid ok as non-guest (user=noonans)
[2002/10/05 21:50:27, 0] smbd/service.c:make_connection(384)
  noonans logged in as admin user (root privileges)
[2002/10/05 21:50:27, 3] smbd/service.c:make_connection(429)
  Forced user root
[2002/10/05 21:50:27, 3] smbd/service.c:make_connection(491)
  Connect path is /
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:push_sec_ctx(297)
  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
[2002/10/05 21:50:27, 3] smbd/uid.c:push_conn_ctx(286)
  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:get_current_groups(172)
  get_current_groups: user is in 8 groups: 0, 0, 2, 3, 4, 5, 20, 31
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:pop_sec_ctx(436)
  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:get_current_groups(172)
  get_current_groups: user is in 8 groups: 0, 0, 2, 3, 4, 5, 20, 31
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(269)
  se_access_check: user sid is S-1-5-21-616125562-1043695104-349128688-3002
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-21-616125562-1043695104-349128688-3003
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-21-616125562-1043695104-349128688-1001
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-21-616125562-1043695104-349128688-513
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-21-616125562-1043695104-349128688-512
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-1-0
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-2
[2002/10/05 21:50:27, 3] lib/util_seaccess.c:se_access_check(273)
  se_access_check: also S-1-5-11
[2002/10/05 21:50:27, 3] smbd/vfs.c:vfs_init_default(122)
  Initialising default vfs hooks
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:27, 3] smbd/vfs.c:vfs_ChDir(568)
  vfs_ChDir to /
[2002/10/05 21:50:27, 1] smbd/service.c:make_connection(640)
  sean (192.168.100.103) connect to service rootpart as user root (uid=0,
gid=0) (pid 9981)
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/reply.c:reply_tcon_and_X(396)
  tconX service=rootpart user=root
[2002/10/05 21:50:27, 3] smbd/process.c:process_smb(878)
  Transaction 3 of length 83
[2002/10/05 21:50:27, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9981)
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
[2002/10/05 21:50:27, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp]
[2002/10/05 21:50:27, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp]
[2002/10/05 21:50:27, 3] smbd/trans2.c:call_trans2qfilepathinfo(1730)
  call_trans2qfilepathinfo tmp level=257 call=5 total_data=0
[2002/10/05 21:50:27, 3] smbd/process.c:process_smb(878)
  Transaction 4 of length 119
[2002/10/05 21:50:27, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 9981)
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:27, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:27, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp/Jennifer-Watkins97Data.mdb]
[2002/10/05 21:50:27, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(tmp/Jennifer-Watkins97Data.mdb) returning 0764
[2002/10/05 21:50:27, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp/Jennifer-Watkins97Data.mdb]
[2002/10/05 21:50:27, 3] smbd/oplock.c:request_oplock_break(960)
  request_oplock_break: sending a oplock break message to pid 9330 on port
3824 for dev = 27405, inode = 36, file_id = 9
[2002/10/05 21:50:59, 0] smbd/oplock.c:request_oplock_break(1008)
  request_oplock_break: no response received to oplock break request to pid
9330 on port 3824 for dev = 27405, inode = 36, file_id = 9
[2002/10/05 21:50:59, 3] smbd/oplock.c:request_oplock_break(1074)
  request_oplock_break: broke oplock.
[2002/10/05 21:50:59, 0] smbd/open.c:open_mode_check(631)
  open_mode_check: exlusive oplock left by process 9330 after break ! For
file tmp/Jennifer-Watkins97Data.mdb, dev = 27405, inode = 36. Deleting it to
continue...
[2002/10/05 21:50:59, 0] smbd/open.c:open_mode_check(635)
  open_mode_check: Existent process 9330 left active oplock.
[2002/10/05 21:50:59, 2] smbd/open.c:open_file(233)
  noonans opened file tmp/Jennifer-Watkins97Data.mdb read=Yes write=No
(numopen=1)
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 5 of length 92
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBntcreateX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp]
[2002/10/05 21:50:59, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(tmp) returning 0764
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 6 of length 76
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/trans2.c:call_trans2qfilepathinfo(1643)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 257
[2002/10/05 21:50:59, 3] smbd/trans2.c:call_trans2qfilepathinfo(1730)
  call_trans2qfilepathinfo tmp/Jennifer-Watkins97Data.mdb level=257 call=7
total_data=0
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 7 of length 88
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBnttrans (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/nttrans.c:call_nt_transact_notify_change(1557)
  call_nt_transact_notify_change
[2002/10/05 21:50:59, 3] smbd/nttrans.c:call_nt_transact_notify_change(1569)
  call_nt_transact_notify_change: notify change called on directory name =
tmp
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 8 of length 88
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBnttrans (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/nttrans.c:call_nt_transact_notify_change(1557)
  call_nt_transact_notify_change
[2002/10/05 21:50:59, 3] smbd/nttrans.c:call_nt_transact_notify_change(1569)
  call_nt_transact_notify_change: notify change called on directory name =
tmp
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 9 of length 120
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/trans2.c:call_trans2setfilepathinfo(2414)
  call_trans2setfilepathinfo(8) tmp/Jennifer-Watkins97Data.mdb
info_level=257 totdata=40
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 10 of length 63
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/reply.c:reply_read_and_X(2485)
  readX fnum=4726 min=4096 max=4096 nread=4096
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 11 of length 63
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/reply.c:reply_read_and_X(2485)
  readX fnum=4726 min=4096 max=4096 nread=4096
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 12 of length 63
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/reply.c:reply_read_and_X(2485)
  readX fnum=4726 min=4096 max=4096 nread=4096
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 13 of length 63
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/reply.c:reply_read_and_X(2485)
  readX fnum=4726 min=4096 max=4096 nread=4096
[2002/10/05 21:50:59, 3] smbd/process.c:process_smb(878)
  Transaction 14 of length 63
[2002/10/05 21:50:59, 3] smbd/process.c:switch_message(685)
  switch message SMBreadX (pid 9981)
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:50:59, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:50:59, 3] smbd/reply.c:reply_read_and_X(2485)
  readX fnum=4726 min=4096 max=4096 nread=4096
[2002/10/05 21:51:01, 3] smbd/process.c:process_smb(878)
  Transaction 15 of length 83
[2002/10/05 21:51:01, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9981)
[2002/10/05 21:51:01, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:51:01, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:51:01, 3] smbd/trans2.c:call_trans2qfilepathinfo(1698)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 257
[2002/10/05 21:51:01, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp]
[2002/10/05 21:51:01, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp]
[2002/10/05 21:51:01, 3] smbd/trans2.c:call_trans2qfilepathinfo(1730)
  call_trans2qfilepathinfo tmp level=257 call=5 total_data=0
[2002/10/05 21:51:01, 3] smbd/process.c:process_smb(878)
  Transaction 16 of length 91
[2002/10/05 21:51:01, 3] smbd/process.c:switch_message(685)
  switch message SMBtrans2 (pid 9981)
[2002/10/05 21:51:01, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:51:01, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:51:01, 3] smbd/trans2.c:call_trans2findfirst(900)
  call_trans2findfirst: dirtype = 22, maxentries = 1366,
close_after_first=0, close_if_end = 1 requires_resume_key = 1 level = 260,
max_data_bytes = 16384
[2002/10/05 21:51:01, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [/tmp/*]
[2002/10/05 21:51:01, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp/*]
[2002/10/05 21:51:01, 3] lib/util.c:unix_clean_name(387)
  unix_clean_name [tmp]
[2002/10/05 21:51:01, 3] smbd/dir.c:dptr_create(488)
  creating new dirptr 256 for path tmp, expect_close = 1
[2002/10/05 21:51:10, 3] smbd/process.c:process_smb(878)
  Transaction 17 of length 45
[2002/10/05 21:51:10, 3] smbd/process.c:switch_message(685)
  switch message SMBclose (pid 9981)
[2002/10/05 21:51:10, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:51:10, 3] smbd/sec_ctx.c:set_sec_ctx(334)
  8 user groups:
  0 0 2 3 4 5 20 31
[2002/10/05 21:51:10, 3] smbd/reply.c:reply_close(3030)
  close fd=24 fnum=4726 (numopen=2)
[2002/10/05 21:51:10, 2] smbd/close.c:close_normal_file(213)
  root closed file tmp/Jennifer-Watkins97Data.mdb (numopen=1)
[2002/10/05 21:51:45, 3] smbd/process.c:process_smb(878)
  Transaction 18 of length 53
[2002/10/05 21:51:45, 3] smbd/process.c:switch_message(685)
  switch message SMBecho (pid 9981)
[2002/10/05 21:51:45, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2002/10/05 21:51:45, 3] smbd/reply.c:reply_echo(3253)
  echo 1 times
[2002/10/05 21:51:45, 3] smbd/sec_ctx.c:set_sec_ctx(329)
  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0


Sorry for the long post, but I'm desperate for help.  ANY help would be very
much appreciated!

TIA,

Sean Noonan




More information about the samba mailing list