[Openswan Users] xl2tpd fails to negotiate ppp connection when running in daemon mode

[Antoine van Gelder]

Platform:

Linux Router 2.4.27-vrs1 #1 Fri Nov 28 13:22:15 SAST 2008 armv4l unknown


Tested with:

xl2tpd version 1.2.3
xl2tpd version 1.1.12


Symptoms:

* Server sends LCP ConfReq but xl2tpd responds by dropping dead in  
select() with EINR
* Server log also shows pppd startup banner in log which it does not  
do when not daemonized.


Workaround:

* Run xl2tpd with the -D flag.


Relevant Log Output from xl2tpd:

Jan  1 00:09:42 debug xl2tpd[516]: start_pppd: I'm running:
Jan  1 00:09:42 debug xl2tpd[516]: "/sbin/pppd"
Jan  1 00:09:42 debug xl2tpd[516]: "passive"
Jan  1 00:09:42 debug xl2tpd[516]: "-detach"
Jan  1 00:09:42 debug xl2tpd[516]: ":"
Jan  1 00:09:42 debug xl2tpd[516]: "debug"
Jan  1 00:09:42 debug xl2tpd[516]: "file"
Jan  1 00:09:42 debug xl2tpd[516]: "/tmp/ppp/options.l2tpd.client"
Jan  1 00:09:42 debug xl2tpd[516]: "/dev/pts/2"
Jan  1 00:09:42 debug xl2tpd[516]: check_control: control, cid = 0, Ns  
= 2, Nr = 3
Jan  1 00:09:42 debug xl2tpd[516]: handle_packet: control ZLB received
Jan  1 00:09:42 notice pppd[520]: pppd 2.4.2 started by system, uid 0
Jan  1 00:09:42 debug xl2tpd[516]: check_control: control, cid =  
48659, Ns = 2, Nr = 4
Jan  1 00:09:42 debug xl2tpd[516]: handle_packet: control ZLB received
Jan  1 00:09:42 debug xl2tpd[516]: expand_payload: payload, cid =  
48659, Ns = 0, Nr = 0
Jan  1 00:09:42 debug xl2tpd[516]: network_thread: select returned  
error 4 (Interrupted system call)
Jan  1 00:09:42 debug xl2tpd[516]: network_thread: select timeout
Jan  1 00:09:42 debug xl2tpd[516]: trying to send control packet to  
51725
Jan  1 00:09:42 debug xl2tpd[516]: control_xmit: Tossing packet 1
Jan  1 00:09:42 debug xl2tpd[516]: network_thread: select timeout
Jan  1 00:09:42 debug xl2tpd[516]: trying to send control packet to  
51725
Jan  1 00:09:42 debug xl2tpd[516]: control_xmit: Tossing packet 2
Jan  1 00:09:43 debug xl2tpd[516]: network_thread: select timeout
Jan  1 00:09:43 debug xl2tpd[516]: trying to send control packet to  
51725
Jan  1 00:09:43 debug xl2tpd[516]: control_xmit: Tossing packet 3
Jan  1 00:09:45 debug xl2tpd[516]: expand_payload: payload, cid =  
48659, Ns = 1, Nr = 0
Jan  1 00:09:45 debug xl2tpd[516]: expand_payload: Oops, lost a packet  
or two (1).  continuing...
Jan  1 00:09:46 debug xl2tpd[516]: expand_payload: payload, cid =  
48659, Ns = 1, Nr = 0
Jan  1 00:09:46 debug xl2tpd[516]: expand_payload: Oops, lost a packet  
or two (1).  continuing...
.
.
.


Anyone have any ideas?

Thank you.

  - antoine