Skip site navigation (1)Skip section navigation (2)
Date:      Tue, 4 Nov 2003 11:26:28 +0200
From:      Vladimir Melnik <melnik@raccoon.kiev.ua>
To:        freebsd-questions@freebsd.org
Subject:   ppp hangs and doesn't finish to return control to mgetty
Message-ID:  <20031104092628.GE16845@tracktor.silver.com.ua>

next in thread | raw e-mail | index | archive | help
Hello.

On my FreeBSD-box (FreeBSD xxxx.xxxx.xxxx 4.8-RELEASE-p13 FreeBSD
4.8-RELEASE-p13 #4: Tue Oct 14 19:19:51 EEST 2003
root@xxxx.xxxx.xxxx:/usr/src/sys/compile/DIALUP1  i386) I has been
confronred with some difficulties.

A ppp process is starting, working, finishing and then hangs, it stays
in memory and doesn't return control to mgetty after connection with
remote peer is lost.

In my log I see such records:

Nov  4 08:02:04 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(187) state = Opened
Nov  4 08:02:04 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(187) state = Opened
Nov  4 08:02:16 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(188) state = Opened
Nov  4 08:02:17 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(188) state = Opened
Nov  4 08:02:29 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(189) state = Opened
Nov  4 08:02:29 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(189) state = Opened
Nov  4 08:02:41 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(190) state = Opened
Nov  4 08:02:41 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(190) state = Opened
Nov  4 08:02:53 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(191) state = Opened
Nov  4 08:02:53 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(191) state = Opened
Nov  4 08:03:05 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(192) state = Opened
Nov  4 08:03:05 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(192) state = Opened
Nov  4 08:03:17 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(193) state = Opened
Nov  4 08:03:17 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(193) state = Opened
Nov  4 08:03:29 xxxx ppp[59631]: tun1: LCP: deflink: SendEchoRequest(194) state = Opened
Nov  4 08:03:29 xxxx ppp[59631]: tun1: LCP: deflink: RecvEchoReply(194) state = Opened
Nov  4 08:03:32 xxxx ppp[59631]: tun1: LCP: deflink: RecvTerminateReq(11) state = Opened
Nov  4 08:03:32 xxxx ppp[59631]: tun1: LCP: deflink: LayerDown
Nov  4 08:03:32 xxxx ppp[59631]: tun1: LCP: deflink: SendTerminateAck(11) state = Opened
Nov  4 08:03:32 xxxx ppp[59631]: tun1: LCP: deflink: State change Opened --> Stopping
Nov  4 08:03:32 xxxx ppp[59631]: tun1: CCP: deflink: State change Stopped --> Closed
Nov  4 08:03:32 xxxx ppp[59631]: tun1: CCP: deflink: State change Closed --> Initial
Nov  4 08:03:32 xxxx ppp[59631]: tun1: Phase: deflink: open -> lcp
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: deflink: LayerDown: xxx.xx.xx0.66
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: deflink: State change Opened --> Starting
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: deflink: LayerFinish.
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: Connect time: 2356 secs: 246763 octets in, 7602421 octets out
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: 5365 packets in, 5387 packets out
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP:  total 3331 bytes/sec, peak 4685 bytes/sec on Tue Nov  4 07:48:48 2003
Nov  4 08:03:32 xxxx ppp[59631]: tun1: IPCP: deflink: State change Starting --> Initial
Nov  4 08:03:32 xxxx ppp[59631]: tun1: Phase: bundle: Terminate
Nov  4 08:03:32 xxxx ppp[59631]: tun1: Phase: Radius(acct): Accounting response received

And then also:

Nov  4 09:29:37 xxxx ppp[59631]: tun1: Warning: Packet too large (4102), discarding.

And after I tried to kill it with SIGTERM, I saw this:

Nov  4 10:39:23 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 10:39:23 xxxx ppp[59631]: tun1: LCP: deflink: State change Stopping --> Closing
Nov  4 11:02:13 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:27 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:28 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:29 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:29 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:29 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.
Nov  4 11:02:30 xxxx ppp[59631]: tun1: Phase: Signal 15, terminate.

SIGHUP is useless too:

Nov  4 11:03:24 xxxx ppp[59631]: tun1: Phase: Signal 1, terminate.
Nov  4 11:03:27 xxxx ppp[59631]: tun1: Phase: Signal 1, terminate.
Nov  4 11:03:28 xxxx ppp[59631]: tun1: Phase: Signal 1, terminate.
Nov  4 11:03:30 xxxx ppp[59631]: tun1: Phase: Signal 1, terminate.

All I can do with it - kill it with SIGKILL.

/etc/ppp/ppp.conf:

default:
        set log Phase Chat LCP IPCP CCP tun command
        set speed 115200
        set dial "ABORT BUSY ABORT NO\\sCARRIER"

dialin:
        disable ipv6cp
        disable chap
        enable  pap
        enable  lqr
        accept  lqr
        set     lqrperiod 6
        set     timeout 600
        set     radius  /etc/ppp/radius.conf
        set     ifaddr xxx.xx.xx0.66 xxx.xx.xx0.128-xxx.xx.xx0.159 255.255.255.255
        accept  dns
        set     dns     xxx.xx.xx0.65 xxx.xx.xx0.2
        !bg     /usr/local/sbin/aon.pl -p PROCESSID

