I'm loggin in . . . but I'm not

[Glorious1]

For a long time I noticed in the messages log that an AFP login by me was happening randomly at times when I know I didn't log in and my laptop was closed, so it couldn't have been Time Machine. These are short and weird. If anyone can decode the sequence of events below, I'd love to understand it.

A seemingly unrelated problem was that I would get occasional video freezes, like buffering, when FreeNAS is streaming video to my HTPC. Last night I marked the time of one such freeze, and then checked the log:

Code:

Jan 26 19:40:45 Tabernacle mountd[2389]: export request succeeded from 192.168.0.101
Jan 26 19:40:45 Tabernacle mountd[2389]: mount request succeeded from 192.168.0.101 for /mnt/Ark/Media
Jan 26 19:46:11 Tabernacle afpd[87965]: Login by jim (AFP3.4)
Jan 26 19:46:11 Tabernacle afpd[87965]: afp_disconnect: trying primary reconnect
Jan 26 19:46:11 Tabernacle afpd[3393]: Reconnect: transfering session to child[89658]
Jan 26 19:46:11 Tabernacle afpd[3393]: Reconnect: killing new session child[87965] after transfer
Jan 26 19:46:11 Tabernacle afpd[89658]: afp_dsi_transfer_session: succesfull primary reconnect
Jan 26 19:46:11 Tabernacle afpd[89658]: AFP Replay Cache match: id: 52691 / cmd: AFP_GETEXTATTR
Jan 26 19:46:13 Tabernacle afpd[87965]: afp_disconnect: primary reconnect succeeded
Jan 26 19:48:18 Tabernacle afpd[89658]: read: Operation timed out
Jan 26 19:48:18 Tabernacle afpd[89658]: dsi_stream_read: len:-1, Operation timed out
Jan 26 19:48:18 Tabernacle afpd[89658]: dsi_stream_read: len:0, unexpected EOF
Jan 26 19:48:18 Tabernacle afpd[89658]: dsi_disconnect: entering disconnected state

You can see the HTPC mounting the Media dataset via NFS at 19:40. At 19:46 there was a video freeze. That's exactly when one of these weird logins happened. What's going on there? I'm pretty sure it must be something inside the machine, not a real external login.

 

[cyberjock]

You using Wifi?

Is your HTPC an apple device?

Keep in mind the lines after the first two are for afpd, which is not the NFS service (nfsd). So they may be related to the problem, but it's not likely the cause unless something really fugly is going on.

 

[Glorious1]

Yes, it is Mac mini, but it gets the media over NFS. Kodi/XBMC doesn't do AFP properly.

CJ, you actually helped me figure out how to share it to the Mac mini via NFS (read-only) while sharing it to my laptop over AFP.

I understand the remaining lines after the first two are AFP. But because they coincide with the freeze in the NFS streaming, I'm suspecting that somehow the weird AFP activity is disrupting the NFS streaming.

Any idea what that AFP activity could be if it's not me?

Edit: I enabled AFP logging in afp.conf, but I'm none the wiser. "debug" level logging sends a constant stream of gibberish to the log; the next level "info" send almost nothing.

 

[cyberjock]

I don't use AFP as I own zero mac devices. AFP is also a dying protocol (even Apple is deprecating it in favor of CIFS).

I tend to think that the AFP problem is a symptom of the problem and not the cause.