thr3ads.net - samba - samba weirdness [Mar 1999]

If this information is useful, please help other people find it:
Share via:

peter@uniq.com.au

1999-Mar-10 09:35 UTC

samba weirdness

I'm having trouble with samba and I'm not sure what the problem is, or
how to fix it. I'm not subscribed to the list so please reply to me as
well.

Here are the details:

Unix System:

    Solaris 2.5.1 Sparc E3000
    Samba 2.0.2 compiled with gcc 2.8.1

Client:

    NT 4.0 sp3.0
    Photoshop 4.0

When saving files, photoshop saves to a temporary file called ~tmp123.tmp.
When complete, the file is renamed to the given name. Quite often the
file save fails with two dialog boxes that say:

    "The system was attempting to transfre data from buffers to
    \Zircon\CQC1\zzolly_tests\~tmp123.tmp. The write operation failed,
    and only some of the data may have been written to the file"

    "Could not save as '\...\l.tif' becasue of a disk error"

It's not a Photoshop specific event as simply doing a file copy
results in a dialog box which says:

    "Cannot copy file: An unexpected network error occurred"

The problem appears much often when running NT 4.0 with service pack
4.

The files are fairly large - usually 30Mb or greater.

There dosn't appear to be anything obvious in the log files either.

    /var/log/log.t7j4k.old:

        [1999/03/10 20:05:06, 3] smbd/process.c:switch_message(402)
          switch message SMBwrite (pid 19236)
        [1999/03/10 20:05:06, 4] smbd/uid.c:become_user(209)
          Skipping become_user - already user
        [1999/03/10 20:05:06, 3] smbd/reply.c:reply_write(2415)
          write fnum=4630 num=61388 wrote=61388
        [1999/03/10 20:05:06, 3] smbd/process.c:process_smb(565)
          Transaction 13238 of length 64
        [1999/03/10 20:05:06, 3] smbd/process.c:switch_message(402)
          switch message SMBreadX (pid 19236)
        [1999/03/10 20:05:06, 4] smbd/uid.c:become_user(209)
          Skipping become_user - already user
        [1999/03/10 20:05:06, 3] smbd/reply.c:reply_read_and_X(2200)
          readX fnum=4631 min=61377 max=61377 nread=61377
        [1999/03/10 20:05:06, 3] smbd/process.c:process_smb(565)
          Transaction 13239 of length 61440
        [1999/03/10 20:05:06, 3] smbd/process.c:switch_message(402)

    /var/log/log.smb:

        [1999/03/10 20:05:06, 4] locking/shmem_sysv.c:sysv_shm_open(544)
          Trying sysv shmem open of size 1048576
        [1999/03/10 20:05:06, 3] locking/shmem_sysv.c:sysv_shm_open(702)
          Initialised IPC area of size 1048576
        [1999/03/10 20:05:06, 2] smbd/server.c:main(702)
          Changed root to /
        [1999/03/10 20:05:06, 3] smbd/oplock.c:open_oplock_ipc(73)
          open_oplock_ipc: opening loopback UDP socket.
        [1999/03/10 20:05:06, 3] lib/util_sock.c:open_socket_in(679)
          bind succeeded on port 0
        [1999/03/10 20:05:06, 3] smbd/oplock.c:open_oplock_ipc(101)
          open_oplock ipc: pid = 19399, global_oplock_port = 57786
        [1999/03/10 20:05:06, 3] smbd/process.c:smbd_process(914)
          priming nmbd
        [1999/03/10 20:05:06, 3] lib/util_sock.c:send_one_packet(608)
          sending a packet of len 1 to (127.0.0.1) on port 137 of type DGRAM
        [1999/03/10 20:05:06, 4] lib/time.c:TimeInit(110)
          Serverzone is -39600
        [1999/03/10 20:05:06, 3] smbd/process.c:process_smb(565)
          Transaction 0 of length 72
        [1999/03/10 20:05:06, 2] smbd/reply.c:reply_special(95)
          netbios connect: name1=ZIRCON           name2=T7J4K

