[plug] afpd going into infinte loop after connect

[Craig Ringer]

Hi folks

I was hoping someone on this list might have run into this before -
because it's causing me major problems right now. 

On our core server, afpd is going into a loop after authenticating the
user and sending them a list of volumes. Strace shows:

select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)
select(0, NULL, NULL, NULL, {0, 25000}) = 0 (Timeout)

The log looks normal:

Mar 02 12:11:47 afpd[11057][afp_config.c:500]: D5:AFPDaemon: Finished
parsing Config File
Mar 02 12:12:50 afpd[11197][dsi_tcp.c:195]: I:Default: ASIP
session:548(5) from 10.0.0.110:49160(4)
Mar 02 12:12:50 afpd[11057][server_child.c:264]: I:Default:
server_child[1] 11197 done
Mar 02 12:12:51 afpd[11198][dsi_tcp.c:195]: I:Default: ASIP
session:548(5) from 10.0.0.110:49161(4)
Mar 02 12:12:51 afpd[11198][uams_guest.c:44]: I:UAMSDaemon: login noauth
Mar 02 12:12:51 afpd[11198][logger.c:409]: I:Logger: doing syslog_setup,
type 0, level 50
Mar 02 12:12:51 afpd[11198][logger.c:434]: D7:Logger: log_file_arr[0]
now contains: {log_filename:, log_file:(nil), log_level: 50}
Mar 02 12:12:51 afpd[11198][logger.c:437]: D5:Logger: syslog_setup[0]
done
Mar 02 12:12:51 afpd[11198][auth.c:168]: I:AFPDaemon: login nobody (uid
99, gid 99) AFP2.2
Mar 02 12:12:51 afpd[11198][volume.c:301]: D5:AFPDaemon: Parsing volset
Old Ads
Mar 02 12:12:51 afpd[11198][volume.c:301]: D5:AFPDaemon: Parsing volset
Old Images
Mar 02 12:12:51 afpd[11198][cnid_open.c:537]: I:Default: CNID DB
initialized using Sleepycat Software: Berkeley DB 4.2.52: (December  3,
2003)
Mar 02 12:12:51 afpd[11198][cnid_open.c:537]: I:Default: CNID DB
initialized using Sleepycat Software: Berkeley DB 4.2.52: (December  3,
2003)

I didn't see any glaring issues in a tcpdump, and the output from the
afpd in debug mode looks reasonable to me:

[root at bucket netatalk]# sbin/afpd -d 2>&1 | tee
/tmp/afpdebug/2afpd_output
command: 18 (AFP_LOGIN)
12 06 41 46 50 32 2E 32 0F 4E 6F 20 55 73 65 72         ..AFP2.2.No User
20 41 75 74 68 65 6E 74                                  Authent        
(end)
reply: 0, 2
(end)
command: 16 (AFP_GETSRVPARAM)
10                                                      .               
(end)
reply: 0, 3
07 D6 C6 43 02 00 0A 4F 6C 64 20 49 6D 61 67 65         ...C...Old Image
73 00 07 4F 6C 64 20 41 64 73                           s..Old Ads      
(end)
command: 24 (AFP_OPENVOL)
18 00 00 20 0A 4F 6C 64 20 49 6D 61 67 65 73            ... .Old Images 
(end)
reply: 0, 4
00 20 01 00                                             . ..            
(end)
command: 34 (AFP_GETFLDRPARAM)
22 00 01 00 00 00 00 02 00 00 10 00 02 00               ".............  
(end)
reply: 0, 5
00 00 10 00 80 00 07 07 07 07                           ..........      
(end)
command: 2 (AFP_CLOSEVOL)
02 00 01 00                                             ....            
(end)
reply: 0, 6
(end)
command: 24 (AFP_OPENVOL)
18 00 00 20 07 4F 6C 64 20 41 64 73                     ... .Old Ads    
(end)
reply: 0, 7
00 20 00 00                                             . ..            
(end)
command: 34 (AFP_GETFLDRPARAM)
22 00 00 00 00 00 00 02 00 00 10 00 02 00               ".............  
(end)
reply: 0, 8
00 00 10 00 80 00 07 07 07 07                           ..........      
(end)

Ideas? This is causing me some major headaches right now...

Craig Ringer