smbd panics

Ephi Dror ephi at agami.com
Fri Dec 3 01:55:22 GMT 2004


Hi Jeremy,

I tried to run with debug level 10 and -d  option but could not hit any
panic and the
log files size was about hundreds of megabytes...

But when I was compiling and running on that system (64 bit dual processors,
running Linux 2.6.7) I was able to panic it. smb.log is 135MB in size!!!!
(17MB gzip, can upload anytime)
So below, I just copied the last 400 lines.

Also, on another system I hit the following panic:
   smb_panic("PANIC: deferred_open_entries_identical: logic error.\n");

You had a comment in the code saying: /* JRA PARANOIA TEST - REMOVE LATER */

I replaced calling smb_panic with DEBUG message and the system seems to
continue running well so far like a Swiss watch...

Is this something worth investigating? What is the risk there if we don't
panic?

I did hit my debug message about 10 times while running the test for about
an hour but no other crashes or strange behavior happened.

My test is simply copy, comparing, creating and deleting files and
directories all day long. BUT I launch many threads that do the same on
working on the same workspace so there are a lot of locking and sharing
issues here.

Thanks so much,
Ephi

Here are the last 400 lines:
I was running 64bit compiled samba 3.0.9 on linux 2.6.7

[2004/12/02 17:59:56.520294, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x29
[2004/12/02 17:59:56.520312, 3] smbd/process.c:process_smb(1092)
  Transaction 30254 of length 45
[2004/12/02 17:59:56.520330, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.520341, 5] lib/util.c:show_msg(471)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=38353
  smt_wct=3
  smb_vwv[ 0]= 9015 (0x2337)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2004/12/02 17:59:56.520467, 3] smbd/process.c:switch_message(887)
  switch message SMBclose (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.520486, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.520507, 3] smbd/reply.c:reply_close(2772)
  close fd=129 fnum=9015 (numopen=115)
[2004/12/02 17:59:56.520531, 10] locking/locking.c:del_share_entry(597)
  del_share_entry: num_share_modes = 1
[2004/12/02 17:59:56.520560, 10] locking/locking.c:del_share_entry(602)
  del_share_entry: deleted share_mode_entry[0]: pid = 17634, share_mode =
0x11, desired_access = 0x30196, port = 0x0, type= 0x0, file_id = 3420, dev =
0xfd03, inode = 115894
[2004/12/02 17:59:56.520589, 10] locking/locking.c:del_share_entry(612)
  del_share_entry: deleting entry 0
[2004/12/02 17:59:56.520621, 10] locking/locking.c:del_share_entry(634)
  del_share_entry: Remaining table.
[2004/12/02 17:59:56.520639, 10] smbd/close.c:close_normal_file(201)
  close_normal_file: share_entry_count = 0 for file f6_1/EPORTABL.BMP
[2004/12/02 17:59:56.520671, 10]
locking/posix.c:posix_locking_close_file(1241)
  posix_locking_close_file: file f6_1/EPORTABL.BMP has no outstanding locks.
[2004/12/02 17:59:56.520730, 2] smbd/close.c:close_normal_file(270)
  root closed file f6_1/EPORTABL.BMP (numopen=114)
[2004/12/02 17:59:56.520750, 5] smbd/files.c:file_free(385)
  freed files structure 9015 (114 used)
