Apr 21 13:26:36 w541 newsyslog[529]: logfile first created
Apr 21 13:26:49 w541 ppp[797]: Phase: Using interface: tun0
Apr 21 13:26:49 w541 ppp[797]: Phase: deflink: Created in closed state
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set device /dev/cuaU0
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set speed 460800
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set authname web@telering.at
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set authkey ********
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 3 "" \d\d\dAT OK-AT-OK \d\d\dAT+CPIN? READY-\d\d\dAT+CPIN=\"2360\"-OK \d\d\dAT+CGDCONT=1,\"IP\",\"WEB\" OK \d\d\dAT+CFUN=1 OK-AT-OK \d\d\dATD*99# CONNECT
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: set ifaddr 10.0.0.1/0 10.0.0.2/0 255.255.255.0 0.0.0.0
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: add default HISADDR
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: resolv reload
Apr 21 13:26:49 w541 ppp[797]: tun0: Command: telering: enable dns
Apr 21 13:26:49 w541 ppp[797]: tun0: Phase: PPP Started (interactive mode).
Apr 21 13:26:57 w541 ppp[797]: tun0: Command: /dev/ttyv0: show physic
Apr 21 13:27:00 w541 ppp[797]: tun0: Command: /dev/ttyv0: dial
Apr 21 13:27:00 w541 ppp[797]: tun0: Phase: bundle: Establish
Apr 21 13:27:00 w541 ppp[797]: tun0: Phase: deflink: closed -> opening
Apr 21 13:27:00 w541 ppp[797]: tun0: Phase: deflink: Connected!
Apr 21 13:27:00 w541 ppp[797]: tun0: Phase: deflink: opening -> dial
Apr 21 13:27:00 w541 ppp[797]: tun0: Chat: deflink: Dial attempt 1 of 1
Apr 21 13:27:00 w541 ppp[797]: tun0: Chat: Send: AT^M
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Expect(3): OK
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Received: ^M
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Received: *EMRDY: 1^M
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Received: AT^M^M
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Received: OK^M
Apr 21 13:27:06 w541 ppp[797]: tun0: Chat: Send: AT+CPIN?^M
Apr 21 13:27:07 w541 ppp[797]: tun0: Command: /dev/ttyv0: show physic
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Expect(3): READY
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Received: AT+CPIN?^M^M
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Received: +CPIN: READY^M
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Received: ^M
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Received: OK^M
Apr 21 13:27:12 w541 ppp[797]: tun0: Chat: Send: AT+CGDCONT=1,"IP","WEB"^M
Apr 21 13:27:18 w541 ppp[797]: tun0: Chat: Send: AT+CFUN=1^M
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Expect(3): OK
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Received: AT+CGDCONT=1,"IP","WEB"^M^M
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Received: OK^M
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Received: AT+CFUN=1^M^M
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Received: OK^M
Apr 21 13:27:25 w541 ppp[797]: tun0: Chat: Send: ATD*99#^M
Apr 21 13:27:31 w541 ppp[797]: tun0: Chat: Expect(3): CONNECT
Apr 21 13:27:31 w541 ppp[797]: tun0: Chat: Received: ATD*99#^M^M
Apr 21 13:27:31 w541 ppp[797]: tun0: Chat: Received: CONNECT^M
Apr 21 13:27:31 w541 ppp[797]: tun0: Phase: deflink: dial -> carrier
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: /dev/cuaU0: CD detected
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: carrier -> login
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: login -> lcp
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: FSM: Using "deflink" as a transport
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: State change Initial --> Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: State change Closed --> Stopped
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvConfigReq(3) state = Stopped
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x82029030
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x47ba007e
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: SendConfigAck(3) state = Stopped
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x82029030
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: LayerStart
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: State change Stopped --> Ack-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvConfigReq(4) state = Ack-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x82029030
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: SendConfigAck(4) state = Ack-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: AUTHPROTO[5] 0xc223 (CHAP 0x05)
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x82029030
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACFCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: PROTOCOMP[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: ACCMAP[6] 0x00000000
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MRU[4] 1500
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: MAGICNUM[6] 0x47ba007e
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: LayerUp
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: bundle: Authenticate
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: Chap Input: CHALLENGE (57 bytes from Kermit)
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: Chap Output: RESPONSE (web@telering.at)
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: Chap Input: SUCCESS (Congratulations!)
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: Using trigger address 0.0.0.0
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: FSM: Using "deflink" as a transport
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: State change Initial --> Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: LayerStart.
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: MPPE: Not usable without CHAP81
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: DEFLATE[4] win 15
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: PRED1[2]
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: State change Closed --> Req-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: lcp -> open
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: bundle: Network
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: FSM: Using "deflink" as a transport
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: State change Initial --> Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: LayerStart.
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: IPADDR[6] 0.0.0.0
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: PRIDNS[6] 192.168.42.129
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: SECDNS[6] 255.255.255.255
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvProtocolRej(1) state = Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvProtocolRej(2) state = Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: -- Protocol 0x8057 (Internet Protocol V6 Control Protocol) was rejected!
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: IPV6CP protocol reject closes IPV6CP !
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvProtocolRej(3) state = Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: -- Protocol 0x8057 (Internet Protocol V6 Control Protocol) was rejected!
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: IPV6CP protocol reject closes IPV6CP !
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: RecvTerminateReq(1) state = Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: LayerDown
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: SendTerminateAck(1) state = Opened
Apr 21 13:27:32 w541 ppp[797]: tun0: LCP: deflink: State change Opened --> Stopping
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: State change Stopped --> Closed
Apr 21 13:27:32 w541 ppp[797]: tun0: CCP: deflink: State change Closed --> Initial
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: deflink: open -> lcp
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: State change Req-Sent --> Starting
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: LayerFinish.
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: Connect time: 0 secs: 0 octets in, 0 octets out
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: 0 packets in, 0 packets out
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: total 0 bytes/sec, peak 0 bytes/sec on Tue Apr 21 13:27:32 2015
Apr 21 13:27:32 w541 ppp[797]: tun0: IPCP: deflink: State change Starting --> Initial
Apr 21 13:27:32 w541 ppp[797]: tun0: Phase: bundle: Terminate
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: Carrier lost
Apr 21 13:27:33 w541 ppp[797]: tun0: LCP: deflink: State change Stopping --> Starting
Apr 21 13:27:33 w541 ppp[797]: tun0: LCP: deflink: LayerFinish
Apr 21 13:27:33 w541 ppp[797]: tun0: LCP: deflink: State change Starting --> Initial
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: Disconnected!
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: lcp -> logout
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: Disconnected!
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: logout -> hangup
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: Connect time: 33 secs: 392 octets in, 316 octets out
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: 11 packets in, 9 packets out
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: total 21 bytes/sec, peak 141 bytes/sec on Tue Apr 21 13:27:33 2015
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: deflink: hangup -> closed
Apr 21 13:27:33 w541 ppp[797]: tun0: Phase: bundle: Dead
Apr 21 13:27:41 w541 ppp[797]: tun0: Command: /dev/ttyv0: show physic
Apr 21 13:27:46 w541 ppp[797]: tun0: Command: /dev/ttyv0: quit
Apr 21 13:27:46 w541 ppp[797]: tun0: Phase: PPP Terminated (normal).