Attachment 5802 Details for Bug 6522 – Config and Log from Lenny and Squeeze

Config and Log from Lenny and Squeeze

pptpd-lenny-squeeze.log (text/x-log), 13.99 KB, created by Robert LeBlanc on 2010-06-21 10:06:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Robert LeBlanc

Created: 2010-06-21 10:06:44 UTC

Size: 13.99 KB

patch

obsolete

>Debian Lenny
>Linux lsweb 2.6.26-2-amd64 #1 SMP Wed May 12 18:03:14 UTC 2010 x86_64 GNU/Linux
>pptpd v1.3.4
>pppd version 2.4.4
># winbindd -V
>Version 3.4.8
>
>##/etc/pptpd.conf
>option /etc/ppp/pptpd-options
>logwtmp
>localip 192.168.51.1
>remoteip 192.168.51.240-250
>
>##/etc/ppp/pptpd-options
>name pptpd
>refuse-pap
>refuse-chap
>refuse-mschap
>require-mschap-v2
>require-mppe-128
>proxyarp
>nodefaultroute
>lock
>nobsdcomp 
>plugin winbind.so
>ntlm_auth-helper "/usr/bin/ntlm_auth --helper-protocol=ntlm-server-1 --require-membership-of='BYU+LFSCI-VPN-Web'"
>dump debug
>
>##/var/log/syslog
>Jun 21 08:38:02 lenny pptpd[11299]: CTRL: Client 10.5.13.41 control connection started
>Jun 21 08:38:02 lenny pptpd[11299]: CTRL: Starting call (launching pppd, opening GRE)
>Jun 21 08:38:02 lenny pppd[11300]: Plugin winbind.so loaded.
>Jun 21 08:38:02 lenny pppd[11300]: WINBIND plugin initialized.
>Jun 21 08:38:02 lenny pppd[11300]: Plugin /usr/lib/pptpd/pptpd-logwtmp.so loaded.
>Jun 21 08:38:02 lenny pppd[11300]: pptpd-logwtmp: $Version$
>Jun 21 08:38:02 lenny pppd[11300]: pppd options in effect:
>Jun 21 08:38:02 lenny pppd[11300]: debug#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: dump#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: plugin winbind.so#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: plugin /usr/lib/pptpd/pptpd-logwtmp.so#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: require-mschap-v2#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: refuse-pap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: refuse-chap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: refuse-mschap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: name pptpd#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: pptpd-original-ip 10.5.13.41#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: ntlm_auth-helper xxx # [don't know how to print value]#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: 115200#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: lock#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: crtscts#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: local#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: asyncmap 0#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: lcp-echo-failure 4#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: lcp-echo-interval 30#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: hide-password#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: ipparam 10.5.13.41#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: nodefaultroute#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: proxyarp#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: 192.168.51.1:192.168.51.240#011#011# (from command line)
>Jun 21 08:38:02 lenny pppd[11300]: nobsdcomp#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: require-mppe-128#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:38:02 lenny pppd[11300]: noipx#011#011# (from /etc/ppp/options)
>Jun 21 08:38:02 lenny pppd[11300]: pppd 2.4.4 started by root, uid 0
>Jun 21 08:38:02 lenny pppd[11300]: using channel 12
>Jun 21 08:38:02 lenny pppd[11300]: Using interface ppp4
>Jun 21 08:38:02 lenny pppd[11300]: Connect: ppp4 <--> /dev/pts/5
>Jun 21 08:38:02 lenny pppd[11300]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0x887ad1c2> <pcomp> <accomp>]
>Jun 21 08:38:02 lenny pptpd[11299]: GRE: Bad checksum from pppd.
>Jun 21 08:38:02 lenny pppd[11300]: rcvd [LCP ConfReq id=0x0 <mru 1400> <magic 0x3ecb3066> <pcomp> <accomp> <callback CBCP>]
>Jun 21 08:38:02 lenny pppd[11300]: sent [LCP ConfRej id=0x0 <callback CBCP>]
>Jun 21 08:38:02 lenny pppd[11300]: rcvd [LCP ConfReq id=0x1 <mru 1400> <magic 0x3ecb3066> <pcomp> <accomp>]
>Jun 21 08:38:02 lenny pppd[11300]: sent [LCP ConfAck id=0x1 <mru 1400> <magic 0x3ecb3066> <pcomp> <accomp>]
>Jun 21 08:38:05 lenny pppd[11300]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0x887ad1c2> <pcomp> <accomp>]
>Jun 21 08:38:05 lenny pptpd[11299]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0x887ad1c2> <pcomp> <accomp>]
>Jun 21 08:38:05 lenny pppd[11300]: sent [LCP EchoReq id=0x0 magic=0x887ad1c2]
>Jun 21 08:38:05 lenny pppd[11300]: sent [CHAP Challenge id=0x58 <34b6faef6ba23bcbeeaa7c8020ae144c>, name = "pptpd"]
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [LCP Ident id=0x2 magic=0x3ecb3066 "MSRASV5.20"]
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [LCP Ident id=0x3 magic=0x3ecb3066 "MSRAS-0-LEBLANC-WIN7"]
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [LCP Ident id=0x4 magic=0x3ecb3066 "\37777777637z\37777777660\37777777771d,[K\37777777641\37777777711\033L\37777777761\37777777760\37777777642\37777777674"]
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [LCP EchoRep id=0x0 magic=0x3ecb3066]
>Jun 21 08:38:05 lenny pppd[11300]: rcvd [CHAP Response id=0x58 <03c1840dd1caa0d9b21bf56f83abf010000000000000000092c5b8dbebdf354d0b5b38fdcc767bb79b546aee86968c6d00>, name = "DOMAIN\\user1"]
>Jun 21 08:38:06 lenny pppd[11300]: sent [CHAP Success id=0x58 "S=A8BC6692AE216B97746EF5F2BECE7E8DBAB3BBE8 M=Access granted"]
>Jun 21 08:38:06 lenny pppd[11300]: sent [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [IPV6CP ConfReq id=0x5 <addr fe80::5ccc:dec9:985d:8140>]
>Jun 21 08:38:06 lenny pppd[11300]: Unsupported protocol 'IPv6 Control Protovol' (0x8057) received
>Jun 21 08:38:06 lenny pppd[11300]: sent [LCP ProtRej id=0x2 80 57 01 05 00 0e 01 0a 5c cc de c9 98 5d 81 40]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [CCP ConfReq id=0x6 <mppe +H -M +S -L -D +C>]
>Jun 21 08:38:06 lenny pppd[11300]: sent [CCP ConfNak id=0x6 <mppe +H -M +S -L -D -C>]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [IPCP ConfReq id=0x7 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-wins 0.0.0.0> <ms-dns3 0.0.0.0> <ms-wins 0.0.0.0>]
>Jun 21 08:38:06 lenny pppd[11300]: sent [IPCP TermAck id=0x7]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [CCP ConfAck id=0x1 <mppe +H -M +S -L -D -C>]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [CCP ConfReq id=0x8 <mppe +H -M +S -L -D -C>]
>Jun 21 08:38:06 lenny pppd[11300]: sent [CCP ConfAck id=0x8 <mppe +H -M +S -L -D -C>]
>Jun 21 08:38:06 lenny pppd[11300]: MPPE 128-bit stateless compression enabled
>Jun 21 08:38:06 lenny pppd[11300]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 192.168.51.1>]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
>Jun 21 08:38:06 lenny pppd[11300]: sent [IPCP ConfReq id=0x2 <addr 192.168.51.1>]
>Jun 21 08:38:06 lenny pppd[11300]: rcvd [IPCP ConfAck id=0x2 <addr 192.168.51.1>]
>Jun 21 08:38:08 lenny pppd[11300]: rcvd [IPCP ConfReq id=0x9 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-wins 0.0.0.0> <ms-dns3 0.0.0.0> <ms-wins 0.0.0.0>]
>Jun 21 08:38:08 lenny pppd[11300]: sent [IPCP ConfRej id=0x9 <ms-dns1 0.0.0.0> <ms-wins 0.0.0.0> <ms-dns3 0.0.0.0> <ms-wins 0.0.0.0>]
>Jun 21 08:38:08 lenny pppd[11300]: rcvd [IPCP ConfReq id=0xa <addr 0.0.0.0>]
>Jun 21 08:38:08 lenny pppd[11300]: sent [IPCP ConfNak id=0xa <addr 192.168.51.240>]
>Jun 21 08:38:08 lenny pppd[11300]: rcvd [IPCP ConfReq id=0xb <addr 192.168.51.240>]
>Jun 21 08:38:08 lenny pppd[11300]: sent [IPCP ConfAck id=0xb <addr 192.168.51.240>]
>Jun 21 08:38:08 lenny pppd[11300]: found interface eth1 for proxy arp
>Jun 21 08:38:08 lenny pppd[11300]: local  IP address 192.168.51.1
>Jun 21 08:38:08 lenny pppd[11300]: remote IP address 192.168.51.240
>Jun 21 08:38:08 lenny pppd[11300]: pptpd-logwtmp.so ip-up ppp4 DOMAIN\user1 10.5.13.41
>Jun 21 08:38:08 lenny pppd[11300]: Script /etc/ppp/ip-up started (pid 11305)
>Jun 21 08:38:08 lenny pppd[11300]: Script /etc/ppp/ip-up finished (pid 11305), status = 0x0
>
>Debian Squeeze
>Linux lsweb2 2.6.32-3-amd64 #1 SMP Wed Feb 24 18:07:42 UTC 2010 x86_64 GNU/Linux3
>pptpd v1.3.4
>pppd version 2.4.4
># winbindd -V
>Version 3.4.8
>
>##/etc/pptpd.conf
>option /etc/ppp/pptpd-options
>logwtmp
>localip 192.168.49.1
>remoteip 192.168.49.240-250
>
>##/etc/ppp/pptpd-options
>name lsweb2
>refuse-pap
>refuse-chap
>refuse-mschap
>require-mschap-v2
>require-mppe-128
>proxyarp
>nodefaultroute
>debug
>lock
>nobsdcomp 
>plugin winbind.so
>ntlm_auth-helper "/usr/bin/ntlm_auth --helper-protocol=ntlm_server-1"
>debug dump
>
>
>##/var/log/syslog
>Jun 21 08:36:17 squeeze pptpd[21234]: CTRL: Client 10.5.13.41 control connection started
>Jun 21 08:36:17 squeeze pptpd[21234]: CTRL: Starting call (launching pppd, opening GRE)
>Jun 21 08:36:17 squeeze pppd[21235]: Plugin winbind.so loaded.
>Jun 21 08:36:17 squeeze pppd[21235]: WINBIND plugin initialized.
>Jun 21 08:36:17 squeeze pppd[21235]: Plugin /usr/lib/pptpd/pptpd-logwtmp.so loaded.
>Jun 21 08:36:17 squeeze pppd[21235]: pptpd-logwtmp: $Version$
>Jun 21 08:36:17 squeeze pppd[21235]: pppd options in effect:
>Jun 21 08:36:17 squeeze pppd[21235]: debug debug#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: dump#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: plugin winbind.so#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: plugin /usr/lib/pptpd/pptpd-logwtmp.so#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: require-mschap-v2#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: refuse-pap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: refuse-chap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: refuse-mschap#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: name squeeze#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: pptpd-original-ip 10.5.13.41#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: ntlm_auth-helper xxx # [don't know how to print value]#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: 115200#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: lock#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: crtscts#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: local#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: asyncmap 0#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: lcp-echo-failure 4#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: lcp-echo-interval 30#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: hide-password#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: ipparam 10.5.13.41#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: nodefaultroute#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: proxyarp#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: 192.168.49.1:192.168.49.240#011#011# (from command line)
>Jun 21 08:36:17 squeeze pppd[21235]: nobsdcomp#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: require-mppe-128#011#011# (from /etc/ppp/pptpd-options)
>Jun 21 08:36:17 squeeze pppd[21235]: noipx#011#011# (from /etc/ppp/options)
>Jun 21 08:36:17 squeeze pppd[21235]: pppd 2.4.4 started by root, uid 0
>Jun 21 08:36:17 squeeze kernel: [233729.258472] PPP generic driver version 2.4.2
>Jun 21 08:36:17 squeeze pppd[21235]: using channel 1
>Jun 21 08:36:17 squeeze pppd[21235]: Using interface ppp0
>Jun 21 08:36:17 squeeze pppd[21235]: Connect: ppp0 <--> /dev/pts/1
>Jun 21 08:36:17 squeeze pppd[21235]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0x4bb307af> <pcomp> <accomp>]
>Jun 21 08:36:17 squeeze pptpd[21234]: GRE: Bad checksum from pppd.
>Jun 21 08:36:17 squeeze pppd[21235]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0x4bb307af> <pcomp> <accomp>]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP ConfReq id=0x1 <mru 1400> <magic 0x2661770> <pcomp> <accomp> <callback CBCP>]
>Jun 21 08:36:18 squeeze pppd[21235]: sent [LCP ConfRej id=0x1 <callback CBCP>]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP ConfReq id=0x2 <mru 1400> <magic 0x2661770> <pcomp> <accomp>]
>Jun 21 08:36:18 squeeze pppd[21235]: sent [LCP ConfAck id=0x2 <mru 1400> <magic 0x2661770> <pcomp> <accomp>]
>Jun 21 08:36:18 squeeze pppd[21235]: sent [LCP EchoReq id=0x0 magic=0x4bb307af]
>Jun 21 08:36:18 squeeze pppd[21235]: sent [CHAP Challenge id=0x34 <55da3c5c43494d9bd69329b1b4a16581>, name = "squeeze"]
>Jun 21 08:36:18 squeeze pptpd[21234]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP Ident id=0x3 magic=0x2661770 "MSRASV5.20"]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP Ident id=0x4 magic=0x2661770 "MSRAS-0-LEBLANC-WIN7"]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP Ident id=0x5 magic=0x2661770 "\37777777746\37777777661\377777777247\37777777712T\022F\37777777653SX\37777777624T\37777777713\020\37777777662"]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP EchoRep id=0x0 magic=0x2661770]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [CHAP Response id=0x34 <e34a5857888551b20e1e00db3917158f0000000000000000ac16001c7c03d818fd9dfba2e6e78265ac0888f71205287900>, name = "DOMAIN\\user1"]
>Jun 21 08:36:18 squeeze pppd[21235]: Peer DOMAIN\\user1 failed CHAP authentication
>Jun 21 08:36:18 squeeze pppd[21235]: sent [CHAP Failure id=0x34 "E=691 R=1 C=55da3c5c43494d9bd69329b1b4a16581 V=0 M=Access denied"]
>Jun 21 08:36:18 squeeze pppd[21235]: sent [LCP TermReq id=0x2 "Authentication failed"]
>Jun 21 08:36:18 squeeze pppd[21235]: rcvd [LCP TermAck id=0x2 "Authentication failed"]
>Jun 21 08:36:18 squeeze pppd[21235]: Connection terminated.
>Jun 21 08:36:19 squeeze pppd[21235]: Exit.
>Jun 21 08:36:19 squeeze pptpd[21234]: GRE: read(fd=6,buffer=610a80,len=8196) from PTY failed: status = -1 error = Input/output error, usually caused by unexpected termination of pppd, check option syntax and pppd logs
>Jun 21 08:36:19 squeeze pptpd[21234]: CTRL: PTY read or GRE write failed (pty,gre)=(6,7)
>Jun 21 08:36:19 squeeze pptpd[21234]: CTRL: Reaping child PPP[21235]
>Jun 21 08:36:19 squeeze pptpd[21234]: CTRL: Client 10.5.13.41 control connection finished

Actions: View

Attachments on bug 6522: 5802