Running gdb shows:

    Core was generated by `/usr/local/bin/smbd -d 4 -D -s /etc/smb.conf -l /var/
log/log.smbd'.
    Program terminated with signal 6, Abort.
    Reading symbols from /usr/lib/libsec.so.1...done.
    Reading symbols from /usr/lib/libsocket.so.1...done.
    Reading symbols from /usr/lib/libnsl.so.1...done.
    Reading symbols from /usr/lib/libc.so.1...done.
    Reading symbols from /usr/lib/libdl.so.1...done.
    Reading symbols from /usr/lib/libintl.so.1...done.
    Reading symbols from /usr/lib/libmp.so.1...done.
    Reading symbols from /usr/lib/libw.so.1...done.
    Reading symbols from
    /usr/platform/SUNW,Ultra-Enterprise/lib/libc_psr.so.1...
    done.
    Reading symbols from /usr/lib/nss_files.so.1...done.
    #0  0xef5f452c in kill ()
    (gdb) bt
    #0  0xef5f452c in kill ()
    #1  0xef5ba620 in abort ()
    #2  0xab55c in smb_panic ()
    #3  0x9ba74 in fault_report ()
    #4  0x9bac8 in sig_fault ()
    #5  <signal handler called>
    #6  0xef5eb298 in vfprintf ()
    #7  0x9b6d0 in Debug1 ()
    #8  0x9b8d0 in dbghdr ()
    #9  0x50f5c in switch_message ()
    #10 0x51338 in construct_reply ()
    #11 0x514f4 in process_smb ()
    #12 0x51f60 in smbd_process ()
    #13 0x21ebc in main ()
    (gdb)

Running truss on the smbd process shows:

    write(3, "     s w i t c h   m e s".., 38)  = 38
    time()                                              = 921055232
    write(3, " [", 1)                           = 1
    write(3, " 1 9 9 9 / 0 3 / 1 0   1".., 19)  = 19
    write(3, " ,   4", 3)                               = 3
    write(3, " ]  ", 2)                         = 2
    write(3, " s m b d / u i d . c", 10)                = 10
    write(3, " :", 1)                           = 1
    write(3, " b e c o m e _ u s e r", 11)              = 11
    write(3, " ( 2 0 9", 4)                             = 4
    write(3, " )\n", 2)                         = 2
    write(3, "     S k i p p i n g   b".., 38)  = 38
    lseek(8, 0x02CEEFCC, SEEK_SET)                      = 0x02CEEFCC
    write(8, "83 vA883 uA882 uA781 tA6".., 4148)        = 4148
    time()                                              = 921055232
    getuid()                                    = 0 [2489]
    fstat(3, 0xEFFFF980)                                = 0
    close(3)                                    = 0
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
        Incurred fault #6, FLTBOUNDS  %pc = 0xEF5EB298
          siginfo: SIGSEGV SEGV_MAPERR addr=0x0000000C
        Received signal #11, SIGSEGV [caught]
          siginfo: SIGSEGV SEGV_MAPERR addr=0x0000000C
    time()                                              = 921055232
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    time()                                              = 921055232
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    getpid()                                    = 18687 [16154]
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    time()                                              = 921055232
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    time()                                              = 921055232
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    umask(022)                                  = 0
    open("/var/log/log.t7j4k", O_WRONLY|O_APPEND|O_CREAT, 0666) Err#13
EACCES
    umask(0)                                    = 022
    lseek(0, 0, SEEK_CUR)                               = 0
    sigaction(SIGABRT, 0x00000000, 0xEFFFF4D0)  = 0
    lseek(0, 0, SEEK_CUR)                               = 0
    sigaction(SIGABRT, 0xEFFFF320, 0xEFFFF420)  = 0
    sigprocmask(SIG_UNBLOCK, 0xEFFFF450, 0x00000000) = 0
    getpid()                                    = 18687 [16154]
    kill(18687, SIGABRT)                                = 0
        Received signal #6, SIGABRT [default]
          siginfo: SIGABRT pid=18687 uid=0
            *** process killed ***

I have tried samba-2.0.3 but for reasons I don't understand, that will
not accept valid passwords. The same passwords accepted by
samba-2.0.2.

###########################################################################

The samba config file contains:

    #
    # ZIRCON: /etc/smb.conf -- samba daemon configuration file
    #  4mar99       clivep  change to scoop to use /spool/pcinput
    # 19990224      bettyg  initial config file
    # 19990226      bettyg  enhanced config file
    #
            guest account = sambausr
            encrypt passwords = yes
            password level = 8
            username level = 8
            security = user
            workgroup = FFXNT
            netbios name = zircon
            wins server = 172.17.20.10
            case sensitive = yes
            preserve case = yes
            default case = lower
            #interfaces = 172.17.10.15/255.255.255.0 128.8.45.20/255.255.255.0
            interfaces = 128.8.45.20/255.255.255.0
            remote announce = 172.17.20.255/FFXNT
            remote browse sync = 172.17.20.10       # PDC or BDC on remote netwo
rk
            log file = /var/log/log.%m

    [SHD]
            comment = "Sun Herald Data Area"
            path = /data/x/SHD
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            kpickett otownsen rsammut sbennett sjackson
            writeable = yes

    [SHD HOLD]
            comment = "Sun Herald Hold Area"
            path = /data/x_hold
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            kpickett otownsen rsammut sbennett sjackson
            writeable = yes

    [AFR]
            comment = "Australian Financial Review Data Area"
            path = /data/r/AFR
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [AFR HOLD]
            comment = "Australian Financial Review Hold Area"
            path = /data/r_hold
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [SMH]
            comment = "Sydney Morning Herald Data Area"
            path = /data/h/SMH
            public = yes

            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith
            writeable = yes

    [SMH HOLD]
            comment = "Sydney Morning Herald Hold Area"
            path = /data/h_hold
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [CQC1]
            comment = "CQC1 Data Area"
            path = /data/cqc1/CQC_1
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [CQC2]
            comment = "CQC2 Data Area"
            path = /data/cqc2/CQC_2
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [TO SCOOP]
            comment = "To Scoop"
            path = /spool/pcinput
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen rsammut
            writeable = yes

    [TRANSFER]
            comment = "Transfer Area"
            path = /spool/transfer
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

    [ESKOFOT SCANS]
            comment = "Eskofot scans"
            path = /spool/esky/cqc
            public = yes
            valid users = mhodges cstevens sivers mwhyman msykes nzatschl adavid
so \
            ahunt mstathak sfitzger dpanayi aarena jhawkes sloxley slin jsmith \
            otownsen
            writeable = yes

###########################################################################

Samba is started as:

    /usr/local/bin/smbd -d 4 -D -s /etc/smb.conf -l /var/log/log.smbd
    /usr/local/bin/nmbd -d 3 -D -s /etc/smb.conf -l /var/log/log


Any help would be much appreciated.

Regards
Peter Samuel

Graham Allan

1999-Mar-10 17:55 UTC

head link

samba weirdness

>When saving files, photoshop saves to a temporary file called ~tmp123.tmp.
>When complete, the file is renamed to the given name. Quite often the
>file save fails with two dialog boxes that say:
>
>    "The system was attempting to transfre data from buffers to
>    \Zircon\CQC1\zzolly_tests\~tmp123.tmp. The write operation failed,
>    and only some of the data may have been written to the file"
Well, it's not just you, and it's not just Photoshop, although I only
seem to get the first of the two messages. I have Samba 2.0.2 running
from inetd on Digital UNIX 4.0D, compiled with DEC C, and get similar
errors from my NT4sp4 client. I don't know what triggers the error, as I
have obtained it when saving Word files, when Netscape saves its
bookmarks on exit, and possibly other times (though certainly not
consistently). I wonder if the "deadtime" timeout affects it? I have
raised it but it always happens so infrequently that I can't be sure if
it has changed anything (it does still occur though).

smbd does produce a core file; running dbx on it shows:

(dbx) tstack

Thread 0x3:>  0 __kill(0x3ff800df210, 0x3ffc0085c98, 0x0, 0x0, 0x3ff80177488)
[0x3ff800ea6c8]   1 (unknown)() [0x3ff8019e770]
   2 __tis_raise(0x3ff80177488, 0x140046258, 0x3ff801147cc, 0x6, 0x3ff80160aec)
[0x3ff801147c8]
   3 raise(0x3ff801147cc, 0x6, 0x3ff80160aec, 0x11ffff790, 0x3ff801774b4)
[0x3ff80160ae8]
   4 abort(0x1200f237c, 0x140046258, 0x0, 0x0, 0xffffffff00000000)
[0x3ff801774b0]
   5 smb_panic(0xffffffff00000000, 0x100000, 0x1200da2d4, 0x1400314e8, 0x1)
[0x1200f2378]
   6 (unknown)() [0x1200da2d0]
   7 (unknown)() [0x1200da374]
   8 vfprintf(0x1200d9d44, 0x1400418e8, 0x11ffff790, 0x100000008, 0x1400418e8)
[0x3ff8014cba4]
   9 Debug1(0x1400418e8, 0x140030c28, 0x3ff, 0x1, 0xa) [0x1200d9d40]
  10 (unknown)() [0x1200d9dc4]
  11 (unknown)() [0x1200d9ef0]
  12 dbgtext(0x14002c250, 0xffffffffc8508119, 0x3ff800ccdf0, 0x0, 0x0)
[0x1200da0d8]
  13 (unknown)() [0x1200c903c]
  14 del_share_mode(0xc850811900000001, 0x16b58, 0x10048, 0x639d, 0x120062b70)
[0x1200c7f40]
  15 close_file(0x14006d001, 0x100000000, 0x14006d001, 0x639dc8508119,
0x100000000) [0x120062b6c]
  16 reply_close(0x0, 0x14000cee0, 0x0, 0x100000027, 0x0) [0x12004a7c4]
  17 (unknown)() [0x120065610]
  18 (unknown)() [0x120065744]
  19 process_smb(0x14003d340, 0xffff, 0x14003a7a0, 0x2e0000002a, 0x0)
[0x120065a14]
  20 smbd_process(0x8b, 0x1, 0x1400004b8, 0x7372006e696d002f, 0x1) [0x120066b8c]
  21 main(0x2, 0x140000800, 0x140007540, 0x140000e20, 0x3) [0x12001c4bc]

which looks very similar to your backtrace. Nothing interesting in the
logfiles, though I only have "debug level = 1". I haven't tried
Samba
2.0.3 yet.

I don't know if this report adds any useful information. Maybe someone
will suggest how best to investigate further!

Graham Allan
Physics, University of Minnesota

samba - Mar 1999 - samba weirdness

samba weirdness

samba weirdness