Netatalk

Summary:

Netatalk seems to be running, but still cannot connect from my Macbook

Netatalk

matzrh 29 Dec 2011 00:50

Hi,

I installed the netatalk 2.2 (normally on white ring) according to this tutorial : netatalk2-2-on-whitelight
the ipkg took a package apparently working for blue rings. After a second trial, I can start afpd
afpd -V gives me the following:

afpd 2.2.0 - Apple Filing Protocol (AFP) daemon of Netatalk

This program is free software; you can redistribute it and/or modify it under
the terms of the GNU General Public License as published by the Free Software
Foundation; either version 2 of the License, or (at your option) any later
version. Please see the file COPYING for further information and details.

afpd has been compiled with support for these features:

        AFP3.x support:    Yes
        TCP/IP Support:    Yes
DDP(AppleTalk) Support:    Yes
         CNID backends:    dbd last tdb 
           SLP support:    No
      Zeroconf support:    No
  TCP wrappers support:    No
         Quota support:    No
   Admin group support:    Yes
    Valid shell checks:    Yes
      cracklib support:    No
        Dropbox kludge:    No
  Force volume uid/gid:    No
           ACL support:    No
            EA support:    ad | sys
          LDAP support:    No

             afpd.conf:    /opt/etc/netatalk/afpd.conf
   AppleVolumes.system:    /opt/etc/netatalk/AppleVolumes.system
  AppleVolumes.default:    /opt/etc/netatalk/AppleVolumes.default
    afp_signature.conf:    /opt/etc/netatalk/afp_signature.conf
      afp_voluuid.conf:    /opt/etc/netatalk/afp_voluuid.conf
         afp_ldap.conf:    not supported
       UAM search path:    /opt/etc/netatalk/uams/
  Server messages path:    /opt/etc/netatalk/msg/
              lockfile:    /var/lock/afpd

When I now try to connect to my shares, it kind of tries to log in, but breaks after some time.
Kernel Logs on Mac:

Dec 29 01:23:57 unknown001b63a7283b kernel[0]: ASP_TCP CheckReqQueueSize: increasing req queue from 32 to 128 entries. so 0xffffff80091f8470 
Dec 29 01:25:37 unknown001b63a7283b kernel[0]: ASP_TCP CancelOneRequest: cancelling slot 3 error 89 reqID 8 flags 0x9 afpCmd 0x14 so 0xffffff80091f8470
Dec 29 01:25:37 unknown001b63a7283b kernel[0]: ASP_TCP do_thread_read: no reqInfo found for reqID 3

logs on Mybook:

Dec 29 02:23:49.554676 afpd[1232] {dsi_tcp.c:212} (I:DSI): AFP/TCP session from 192.168.1.41:51932
Dec 29 02:23:49.563844 afpd[1229] {main.c:185} (I:AFPDaemon): child[1232]: done
Dec 29 02:23:49.567034 afpd[1229] {main.c:183} (I:AFPDaemon): child[1231]: exited 1
Dec 29 02:23:56.407301 afpd[1233] {dsi_tcp.c:212} (I:DSI): AFP/TCP session from 192.168.1.41:51933
Dec 29 02:23:56.416892 afpd[1233] {uams_dhx2_passwd.c:275} (I:UAMS): DHX2 login: MHOELLING
Dec 29 02:25:37.073499 afpd[1233] {afp_dsi.c:99} (N:AFPDaemon): AFP statistics: 0.56 KB read, 0.41 KB written
Dec 29 02:25:37.074866 afpd[1233] {dircache.c:615} (I:AFPDaemon): dircache statistics: entries: 0, lookups: 0, hits: 0, misses: 0, added: 0, removed: 0, expunged: 0, evicted: 0
Dec 29 02:25:37.081047 afpd[1229] {main.c:185} (I:AFPDaemon): child[1233]: done

(there is an hour difference in system times due to time zones)

The lines in the Applevolumes.default are:

# ~
/shares/internal/MEDIEN allow:MHOELLING options:usedots,upriv
/shares/internal/BACKUP allow:MHOELLING options:usedots,upriv,tm
# End of File

I tried it also without the allow: parameters.

Anything obviously wrong or any idea? Would be great

Re: Netatalk, still not working

matzrh 01 Jan 2012 20:51

