Проблемы с PPPoE. PfSense 2.0.1



  • Доброго времени суток!

    Имеем: Железку Supermicro сVMware 5.0 на которой крутится PfSense 2.0.1-RELEASE (i386) built on Mon Dec 12 17:53:52 EST 2011 в качестве корпоративного шлюза.
    В последнее время появились проблемы с подключением по PPPoE. Вот лог:

    SYSTEM:

    Dec 23 20:54:24	apinger: alarm canceled: WAN(83.221.214.195) *** loss ***
    Dec 23 20:54:34	check_reload_status: Reloading filter
    Dec 23 20:56:31	apinger: ALARM: WAN(83.221.214.195) *** loss ***
    Dec 23 20:56:41	check_reload_status: Reloading filter
    Dec 23 20:58:37	apinger: alarm canceled: WAN(83.221.214.195) *** loss ***
    Dec 23 20:58:47	check_reload_status: Reloading filter
    Dec 23 21:09:25	check_reload_status: Rewriting resolv.conf
    Dec 23 21:09:34	apinger: ALARM: WAN(83.221.214.195) *** down ***
    Dec 23 21:09:44	check_reload_status: Reloading filter
    Dec 23 21:09:47	dnsmasq[54234]: reading /etc/resolv.conf
    Dec 23 21:09:47	dnsmasq[54234]: using nameserver 80.254.111.13#53
    Dec 23 21:09:47	dnsmasq[54234]: using nameserver 83.221.202.254#53
    Dec 23 21:09:47	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 23 21:09:47	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:00:00	check_reload_status: Configuring interface wan
    Dec 24 00:00:11	check_reload_status: Rewriting resolv.conf
    Dec 24 00:00:12	apinger: alarm canceled: WAN(83.221.214.195) *** down ***
    Dec 24 00:00:18	dnsmasq[54234]: reading /etc/resolv.conf
    Dec 24 00:00:18	dnsmasq[54234]: using nameserver 80.254.111.13#53
    Dec 24 00:00:18	dnsmasq[54234]: using nameserver 83.221.202.254#53
    Dec 24 00:00:18	dnsmasq[54234]: using nameserver 80.254.108.206#53
    Dec 24 00:00:18	dnsmasq[54234]: using nameserver 80.254.108.198#53
    Dec 24 00:00:18	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:00:18	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:00:18	php: : ROUTING: setting default route to 83.221.214.195
    Dec 24 00:00:18	check_reload_status: Reloading filter
    Dec 24 00:00:18	apinger: Exiting on signal 15.
    Dec 24 00:00:19	check_reload_status: Reloading filter
    Dec 24 00:00:20	dnsmasq[54234]: reading /etc/resolv.conf
    Dec 24 00:00:20	dnsmasq[54234]: using nameserver 80.254.111.13#53
    Dec 24 00:00:20	dnsmasq[54234]: using nameserver 83.221.202.254#53
    Dec 24 00:00:20	dnsmasq[54234]: using nameserver 80.254.108.206#53
    Dec 24 00:00:20	dnsmasq[54234]: using nameserver 80.254.108.198#53
    Dec 24 00:00:20	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:00:20	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:00:20	apinger: Starting Alarm Pinger, apinger(44483)
    Dec 24 00:00:24	php: : Resyncing OpenVPN instances for interface WAN.
    Dec 24 00:30:02	check_reload_status: Rewriting resolv.conf
    Dec 24 00:30:10	dnsmasq[54234]: reading /etc/resolv.conf
    Dec 24 00:30:10	dnsmasq[54234]: using nameserver 80.254.111.13#53
    Dec 24 00:30:10	dnsmasq[54234]: using nameserver 83.221.202.254#53
    Dec 24 00:30:10	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:30:10	dnsmasq[54234]: ignoring nameserver 127.0.0.1 - local interface
    Dec 24 00:30:12	apinger: ALARM: WAN(83.221.214.195) *** down ***
    Dec 24 00:30:22	check_reload_status: Reloading filter
    

    PPP:

    Dec 23 21:03:06	ppp: [wan_link0] rec'd unexpected protocol 0x0221, rejecting
    Dec 23 21:03:06	ppp: [wan_link0] rec'd unexpected protocol Padding Protocol, rejecting
    Dec 23 21:05:51	ppp: [wan_link0] rec'd unexpected protocol 0x1021, rejecting
    Dec 23 21:05:51	ppp: [wan] error writing len 1502 frame to b0: Message too long
    Dec 23 21:09:25	ppp: [wan] IPCP: unknown code 69
    Dec 23 21:09:25	ppp: [wan] IPCP: rec'd Terminate Request #206 (Opened)
    Dec 23 21:09:25	ppp: [wan] IPCP: state change Opened --> Stopping
    Dec 23 21:09:25	ppp: [wan] IPCP: SendTerminateAck #3
    Dec 23 21:09:25	ppp: [wan] IPCP: LayerDown
    Dec 23 21:09:25	ppp: [wan] IFACE: Down event
    Dec 23 21:09:27	ppp: [wan] IPCP: state change Stopping --> Stopped
    Dec 23 21:09:27	ppp: [wan] IPCP: LayerFinish
    Dec 23 21:09:27	ppp: [wan] Bundle: No NCPs left. Closing links...
    Dec 23 21:09:27	ppp: [wan] Bundle: closing link "wan_link0"...
    Dec 23 21:09:27	ppp: [wan_link0] Link: CLOSE event
    Dec 23 21:09:27	ppp: [wan_link0] LCP: Close event
    Dec 23 21:09:27	ppp: [wan_link0] LCP: state change Opened --> Closing
    Dec 23 21:09:27	ppp: [wan_link0] Link: Leave bundle "wan"
    Dec 23 21:09:27	ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    Dec 23 21:09:27	ppp: [wan] IPCP: Close event
    Dec 23 21:09:27	ppp: [wan] IPCP: state change Stopped --> Closed
    Dec 23 21:09:27	ppp: [wan] IPCP: Down event
    Dec 23 21:09:27	ppp: [wan] IPCP: state change Closed --> Initial
    Dec 23 21:09:27	ppp: [wan_link0] LCP: SendTerminateReq #2
    Dec 23 21:09:27	ppp: [wan_link0] LCP: LayerDown
    Dec 23 21:09:27	ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing)
    Dec 23 21:09:27	ppp: [wan_link0] LCP: state change Closing --> Closed
    Dec 23 21:09:27	ppp: [wan_link0] LCP: LayerFinish
    Dec 23 21:09:27	ppp: [wan_link0] Link: DOWN event
    Dec 23 21:09:27	ppp: [wan_link0] LCP: Down event
    Dec 23 21:09:27	ppp: [wan_link0] LCP: state change Closed --> Initial
    Dec 24 00:00:07	ppp: Multi-link PPP daemon for FreeBSD
    Dec 24 00:00:07	ppp:
    Dec 24 00:00:07	ppp: process 33610 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-snaps.pfsense.org 10:25 12-Oct-2011)
    Dec 24 00:00:07	ppp: caught fatal signal term
    Dec 24 00:00:07	ppp: [wan] IFACE: Close event
    Dec 24 00:00:07	ppp: [wan] IPCP: Close event
    Dec 24 00:00:07	ppp: waiting for process 46345 to die...
    Dec 24 00:00:09	ppp: [wan] Bundle: Shutdown
    Dec 24 00:00:09	ppp: [wan_link0] Link: Shutdown
    Dec 24 00:00:09	ppp: process 46345 terminated
    Dec 24 00:00:09	ppp: waiting for process 46345 to die...
    Dec 24 00:00:09	ppp: web: web is not running
    Dec 24 00:00:09	ppp: [wan] Bundle: Interface ng0 created
    Dec 24 00:00:09	ppp: [wan_link0] Link: OPEN event
    Dec 24 00:00:09	ppp: [wan_link0] LCP: Open event
    Dec 24 00:00:09	ppp: [wan_link0] LCP: state change Initial --> Starting
    Dec 24 00:00:09	ppp: [wan_link0] LCP: LayerStart
    Dec 24 00:00:09	ppp: [wan_link0] PPPoE: Connecting to 'inet'
    Dec 24 00:00:09	ppp: PPPoE: rec'd ACNAME "RST-E320-2"
    Dec 24 00:00:09	ppp: [wan_link0] PPPoE: connection successful
    Dec 24 00:00:09	ppp: [wan_link0] Link: UP event
    Dec 24 00:00:09	ppp: [wan_link0] LCP: Up event
    Dec 24 00:00:09	ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    Dec 24 00:00:09	ppp: [wan_link0] LCP: SendConfigReq #1
    Dec 24 00:00:09	ppp: [wan_link0] PROTOCOMP
    Dec 24 00:00:10	ppp: [wan_link0] MRU 1492
    Dec 24 00:00:10	ppp: [wan_link0] MAGICNUM 74b4aa82
    Dec 24 00:00:10	ppp: [wan_link0] LCP: rec'd Configure Request #98 (Req-Sent)
    Dec 24 00:00:10	ppp: [wan_link0] MRU 1492
    Dec 24 00:00:10	ppp: [wan_link0] AUTHPROTO PAP
    Dec 24 00:00:10	ppp: [wan_link0] MAGICNUM 79342bda
    Dec 24 00:00:10	ppp: [wan_link0] LCP: SendConfigAck #98
    Dec 24 00:00:10	ppp: [wan_link0] MRU 1492
    Dec 24 00:00:10	ppp: [wan_link0] AUTHPROTO PAP
    Dec 24 00:00:10	ppp: [wan_link0] MAGICNUM 79342bda
    Dec 24 00:00:10	ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
    Dec 24 00:00:10	ppp: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
    Dec 24 00:00:10	ppp: [wan_link0] PROTOCOMP
    Dec 24 00:00:10	ppp: [wan_link0] MRU 1492
    Dec 24 00:00:10	ppp: [wan_link0] MAGICNUM 74b4aa82
    Dec 24 00:00:10	ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
    Dec 24 00:00:10	ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
    Dec 24 00:00:10	ppp: [wan_link0] PAP: using authname "ИМЯ ПОЛЬЗОВАТЕЛЯ"
    Dec 24 00:00:10	ppp: [wan_link0] PAP: sending REQUEST #1 len: 24
    Dec 24 00:00:10	ppp: [wan_link0] LCP: LayerUp
    Dec 24 00:00:10	ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
    Dec 24 00:00:10	ppp: [wan_link0] LCP: authorization successful
    Dec 24 00:00:10	ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
    Dec 24 00:00:10	ppp: [wan_link0] Link: Join bundle "wan"
    Dec 24 00:00:10	ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
    Dec 24 00:00:10	ppp: [wan] IPCP: Open event
    Dec 24 00:00:10	ppp: [wan] IPCP: state change Initial --> Starting
    Dec 24 00:00:10	ppp: [wan] IPCP: LayerStart
    Dec 24 00:00:10	ppp: [wan] IPCP: Up event
    Dec 24 00:00:10	ppp: [wan] IPCP: state change Starting --> Req-Sent
    Dec 24 00:00:10	ppp: [wan] IPCP: SendConfigReq #1
    Dec 24 00:00:10	ppp: [wan] IPADDR 0.0.0.0
    Dec 24 00:00:10	ppp: [wan] PRIDNS 0.0.0.0
    Dec 24 00:00:10	ppp: [wan] SECDNS 0.0.0.0
    Dec 24 00:00:10	ppp: [wan] IPCP: rec'd Configure Nak #1 (Req-Sent)
    Dec 24 00:00:10	ppp: [wan] IPADDR НАШ IP
    Dec 24 00:00:10	ppp: [wan] НАШ IP is OK
    Dec 24 00:00:10	ppp: [wan] PRIDNS 80.254.108.198
    Dec 24 00:00:10	ppp: [wan] SECDNS 80.254.108.206
    Dec 24 00:00:10	ppp: [wan] IPCP: SendConfigReq #2
    Dec 24 00:00:10	ppp: [wan] IPADDR НАШ IP
    Dec 24 00:00:10	ppp: [wan] PRIDNS 80.254.108.198
    Dec 24 00:00:10	ppp: [wan] SECDNS 80.254.108.206
    Dec 24 00:00:10	ppp: [wan] IPCP: rec'd Configure Ack #2 (Req-Sent)
    Dec 24 00:00:10	ppp: [wan] IPADDR НАШ IP
    Dec 24 00:00:10	ppp: [wan] PRIDNS 80.254.108.198
    Dec 24 00:00:10	ppp: [wan] SECDNS 80.254.108.206
    Dec 24 00:00:10	ppp: [wan] IPCP: state change Req-Sent --> Ack-Rcvd
    Dec 24 00:00:11	ppp: [wan] IPCP: rec'd Configure Request #220 (Ack-Rcvd)
    Dec 24 00:00:11	ppp: [wan] IPADDR 83.221.214.195
    Dec 24 00:00:11	ppp: [wan] 83.221.214.195 is OK
    Dec 24 00:00:11	ppp: [wan] IPCP: SendConfigAck #220
    Dec 24 00:00:11	ppp: [wan] IPADDR 83.221.214.195
    Dec 24 00:00:11	ppp: [wan] IPCP: state change Ack-Rcvd --> Opened
    Dec 24 00:00:11	ppp: [wan] IPCP: LayerUp
    Dec 24 00:00:11	ppp: [wan] НАШ IP -> 83.221.214.195
    Dec 24 00:00:12	ppp: [wan] IFACE: Up event
    Dec 24 00:04:34	ppp: [wan_link0] rec'd unexpected protocol 0x0821, rejecting
    Dec 24 00:04:46	ppp: [wan_link0] rec'd unexpected protocol 0x0061, rejecting
    Dec 24 00:04:47	ppp: [wan_link0] rec'd unexpected protocol 0x4021, rejecting
    Dec 24 00:04:48	ppp: [wan] IPCP: bad length: says 1372, rec'd 1308
    Dec 24 00:04:55	ppp: [wan_link0] rec'd unexpected protocol Appletalk, rejecting
    Dec 24 00:04:55	ppp: [wan] error writing len 1502 frame to b0: Message too long
    Dec 24 00:04:59	ppp: [wan_link0] rec'd unexpected protocol 0x0061, rejecting
    Dec 24 00:15:16	ppp: [wan_link0] rec'd unexpected protocol 0x0821, rejecting
    Dec 24 00:15:29	ppp: [wan_link0] rec'd unexpected protocol 0x00a1, rejecting
    Dec 24 00:15:34	ppp: [wan_link0] rec'd unexpected protocol 0x00a1, rejecting
    Dec 24 00:15:34	ppp: [wan_link0] rec'd unexpected protocol Appletalk, rejecting
    Dec 24 00:15:37	ppp: [wan_link0] rec'd unexpected protocol Bridging PDU, rejecting
    Dec 24 00:18:53	ppp: [wan_link0] rec'd unexpected protocol 0x0421, rejecting
    Dec 24 00:29:54	ppp: [wan_link0] rec'd unexpected protocol 0x2021, rejecting
    Dec 24 00:29:54	ppp: [wan] error writing len 1502 frame to b0: Message too long
    Dec 24 00:29:56	ppp: [wan_link0] rec'd unexpected protocol 0x0421, rejecting
    Dec 24 00:30:02	ppp: [wan] IPCP: unknown code 69
    Dec 24 00:30:02	ppp: [wan] IPCP: rec'd Terminate Request #221 (Opened)
    Dec 24 00:30:02	ppp: [wan] IPCP: state change Opened --> Stopping
    Dec 24 00:30:02	ppp: [wan] IPCP: SendTerminateAck #3
    Dec 24 00:30:02	ppp: [wan] IPCP: LayerDown
    Dec 24 00:30:02	ppp: [wan] IFACE: Down event
    Dec 24 00:30:04	ppp: [wan] IPCP: state change Stopping --> Stopped
    Dec 24 00:30:04	ppp: [wan] IPCP: LayerFinish
    Dec 24 00:30:04	ppp: [wan] Bundle: No NCPs left. Closing links...
    Dec 24 00:30:04	ppp: [wan] Bundle: closing link "wan_link0"...
    Dec 24 00:30:04	ppp: [wan_link0] Link: CLOSE event
    Dec 24 00:30:04	ppp: [wan_link0] LCP: Close event
    Dec 24 00:30:04	ppp: [wan_link0] LCP: state change Opened --> Closing
    Dec 24 00:30:04	ppp: [wan_link0] Link: Leave bundle "wan"
    Dec 24 00:30:04	ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    Dec 24 00:30:04	ppp: [wan] IPCP: Close event
    Dec 24 00:30:04	ppp: [wan] IPCP: state change Stopped --> Closed
    Dec 24 00:30:04	ppp: [wan] IPCP: Down event
    Dec 24 00:30:04	ppp: [wan] IPCP: state change Closed --> Initial
    Dec 24 00:30:04	ppp: [wan_link0] LCP: SendTerminateReq #2
    Dec 24 00:30:04	ppp: [wan_link0] LCP: LayerDown
    Dec 24 00:30:04	ppp: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing)
    Dec 24 00:30:04	ppp: [wan_link0] LCP: state change Closing --> Closed
    Dec 24 00:30:04	ppp: [wan_link0] LCP: LayerFinish
    Dec 24 00:30:04	ppp: [wan_link0] Link: DOWN event
    Dec 24 00:30:04	ppp: [wan_link0] LCP: Down event
    Dec 24 00:30:04	ppp: [wan_link0] LCP: state change Closed --> Initial
    
    

    Из лога видно, что линк пропадает в 21:09. Но не понятно почему именно… (в 00:00 - periodic reset, every day)



  • Провайдер сбрасывает коннект? У вас тариф анлим или счетчик?



  • @dvserg:

    Провайдер сбрасывает коннект? У вас тариф анлим или счетчик?

    Тариф - анлим! На счет сброса коннекта - не знаю.

    Кстати, вот еще что! Проверяю resolv.conf:

    domain pp.local
    nameserver 127.0.0.1
    nameserver 80.254.108.206
    nameserver 80.254.108.198
    nameserver 83.221.202.254
    nameserver 80.254.111.13
    

    Провайдером выданы: DNS: 83.221.202.254, альтернативный DNS: 80.254.111.13. В web морде: General Setup - DNS Servers указаны только они.



  • Провайдер сбрасывает скорее всего
    У Домолинка это постоянная картина.


Locked