< TEST_PROGS=pppol2tp.sh TEST_GEN_PROGS="" run_tests
make: Entering directory '/srv/vmksft/testing/wt-5/tools/testing/selftests'
make[1]: Nothing to be done for 'all'.
# timeout set to 45
# selftests: net/ppp: pppol2tp.sh
# xl2tpd[9306]: Not looking for kernel SAref support.
# xl2tpd[9306]: Using l2tp kernel support.
# xl2tpd[9306]: xl2tpd version xl2tpd-1.3.20 started on vmksft-net-extra,debug-threads=on PID:9306
# xl2tpd[9306]: Written by Mark Spencer, Copyright (C) 1998, Adtran, Inc.
# xl2tpd[9306]: Forked by Scott Balmos and David Stipp, (C) 2001
# xl2tpd[9306]: Inherited by Jeff McAdams, (C) 2002
# xl2tpd[9306]: Forked again by Xelerance (www.xelerance.com) (C) 2006-2016
# xl2tpd[9306]: Listening on IP address 172.16.1.1, port 1701
# xl2tpd[9307]: Not looking for kernel SAref support.
# xl2tpd[9307]: Using l2tp kernel support.
# xl2tpd[9307]: xl2tpd version xl2tpd-1.3.20 started on vmksft-net-extra,debug-threads=on PID:9307
# xl2tpd[9307]: Written by Mark Spencer, Copyright (C) 1998, Adtran, Inc.
# xl2tpd[9307]: Forked by Scott Balmos and David Stipp, (C) 2001
# xl2tpd[9307]: Inherited by Jeff McAdams, (C) 2002
# xl2tpd[9307]: Forked again by Xelerance (www.xelerance.com) (C) 2006-2016
# xl2tpd[9307]: Listening on IP address 172.16.1.2, port 1701
# xl2tpd[9307]: Connecting to host 172.16.1.1, port 1701
# xl2tpd[9306]: Connection established to 172.16.1.2, 1701.  Local: 911, Remote: 14979 (ref=0/0).  LNS session is 'default'
# xl2tpd[9307]: Connection established to 172.16.1.1, 1701.  Local: 14979, Remote: 911 (ref=0/0).
# xl2tpd[9307]: Calling on tunnel 14979
# xl2tpd[9307]: Call established with 172.16.1.1, Local: 20608, Remote: 43319, Serial: 1 (ref=0/0)
# xl2tpd[9306]: control_finish: Warning: Peer did not specify transmit speed
# xl2tpd[9307]: start_pppd: I'm running: 
# xl2tpd[9307]: "/usr/sbin/pppd" 
# xl2tpd[9307]: "plugin" 
# xl2tpd[9307]: "pppol2tp.so" 
# xl2tpd[9307]: "pppol2tp" 
# xl2tpd[9307]: "9" 
# xl2tpd[9307]: "passive" 
# xl2tpd[9306]: start_pppd: I'm running: 
# xl2tpd[9307]: "nodetach" 
# xl2tpd[9307]: ":" 
# xl2tpd[9306]: "/usr/sbin/pppd" 
# xl2tpd[9307]: "debug" 
# xl2tpd[9306]: "plugin" 
# xl2tpd[9307]: "file" 
# xl2tpd[9306]: "pppol2tp.so" 
# xl2tpd[9307]: "/srv/vmksft/testing/wt-5/tools/testing/selftests/net/ppp/pppoe-server-options" 
# xl2tpd[9306]: "pppol2tp" 
# xl2tpd[9306]: "9" 
# xl2tpd[9306]: "pppol2tp_lns_mode" 
# xl2tpd[9306]: "pppol2tp_tunnel_id" 
# xl2tpd[9306]: "911" 
# xl2tpd[9306]: "pppol2tp_session_id" 
# xl2tpd[9306]: "43319" 
# xl2tpd[9306]: "passive" 
# xl2tpd[9306]: "nodetach" 
# xl2tpd[9306]: "192.168.200.1:192.168.200.2" 
# xl2tpd[9306]: "debug" 
# xl2tpd[9306]: "file" 
# xl2tpd[9306]: "/srv/vmksft/testing/wt-5/tools/testing/selftests/net/ppp/pppoe-server-options" 
# xl2tpd[9306]: Call established with 172.16.1.2, PID: 9316, Local: 43319, Remote: 20608, Serial: 1
# xl2tpd[9306]: child_handler : pppd exited for call 20608 with code 17
# xl2tpd[9306]: call_close: Call 43319 to 172.16.1.2 disconnected
# xl2tpd[9307]: control_finish: Connection closed to 172.16.1.1, serial 1 ()
# xl2tpd[9307]: Terminating pppd: sending TERM signal to pid 9317
# ping: connect: Network is unreachable
# iperf3: error - unable to connect to server - server may have stopped running or use a different port, firewall issue, etc.: Network is unreachable
# TEST: PPPoL2TP                                                      [FAIL]
# Cannot find device "ppp0"
# ping: connect: Network is unreachable
# TEST: PPPoL2TP Recursion                                            [ OK ]
# <30>May 29 23:11:14 pppd[9316]: Plugin pppol2tp.so loaded.
# <30>May 29 23:11:14 pppd[9317]: Plugin pppol2tp.so loaded.
# <29>May 29 23:11:14 pppd[9317]: pppd 2.5.1 started by root, uid 0
# <29>May 29 23:11:14 pppd[9316]: pppd 2.5.1 started by root, uid 0
# <31>May 29 23:11:14 pppd[9316]: using channel 1
# <31>May 29 23:11:14 pppd[9317]: using channel 1
# <30>May 29 23:11:14 pppd[9317]: Using interface ppp0
# <30>May 29 23:11:14 pppd[9316]: Using interface ppp0
# <29>May 29 23:11:14 pppd[9316]: Connect: ppp0 <-->
# <29>May 29 23:11:14 pppd[9317]: Connect: ppp0 <-->
# <31>May 29 23:11:14 pppd[9317]: PPPoL2TP options: debugmask 0
# <31>May 29 23:11:14 pppd[9316]: PPPoL2TP options: lnsmode tid 911 sid 43319 debugmask 0
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7ef192ae>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7ef192ae>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7ef192ae>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x1 <magic 0x77ef343d>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7ef192ae>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x1 <magic 0x77ef343d>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x1 <magic 0x77ef343d>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x4447d051>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x1 <magic 0x77ef343d>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x4447d051>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x4447d051>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x2 <magic 0x614f14cf>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x4447d051>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x2 <magic 0x614f14cf>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x2 <magic 0x614f14cf>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x9ecf67a9>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x2 <magic 0x614f14cf>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x9ecf67a9>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x9ecf67a9>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x3 <magic 0x1bedc5d1>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x9ecf67a9>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x3 <magic 0x1bedc5d1>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x3 <magic 0x1bedc5d1>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x3 <magic 0x1bedc5d1>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xc5431494>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xc5431494>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xc5431494>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x4 <magic 0xe9f264c>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0xc5431494>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x4 <magic 0xe9f264c>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x4 <magic 0xe9f264c>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x4 <magic 0xe9f264c>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xd4b11783>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xd4b11783>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xd4b11783>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x5 <magic 0x926faa33>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0xd4b11783>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x5 <magic 0x926faa33>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x5 <magic 0x926faa33>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x8f2436a4>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x5 <magic 0x926faa33>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x8f2436a4>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x8f2436a4>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x6 <magic 0x21b1a550>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x8f2436a4>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x6 <magic 0x21b1a550>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x6 <magic 0x21b1a550>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x917ce298>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x6 <magic 0x21b1a550>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x917ce298>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x917ce298>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x7 <magic 0xe2a00e3>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x917ce298>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x7 <magic 0xe2a00e3>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x7 <magic 0xe2a00e3>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x97cc9bae>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x7 <magic 0xe2a00e3>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x97cc9bae>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x97cc9bae>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x8 <magic 0xc82b1acd>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x97cc9bae>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x8 <magic 0xc82b1acd>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x8 <magic 0xc82b1acd>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x8 <magic 0xc82b1acd>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xd0282b6>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xd0282b6>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xd0282b6>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0x9 <magic 0xfaddde40>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0xd0282b6>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0x9 <magic 0xfaddde40>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0x9 <magic 0xfaddde40>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x3af718df>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0x9 <magic 0xfaddde40>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x3af718df>]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x3af718df>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x3af718df>]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP ConfNak id=0xa <magic 0x9f9ff94f>]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP ConfNak id=0xa <magic 0x9f9ff94f>]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP ConfNak id=0xa <magic 0x9f9ff94f>]
# <29>May 29 23:11:14 pppd[9316]: Serial line is looped back.
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP ConfNak id=0xa <magic 0x9f9ff94f>]
# <29>May 29 23:11:14 pppd[9317]: Serial line is looped back.
# <31>May 29 23:11:14 pppd[9317]: sent [LCP TermReq id=0xb "Loopback detected"]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP TermReq id=0xb "Loopback detected"]
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP TermReq id=0xb "Loopback detected"]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP TermReq id=0xb "Loopback detected"]
# <31>May 29 23:11:14 pppd[9316]: sent [LCP TermAck id=0xb]
# <31>May 29 23:11:14 pppd[9317]: sent [LCP TermAck id=0xb]
# <31>May 29 23:11:14 pppd[9316]: rcvd [LCP TermAck id=0xb]
# <29>May 29 23:11:14 pppd[9316]: Connection terminated.
# <31>May 29 23:11:14 pppd[9317]: rcvd [LCP TermAck id=0xb]
# <29>May 29 23:11:14 pppd[9317]: Connection terminated.
# <30>May 29 23:11:14 pppd[9316]: Exit.
# <30>May 29 23:11:14 pppd[9317]: Exit.
# xl2tpd[9306]: death_handler: Fatal signal 15 received
# xl2tpd[9306]: Connection 14979 closed to 172.16.1.2, port 1701 (Server closing)
# xl2tpd[9307]: control_finish: Connection closed to 172.16.1.1, port 1701 (Server closing), Local: 14979, Remote: 911
# xl2tpd[9307]: udp_xmit failed to 172.16.1.1:1701 with err=-1:Network is unreachable
# xl2tpd[9307]: death_handler: Fatal signal 15 received
not ok 1 selftests: net/ppp: pppol2tp.sh # exit=1
make: Leaving directory '/srv/vmksft/testing/wt-5/tools/testing/selftests'
xx__-> echo $?
0
xx__->