No one has any idea?
If someone has an insight that for some reason netatalk is not possible on blue-ring MyBook World it would be appreciated, too, but it seems so close as the afpd daemon is running and reacting on my login attempts from the Macbook.

I include a more detailed log. Maybe someone has a clue what the messages mean
Kind of desperate, so any clue would be greatly appreciated.

Jan 01 21:39:28.632855 afpd[18492] {afp_dsi.c:586} (D5:AFPDaemon): DSI request ID: 2
Jan 01 21:39:28.633714 afpd[18492] {afp_dsi.c:601} (D5:AFPDaemon): <== Start AFP command: AFP_LOGIN_EXT
Jan 01 21:39:28.636590 afpd[18492] {uams_dhx2_passwd.c:275} (I:UAMS): DHX2 login: MHOELLING
Jan 01 21:40:28.634084 afpd[18492] {afp_dsi.c:338} (D5:AFPDaemon): afp_alarm: sending DSI tickle
Jan 01 21:40:58.637662 afpd[18492] {afp_dsi.c:338} (D5:AFPDaemon): afp_alarm: sending DSI tickle
Jan 01 21:41:06.444359 afpd[18492] {afp_dsi.c:608} (D5:AFPDaemon): ==> Finished AFP command: AFP_LOGIN_EXT -> AFPERR_AUTHCONT
Jan 01 21:41:06.445853 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 0
Jan 01 21:41:06.447039 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 16, unread:32 , space left: 3646032): returning 16
Jan 01 21:41:06.447779 afpd[18492] {afp_dsi.c:566} (D5:AFPDaemon): DSI: client tickle
Jan 01 21:41:06.448690 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 32, unread:16 , space left: 3646032): returning 16
Jan 01 21:41:06.449438 afpd[18492] {afp_dsi.c:566} (D5:AFPDaemon): DSI: client tickle
Jan 01 21:41:06.450245 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 16
Jan 01 21:41:06.450946 afpd[18492] {afp_dsi.c:566} (D5:AFPDaemon): DSI: client tickle
Jan 01 21:41:06.451755 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 0
Jan 01 21:41:06.452587 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 0
Jan 01 21:41:06.469260 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 0
Jan 01 21:41:06.470181 afpd[18492] {afp_dsi.c:586} (D5:AFPDaemon): DSI request ID: 6
Jan 01 21:41:06.470916 afpd[18492] {afp_dsi.c:601} (D5:AFPDaemon): <== Start AFP command: AFP_LOGINCONT
Jan 01 21:41:07.160256 afpd[18492] {afp_dsi.c:608} (D5:AFPDaemon): ==> Finished AFP command: AFP_LOGINCONT -> AFPERR_AUTHCONT
Jan 01 21:41:07.161510 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 0
Jan 01 21:41:07.162933 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 16, unread:276 , space left: 3645788): returning 16
Jan 01 21:41:07.163756 afpd[18492] {dsi_stream.c:150} (D5:DSI): from_buf(read: 0, unread:0 , space left: 3646080): returning 276
Jan 01 21:41:07.164500 afpd[18492] {afp_dsi.c:586} (D5:AFPDaemon): DSI request ID: 7
Jan 01 21:41:07.165234 afpd[18492] {afp_dsi.c:601} (D5:AFPDaemon): <== Start AFP command: AFP_LOGINCONT
Jan 01 21:41:07.179744 afpd[18492] {afp_dsi.c:99} (N:AFPDaemon): AFP statistics: 0.56 KB read, 0.41 KB written
Jan 01 21:41:07.180841 afpd[18492] {dircache.c:615} (I:AFPDaemon): dircache statistics: entries: 0, lookups: 0, hits: 0, misses: 0, added: 0, removed: 0, expunged: 0, evicted: 0
Jan 01 21:41:07.187359 afpd[18408] {main.c:185} (I:AFPDaemon): child[18492]: done

Re: Netatalk, one step further... :)

matzrh 03 Jan 2012 18:40

afpd does not seem to read /etc/shadow, but expects the password to be in /etc/passwd.
When I copied the password hash for the user mhoelling to /etc/passwd, I could log in :)
Still not ideal and I will try to fix it, as there is a reason for the password hashes being in /etc/shadow, after all….
Now I still have errors on corrupted CNID DBs. But I hope to be able to sort that out. There is some information around from people having had similar problems…