Samba is filling the disk with two logfiles (syslog and daemon.log) with messages on "No data on inotify ds?!

Bug #656456 reported by Patrick Haller
18
This bug affects 3 people
Affects Status Importance Assigned to Milestone
samba (Ubuntu)
Confirmed
Low
Unassigned

Bug Description

Binary package hint: samba

The Samba Fileserver serves 32-bit Windows XP and 64-bit Windows 7 clients.
Without any apparent reason (telling from the log information), the Linux kernel / Samba daemon starts logging "No data on inotify fd?!" messages until the harddrive is full. (Existing log limits are ignored).

The effect occurred 4 times since upgradng from Ubuntu 8.0.4 LTS to Ubuntu 10.0.4 LTS in May 2010.

The machine can only be recovered by rebooting.

System:
- Ubuntu 10.0.4 LTS on AMD64
- Linux se003 2.6.32-25-generic #44-Ubuntu SMP Fri Sep 17 20:05:27 UTC 2010 x86_64 GNU/Linux
- Samba Version 3.4.7

[Windows 7 64-bit Client machine's log in /var/log/samba]
[2010/10/07 09:31:20, 0] smbd/notify_inotify.c:244(inotify_handler)
  No data on inotify fd?!
[2010/10/07 09:31:20, 0] smbd/notify_inotify.c:244(inotify_handler)
  No data on inotify fd?!
[2010/10/07 09:31:20, 0] smbd/notify_inotify.c:244(inotify_handler)
  No data on inotify fd?!
...

[syslog]
Oct 7 06:28:28 se003 smbd[27192]: [2010/10/07 06:28:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:28:28 se003 smbd[27192]: chdir (/home/user01) failed
Oct 7 06:28:28 se003 smbd[27192]: [2010/10/07 06:28:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:28:28 se003 smbd[27192]: chdir (/home/user02) failed
Oct 7 06:28:49 se003 kernel: [719413.280723] lo: Disabled Privacy Extensions
Oct 7 06:28:49 se003 postfix/smtpd[27890]: connect from localhost[127.0.0.1]
Oct 7 06:28:49 se003 postfix/smtpd[27890]: disconnect from localhost[127.0.0.1]
Oct 7 06:29:27 se003 fetchmail[2889]: awakened at Thu Oct 7 06:29:27 2010
Oct 7 06:29:28 se003 fetchmail[2889]: Server certificate verification error: certificate has expired
Oct 7 06:29:32 se003 fetchmail[2889]: last message repeated 4 times
Oct 7 06:29:32 se003 fetchmail[2889]: sleeping at Thu Oct 7 06:29:32 2010 for 60 seconds
Oct 7 06:30:32 se003 fetchmail[2889]: awakened at Thu Oct 7 06:30:32 2010
Oct 7 06:30:33 se003 fetchmail[2889]: Server certificate verification error: certificate has expired
Oct 7 06:30:38 se003 fetchmail[2889]: last message repeated 4 times
Oct 7 06:30:38 se003 fetchmail[2889]: sleeping at Thu Oct 7 06:30:38 2010 for 60 seconds
Oct 7 06:31:04 se003 imapd: Connection, ip=[::ffff:127.0.0.1]
Oct 7 06:31:04 se003 imapd: LOGOUT, ip=[::ffff:127.0.0.1], rcvd=11, sent=307
Oct 7 06:31:12 se003 smbd[27192]: [2010/10/07 06:31:12, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 06:31:12 se003 smbd[27192]: No data on inotify fd?!
Oct 7 06:31:12 se003 smbd[27192]: [2010/10/07 06:31:12, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 06:31:12 se003 smbd[27192]: No data on inotify fd?!
...
Oct 7 09:32:17 se003 smbd[27192]: [2010/10/07 09:32:17, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 09:32:17 se003 smbd[27192]: No data on inotify fd?!
Oct 7 09:32:17 se003 smbd[27192]: [2010/10/07 09:32:17, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 09:32:17 se003 smbd[27192]: No data on inotify fd?!
Oct 7 09:32:17 se003 smbd[27192]: [2010/10/07 09:32:17, 0] smbd/notify_inotify.c:244(inotify_handler)
...
Oct 7 09:32:17 se003 smbd[27192]: [2010/10/07 09:32:17, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 09:32:17 se003 smbd[27192]: No data on inotify fd?!
Oct 7 09:32:18 se003 smbd[27192]: [2010/10/07 09:32:18, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 09:32:18 se003 smbd[27192]: No data on inotify fd?!
>>> MANUAL MACHINE RESTART <<<
Oct 7 11:52:05 se003 kernel: imklog 4.2.0, log source = /proc/kmsg started.
Oct 7 11:52:05 se003 rsyslogd: [origin software="rsyslogd" swVersion="4.2.0" x-pid="1180" x-info="http://www.rsyslog.com"] (re)start
Oct 7 11:52:05 se003 rsyslogd: rsyslogd's groupid changed to 102
Oct 7 11:52:05 se003 rsyslogd: rsyslogd's userid changed to 101
Oct 7 11:52:04 se003 rsyslogd-2039: Could no open output file '/dev/xconsole' [try http://www.rsyslog.com/e/2039 ]
Oct 7 11:52:05 se003 nmbd[1179]: [2010/10/07 11:52:05, 0] nmbd/nmbd_logonnames.c:160(add_logon_names)
Oct 7 11:52:05 se003 nmbd[1179]: add_domain_logon_names:
Oct 7 11:52:05 se003 kernel: [ 0.000000] Initializing cgroup subsys cpuset
Oct 7 11:52:05 se003 kernel: [ 0.000000] Initializing cgroup subsys cpu
Oct 7 11:52:05 se003 kernel: [ 0.000000] Linux version 2.6.32-25-generic (buildd@yellow) (gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) ) #44-Ubuntu SMP Fri
Sep 17 20:05:27 UTC 2010 (Ubuntu 2.6.32-25.44-generic 2.6.32.21+drm33.7)
Oct 7 11:52:05 se003 kernel: [ 0.000000] Command line: root=/dev/md1 ro quiet splash
Oct 7 11:52:05 se003 kernel: [ 0.000000] KERNEL supported cpus:

[daemon.log]
Oct 7 06:08:18 se003 smbd[27192]: [2010/10/07 06:08:18, 0] smbd/nttrans.c:2123(call_nt_transact_ioctl)
Oct 7 06:08:18 se003 smbd[27192]: call_nt_transact_ioctl(0x900eb): Currently not implemented.
Oct 7 06:08:28 se003 smbd[27192]: [2010/10/07 06:08:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:08:28 se003 smbd[27192]: chdir (/home/user01) failed
Oct 7 06:08:28 se003 smbd[27192]: [2010/10/07 06:08:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:08:28 se003 smbd[27192]: chdir (/home/user02) failed
Oct 7 06:10:31 se003 dnsmasq-dhcp[1316]: DHCPINFORM(eth0) 192.168.2.248 e0:cb:4e:5d:b9:75
Oct 7 06:10:31 se003 dnsmasq-dhcp[1316]: DHCPACK(eth0) 192.168.2.248 e0:cb:4e:5d:b9:75 PCE018
Oct 7 06:13:28 se003 smbd[27192]: [2010/10/07 06:13:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:13:28 se003 smbd[27192]: chdir (/home/user01) failed
Oct 7 06:13:28 se003 smbd[27192]: [2010/10/07 06:13:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:13:28 se003 smbd[27192]: chdir (/home/user02) failed
Oct 7 06:25:52 se003 smbd[24792]: [2010/10/07 06:25:52, 0] lib/util_sock.c:539(read_fd_with_timeout)
Oct 7 06:25:52 se003 smbd[24792]: [2010/10/07 06:25:52, 0] lib/util_sock.c:1491(get_peer_addr_internal)
Oct 7 06:25:52 se003 smbd[24792]: getpeername failed. Error was Transport endpoint is not connected
Oct 7 06:25:52 se003 smbd[24792]: read_fd_with_timeout: client 0.0.0.0 read error = No route to host.
Oct 7 06:28:28 se003 smbd[27192]: [2010/10/07 06:28:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:28:28 se003 smbd[27192]: chdir (/home/user01) failed
Oct 7 06:28:28 se003 smbd[27192]: [2010/10/07 06:28:28, 0] smbd/service.c:191(set_current_service)
Oct 7 06:28:28 se003 smbd[27192]: chdir (/home/user02) failed
Oct 7 06:31:12 se003 smbd[27192]: [2010/10/07 06:31:12, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 06:31:12 se003 smbd[27192]: No data on inotify fd?!
...
Oct 7 06:31:12 se003 smbd[27192]: [2010/10/07 06:31:12, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 06:31:12 se003 smbd[27192]: No data on inotify fd?!
Oct 7 06:31:12 se003 smbd[27192]: [2010/10/07 06:31:12, 0] smbd/notify_inotify.c:244(inotify_handler)
Oct 7 06:31:12 se003 smbd[27192]: No data on inotify fd?!

ProblemType: Bug
DistroRelease: Ubuntu 10.04
Package: samba 2:3.4.7~dfsg-1ubuntu3.2
ProcVersionSignature: Ubuntu 2.6.32-25.44-generic 2.6.32.21+drm33.7
Uname: Linux 2.6.32-25-generic x86_64
Architecture: amd64
Date: Thu Oct 7 20:09:15 2010
ProcEnviron:
 SHELL=/bin/bash
 LANG=de_DE.UTF-8
SambaServerRegression: Yes
SourcePackage: samba
UbuntuFailedConnect: Yes

Revision history for this message
Patrick Haller (patrick-haller) wrote :
Revision history for this message
Thierry Carrez (ttx) wrote :

Apparently this is due to a combination of factors, issues in kernel notify implementation and a specific unsupported situation (for example, Outlook PSTs and OST on a network share).

Could you add the following to the [global] section of your /etc/samba/smb.conf and see if it fixes it ?
kernel change notify = false

References:
http://ubuntuforums.org/showthread.php?t=1099408
http://samba.2283325.n4.nabble.com/No-data-on-inotify-fd-error-td2452847.html

Changed in samba (Ubuntu):
importance: Undecided → Low
status: New → Incomplete
Revision history for this message
Patrick Haller (patrick-haller) wrote :

OK, since adding "kernel change notify = false", the issue has not reoccurred so far.
So for now, the workaround seems acceptable.

Revision history for this message
Launchpad Janitor (janitor) wrote :

[Expired for samba (Ubuntu) because there has been no activity for 60 days.]

Changed in samba (Ubuntu):
status: Incomplete → Expired
Revision history for this message
Jeet (gour-jitendrasingh) wrote :

I tried same thing and able to reproduce .. sorry i can not give logs here

Changed in samba (Ubuntu):
status: Expired → Confirmed
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.