Jul 7 17:07:49 serv2 ppp[891]: Phase: Using interface: tun0
Jul 7 17:07:49 serv2 ppp[891]: Phase: deflink: Created in closed state
Jul 7 17:07:49 serv2 ppp[891]: tun0: ID0: 0x28389e78 = fopen("/etc/ppp/ppp.conf", "r")
Jul 7 17:07:49 serv2 ppp[891]: tun0: Debug: ReadSystem: Checking optima_telecom (/etc/ppp/ppp.conf).
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set device PPPoE:rl0
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set authname asso1459
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set authkey ********
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set dial
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set login
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set timeout 0
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: set speed sync
Jul 7 17:07:49 serv2 ppp[891]: tun0: Command: optima_telecom: add default HISADDR
Jul 7 17:07:49 serv2 ppp[891]: tun0: ID0: 10 = socket(17, 3, 0)
Jul 7 17:07:49 serv2 ppp[906]: tun0: ID0: 0x28389e78 = fopen("/var/run/tun0.pid", "w")
Jul 7 17:07:49 serv2 ppp[906]: tun0: Phase: PPP Started (ddial mode).
Jul 7 17:07:49 serv2 ppp[906]: tun0: Phase: bundle: Establish
Jul 7 17:07:49 serv2 ppp[906]: tun0: Phase: deflink: closed -> opening
Jul 7 17:07:49 serv2 ppp[906]: tun0: ID0: 6 = kldload("netgraph")
Jul 7 17:07:49 serv2 ppp[906]: tun0: ID0: 7 = kldload("ng_ether")
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 8 = kldload("ng_pppoe")
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 9 = kldload("ng_socket")
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 0 = NgMkSockNode("", &cs, &ds)
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: List of netgraph node ``rl0:'' (id 1) hooks:
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Creating PPPoE netgraph node [1]:orphans -> ethernet
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Connecting netgraph socket .:tun0 -> rl0:orphans:tun0
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 2 = socket(2, 2, 0)
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 3223349521, 0xbfbfde4c)
Jul 7 17:07:50 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 2149607696, 0xbfbfde4c)
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Found the following interfaces:
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 1, name "rl0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 2, name "vr0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 3, name "plip0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 4, name "lo0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 5, name "pflog0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Index 6, name "tun0"
Jul 7 17:07:50 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:50 serv2 ppp[906]: tun0: Phase: deflink: Connected!
Jul 7 17:07:50 serv2 ppp[906]: tun0: Phase: deflink: opening -> dial
Jul 7 17:07:50 serv2 ppp[906]: tun0: Phase: deflink: dial -> carrier
Jul 7 17:07:50 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:07:51 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:07:51 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:07:51 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:07:51 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:07:51 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:51 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:07:52 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:07:52 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:07:52 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:07:52 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:07:52 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:52 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:07:53 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:07:53 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:07:53 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:07:53 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:07:53 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:53 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:07:54 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:07:54 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:07:54 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:07:54 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:07:54 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:54 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: Disconnected!
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: carrier -> hangup
Jul 7 17:07:55 serv2 ppp[906]: tun0: Debug: deflink: Close
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Mon Jul 7 17:07:50 2008
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: hangup -> opening
Jul 7 17:07:55 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting dial timer[0x2840dd44]
Jul 7 17:07:55 serv2 ppp[906]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul 7 17:08:25 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:25 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:25 serv2 ppp[906]: tun0: Timer: dial timer[0x2840dd44]: freq = 30.00s, next = 0.00s, state = running
Jul 7 17:08:25 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:25 serv2 ppp[906]: tun0: Chat: deflink: Redial timer expired.
Jul 7 17:08:25 serv2 ppp[906]: tun0: ID0: 0 = NgMkSockNode("", &cs, &ds)
Jul 7 17:08:25 serv2 ppp[906]: tun0: Debug: List of netgraph node ``rl0:'' (id 1) hooks:
Jul 7 17:08:25 serv2 ppp[906]: tun0: Debug: Found orphans -> ethernet
Jul 7 17:08:25 serv2 ppp[906]: tun0: Debug: Connecting netgraph socket .:tun0 -> [4]::tun0
Jul 7 17:08:25 serv2 ppp[906]: tun0: ID0: 2 = socket(2, 2, 0)
Jul 7 17:08:25 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 3223349521, 0xbfbfde4c)
Jul 7 17:08:25 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 2149607696, 0xbfbfde4c)
Jul 7 17:08:25 serv2 ppp[906]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0
Jul 7 17:08:25 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:25 serv2 ppp[906]: tun0: Phase: deflink: Connected!
Jul 7 17:08:25 serv2 ppp[906]: tun0: Phase: deflink: opening -> dial
Jul 7 17:08:25 serv2 ppp[906]: tun0: Phase: deflink: dial -> carrier
Jul 7 17:08:25 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:08:26 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:26 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:26 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:08:26 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:26 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:26 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:08:27 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:27 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:27 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:08:27 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:27 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:27 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:08:28 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:28 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:28 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:08:28 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:28 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:28 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:08:29 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:29 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:29 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:08:29 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:29 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:29 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: Disconnected!
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: carrier -> hangup
Jul 7 17:08:30 serv2 ppp[906]: tun0: Debug: deflink: Close
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Mon Jul 7 17:08:25 2008
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: hangup -> opening
Jul 7 17:08:30 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting dial timer[0x2840dd44]
Jul 7 17:08:30 serv2 ppp[906]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul 7 17:09:00 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:00 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:00 serv2 ppp[906]: tun0: Timer: dial timer[0x2840dd44]: freq = 30.00s, next = 0.00s, state = running
Jul 7 17:09:00 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:00 serv2 ppp[906]: tun0: Chat: deflink: Redial timer expired.
Jul 7 17:09:00 serv2 ppp[906]: tun0: ID0: 0 = NgMkSockNode("", &cs, &ds)
Jul 7 17:09:00 serv2 ppp[906]: tun0: Debug: List of netgraph node ``rl0:'' (id 1) hooks:
Jul 7 17:09:00 serv2 ppp[906]: tun0: Debug: Found orphans -> ethernet
Jul 7 17:09:00 serv2 ppp[906]: tun0: Debug: Connecting netgraph socket .:tun0 -> [4]::tun0
Jul 7 17:09:00 serv2 ppp[906]: tun0: ID0: 2 = socket(2, 2, 0)
Jul 7 17:09:00 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 3223349521, 0xbfbfde4c)
Jul 7 17:09:00 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 2149607696, 0xbfbfde4c)
Jul 7 17:09:00 serv2 ppp[906]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0
Jul 7 17:09:00 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:00 serv2 ppp[906]: tun0: Phase: deflink: Connected!
Jul 7 17:09:00 serv2 ppp[906]: tun0: Phase: deflink: opening -> dial
Jul 7 17:09:00 serv2 ppp[906]: tun0: Phase: deflink: dial -> carrier
Jul 7 17:09:00 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:01 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:01 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:01 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:01 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:01 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:01 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:02 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:02 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:02 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:02 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:02 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:02 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:03 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:03 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:03 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:03 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:03 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:03 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:04 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:04 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:04 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:04 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:04 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:04 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: Disconnected!
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: carrier -> hangup
Jul 7 17:09:05 serv2 ppp[906]: tun0: Debug: deflink: Close
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Mon Jul 7 17:09:00 2008
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: hangup -> opening
Jul 7 17:09:05 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting dial timer[0x2840dd44]
Jul 7 17:09:05 serv2 ppp[906]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul 7 17:09:35 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:35 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:35 serv2 ppp[906]: tun0: Timer: dial timer[0x2840dd44]: freq = 30.00s, next = 0.00s, state = running
Jul 7 17:09:35 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:35 serv2 ppp[906]: tun0: Chat: deflink: Redial timer expired.
Jul 7 17:09:35 serv2 ppp[906]: tun0: ID0: 0 = NgMkSockNode("", &cs, &ds)
Jul 7 17:09:35 serv2 ppp[906]: tun0: Debug: List of netgraph node ``rl0:'' (id 1) hooks:
Jul 7 17:09:35 serv2 ppp[906]: tun0: Debug: Found orphans -> ethernet
Jul 7 17:09:35 serv2 ppp[906]: tun0: Debug: Connecting netgraph socket .:tun0 -> [4]::tun0
Jul 7 17:09:35 serv2 ppp[906]: tun0: ID0: 2 = socket(2, 2, 0)
Jul 7 17:09:35 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 3223349521, 0xbfbfde4c)
Jul 7 17:09:35 serv2 ppp[906]: tun0: ID0: 0 = ioctl(2, 2149607696, 0xbfbfde4c)
Jul 7 17:09:35 serv2 ppp[906]: tun0: Debug: Sending PPPOE_CONNECT to .:tun0
Jul 7 17:09:35 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:35 serv2 ppp[906]: tun0: Phase: deflink: Connected!
Jul 7 17:09:35 serv2 ppp[906]: tun0: Phase: deflink: opening -> dial
Jul 7 17:09:35 serv2 ppp[906]: tun0: Phase: deflink: dial -> carrier
Jul 7 17:09:35 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:36 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:36 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:36 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:36 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:36 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:36 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:37 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:37 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:37 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:37 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:37 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:37 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:38 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:38 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:38 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:38 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:38 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:38 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:39 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:39 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:39 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:39 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:39 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:39 serv2 ppp[906]: tun0: Debug: Waiting for carrier
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: ---- Begin of Timer Service List---
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: physical throughput timer[0x2840f068]: freq = 1.00s, next = 0.00s, state = running
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: ---- End of Timer Service List ---
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting physical throughput timer[0x2840f068]
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: Disconnected!
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: carrier -> hangup
Jul 7 17:09:40 serv2 ppp[906]: tun0: Debug: deflink: Close
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: Connect time: 5 secs: 0 octets in, 0 octets out
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: 0 packets in, 0 packets out
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: total 0 bytes/sec, peak 0 bytes/sec on Mon Jul 7 17:09:35 2008
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: hangup -> opening
Jul 7 17:09:40 serv2 ppp[906]: tun0: Timer: timer_Start: Inserting dial timer[0x2840dd44]
Jul 7 17:09:40 serv2 ppp[906]: tun0: Phase: deflink: Enter pause (30) for redialing.
Jul 7 17:09:49 serv2 ppp[906]: tun0: Timer: Select returns -1
Jul 7 17:09:49 serv2 ppp[906]: tun0: Phase: Signal 15, terminate.
Jul 7 17:09:49 serv2 ppp[906]: tun0: Phase: deflink: Disconnected!
Jul 7 17:09:49 serv2 ppp[906]: tun0: Phase: deflink: opening -> closed
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: route_IfDelete (6)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: Found ff01:6::/32 <AF_UNSPEC>
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: route_IfDelete: Skip it (pass 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: Found ff02:6::/32 fe80:6::230:4fff:fe5b:d9bb
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: route_IfDelete: Skip it (pass 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: Found ff01:6::/32 <AF_UNSPEC>
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = socket(17, 3, 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 148 = write(0, data, 148)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: wrote 148: cmd = Delete, dst = ff01:6::/32, gateway = <none>
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: Found ff02:6::/32 fe80:6::230:4fff:fe5b:d9bb
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = socket(17, 3, 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 148 = write(0, data, 148)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: wrote 148: cmd = Delete, dst = ff02:6::/32, gateway = <none>
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = socket(2, 2, 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = ioctl(0, 3223349521, 0xbfbfe9cc)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = ioctl(0, 2149607696, 0xbfbfe9cc)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Phase: bundle: Dead
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: DoLoop done.
Jul 7 17:09:49 serv2 ppp[906]: tun0: Phase: PPP Terminated (normal).
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: route_IfDelete (6)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = socket(2, 2, 0)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = ioctl(0, 3223349521, 0xbfbfe63c)
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = ioctl(0, 2149607696, 0xbfbfe63c)
Jul 7 17:09:49 serv2 ppp[906]: tun0: Debug: Radius: radius_Destroy
Jul 7 17:09:49 serv2 ppp[906]: tun0: ID0: 0 = unlink("/var/run/tun0.pid")