[2004/12/02 17:59:56.520768, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.520778, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=38353
  smt_wct=0
  smb_bcc=0
[2004/12/02 17:59:56.520871, 6] lib/util_sock.c:write_socket(449)
  write_socket(23,39)
[2004/12/02 17:59:56.520894, 6] lib/util_sock.c:write_socket(452)
  write_socket(23,39) wrote 39
[2004/12/02 17:59:56.520984, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 116
[2004/12/02 17:59:56.521068, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x74
[2004/12/02 17:59:56.521086, 3] smbd/process.c:process_smb(1092)
  Transaction 30255 of length 120
[2004/12/02 17:59:56.521103, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.521113, 5] lib/util.c:show_msg(471)
  size=116
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=2656
  smb_uid=100
  smb_mid=38436
  smt_wct=15
  smb_vwv[ 0]=    6 (0x6)
  smb_vwv[ 1]=   40 (0x28)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=    0 (0x0)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    6 (0x6)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=   40 (0x28)
  smb_vwv[12]=   76 (0x4C)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    8 (0x8)
  smb_bcc=51
[2004/12/02 17:59:56.521324, 10] lib/util.c:dump_data(1899)
  [000] 00 4C 00 76 23 EC 03 00  00 45 00 00 00 00 00 00  .L.v#... .E......
  [010] 00 00 00 00 00 00 00 00  00 00 00 00 43 8E 49 02  ........ ....C.I.
  [020] 0E C4 01 00 43 8E 49 02  0E C4 01 00 00 00 00 00  ....C.I. ........
  [030] 00 00 00                                          ...
[2004/12/02 17:59:56.521412, 3] smbd/process.c:switch_message(887)
  switch message SMBtrans2 (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.521434, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.521458, 3]
smbd/trans2.c:call_trans2setfilepathinfo(3096)
  call_trans2setfilepathinfo(8) u_1/Blue hills.jpg (fnum 9078)
info_level=1004 totdata=40
[2004/12/02 17:59:56.521480, 8] smbd/dosmode.c:dos_mode(283)
  dos_mode: u_1/Blue hills.jpg
[2004/12/02 17:59:56.521499, 8] smbd/dosmode.c:dos_mode_from_sbuf(151)
  dos_mode_from_sbuf returning a
[2004/12/02 17:59:56.521518, 8] smbd/dosmode.c:dos_mode(315)
  dos_mode returning a
[2004/12/02 17:59:56.521548, 6]
smbd/trans2.c:call_trans2setfilepathinfo(3621)
  actime: Fri Mar 19 14:34:06 2004
   modtime: Fri Mar 19 14:34:06 2004
   size: 28521 dosmode: 0
[2004/12/02 17:59:56.521594, 10]
smbd/trans2.c:call_trans2setfilepathinfo(3662)
  call_trans2setfilepathinfo: setting pending modtime to Fri Mar 19 14:34:06
2004

[2004/12/02 17:59:56.521624, 9] smbd/trans2.c:send_trans2_replies(557)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 0, useable_space =
131012
[2004/12/02 17:59:56.521643, 9] smbd/trans2.c:send_trans2_replies(559)
  t2_rep: params_to_send = 2, data_to_send = 0, paramsize = 2, datasize = 0
[2004/12/02 17:59:56.521662, 6] lib/util_sock.c:write_socket(449)
  write_socket(23,62)
[2004/12/02 17:59:56.521685, 6] lib/util_sock.c:write_socket(452)
  write_socket(23,62) wrote 62
[2004/12/02 17:59:56.521773, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 41
[2004/12/02 17:59:56.521856, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x29
[2004/12/02 17:59:56.521873, 3] smbd/process.c:process_smb(1092)
  Transaction 30256 of length 45
[2004/12/02 17:59:56.521890, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.521900, 5] lib/util.c:show_msg(471)
  size=41
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=38469
  smt_wct=3
  smb_vwv[ 0]= 9105 (0x2391)
  smb_vwv[ 1]=65535 (0xFFFF)
  smb_vwv[ 2]=65535 (0xFFFF)
  smb_bcc=0
[2004/12/02 17:59:56.522043, 3] smbd/process.c:switch_message(887)
  switch message SMBclose (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.522063, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.522083, 3] smbd/reply.c:reply_close(2772)
  close fd=-1 fnum=9105 (numopen=114)
[2004/12/02 17:59:56.522107, 10] smbd/close.c:close_normal_file(201)
  close_normal_file: share_entry_count = 18446744073709551615 for file
p_1/p_1/EPORTABL.BMP
[2004/12/02 17:59:56.522142, 10]
locking/posix.c:posix_locking_close_file(1241)
  posix_locking_close_file: file p_1/p_1/EPORTABL.BMP has no outstanding
locks.
[2004/12/02 17:59:56.522164, 2] smbd/close.c:close_normal_file(270)
  root closed file p_1/p_1/EPORTABL.BMP (numopen=113)
[2004/12/02 17:59:56.522183, 5] smbd/files.c:file_free(385)
  freed files structure 9105 (113 used)
[2004/12/02 17:59:56.522202, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.522212, 5] lib/util.c:show_msg(471)
  size=35
  smb_com=0x4
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=136
  smb_flg2=51201
  smb_tid=1
  smb_pid=65279
  smb_uid=100
  smb_mid=38469
  smt_wct=0
  smb_bcc=0
[2004/12/02 17:59:56.522311, 6] lib/util_sock.c:write_socket(449)
  write_socket(23,39)
[2004/12/02 17:59:56.522335, 6] lib/util_sock.c:write_socket(452)
  write_socket(23,39) wrote 39
[2004/12/02 17:59:56.522423, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 136
[2004/12/02 17:59:56.522506, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x88
[2004/12/02 17:59:56.522524, 3] smbd/process.c:process_smb(1092)
  Transaction 30257 of length 140
[2004/12/02 17:59:56.522551, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.522562, 5] lib/util.c:show_msg(471)
  size=136
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=2240
  smb_uid=100
  smb_mid=38557
  smt_wct=15
  smb_vwv[ 0]=   68 (0x44)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=   40 (0x28)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=   68 (0x44)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    5 (0x5)
  smb_bcc=71
[2004/12/02 17:59:56.522783, 10] lib/util.c:dump_data(1899)
  [000] 00 00 00 EC 03 00 00 00  00 5C 00 65 00 5F 00 31  ........ .\.e._.1
  [010] 00 5C 00 65 00 5F 00 31  00 5C 00 65 00 5F 00 31  .\.e._.1 .\.e._.1
  [020] 00 5C 00 48 00 79 00 70  00 65 00 72 00 54 00 65  .\.H.y.p .e.r.T.e
  [030] 00 72 00 6D 00 69 00 6E  00 61 00 6C 00 2E 00 64  .r.m.i.n .a.l...d
  [040] 00 6F 00 63 00 00 00                              .o.c...
[2004/12/02 17:59:56.522895, 3] smbd/process.c:switch_message(887)
  switch message SMBtrans2 (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.522915, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.522934, 3] smbd/trans2.c:call_trans2qfilepathinfo(2346)
  call_trans2qfilepathinfo: TRANSACT2_QPATHINFO: level = 1004
[2004/12/02 17:59:56.522955, 5] smbd/filename.c:unix_convert(108)
  unix_convert called on file "e_1/e_1/e_1/HyperTerminal.doc"
[2004/12/02 17:59:56.522978, 10] smbd/statcache.c:stat_cache_lookup(243)
  stat_cache_lookup: lookup succeeded for name
[E_1/E_1/E_1/HYPERTERMINAL.DOC] -> [e_1/e_1/e_1/HyperTerminal.doc]
[2004/12/02 17:59:56.523007, 3] smbd/trans2.c:call_trans2qfilepathinfo(2380)
  call_trans2qfilepathinfo e_1/e_1/e_1/HyperTerminal.doc (fnum = -1)
level=1004 call=5 total_data=0
[2004/12/02 17:59:56.523028, 8] smbd/dosmode.c:dos_mode(283)
  dos_mode: e_1/e_1/e_1/HyperTerminal.doc
[2004/12/02 17:59:56.523046, 8] smbd/dosmode.c:dos_mode_from_sbuf(151)
  dos_mode_from_sbuf returning a
[2004/12/02 17:59:56.523065, 8] smbd/dosmode.c:dos_mode(315)
  dos_mode returning a
[2004/12/02 17:59:56.523106, 10]
smbd/trans2.c:call_trans2qfilepathinfo(2496)
  call_trans2qfilepathinfo: SMB_FILE_BASIC_INFORMATION
[2004/12/02 17:59:56.523149, 5] smbd/trans2.c:call_trans2qfilepathinfo(2506)
  SMB_QFBI - create: Wed Nov  3 18:03:08 2004
   access: Wed Nov  3 18:03:08 2004
   write: Wed Nov  3 18:03:08 2004
   change: Wed Nov  3 18:03:08 2004
   mode: 20
[2004/12/02 17:59:56.523216, 9] smbd/trans2.c:send_trans2_replies(557)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 40, useable_space =
131010
[2004/12/02 17:59:56.523236, 9] smbd/trans2.c:send_trans2_replies(559)
  t2_rep: params_to_send = 2, data_to_send = 40, paramsize = 2, datasize =
40
[2004/12/02 17:59:56.523256, 6] lib/util_sock.c:write_socket(449)
  write_socket(23,104)
[2004/12/02 17:59:56.523280, 6] lib/util_sock.c:write_socket(452)
  write_socket(23,104) wrote 104
[2004/12/02 17:59:56.523439, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 72
[2004/12/02 17:59:56.523526, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x48
[2004/12/02 17:59:56.523554, 3] smbd/process.c:process_smb(1092)
  Transaction 30258 of length 76
[2004/12/02 17:59:56.523572, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.523582, 5] lib/util.c:show_msg(471)
  size=72
  smb_com=0x32
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=3292
  smb_uid=100
  smb_mid=38631
  smt_wct=15
  smb_vwv[ 0]=    4 (0x4)
  smb_vwv[ 1]=    0 (0x0)
  smb_vwv[ 2]=    2 (0x2)
  smb_vwv[ 3]=    8 (0x8)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=    0 (0x0)
  smb_vwv[ 8]=    0 (0x0)
  smb_vwv[ 9]=    4 (0x4)
  smb_vwv[10]=   68 (0x44)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    1 (0x1)
  smb_vwv[14]=    7 (0x7)
  smb_bcc=7
[2004/12/02 17:59:56.523879, 10] lib/util.c:dump_data(1899)
  [000] 00 00 00 9E 23 EE 03                              ....#..
[2004/12/02 17:59:56.523908, 3] smbd/process.c:switch_message(887)
  switch message SMBtrans2 (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.523927, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.523946, 3] smbd/trans2.c:call_trans2qfilepathinfo(2291)
  call_trans2qfilepathinfo: TRANSACT2_QFILEINFO: level = 1006
[2004/12/02 17:59:56.523971, 3] smbd/trans2.c:call_trans2qfilepathinfo(2380)
  call_trans2qfilepathinfo j_1/j_1/j_1/EPORTABL.BMP (fnum = 9118) level=1006
call=7 total_data=0
[2004/12/02 17:59:56.523991, 8] smbd/dosmode.c:dos_mode(283)
  dos_mode: j_1/j_1/j_1/EPORTABL.BMP
[2004/12/02 17:59:56.524008, 8] smbd/dosmode.c:dos_mode_from_sbuf(151)
  dos_mode_from_sbuf returning a
[2004/12/02 17:59:56.524026, 8] smbd/dosmode.c:dos_mode(315)
  dos_mode returning a
[2004/12/02 17:59:56.524046, 10]
smbd/trans2.c:call_trans2qfilepathinfo(2620)
  call_trans2qfilepathinfo: SMB_FILE_INTERNAL_INFORMATION
[2004/12/02 17:59:56.524064, 9] smbd/trans2.c:send_trans2_replies(557)
  t2_rep: params_sent_thistime = 2, data_sent_thistime = 8, useable_space =
131010
[2004/12/02 17:59:56.524083, 9] smbd/trans2.c:send_trans2_replies(559)
  t2_rep: params_to_send = 2, data_to_send = 8, paramsize = 2, datasize = 8
[2004/12/02 17:59:56.524101, 6] lib/util_sock.c:write_socket(449)
  write_socket(23,72)
[2004/12/02 17:59:56.524127, 6] lib/util_sock.c:write_socket(452)
  write_socket(23,72) wrote 72
[2004/12/02 17:59:56.524217, 10]
lib/util_sock.c:read_smb_length_return_keepalive(505)
  got smb length of 144
[2004/12/02 17:59:56.524300, 6] smbd/process.c:process_smb(1091)
  got message type 0x0 of len 0x90
[2004/12/02 17:59:56.524317, 3] smbd/process.c:process_smb(1092)
  Transaction 30259 of length 148
[2004/12/02 17:59:56.524334, 5] lib/util.c:show_msg(461)
[2004/12/02 17:59:56.524344, 5] lib/util.c:show_msg(471)
  size=144
  smb_com=0xa2
  smb_rcls=0
  smb_reh=0
  smb_err=0
  smb_flg=24
  smb_flg2=51207
  smb_tid=1
  smb_pid=5892
  smb_uid=100
  smb_mid=38698
  smt_wct=24
  smb_vwv[ 0]=  255 (0xFF)
  smb_vwv[ 1]=57054 (0xDEDE)
  smb_vwv[ 2]=14848 (0x3A00)
  smb_vwv[ 3]= 5632 (0x1600)
  smb_vwv[ 4]=    0 (0x0)
  smb_vwv[ 5]=    0 (0x0)
  smb_vwv[ 6]=    0 (0x0)
  smb_vwv[ 7]=35072 (0x8900)
  smb_vwv[ 8]=  512 (0x200)
  smb_vwv[ 9]=    0 (0x0)
  smb_vwv[10]=    0 (0x0)
  smb_vwv[11]=    0 (0x0)
  smb_vwv[12]=    0 (0x0)
  smb_vwv[13]=    0 (0x0)
  smb_vwv[14]=    0 (0x0)
  smb_vwv[15]=  256 (0x100)
  smb_vwv[16]=    0 (0x0)
  smb_vwv[17]=  256 (0x100)
  smb_vwv[18]=    0 (0x0)
  smb_vwv[19]=17408 (0x4400)
  smb_vwv[20]= 8192 (0x2000)
  smb_vwv[21]=  512 (0x200)
  smb_vwv[22]=    0 (0x0)
  smb_vwv[23]=  768 (0x300)
  smb_bcc=61
[2004/12/02 17:59:56.524661, 10] lib/util.c:dump_data(1899)
  [000] 00 5C 00 74 00 65 00 73  00 74 00 31 00 5C 00 48  .\.t.e.s .t.1.\.H
  [010] 00 61 00 72 00 64 00 77  00 61 00 72 00 65 00 20  .a.r.d.w .a.r.e.
  [020] 00 43 00 68 00 65 00 63  00 6B 00 6C 00 69 00 73  .C.h.e.c .k.l.i.s
  [030] 00 74 00 2E 00 64 00 6F  00 63 00 00 00           .t...d.o .c...
[2004/12/02 17:59:56.524750, 3] smbd/process.c:switch_message(887)
  switch message SMBntcreateX (pid 17634) conn 0x8ad180
[2004/12/02 17:59:56.524770, 4] smbd/uid.c:change_to_user(194)
  change_to_user: Skipping user change - already user
[2004/12/02 17:59:56.524789, 10] smbd/nttrans.c:reply_ntcreate_and_X(606)
  reply_ntcreateX: flags = 0x16, desired_access = 0x20089 file_attributes =
0x0, share_access = 0x1, create_disposition = 0x1 create_options = 0x200044
root_dir_fid = 0x0
[2004/12/02 17:59:56.524812, 10] smbd/nttrans.c:map_create_disposition(340)
  map_create_disposition: Mapped create_disposition 0x1 to 0x1
[2004/12/02 17:59:56.524834, 10] smbd/nttrans.c:map_share_mode(479)
  map_share_mode: Mapped desired access 0x20089, share access 0x1, file
attributes 0x0 to open_mode 0x20
[2004/12/02 17:59:56.524856, 5] smbd/filename.c:unix_convert(108)
  unix_convert called on file "test1/Hardware Checklist.doc"
[2004/12/02 17:59:56.524879, 10] smbd/statcache.c:stat_cache_lookup(243)
  stat_cache_lookup: lookup succeeded for name [TEST1/HARDWARE
CHECKLIST.DOC] -> [test1/Hardware Checklist.doc]
[2004/12/02 17:59:56.524905, 3] smbd/dosmode.c:unix_mode(111)
  unix_mode(test1/Hardware Checklist.doc) returning 0744
[2004/12/02 17:59:56.524926, 5] smbd/files.c:file_new(122)
  allocated file structure 5028, fnum = 9124 (114 used)
[2004/12/02 17:59:56.524945, 10] smbd/open.c:open_file_shared1(1038)
  open_file_shared: fname = test1/Hardware Checklist.doc, dos_attrs = 0,
share_mode = 20, ofun = 1, mode = 744, oplock request = 3
[2004/12/02 17:59:56.524968, 8] smbd/dosmode.c:dos_mode(283)
  dos_mode: test1/Hardware Checklist.doc
[2004/12/02 17:59:56.524986, 8] smbd/dosmode.c:dos_mode_from_sbuf(151)
  dos_mode_from_sbuf returning a
[2004/12/02 17:59:56.525004, 8] smbd/dosmode.c:dos_mode(315)
  dos_mode returning a
[2004/12/02 17:59:56.525032, 10] locking/locking.c:get_share_modes(468)
  get_share_modes: share_mode_entry[0]: pid = 17634, share_mode = 0x40,
desired_access = 0x20089, port = 0x0, type= 0x0, file_id = 3487, dev =
0xfd03, inode = 81971
[2004/12/02 17:59:56.525062, 10] locking/locking.c:get_share_modes(471)
  get_share_modes: deleted share_mode_entry[1]: pid = 17628, share_mode =
0x40, desired_access = 0x20089, port = 0x0, type= 0x0, file_id = 4130, dev =
0xfd03, inode = 81971
[2004/12/02 17:59:56.525090, 10] locking/locking.c:get_share_modes(471)
  get_share_modes: deleted share_mode_entry[1]: pid = 17628, share_mode =
0x20, desired_access = 0x20089, port = 0x0, type= 0x0, file_id = 4112, dev =
0xfd03, inode = 81971
[2004/12/02 17:59:56.525116, 10] locking/locking.c:get_share_modes(471)
  get_share_modes: deleted share_mode_entry[1]: pid = 17628, share_mode =
0x40, desired_access = 0x20089, port = 0x0, type= 0x0, file_id = 4022, dev =
0xfd03, inode = 81971
[2004/12/02 17:59:56.525189, 0] lib/fault.c:fault_report(36)
  ===============================================================
[2004/12/02 17:59:56.525314, 0] lib/fault.c:fault_report(37)
  INTERNAL ERROR: Signal 11 in pid 17634 (3.0.9)
  Please read the appendix Bugs of the Samba HOWTO collection
[2004/12/02 17:59:56.572729, 0] lib/fault.c:fault_report(39)
  ===============================================================
[2004/12/02 17:59:56.612898, 0] lib/util.c:smb_panic2(1382)
  smb_panic: clobber_region() last called from [get_share_modes(489)]
[2004/12/02 17:59:56.645674, 0] lib/util.c:smb_panic2(1403)
  PANIC: internal error
[2004/12/02 17:59:56.703245, 0] lib/util.c:smb_panic2(1411)
  BACKTRACE: 18 stack frames:
   #0 ./smbd(smb_panic2+0x24f) [0x5e1192]
   #1 ./smbd(smb_panic+0x1a) [0x5e0f41]
   #2 ./smbd [0x5ca29c]
   #3 ./smbd [0x5ca307]
   #4 /lib64/tls/libc.so.6 [0x2a963cc5e0]
   #5 /lib64/tls/libc.so.6 [0x2a96414378]
   #6 /lib64/tls/libc.so.6(__libc_free+0x71) [0x2a96413201]
   #7 ./smbd(get_share_modes+0x479) [0x59b013]
   #8 ./smbd [0x49af5c]
   #9 ./smbd(open_file_shared1+0x907) [0x49c6c4]
   #10 ./smbd(reply_ntcreate_and_X+0xbd1) [0x464c0c]
   #11 ./smbd [0x4b0e45]
   #12 ./smbd [0x4b0f0a]
   #13 ./smbd(process_smb+0x1fb) [0x4b1274]
   #14 ./smbd(smbd_process+0x1be) [0x4b2237]
   #15 ./smbd(main+0x76e) [0x664c61]
   #16 /lib64/tls/libc.so.6(__libc_start_main+0x101) [0x2a963ba181]
   #17 ./smbd(fsetxattr+0x3a) [0x43a3aa]





-----Original Message-----
From: Jeremy Allison [mailto:jra at samba.org]
Sent: Tuesday, November 30, 2004 2:00 PM
To: Jeremy Allison
Cc: Ephi Dror; Samba-Technical
Subject: Re: smbd panics


On Tue, Nov 30, 2004 at 01:52:15PM -0800, Jeremy Allison wrote:
>
> I'd like to see the debug level 10 logs split out
> into one log per active smbd process (use the %d
> macro in the logname to do this). This might help
> in showing the problem.

Oh yeah, one more thing. It would be nice to see
the high-resolution timestamps on these logs too,
that would make it much easier to sync up events
between the logs.

Thanks,

	Jeremy.



More information about the samba-technical mailing list