upgrade to 9.2.1.3 AFP not working correctly

[labtopia]

i've upgraded and since the upgrade connecting with afp is slow and finder then hangs, cifs is working fine.

i got the cnid error and told it from shell to rebuild the cnid db, that error went away but when i try to connect i get this kind of thing wherever the mac is hung up trying to browse the share- in the console:

Code:

: INTERNAL ERROR: Signal 11 in pid 14879 (3.1.1)
Apr  2 16:29:00 labpod1 afpd[14879]: ===============================================================
Apr  2 16:29:01 labpod1 kernel: pid 14879 (afpd), uid 1001: exited on signal 6
Apr  2 16:29:01 labpod1 afpd[20435]: Login by poduser (AFP3.4)
Apr  2 16:29:01 labpod1 afpd[20435]: afp_disconnect: trying primary reconnect
Apr  2 16:29:01 labpod1 afpd[55498]: Reconnect: no child[14879]
Apr  2 16:29:06 labpod1 afpd[20435]: afp_disconnect: primary reconnect failed
Apr  2 16:29:06 labpod1 afpd[20435]: parse_entries: bogus eid: 9, off: 50, len: 3760
Apr  2 16:29:06 labpod1 afpd[20435]: parse_entries: bogus eid: 2, off: 3810, len: 286
Apr  2 16:29:06 labpod1 afpd[20435]: ad_header_read(/mnt/pod1/LAB_BAK/installers/mac/._aclfix.dmg): malformed AppleDouble
Apr  2 16:29:06 labpod1 afpd[20435]: Convert OS X to Netatalk AppleDouble: /mnt/pod1/LAB_BAK/installers/mac/._aclfix.dmg
Apr  2 16:29:06 labpod1 afpd[20435]: ===============================================================
Apr  2 16:29:06 labpod1 afpd[20435]: INTERNAL ERROR: Signal 11 in pid 20435 (3.1.1)
Apr  2 16:29:06 labpod1 afpd[20435]: ===============================================================

i was set to use unix permissiinos and tried changing to ACL from the gui.

any thoughts on how to get afp working for me again?

 

[cyberjock]

Ok.. hardware? software version? The forum rules list all the stuff we want to see in a post. ;)

 

[gregober]

I have the same problem :Finder freeze while trying to browse the shared directories / sub-directories.

Code:

Apr  3 15:57:25 sand afpd[17898]: Login by xxxxxx (AFP3.4)
Apr  3 15:57:25 sand afpd[17898]: afp_disconnect: trying primary reconnect
Apr  3 15:57:25 sand afpd[11906]: Reconnect: no child[17895]
Apr  3 15:57:30 sand afpd[17898]: afp_disconnect: primary reconnect failed
Apr  3 15:57:30 sand afpd[17898]: parse_entries: bogus eid: 9, off: 50, len: 3760
Apr  3 15:57:30 sand afpd[17898]: parse_entries: bogus eid: 2, off: 3810, len: 286
Apr  3 15:57:30 sand afpd[17898]: ad_header_read(/mnt/tank/Partage/ToDoo/Numerisation/._numérisation.pdf): malformed AppleDouble
Apr  3 15:57:30 sand afpd[17898]: Convert OS X to Netatalk AppleDouble: /mnt/tank/Partage/ToDoo/Numerisation/._numérisation.pdf
Apr  3 15:57:30 sand afpd[17898]: ===============================================================
Apr  3 15:57:30 sand afpd[17898]: INTERNAL ERROR: Signal 11 in pid 17898 (3.1.1)
Apr  3 15:57:30 sand afpd[17898]: ===============================================================
Apr  3 15:57:30 sand kernel: Failed to write core file for process afpd (error 14)
Apr  3 15:57:30 sand kernel: pid 17898 (afpd), uid 1001: exited on signal 6

Hardware is plain Intel MB software is 9.2.1.3

 

[labtopia]

thanks i have:
intel i5 760@2.8ghz
9.2.1.3x64
8gig ram

i've read that netatalk has changed some ways they handle the acl's and ea and appledouble. i've started a cnid db rebuild to see if that clears this up. it looks like netatalk maybe isnt 'upgrading' the appledouble as their docs might refer too, but this is from my lay understanding of what i'm reading.

dave

 

[zoidberg]

I had the same problem as you did. Looking for solutions and found this thread. I tried the cnid db rebuild but that didn't work for me. I did eventually fix my problem by deleting the malformed appledouble file. In your case it's found in the in line:

ad_header_read(/mnt/pod1/LAB_BAK/installers/mac/._aclfix.dmg): malformed AppleDouble

So it would be deleting the ._aclfix.dmg file.

