AFP Connection to FreeNAS randomly drops in OSX 10.9.5

Status
Not open for further replies.
Shaun Steinke

Shaun Steinke

Cadet
Joined
Mar 6, 2015
Messages
8
Hello everyone,

I just wanted to see if anyone else is having issues with AFP connections randomly dropping in Mavericks. We have several FreeNAS servers setup, all at FreeNAS-9.3-STABLE-201505040117. It seems that from a couple users MACs they can be working fine transferring files and then suddenly without reason AFP flat lines and the connection drops. Nothing shows in the console on either the FreeNAS or the MAC.

Any ideas?
 
Shaun Steinke

Shaun Steinke

Cadet
Joined
Mar 6, 2015
Messages
8
Here is the console dump.

Jun 29 00:00:00 sfmrc51 newsyslog[24140]: logfile turned over due to size>100K
Jun 29 00:00:00 sfmrc51 syslog-ng[2189]: Configuration reload request received, reloading configuration;
Jun 29 09:00:06 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs snapshot -r "DATA@auto-20150629.0900-2w"
Jun 29 09:00:06 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/.system/samba4@auto-20150615.0900-2w"
Jun 29 09:00:06 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/.system/samba4@auto-20150615.0900-2w"
Jun 29 09:00:06 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51@auto-20150615.0900-2w"
Jun 29 09:00:06 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/Area51@auto-20150615.0900-2w"
Jun 29 09:00:07 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/Transfers@auto-20150615.0900-2w"
Jun 29 09:00:07 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/.system/rrd-b9f7fb55ddfd42b099fb592d008e42b1@auto-20150615.0900-2w"
Jun 29 09:00:07 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/.system/rrd-b9f7fb55ddfd42b099fb592d008e42b1@auto-20150615.0900-2w"
Jun 29 09:00:08 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/.system@auto-20150615.0900-2w"
Jun 29 09:00:08 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/.system@auto-20150615.0900-2w"
Jun 29 09:00:08 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/NIRU@auto-20150615.0900-2w"
Jun 29 09:00:08 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/.system/syslog-b9f7fb55ddfd42b099fb592d008e42b1@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Users/previous_users@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Pexman@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Goodyear@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Common@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Workspace@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/Workspace@auto-20150615.0900-2w"
Jun 29 09:00:09 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Clinical51@auto-20150615.0900-2w"
Jun 29 09:00:10 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/Clinical51@auto-20150615.0900-2w"
Jun 29 09:00:10 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Smith@auto-20150615.0900-2w"
Jun 29 09:00:10 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/DropZone@auto-20150615.0900-2w"
Jun 29 09:00:10 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA/DropZone@auto-20150615.0900-2w"
Jun 29 09:00:10 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Public@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Dewey@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Clinical51/Clinical@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Users@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Ramasubbu@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/.system/cores@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data/MRcentre/Federico@auto-20150615.0900-2w"
Jun 29 09:00:11 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA/Area51/Data@auto-20150615.0900-2w"
Jun 29 09:00:12 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs get -H freenas:state "DATA@auto-20150615.0900-2w"
Jun 29 09:00:12 sfmrc51 autosnap.py: [tools.autosnap:59] Popen()ing: /sbin/zfs destroy -r -d "DATA@auto-20150615.0900-2w"
Jun 29 09:21:16 sfmrc51 afpd[47594]: Login by vamosher (AFP3.4)
Jun 29 09:39:00 sfmrc51 winbindd[3086]: [2015/06/29 09:39:00.860671, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Jun 29 09:39:00 sfmrc51 winbindd[3086]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2417289029-1894343029-2711479017
Jun 29 09:39:01 sfmrc51 smbd[48414]: [2015/06/29 09:39:01.061728, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 09:39:01 sfmrc51 smbd[48414]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 09:39:01 sfmrc51 smbd[48414]: [2015/06/29 09:39:01.090311, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 09:39:01 sfmrc51 smbd[48414]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 09:39:01 sfmrc51 smbd[48414]: [2015/06/29 09:39:01.093889, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 09:39:01 sfmrc51 smbd[48414]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 09:39:01 sfmrc51 smbd[48414]: [2015/06/29 09:39:01.094774, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 09:39:01 sfmrc51 smbd[48414]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 09:56:10 sfmrc51 winbindd[3086]: [2015/06/29 09:56:10.296999, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Jun 29 09:56:10 sfmrc51 winbindd[3086]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2417289029-1894343029-2711479017
Jun 29 09:56:10 sfmrc51 smbd[49149]: [2015/06/29 09:56:10.353450, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 10:37:55 sfmrc51 afpd[51073]: Login by xingwang (AFP3.3)
Jun 29 10:38:41 sfmrc51 afpd[61687]: AFP logout by cbeers
Jun 29 10:38:41 sfmrc51 afpd[61687]: AFP statistics: 1881.56 KB read, 299835.78 KB written
Jun 29 10:38:41 sfmrc51 afpd[61687]: done
Jun 29 10:46:12 sfmrc51 afpd[47594]: AFP logout by vamosher
Jun 29 10:46:12 sfmrc51 afpd[47594]: AFP statistics: 647.70 KB read, 5362.21 KB written
Jun 29 10:46:12 sfmrc51 afpd[47594]: done
Jun 29 10:46:18 sfmrc51 afpd[51526]: AFP statistics: 0.51 KB read, 0.38 KB written
Jun 29 10:46:21 sfmrc51 afpd[51527]: AFP statistics: 0.52 KB read, 0.38 KB written
Jun 29 10:46:25 sfmrc51 afpd[51528]: Login by vamosher (AFP3.4)
Jun 29 10:56:25 sfmrc51 afpd[51073]: afp_zzz: entering extended sleep
Jun 29 11:02:14 sfmrc51 afpd[52316]: Login by xingwang (AFP3.3)
Jun 29 11:02:14 sfmrc51 afpd[52316]: afp_disconnect: trying primary reconnect
Jun 29 11:02:14 sfmrc51 afpd[3332]: Reconnect: transfering session to child[51073]
Jun 29 11:02:14 sfmrc51 afpd[3332]: Reconnect: killing new session child[52316] after transfer
Jun 29 11:02:14 sfmrc51 afpd[51073]: afp_dsi_transfer_session: succesfull primary reconnect
Jun 29 11:02:16 sfmrc51 afpd[52316]: afp_disconnect: primary reconnect succeeded
Jun 29 11:28:39 sfmrc51 afpd[51528]: AFP logout by vamosher
Jun 29 11:28:39 sfmrc51 afpd[51528]: AFP statistics: 4620230.63 KB read, 8977.53 KB written
Jun 29 11:28:39 sfmrc51 afpd[51528]: done
Jun 29 11:28:40 sfmrc51 afpd[53474]: AFP statistics: 0.51 KB read, 0.38 KB written
Jun 29 11:28:44 sfmrc51 afpd[53477]: AFP statistics: 0.51 KB read, 0.38 KB written
Jun 29 11:28:47 sfmrc51 afpd[53478]: Login by vamosher (AFP3.4)
Jun 29 11:33:16 sfmrc51 afpd[53478]: AFP logout by vamosher
Jun 29 11:33:16 sfmrc51 afpd[53478]: AFP statistics: 3729097.36 KB read, 7218.78 KB written
Jun 29 11:33:16 sfmrc51 afpd[53478]: done
Jun 29 11:33:21 sfmrc51 afpd[53711]: AFP statistics: 0.51 KB read, 0.38 KB written
Jun 29 11:33:23 sfmrc51 afpd[53712]: Login by vamosher (AFP3.4)
Jun 29 12:16:55 sfmrc51 afpd[51073]: afp_zzz: entering extended sleep
Jun 29 12:18:30 sfmrc51 afpd[55806]: Login by xingwang (AFP3.3)
Jun 29 12:18:30 sfmrc51 afpd[55806]: afp_disconnect: trying primary reconnect
Jun 29 12:18:30 sfmrc51 afpd[3332]: Reconnect: transfering session to child[51073]
Jun 29 12:18:30 sfmrc51 afpd[3332]: Reconnect: killing new session child[55806] after transfer
Jun 29 12:18:30 sfmrc51 afpd[51073]: afp_dsi_transfer_session: succesfull primary reconnect
Jun 29 12:18:32 sfmrc51 afpd[55806]: afp_disconnect: primary reconnect succeeded
Jun 29 13:16:29 sfmrc51 kernel: arp: 139.48.44.253 moved from 00:25:00:d1:c1:fc to c8:2a:14:22:c6:f7 on em0Jun 29 14:01:24 sfmrc51 afpd[53712]: AFP logout by vamosher
Jun 29 14:01:24 sfmrc51 afpd[53712]: AFP statistics: 1670070.67 KB read, 26392.10 KB written
Jun 29 14:01:24 sfmrc51 afpd[53712]: done
Jun 29 14:04:59 sfmrc51 winbindd[3086]: [2015/06/29 14:04:59.808258, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Jun 29 14:04:59 sfmrc51 winbindd[3086]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-2417289029-1894343029-2711479017
Jun 29 14:04:59 sfmrc51 smbd[60691]: [2015/06/29 14:04:59.858302, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 14:04:59 sfmrc51 smbd[60691]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 14:04:59 sfmrc51 smbd[60691]: [2015/06/29 14:04:59.877978, 0] ../source3/lib/access.c:338(allow_access)
Jun 29 14:04:59 sfmrc51 smbd[60691]: Denied connection from 139.48.44.247 (139.48.44.247)
Jun 29 14:06:54 sfmrc51 afpd[60761]: AFP statistics: 0.51 KB read, 0.38 KB written
Jun 29 14:06:58 sfmrc51 afpd[60762]: Login by igaxiola (AFP3.4)
Jun 29 17:21:57 sfmrc51 afpd[60762]: AFP logout by igaxiola
Jun 29 17:21:57 sfmrc51 afpd[60762]: AFP statistics: 643702.62 KB read, 41965.24 KB written
Jun 29 17:21:57 sfmrc51 afpd[60762]: done
JStop refresh
 
Status
Not open for further replies.

Similar threads

K
  • Locked
Interference between jail and afp share
2 3
Replies
54
Views
7K
Fraoch
Fraoch
T
  • Locked
FreeNAS loses AD connection randomly
Replies
4
Views
2K
toolchain
toolchain
G
Corrupted or truncated afp extended attributes (netatalk)
Replies
7
Views
1K
anodos
anodos
csmac3144
  • Locked
Dropped Connection using AFP
Replies
0
Views
1K
csmac3144
csmac3144
Kevin Horton
  • Locked
Poor performance copying to AFP share with large number of files
Replies
4
Views
1K
Kevin Horton
Kevin Horton
Top