Kernel configuration is:

machine		i386
cpu		I386_CPU
cpu		I486_CPU
cpu		I586_CPU
cpu		I686_CPU
ident		DIALUP1
maxusers	0
options 	MATH_EMULATE
options 	INET
options 	INET6
options 	FFS
options 	FFS_ROOT
options 	SOFTUPDATES
options 	UFS_DIRHASH
options 	MFS
options 	MD_ROOT
options 	NFS
options 	NFS_ROOT
options 	MSDOSFS
options 	CD9660
options 	CD9660_ROOT
options 	PROCFS
options 	COMPAT_43
options 	SCSI_DELAY=15000
options 	UCONSOLE
options 	USERCONFIG
options 	VISUAL_USERCONFIG
options 	KTRACE
options 	SYSVSHM
options 	SYSVMSG
options 	SYSVSEM
options 	P1003_1B
options 	_KPOSIX_PRIORITY_SCHEDULING
options 	ICMP_BANDLIM
options 	KBD_INSTALL_CDEV
options 	AHC_REG_PRETTY_PRINT
options 	AHD_REG_PRETTY_PRINT
device		isa
device		eisa
device		pci
device		fdc0	at isa? port IO_FD1 irq 6 drq 2
device		fd0	at fdc0 drive 0
device		fd1	at fdc0 drive 1
device		ata0	at isa? port IO_WD1 irq 14
device		ata1	at isa? port IO_WD2 irq 15
device		ata
device		atadisk
device		atapicd
device		atapifd
device		atapist
options 	ATA_STATIC_ID
device		atkbdc0	at isa? port IO_KBD
device		atkbd0	at atkbdc? irq 1 flags 0x1
device		psm0	at atkbdc? irq 12
device		vga0	at isa?
pseudo-device	splash
device		sc0	at isa? flags 0x100
device		agp
device		npx0	at nexus? port IO_NPX irq 13
device		apm0	at nexus? disable flags 0x20
device		card
device		pcic0	at isa? irq 0 port 0x3e0 iomem 0xd0000
device		pcic1	at isa? irq 0 port 0x3e2 iomem 0xd4000 disable
options 	COM_MULTIPORT
device		sio0	at isa? port 0x100 irq 3
device		sio1	at isa? port 0x108 irq 3
device		sio2	at isa? port 0x110 irq 3
device		sio3	at isa? port 0x118 irq 3
device		sio4	at isa? port 0x120 irq 3
device		sio5	at isa? port 0x128 irq 3
device		sio6	at isa? port 0x130 irq 3
device		sio7	at isa? port 0x138 irq 3
device		sio8	at isa? port 0x240 irq 3
device		sio9	at isa? port 0x248 irq 3
device		sio10	at isa? port 0x250 irq 3
device		sio11	at isa? port 0x258 irq 3
device		sio12	at isa? port 0x260 irq 3
device		sio13	at isa? port 0x268 irq 3
device		sio14	at isa? port 0x270 irq 3
device		sio15	at isa? port 0x278 irq 3
device		sio16	at isa? port 0x180 irq 4
device		sio17	at isa? port 0x188 irq 4
device		sio18	at isa? port 0x190 irq 4
device		sio19	at isa? port 0x198 irq 4
device		sio20	at isa? port 0x1a0 irq 4
device		sio21	at isa? port 0x1a8 irq 4
device		sio22	at isa? port 0x1b0 irq 4
device		sio23	at isa? port 0x1b8 irq 4
device		sio24	at isa? port 0x2c0 irq 4
device		sio25	at isa? port 0x2c8 irq 4
device		sio26	at isa? port 0x2d0 irq 4
device		sio27	at isa? port 0x2d8 irq 4
device		sio28	at isa? port 0x2e0 irq 4
device		sio29	at isa? port 0x2e8 irq 4
device		sio30	at isa? port 0x2f0 irq 4
device		sio31	at isa? port 0x2f8 irq 4
device		ppc0	at isa? irq 7
device		ppbus
device		lpt
device		plip
device		ppi
device		de
device		em
device		txp
device		vx
device		miibus
device		dc
device		fxp
device		pcn
device		rl
device		sf
device		sis
device		ste
device		tl
device		tx
device		vr
device		wb
device		xl
device		bge
device		ed0	at isa? disable port 0x280 irq 10 iomem 0xd8000
device		ex
device		ep
device		fe0	at isa? disable port 0x300
device		xe
device		awi
device		wi
device		an
device		ie0	at isa? disable port 0x300 irq 10 iomem 0xd0000
device		lnc0	at isa? disable port 0x280 irq 10 drq 0
device		cs0	at isa? disable port 0x300
device		sn0	at isa? disable port 0x300 irq 10
pseudo-device	loop
pseudo-device	ether
pseudo-device	sl	1
pseudo-device	ppp	1
pseudo-device	tun
pseudo-device	pty
pseudo-device	md
pseudo-device	gif
pseudo-device	faith	1
pseudo-device	bpf

-- 
V.Melnik



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20031104092628.GE16845>