Project

General

Profile

Bug #943 » system.log

R B, 10/11/2010 03:34 PM

 
1
Oct 11 13:05:46 pfSense root: __ unplugging DSL phone line from modem __
2
Oct 11 13:06:05 pfSense apinger: ALARM: GW_WAN(A.B.112.146)  *** down ***
3
Oct 11 13:06:15 pfSense check_reload_status: reloading filter
4
Oct 11 13:06:23 pfSense ppp: [wan_link0] LCP: no reply to 1 echo request(s)
5
Oct 11 13:06:33 pfSense ppp: [wan_link0] LCP: no reply to 2 echo request(s)
6
Oct 11 13:06:43 pfSense ppp: [wan_link0] LCP: no reply to 3 echo request(s)
7
Oct 11 13:06:53 pfSense ppp: [wan_link0] LCP: no reply to 4 echo request(s)
8
Oct 11 13:07:03 pfSense ppp: [wan_link0] LCP: no reply to 5 echo request(s)
9
Oct 11 13:07:03 pfSense ppp: [wan_link0] LCP: peer not responding to echo requests
10
Oct 11 13:07:03 pfSense ppp: [wan_link0] LCP: state change Opened --> Stopping
11
Oct 11 13:07:03 pfSense ppp: [wan_link0] Link: Leave bundle "wan"
12
Oct 11 13:07:03 pfSense ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
13
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: Close event
14
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: state change Opened --> Closing
15
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: SendTerminateReq #4
16
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: LayerDown
17
Oct 11 13:07:03 pfSense check_reload_status: Rewriting resolv.conf
18
Oct 11 13:07:03 pfSense ppp: [wan] IFACE: Down event
19
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: Down event
20
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: LayerFinish
21
Oct 11 13:07:03 pfSense ppp: [wan] Bundle: No NCPs left. Closing links...
22
Oct 11 13:07:03 pfSense ppp: [wan] IPCP: state change Closing --> Initial
23
Oct 11 13:07:03 pfSense ppp: [wan_link0] LCP: SendTerminateReq #2
24
Oct 11 13:07:03 pfSense ppp: [wan_link0] LCP: LayerDown
25
Oct 11 13:07:05 pfSense ppp: [wan_link0] LCP: SendTerminateReq #3
26
Oct 11 13:07:07 pfSense ppp: [wan_link0] LCP: state change Stopping --> Stopped
27
Oct 11 13:07:07 pfSense ppp: [wan_link0] LCP: LayerFinish
28
Oct 11 13:07:07 pfSense ppp: [wan_link0] PPPoE: connection closed
29
Oct 11 13:07:07 pfSense ppp: [wan_link0] Link: DOWN event
30
Oct 11 13:07:07 pfSense ppp: [wan_link0] LCP: Down event
31
Oct 11 13:07:07 pfSense ppp: [wan_link0] LCP: state change Stopped --> Starting
32
Oct 11 13:07:07 pfSense ppp: [wan_link0] LCP: LayerStart
33
Oct 11 13:07:07 pfSense ppp: [wan_link0] Link: reconnection attempt 1 in 2 seconds
34
Oct 11 13:07:08 pfSense dnsmasq[32833]: reading /etc/resolv.conf
35
Oct 11 13:07:08 pfSense dnsmasq[32833]: using nameserver x.x.x.2#53
36
Oct 11 13:07:08 pfSense dnsmasq[32833]: using nameserver x.x.x.1#53
37
Oct 11 13:07:09 pfSense ppp: [wan_link0] Link: reconnection attempt 1
38
Oct 11 13:07:09 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
39
Oct 11 13:07:18 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
40
Oct 11 13:07:18 pfSense ppp: [wan_link0] Link: DOWN event
41
Oct 11 13:07:18 pfSense ppp: [wan_link0] LCP: Down event
42
Oct 11 13:07:18 pfSense ppp: [wan_link0] Link: reconnection attempt 2 in 3 seconds
43
Oct 11 13:07:21 pfSense ppp: [wan_link0] Link: reconnection attempt 2
44
Oct 11 13:07:21 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
45
Oct 11 13:07:30 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
46
Oct 11 13:07:30 pfSense ppp: [wan_link0] Link: DOWN event
47
Oct 11 13:07:30 pfSense ppp: [wan_link0] LCP: Down event
48
Oct 11 13:07:30 pfSense ppp: [wan_link0] Link: reconnection attempt 3 in 1 seconds
49
Oct 11 13:07:31 pfSense ppp: [wan_link0] Link: reconnection attempt 3
50
Oct 11 13:07:31 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
51
Oct 11 13:07:34 pfSense root: __ reconnecting DSL phone line __
52
Oct 11 13:07:40 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
53
Oct 11 13:07:40 pfSense ppp: [wan_link0] Link: DOWN event
54
Oct 11 13:07:40 pfSense ppp: [wan_link0] LCP: Down event
55
Oct 11 13:07:40 pfSense ppp: [wan_link0] Link: reconnection attempt 4 in 3 seconds
56
Oct 11 13:07:43 pfSense ppp: [wan_link0] Link: reconnection attempt 4
57
Oct 11 13:07:43 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
58
Oct 11 13:07:52 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
59
Oct 11 13:07:52 pfSense ppp: [wan_link0] Link: DOWN event
60
Oct 11 13:07:52 pfSense ppp: [wan_link0] LCP: Down event
61
Oct 11 13:07:52 pfSense ppp: [wan_link0] Link: reconnection attempt 5 in 1 seconds
62
Oct 11 13:07:53 pfSense ppp: [wan_link0] Link: reconnection attempt 5
63
Oct 11 13:07:53 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
64
Oct 11 13:08:02 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
65
Oct 11 13:08:02 pfSense ppp: [wan_link0] Link: DOWN event
66
Oct 11 13:08:02 pfSense ppp: [wan_link0] LCP: Down event
67
Oct 11 13:08:02 pfSense ppp: [wan_link0] Link: reconnection attempt 6 in 1 seconds
68
Oct 11 13:08:03 pfSense ppp: [wan_link0] Link: reconnection attempt 6
69
Oct 11 13:08:03 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
70
Oct 11 13:08:12 pfSense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
71
Oct 11 13:08:12 pfSense ppp: [wan_link0] Link: DOWN event
72
Oct 11 13:08:12 pfSense ppp: [wan_link0] LCP: Down event
73
Oct 11 13:08:12 pfSense ppp: [wan_link0] Link: reconnection attempt 7 in 1 seconds
74
Oct 11 13:08:13 pfSense ppp: [wan_link0] Link: reconnection attempt 7
75
Oct 11 13:08:13 pfSense ppp: [wan_link0] PPPoE: Connecting to '*'
76
Oct 11 13:08:19 pfSense ppp: PPPoE: rec'd ACNAME "my-dynamic-hostname.isp.net"
77
Oct 11 13:08:19 pfSense ppp: [wan_link0] PPPoE: connection successful
78
Oct 11 13:08:19 pfSense ppp: [wan_link0] Link: UP event
79
Oct 11 13:08:19 pfSense ppp: [wan_link0] LCP: Up event
80
Oct 11 13:08:19 pfSense ppp: [wan_link0] LCP: state change Starting --> Req-Sent
81
Oct 11 13:08:19 pfSense ppp: [wan_link0] LCP: SendConfigReq #4
82
Oct 11 13:08:19 pfSense ppp: [wan_link0]   PROTOCOMP
83
Oct 11 13:08:19 pfSense ppp: [wan_link0]   MRU 1492
84
Oct 11 13:08:19 pfSense ppp: [wan_link0]   MAGICNUM d7a5000a
85
Oct 11 13:08:19 pfSense ppp: [wan_link0] LCP: rec'd Configure Ack #4 (Req-Sent)
86
Oct 11 13:08:19 pfSense ppp: [wan_link0]   PROTOCOMP
87
Oct 11 13:08:19 pfSense ppp: [wan_link0]   MRU 1492
88
Oct 11 13:08:19 pfSense ppp: [wan_link0]   MAGICNUM d7a5000a
89
Oct 11 13:08:19 pfSense ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Rcvd
90
Oct 11 13:08:21 pfSense ppp: [wan_link0] LCP: rec'd Configure Request #2 (Ack-Rcvd)
91
Oct 11 13:08:21 pfSense ppp: [wan_link0]   MRU 1492
92
Oct 11 13:08:21 pfSense ppp: [wan_link0]   AUTHPROTO PAP
93
Oct 11 13:08:21 pfSense ppp: [wan_link0]   MAGICNUM 63a052fb
94
Oct 11 13:08:21 pfSense ppp: [wan_link0] LCP: SendConfigAck #2
95
Oct 11 13:08:21 pfSense ppp: [wan_link0]   MRU 1492
96
Oct 11 13:08:21 pfSense ppp: [wan_link0]   AUTHPROTO PAP
97
Oct 11 13:08:21 pfSense ppp: [wan_link0]   MAGICNUM 63a052fb
98
Oct 11 13:08:21 pfSense ppp: [wan_link0] LCP: state change Ack-Rcvd --> Opened
99
Oct 11 13:08:21 pfSense ppp: [wan_link0] LCP: auth: peer wants PAP, I want nothing
100
Oct 11 13:08:21 pfSense ppp: [wan_link0] PAP: using authname "myuser@isp.net"
101
Oct 11 13:08:21 pfSense ppp: [wan_link0] PAP: sending REQUEST #1 len: 31
102
Oct 11 13:08:21 pfSense ppp: [wan_link0] LCP: LayerUp
103
Oct 11 13:08:22 pfSense ppp: [wan_link0] PAP: rec'd ACK #1 len: 5
104
Oct 11 13:08:22 pfSense ppp: [wan_link0] LCP: authorization successful
105
Oct 11 13:08:22 pfSense ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
106
Oct 11 13:08:22 pfSense ppp: [wan_link0] Link: Join bundle "wan"
107
Oct 11 13:08:22 pfSense ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
108
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: Open event
109
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: state change Initial --> Starting
110
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: LayerStart
111
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: Up event
112
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: state change Starting --> Req-Sent
113
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: SendConfigReq #5
114
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR 0.0.0.0
115
Oct 11 13:08:22 pfSense ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
116
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
117
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR A.B.112.146
118
Oct 11 13:08:22 pfSense ppp: [wan]     A.B.112.146 is OK
119
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: SendConfigAck #1
120
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR A.B.112.146
121
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
122
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: rec'd Configure Reject #5 (Ack-Sent)
123
Oct 11 13:08:22 pfSense ppp: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
124
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: SendConfigReq #6
125
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR 0.0.0.0
126
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: rec'd Configure Nak #6 (Ack-Sent)
127
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR X.Y.136.130
128
Oct 11 13:08:22 pfSense ppp: [wan]     X.Y.136.130 is OK
129
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: SendConfigReq #7
130
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR X.Y.136.130
131
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: rec'd Configure Ack #7 (Ack-Sent)
132
Oct 11 13:08:22 pfSense ppp: [wan]   IPADDR X.Y.136.130
133
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: state change Ack-Sent --> Opened
134
Oct 11 13:08:22 pfSense ppp: [wan] IPCP: LayerUp
135
Oct 11 13:08:22 pfSense ppp: [wan]   X.Y.136.130 -> A.B.112.146
136
Oct 11 13:08:22 pfSense check_reload_status: Rewriting resolv.conf
137
Oct 11 13:08:22 pfSense check_reload_status: rc.newwanip starting pppoe1
138
Oct 11 13:08:22 pfSense ppp: [wan] IFACE: Up event
139
Oct 11 13:08:23 pfSense php: : rc.newwanip: Informational is starting pppoe1.
140
Oct 11 13:08:23 pfSense php: : rc.newwanip: on (IP address: X.Y.136.130) (interface: wan) (real interface: pppoe1).
141
Oct 11 13:08:23 pfSense php: : ROUTING: change default route to A.B.112.146
142
Oct 11 13:08:23 pfSense apinger: Exiting on signal 15.
143
Oct 11 13:08:23 pfSense check_reload_status: updating dyndns wan
144
Oct 11 13:08:23 pfSense apinger: Starting Alarm Pinger, apinger(56212)
145
Oct 11 13:08:25 pfSense dnsmasq[32833]: reading /etc/resolv.conf
146
Oct 11 13:08:25 pfSense dnsmasq[32833]: using nameserver x.x.x.2#53
147
Oct 11 13:08:25 pfSense dnsmasq[32833]: using nameserver x.x.x.1#53
148
Oct 11 13:08:28 pfSense php: : Resyncing openvpn instances configurations for interface WAN.
149
Oct 11 13:08:28 pfSense check_reload_status: reloading filter
150
Oct 11 13:08:33 pfSense apinger: ALARM: GW_WAN(A.B.112.146)  *** down ***
151
Oct 11 13:08:43 pfSense check_reload_status: reloading filter
152
Oct 11 13:08:46 pfSense apinger: alarm canceled: GW_WAN(A.B.112.146)  *** down ***
153
Oct 11 13:08:56 pfSense check_reload_status: reloading filter
154
Oct 11 13:09:37 pfSense root: __ test complete __
(1-1/4)