On Fri, 13 Sep 2002, Urban Widmark wrote:
> On Thu, 12 Sep 2002, Joel Votaw wrote:
>
> > smb_retry: no connection process
>
> This means that your smbmount process has terminated (crashed?). You could
> try some things with that, eg upgrading samba to the latest 2.2.x, running
> it with a higher debug level (4, check your smbmount.log), add more debug
> printouts to the smbmount main loop, getting a core dump from it.
I upped the debug level from 1 to 5, which appears to give a ton of
information. I didn't see any interesting messages from debug=1; the
relevant areas from smbmount.log are below. I enabled core dumps just
now -- RedHat 7.3 has them disabled for bash by default. So with luck
I should have more information if this happens again.
I upgraded to kernel 2.4.19 just in case that was the problem, and I'll
upgrade Samba in the next few days.
> I believe this is at umount time.
That's what I thought too. Interestingly, however, I think the log file
of my backup script from the second crash indicated that that crash
happened when the filesystem was first mounted. I accidently deleted
that log though so I can't check it.... :-(
> And if smb_delete_inode wants to
> actually close something and smb_retry is failing it won't be able to.
>
> I need to find out if/why that would cause the busy message. When the
> connection closes any open files are closed, so closing shouldn't be a
> problem.
>
> Did you get either of these the second time?
No. I've included more of /var/log/messages (and also
/var/log/samba/smbmount.log) below, in case there's something useful
in them that I overlooked.
> > VFS: Busy inodes after unmount. Self-destruct in 5 seconds. Have a nice
> > day...
>
> Kabooom!
Yup! It's actually nice to have a bit of humor in the middle of one's
server crashing.
-Joel
======================= Crash #1 ===================================
----------------------- /var/log/samba/smbmount.log #1 -------------
[2002/09/05 11:58:47, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc22\usr, pid=17752
[2002/09/05 11:58:47, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc15\c$, pid=17762
[2002/09/05 11:58:47, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc52\c$, pid=17770
[2002/09/05 11:58:48, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc96\c$, pid=17787
[2002/09/05 11:58:48, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc34\c$, pid=17796
[2002/09/05 11:58:48, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc23\user, pid=17804
----------------------- /var/log/messages #1 -----------------------
Sep 4 20:46:13 files2 kernel: smb_proc_readdir_long: name=\usr\usr\???????\*, result=-2, rcls=1, err=123
Sep 4 20:46:13 files2 kernel: smb_proc_readdir_long: name=\usr\usr\sch\*, result=-13, rcls=1, err=5
Sep 4 20:46:13 files2 kernel: smb_proc_readdir_long: name=\usr\mentor_guide\*, result=-13, rcls=1, err=5
Sep 5 06:59:05 files2 kernel: raid5: multiple 0 requests for sector 196608024
Sep 5 06:59:25 files2 kernel: raid5: multiple 0 requests for sector 256464264
Sep 5 11:56:35 files2 kernel: smb_retry: no connection process
Sep 5 11:56:37 files2 last message repeated 2 times
Sep 5 11:56:37 files2 kernel: smb_delete_inode: could not close inode 2
Sep 5 11:56:37 files2 kernel: VFS: Busy inodes after unmount. Self-destruct in 5 seconds. Have a nice day...
Sep 5 11:58:50 files2 kernel: Unable to handle kernel paging request at virtual address 740a6279
Sep 5 11:58:50 files2 kernel: printing eip:
Sep 5 11:58:50 files2 kernel: c01588d3
Sep 5 11:58:50 files2 kernel: *pde = 00000000
Sep 5 11:58:50 files2 kernel: Oops: 0000
Sep 5 11:58:50 files2 kernel: smbfs nfs lockd sunrpc tg3 eepro100 ide-cd cdrom st usb-ohci usbcore ext3 jbd
Sep 5 11:58:50 files2 kernel: CPU: 0
Sep 5 11:58:50 files2 kernel: EIP: 0010:[<c01588d3>] Not tainted
Sep 5 11:58:50 files2 kernel: EFLAGS: 00010206
Sep 5 11:58:50 files2 kernel:
Sep 5 11:58:50 files2 kernel: EIP is at iput [kernel] 0x43 (2.4.18-10smp)
Sep 5 11:58:50 files2 kernel: eax: 740a6269 ebx: c9111da0 ecx: f4bfcbd0 edx: c9111db0
Sep 5 11:58:50 files2 kernel: esi: c26cc400 edi: 740a6269 ebp: 00000041 esp: f7ed3f5c
Sep 5 11:58:51 files2 kernel: ds: 0018 es: 0018 ss: 0018
Sep 5 11:58:51 files2 kernel: Process kswapd (pid: 7, stackpage=f7ed3000)
Sep 5 11:58:51 files2 kernel: Stack: f4bfcbb8 f4bfcba0 c9111da0 c0155fa6 c9111da0 00000375 000000c3 ffffffff
Sep 5 11:58:51 files2 kernel: c02f3c28 00000375 00000185 00000493 c01381e8 000001d0 00000556 00000000
Sep 5 11:58:51 files2 kernel: 00000185 c01563d0 0000230c c0138b3c 00000006 000001d0 000001d0 f7ed2000
Sep 5 11:58:51 files2 kernel: Call Trace: [<c0155fa6>] prune_dcache [kernel] 0xe6
Sep 5 11:58:51 files2 kernel: [<c01381e8>] page_launder [kernel] 0x168
Sep 5 11:58:51 files2 kernel: [<c01563d0>] shrink_dcache_memory [kernel] 0x20
Sep 5 11:58:51 files2 kernel: [<c0138b3c>] do_try_to_free_pages [kernel] 0x1c
Sep 5 11:58:51 files2 kernel: [<c0138e31>] kswapd [kernel] 0x101
Sep 5 11:58:52 files2 kernel: [<c0105000>] stext [kernel] 0x0
Sep 5 11:58:52 files2 kernel: [<c0107286>] kernel_thread [kernel] 0x26
Sep 5 11:58:52 files2 kernel: [<c0138d30>] kswapd [kernel] 0x0
Sep 5 11:58:52 files2 kernel:
Sep 5 11:58:58 files2 kernel:
Sep 5 11:58:59 files2 kernel: Code: 8b 47 10 85 c0 74 04 53 ff d0 58 68 bc 49 2f c0 8d 43 2c 50
Sep 5 12:40:28 files2 syslogd 1.4.1: restart.
Sep 5 12:40:28 files2 syslog: syslogd startup succeeded
Sep 5 12:40:28 files2 kernel: klogd 1.4.1, log source = /proc/kmsg started.
Sep 5 12:40:28 files2 kernel: Linux version 2.4.18-10smp (bhcompile@stripples.devel.redhat.com) (gcc version 2.96 20000731 (Red Hat Linux 7.3 2.96-110)) #1 SMP Wed Aug 7 11:17:48 EDT 2002
======================= Crash #2 ===================================
----------------------- /var/log/samba/smbmount.log #2 -------------
[2002/09/12 04:58:19, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc43\c$, pid=3230
[2002/09/12 04:59:07, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc74\c$, pid=3274
[2002/09/12 04:59:43, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc74\c$, pid=3311
[2002/09/12 05:03:23, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc67\c$, pid=3361
[2002/09/12 05:04:07, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc67\c$, pid=3398
[2002/09/12 05:07:14, 0] client/smbmount.c:send_fs_socket(386)
mount.smbfs: entering daemon mode for service \\pc3\c$, pid=3442
----------------------- /var/log/messages #2 -----------------------
Sep 12 03:47:23 files2 kernel: smb_proc_readdir_long: name=\usr\usr\???????\*, result=-2, rcls=1, err=123
Sep 12 03:47:23 files2 kernel: smb_proc_readdir_long: name=\usr\usr\sch\*, result=-13, rcls=1, err=5
Sep 12 03:47:23 files2 kernel: smb_proc_readdir_long: name=\usr\mentor_guide\*, result=-13, rcls=1, err=5
Sep 12 03:55:52 files2 kernel: smb_proc_readdir_long: name=\Cygwin\home\tim\Mail\*, result=-13, rcls=1, err=5
Sep 12 04:01:22 files2 kernel: smb_proc_readdir_long: name=\Projects\sahara30\production\production\production\*, result=-13, rcls=1, err=5
Sep 12 05:00:35 files2 kernel: smb_open: Mail/Inbox.snm open failed, result=-26
Sep 12 05:00:37 files2 kernel: smb_open: Mail/Inbox.snm open failed, result=-26
Sep 12 05:00:37 files2 kernel: smb_readpage_sync: Mail/Inbox.snm open failed, error=-26
Sep 12 05:37:05 files2 kernel: ------------[ cut here ]------------
Sep 12 05:37:05 files2 kernel: kernel BUG at inode.c:1066!
Sep 12 05:37:05 files2 kernel: invalid operand: 0000
Sep 12 05:37:05 files2 kernel: smbfs nfs lockd sunrpc tg3 eepro100 ide-cd cdrom st usb-ohci usbcore ext3 jbd
Sep 12 05:37:05 files2 kernel: CPU: 1
Sep 12 05:37:05 files2 kernel: EIP: 0010:[<c01588bf>] Not tainted
Sep 12 05:37:05 files2 kernel: EFLAGS: 00010286
Sep 12 05:37:05 files2 kernel:
Sep 12 05:37:05 files2 kernel: EIP is at iput [kernel] 0x2f (2.4.18-10smp)
Sep 12 05:37:05 files2 kernel: eax: 0000001c ebx: d94fe420 ecx: c02f25e0 edx: 00008f60
Sep 12 05:37:05 files2 kernel: esi: f7d1c000 edi: 00000000 ebp: 00000051 esp: f7ed3f54
Sep 12 05:37:05 files2 kernel: ds: 0018 es: 0018 ss: 0018
Sep 12 05:37:05 files2 kernel: Process kswapd (pid: 7, stackpage=f7ed3000)
Sep 12 05:37:13 files2 kernel: Stack: c024a65f 0000042a ddd49e58 ddd49e40 d94fe420 c0155fa6 d94fe420 0000038e
Sep 12 05:37:13 files2 kernel: 00000098 ffffffff c02f3c28 0000038e 0000016c 000003cd c01381e8 000001d0
Sep 12 05:37:13 files2 kernel: 00000465 00000000 0000016c c01563d0 000020a0 c0138b3c 00000006 000001d0
Sep 12 05:37:13 files2 kernel: Call Trace: [<c0155fa6>] prune_dcache [kernel] 0xe6
Sep 12 05:37:13 files2 kernel: [<c01381e8>] page_launder [kernel] 0x168
Sep 12 05:37:13 files2 kernel: [<c01563d0>] shrink_dcache_memory [kernel] 0x20
Sep 12 05:37:14 files2 kernel: [<c0138b3c>] do_try_to_free_pages [kernel] 0x1c
Sep 12 05:37:14 files2 kernel: [<c0138e31>] kswapd [kernel] 0x101
Sep 12 05:37:14 files2 kernel: [<c0105000>] stext [kernel] 0x0
Sep 12 05:37:14 files2 kernel: [<c0107286>] kernel_thread [kernel] 0x26
Sep 12 05:37:14 files2 kernel: [<c0138d30>] kswapd [kernel] 0x0
Sep 12 05:37:14 files2 kernel:
Sep 12 05:37:14 files2 kernel:
Sep 12 05:37:14 files2 kernel: Code: 0f 0b 5a 59 85 f6 74 08 8b 46 20 85 c0 0f 45 f8 85 ff 74 0b
Sep 12 10:21:30 files2 syslogd 1.4.1: restart.
Sep 12 10:21:30 files2 syslog: syslogd startup succeeded
Sep 12 10:21:30 files2 syslog: klogd startup succeeded
Sep 12 10:21:30 files2 kernel: klogd 1.4.1, log source = /proc/kmsg started.
Sep 12 10:21:30 files2 kernel: Linux version 2.4.18-10smp (bhcompile@stripples.devel.redhat.com) (gcc version 2.96 20000731 (Red Hat Linux 7.3 2.96-110)) #1 SMP Wed Aug 7 11:17:48 EDT 2002
-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/
This archive was generated by hypermail 2b29 : Sun Sep 15 2002 - 22:00:35 EST