Hope that works for you.

 

[labtopia]

i have been able to resolve this by running this command from a shell on directories the mac wants to browse via afp. deleting all of the ._ seems to have worked. no more crashing afpd, browsing and file transfers are now working afp.

Code:

find . -name '._*' -exec rm '{}' ';'

 

[sirkkalap]

I am now seeing this same problem on 9.2.1.4 (afpd 3.1.1?). The issue looks very similar to my output:

Code:

Apr 26 13:07:26 tiipii afpd[23618]: Login by petes (AFP3.4)
Apr 26 13:07:26 tiipii afpd[23618]: afp_disconnect: trying primary reconnect
Apr 26 13:07:26 tiipii afpd[3174]: Reconnect: no child[23611]
Apr 26 13:07:31 tiipii afpd[23618]: afp_disconnect: primary reconnect failed
Apr 26 13:07:31 tiipii afpd[23618]: parse_entries: bogus eid: 9, off: 50, len: 3760
Apr 26 13:07:31 tiipii afpd[23618]: parse_entries: bogus eid: 2, off: 3810, len: 286
Apr 26 13:07:31 tiipii afpd[23618]: ad_header_read(/mnt/vol1/music/jazz/Wes Montgomery/California Dreaming/._01 California Dreaming.m4a): malformed AppleDouble
Apr 26 13:07:31 tiipii kernel: pid 23618 (afpd), uid 1001: exited on signal 6
Apr 26 13:07:31 tiipii afpd[23618]: Convert OS X to Netatalk AppleDouble: /mnt/vol1/music/jazz/Wes Montgomery/California Dreaming/._01 California Dreaming.m4a
Apr 26 13:07:31 tiipii afpd[23618]: ===============================================================
Apr 26 13:07:31 tiipii afpd[23618]: INTERNAL ERROR: Signal 11 in pid 23618 (3.1.1)
Apr 26 13:07:31 tiipii afpd[23618]: ===============================================================
Apr 26 13:07:31 tiipii afpd[23622]: Login by petes (AFP3.4)
Apr 26 13:07:31 tiipii afpd[23622]: afp_disconnect: trying primary reconnect
Apr 26 13:07:31 tiipii afpd[3174]: Reconnect: no child[23611]
Apr 26 13:07:36 tiipii afpd[23622]: afp_disconnect: primary reconnect failed
Apr 26 13:07:36 tiipii afpd[23622]: parse_entries: bogus eid: 9, off: 50, len: 3760
Apr 26 13:07:36 tiipii afpd[23622]: parse_entries: bogus eid: 2, off: 3810, len: 286
Apr 26 13:07:36 tiipii afpd[23622]: ad_header_read(/mnt/vol1/music/jazz/Wes Montgomery/California Dreaming/._01 California Dreaming.m4a): malformed AppleDouble
Apr 26 13:07:36 tiipii afpd[23622]: Convert OS X to Netatalk AppleDouble: /mnt/vol1/music/jazz/Wes Montgomery/California Dreaming/._01 California Dreaming.m4a
Apr 26 13:07:36 tiipii afpd[23622]: ===============================================================
Apr 26 13:07:36 tiipii kernel: pid 23622 (afpd), uid 1001: exited on signal 6
Apr 26 13:07:36 tiipii afpd[23622]: INTERNAL ERROR: Signal 11 in pid 23622 (3.1.1)
Apr 26 13:07:36 tiipii afpd[23622]: ===============================================================

Deleting the ._ -file for that song did stop the error from repeating over and over again.

-Pete

 

[dlavigne]

Looks like this issue: https://bugs.freenas.org/issues/4667.

 

[sirkkalap]

Looks like this issue: https://bugs.freenas.org/issues/4667.

I have posted a note to 4667 containing the offending malformed AppleDouble file as an attachment https://bugs.freenas.org/issues/4667#note-17
Hopefully someone can reproduce the bug in testing of afpd. I do not have courage myself to rig up afpd development environment and debug this through.

-Pete

 

[sirkkalap]

i have been able to resolve this by running this command from a shell on directories the mac wants to browse via afp. deleting all of the ._ seems to have worked. no more crashing afpd, browsing and file transfers are now working afp.

Code:

find . -name '._*' -exec rm '{}' ';'

I would like to warn people, that this will delete Apple special metadata from those files. So you will likely loose some labels, download origins, custom application bindings, possibly some parsed image data like aperture stop flash and similar values.

-Pete

 

[andyclimb]

I had this issue after copying all my data to a new location. Every time i made a share either by AFP or CIFS when i browsed to a folder in finder i would get the spinning wheel and finder would crash. Deleting all the ._ files solved it instantly.