ewhac
Contributor
- Joined
- Aug 20, 2013
- Messages
- 177
I am currently running FreeNAS-9.3-STABLE-201505130355, and have observed a weird issue that will probably have @cyberjock issuing well-earned snark.
I have a single CIFS share that is sharing home directories for all the FreeNAS users. I set it up back in the FreeNAS 8.3 days, using UNIX permissions. The share is accessed via both Windows and Linux clients. This setup has worked without major issue for some time.
Last week, an attempt to delete a file caused smbd to repeatedly crash:
The file in question had been created using the Windows client; the deletion was attempted using the 'rm' command from a Linux client. The 'rm' command hung as the above messages accumulated in the logs, until I killed it with ^C. The file itself was perfectly readable from the Linux client.
I ssh'ed into the FreeNAS server and 'rm'ed the file from there without incident.
I haven't yet tried all possible creation/deletion client permutations yet, but the fact that smbd is flat-out crashing seems unusually bad. Any suggestions on how to drill down on the problem? Config file contents available upon request.
I have a single CIFS share that is sharing home directories for all the FreeNAS users. I set it up back in the FreeNAS 8.3 days, using UNIX permissions. The share is accessed via both Windows and Linux clients. This setup has worked without major issue for some time.
Last week, an attempt to delete a file caused smbd to repeatedly crash:
Code:
May 31 01:28:49 alexandria smbd[2289]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsScheduled cleanup of brl and lock database after unclean shutdown May 31 01:28:49 alexandria kernel: pid 80807 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:28:59 alexandria winbindd[2296]: [2015/05/31 01:28:59.964789, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:28:59 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:00 alexandria winbindd[2296]: [2015/05/31 01:29:00.055145, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:00 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:00 alexandria winbindd[2296]: [2015/05/31 01:29:00.056297, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:00 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:00 alexandria winbindd[2296]: [2015/05/31 01:29:00.059923, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:00 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:00 alexandria kernel: pid 81087 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:29:10 alexandria winbindd[2296]: [2015/05/31 01:29:10.484314, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:10 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:10 alexandria winbindd[2296]: [2015/05/31 01:29:10.507320, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:10 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:10 alexandria winbindd[2296]: [2015/05/31 01:29:10.508460, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:10 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:10 alexandria winbindd[2296]: [2015/05/31 01:29:10.512060, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:10 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:10 alexandria kernel: pid 81088 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:29:20 alexandria winbindd[2296]: [2015/05/31 01:29:20.928624, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:20 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:20 alexandria winbindd[2296]: [2015/05/31 01:29:20.952273, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:20 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:20 alexandria winbindd[2296]: [2015/05/31 01:29:20.953541, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:20 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:20 alexandria winbindd[2296]: [2015/05/31 01:29:20.957199, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:20 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:21 alexandria kernel: pid 81089 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:29:31 alexandria winbindd[2296]: [2015/05/31 01:29:31.373367, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:31 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:31 alexandria winbindd[2296]: [2015/05/31 01:29:31.396407, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:31 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:31 alexandria winbindd[2296]: [2015/05/31 01:29:31.397558, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:31 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:31 alexandria winbindd[2296]: [2015/05/31 01:29:31.401161, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:31 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:31 alexandria kernel: pid 81095 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:29:41 alexandria winbindd[2296]: [2015/05/31 01:29:41.820456, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:41 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:41 alexandria winbindd[2296]: [2015/05/31 01:29:41.844528, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:41 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:41 alexandria winbindd[2296]: [2015/05/31 01:29:41.845922, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:41 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:41 alexandria winbindd[2296]: [2015/05/31 01:29:41.849732, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:41 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:42 alexandria kernel: pid 81097 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:29:52 alexandria winbindd[2296]: [2015/05/31 01:29:52.263644, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:52 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:52 alexandria winbindd[2296]: [2015/05/31 01:29:52.286747, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:52 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:52 alexandria winbindd[2296]: [2015/05/31 01:29:52.287889, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:52 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:52 alexandria winbindd[2296]: [2015/05/31 01:29:52.291479, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:29:52 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:29:52 alexandria kernel: pid 81098 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:30:02 alexandria winbindd[2296]: [2015/05/31 01:30:02.707551, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:02 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:02 alexandria winbindd[2296]: [2015/05/31 01:30:02.730806, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:02 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:02 alexandria winbindd[2296]: [2015/05/31 01:30:02.731970, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:02 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:02 alexandria winbindd[2296]: [2015/05/31 01:30:02.735625, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:02 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:03 alexandria kernel: pid 81104 (smbd), uid 1001: exited on signal 4 (core dumped) May 31 01:30:13 alexandria winbindd[2296]: [2015/05/31 01:30:13.151577, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:13 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:13 alexandria winbindd[2296]: [2015/05/31 01:30:13.174690, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:13 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:13 alexandria winbindd[2296]: [2015/05/31 01:30:13.175842, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:13 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:13 alexandria winbindd[2296]: [2015/05/31 01:30:13.179461, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names) May 31 01:30:13 alexandria winbindd[2296]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2039921271-1382851169-3180764252 May 31 01:30:13 alexandria kernel: pid 81113 (smbd), uid 1001: exited on signal 4 (core dumped)
The file in question had been created using the Windows client; the deletion was attempted using the 'rm' command from a Linux client. The 'rm' command hung as the above messages accumulated in the logs, until I killed it with ^C. The file itself was perfectly readable from the Linux client.
I ssh'ed into the FreeNAS server and 'rm'ed the file from there without incident.
I haven't yet tried all possible creation/deletion client permutations yet, but the fact that smbd is flat-out crashing seems unusually bad. Any suggestions on how to drill down on the problem? Config file contents available upon request.