thr3ads.net - samba - [Samba] macintosh disconnect problem [Jan 2020]

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

Chad W Seys

2019-Dec-23 18:06 UTC

[Samba] macintosh disconnect problem

Hi all,
   We're having trouble with Macintosh clients (at least) being 
disconnected from Samba.  Below is one macintosh console output when 
this happens, filtered by "smb".  (Yeah, maybe some clues are
missing...)
   ATM I don't see anything that stands out in the Samba log file. 
However one thing I don't see in the samba log file is session disconnects.
   Does anyone know how to log disconnects?  I've found one such way on 
stackexchange involving 'postexec' 
https://unix.stackexchange.com/a/478881/275843, but I thought maybe 
there was a log subsection and level which would also work.  (I don't 
want to set log 10 b/c that would generate so much log file it would 
either fill the drive or get rotated out.)

Thanks!
Chad.

apt-cache policy samba
samba:
   Installed: 2:4.9.5+dfsg-5+deb10u1

macintosh client console filtered with word "smb":

12/18/19 8:14:42.000 AM kernel[0]: smbfs_down: Remounting 
smb.physics.wisc.edu/SMB
12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify: 
smb_rq_reply failed 60
12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify: 
smb_rq_reply failed 60
12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify: 
smb_rq_reply failed 60
12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify: 
smb_rq_reply failed 60
12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify: 
smb_rq_reply failed 60
12/18/19 8:14:42.775 AM smbremountserver[23160]: 
isReferralPathNotCovered Dfs Check path on /smb.physics.wisc.edu/smb 
return ntstatus = 0xc000000d, syserr = Invalid argument
12/18/19 8:15:39.000 AM kernel[0]: smb2_rq_sign: No session key for signing.
12/18/19 8:15:40.000 AM kernel[0]: smbfs_vnop_strategy: READ on 
4867_rylander_timesheet_2016.xlsx failed with an error of 57
12/18/19 8:15:40.000 AM kernel[0]: smbfs_lookup: Server failed Query Info 57
12/18/19 8:15:40.403 AM KernelEventAgent[101]: tid 54485244 type 
'smbfs', mounted on '/Volumes/smb', from 
'//lefkow at smb.physics.wisc.edu/smb', dead
12/18/19 8:15:40.403 AM KernelEventAgent[101]: tid 54485244 force 
unmount //lefkow at smb.physics.wisc.edu/smb from /Volumes/smb
12/18/19 8:15:40.455 AM deleted[2105]: volumeFreespace:548 statfs failed 
for /Volumes/smb : No such file or directory
12/18/19 8:15:40.456 AM deleted[2105]: normalizeUserMountpoint:791 
volRoot failed for /Volumes/smb
12/18/19 8:15:40.456 AM deleted[2105]: _validateVolume:813 unable to 
normalize volume: "/Volumes/smb", vol: (null)
12/18/19 8:15:40.458 AM deleted[2105]: volumeFreespace:548 statfs failed 
for /Volumes/smb : No such file or directory
12/18/19 8:15:40.458 AM deleted[2105]: normalizeUserMountpoint:791 
volRoot failed for /Volumes/smb
12/18/19 8:15:40.458 AM deleted[2105]: _validateVolume:813 unable to 
normalize volume: "/Volumes/smb", vol: (null)
12/18/19 8:15:40.471 AM deleted[2105]: volumeFreespace:548 statfs failed 
for /Volumes/smb : No such file or directory
12/18/19 8:15:40.471 AM deleted[2105]: normalizeUserMountpoint:791 
volRoot failed for /Volumes/smb
12/18/19 8:15:40.471 AM deleted[2105]: _validateVolume:813 unable to 
normalize volume: "/Volumes/smb", vol: (null)
12/18/19 8:15:40.547 AM TMCacheDelete[2183]: Couldn't get resource value 
'NSURLVolumeIsLocalKey' for 'file:///Volumes/smb/': Error 
Domain=NSCocoaErrorDomain Code=4 "The file ?smb? doesn?t exist." 
UserInfo={NSURL=file:///Volumes/smb/, NSFilePath=/Volumes/smb}
12/18/19 8:15:40.548 AM deleted[2105]: volumeFreespace:548 statfs failed 
for /Volumes/smb : No such file or directory
12/18/19 8:15:40.548 AM deleted[2105]: normalizeUserMountpoint:791 
volRoot failed for /Volumes/smb
12/18/19 8:15:40.548 AM deleted[2105]: _validateVolume:813 unable to 
normalize volume: "/Volumes/smb", vol: (null)
12/18/19 8:15:40.568 AM deleted[2105]: normalizeUserMountpoint:791 
volRoot failed for /Volumes/smb
12/18/19 8:15:40.568 AM deleted[2105]: _validateVolume:813 unable to 
normalize volume: "/Volumes/smb", vol: (null)
12/18/19 8:15:40.568 AM deleted[2105]: -[CacheDeleteOperation 
initWithInfo:services:]:33 Unable to validate volume: /Volumes/smb

12/18/19 8:15:40.547 AM TMCacheDelete[2183]: Couldn't get resource value 
'NSURLVolumeIsLocalKey' for 'file:///Volumes/smb/': Error 
Domain=NSCocoaErrorDomain Code=4 "The file ?smb? doesn?t exist." 
UserInfo={NSURL=file:///Volumes/smb/, NSFilePath=/Volumes/smb}

   I'm having trouble with Macintosh clients being disconnected
https://unix.stackexchange.com/a/478881/275843

Rowland penny

2019-Dec-23 18:51 UTC

head link

[Samba] macintosh disconnect problem

On 23/12/2019 18:06, Chad W Seys via samba wrote:> Hi all,
>     We're having trouble with Macintosh clients (at least) being
> disconnected from Samba.  Below is one macintosh console output when
> this happens, filtered by "smb".  (Yeah, maybe some clues are
missing...)
>     ATM I don't see anything that stands out in the Samba log file.
> However one thing I don't see in the samba log file is session
disconnects.
>     Does anyone know how to log disconnects?  I've found one such way
on
> stackexchange involving 'postexec'
> https://unix.stackexchange.com/a/478881/275843, but I thought maybe
> there was a log subsection and level which would also work.  (I don't
> want to set log 10 b/c that would generate so much log file it would
> either fill the drive or get rotated out.)
>
> Thanks!
> Chad.
>
> apt-cache policy samba
> samba:
>     Installed: 2:4.9.5+dfsg-5+deb10u1
>
> macintosh client console filtered with word "smb":
>
> 12/18/19 8:14:42.000 AM kernel[0]: smbfs_down: Remounting
> smb.physics.wisc.edu/SMB
> 12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify:
> smb_rq_reply failed 60
> 12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify:
> smb_rq_reply failed 60
> 12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify:
> smb_rq_reply failed 60
> 12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify:
> smb_rq_reply failed 60
> 12/18/19 8:14:42.000 AM kernel[0]: smb2_smb_parse_change_notify:
> smb_rq_reply failed 60
> 12/18/19 8:14:42.775 AM smbremountserver[23160]:
> isReferralPathNotCovered Dfs Check path on /smb.physics.wisc.edu/smb
> return ntstatus = 0xc000000d, syserr = Invalid argument
> 12/18/19 8:15:39.000 AM kernel[0]: smb2_rq_sign: No session key for
signing.
> 12/18/19 8:15:40.000 AM kernel[0]: smbfs_vnop_strategy: READ on
> 4867_rylander_timesheet_2016.xlsx failed with an error of 57
> 12/18/19 8:15:40.000 AM kernel[0]: smbfs_lookup: Server failed Query Info
57
> 12/18/19 8:15:40.403 AM KernelEventAgent[101]: tid 54485244 type
> 'smbfs', mounted on '/Volumes/smb', from
> '//lefkow at smb.physics.wisc.edu/smb', dead
> 12/18/19 8:15:40.403 AM KernelEventAgent[101]: tid 54485244 force
> unmount //lefkow at smb.physics.wisc.edu/smb from /Volumes/smb
> 12/18/19 8:15:40.455 AM deleted[2105]: volumeFreespace:548 statfs failed
> for /Volumes/smb : No such file or directory
> 12/18/19 8:15:40.456 AM deleted[2105]: normalizeUserMountpoint:791
> volRoot failed for /Volumes/smb
> 12/18/19 8:15:40.456 AM deleted[2105]: _validateVolume:813 unable to
> normalize volume: "/Volumes/smb", vol: (null)
> 12/18/19 8:15:40.458 AM deleted[2105]: volumeFreespace:548 statfs failed
> for /Volumes/smb : No such file or directory
> 12/18/19 8:15:40.458 AM deleted[2105]: normalizeUserMountpoint:791
> volRoot failed for /Volumes/smb
> 12/18/19 8:15:40.458 AM deleted[2105]: _validateVolume:813 unable to
> normalize volume: "/Volumes/smb", vol: (null)
> 12/18/19 8:15:40.471 AM deleted[2105]: volumeFreespace:548 statfs failed
> for /Volumes/smb : No such file or directory
> 12/18/19 8:15:40.471 AM deleted[2105]: normalizeUserMountpoint:791
> volRoot failed for /Volumes/smb
> 12/18/19 8:15:40.471 AM deleted[2105]: _validateVolume:813 unable to
> normalize volume: "/Volumes/smb", vol: (null)
> 12/18/19 8:15:40.547 AM TMCacheDelete[2183]: Couldn't get resource
value
> 'NSURLVolumeIsLocalKey' for 'file:///Volumes/smb/': Error
> Domain=NSCocoaErrorDomain Code=4 "The file ?smb? doesn?t exist."
> UserInfo={NSURL=file:///Volumes/smb/, NSFilePath=/Volumes/smb}
> 12/18/19 8:15:40.548 AM deleted[2105]: volumeFreespace:548 statfs failed
> for /Volumes/smb : No such file or directory
> 12/18/19 8:15:40.548 AM deleted[2105]: normalizeUserMountpoint:791
> volRoot failed for /Volumes/smb
> 12/18/19 8:15:40.548 AM deleted[2105]: _validateVolume:813 unable to
> normalize volume: "/Volumes/smb", vol: (null)
> 12/18/19 8:15:40.568 AM deleted[2105]: normalizeUserMountpoint:791
> volRoot failed for /Volumes/smb
> 12/18/19 8:15:40.568 AM deleted[2105]: _validateVolume:813 unable to
> normalize volume: "/Volumes/smb", vol: (null)
> 12/18/19 8:15:40.568 AM deleted[2105]: -[CacheDeleteOperation
> initWithInfo:services:]:33 Unable to validate volume: /Volumes/smb
>
> 12/18/19 8:15:40.547 AM TMCacheDelete[2183]: Couldn't get resource
value
> 'NSURLVolumeIsLocalKey' for 'file:///Volumes/smb/': Error
> Domain=NSCocoaErrorDomain Code=4 "The file ?smb? doesn?t exist."
> UserInfo={NSURL=file:///Volumes/smb/, NSFilePath=/Volumes/smb}
>
>     I'm having trouble with Macintosh clients being disconnected
> https://unix.stackexchange.com/a/478881/275843
It looks like it cannot find/see the share, how are you running Samba ?

Can you please post your smb.conf.

Rowland

Andrea Venturoli

2019-Dec-23 19:10 UTC

head link

[Samba] macintosh disconnect problem

On 2019-12-23 19:06, Chad W Seys via samba wrote:> Hi all,
>     We're having trouble with Macintosh clients (at least) being
> disconnected from Samba.
Hello.
I'm seeing them too (see my messages from a few days ago).

> However one thing I don't see in the samba log file is session
disconnects.
Neither do I.
I've skimmed briefly a level 10 log and didn't see anything useful, but 
I'd need to look better before I state this for sure.

However I see smbd crashing on notifications and I'm still investigating 
why and whether the two things are related.
Do you also see crashes?

Also when did this start?
Did you do some upgrade?
Did it work before?

In my case it started when I moved shares from a base 4.8 installation 
to a jailed 4.10.

  bye & Thanks
	av.

Chad W Seys

2020-Jan-02 15:55 UTC

head link

[Samba] macintosh disconnect problem

Hi Andrea,
> Neither do I.
> I've skimmed briefly a level 10 log and didn't see anything useful,
but
> I'd need to look better before I state this for sure.
The preexec / postexec hooks mentioned at 
https://unix.stackexchange.com/a/478881/275843 work well for this.  I 
see Mac clients disconnecting when they have problems, but haven't yet 
correlated that occurrence to anything suspicous in the log.smbd file yet.

> However I see smbd crashing on notifications and I'm still
investigating
> why and whether the two things are related.
> Do you also see crashes?
I haven't noticed this.  What do you look for to find this?
> 
> Also when did this start?
> Did you do some upgrade?
> Did it work before?
These questions are hard to answer.  I have the suspicion that there is 
more than one problem going on AND my end users have just lived with it 
for awhile.

I need to do more testing before I have enough to go on before using 
someone like Rowland's time. :)

For example, one failure mode is that sometimes macOS (10.13 ,10.15, ?) 
will block access to paths hosted by Samba.  It is most easily 
reproducible if a DFS link is involved.  So far I know this happens with 
Samba 4.9.17, 4.10.11, and 4.11.4 .

Try searching for "finder" and "sandbox" in Console of a
Macintosh which
is having problems.  Press "return" after each term so you're not 
searchign for "finder sandbox" but "finder" AND
"sandbox".

Eventually I'll submit a bug report on that, but need to write it up.

That is not the only problem however.

Chad.

Reasonably Related Threads

Search for more reasonably related threads

samba - Jan 2020 - macintosh disconnect problem

[Samba] macintosh disconnect problem

[Samba] macintosh disconnect problem

[Samba] macintosh disconnect problem

[Samba] macintosh disconnect problem

Reasonably Related Threads