which end terminated the connection?
Posted: Thu Mar 21, 2013 8:59 pm
Hi
Can you tell from the logs which end terminated the connection? The connection dropped after being stable @ 6db SNR margin for a while and has now reconnected at 9db - I was getting quite a lot of CRC errors (400 per hour) but apart from that it all looked good.
Mar 21 05:48:23 syslog -- MARK --
Mar 21 06:48:23 syslog -- MARK --
Mar 21 07:48:23 syslog -- MARK --
Mar 21 08:48:23 syslog -- MARK --
Mar 21 09:34:18 user kernel: Line 0: ADSL link down
Mar 21 09:34:19 user syslog: tc qdisc del dev ppp_0_0_38_1 root 2>/dev/null
Mar 21 09:34:20 user kernel: Line 0: xDSL G.994 training
Mar 21 09:34:22 daemon pppd[788]: Terminating connection due to link down.
Mar 21 09:34:29 user kernel: Line 0: ADSL G.992 started
Mar 21 09:34:34 user kernel: Line 0: ADSL G.992 channel analysis
Mar 21 09:34:40 user kernel: Line 0: ADSL G.992 message exchange
Mar 21 09:34:41 user kernel: Line 0: ADSL link down
Mar 21 09:34:46 user kernel: Line 0: xDSL G.994 training
Mar 21 09:34:55 user kernel: Line 0: ADSL G.992 started
Mar 21 09:34:59 user kernel: Line 0: ADSL G.992 channel analysis
Mar 21 09:35:06 user kernel: Line 0: ADSL link up, Path 0, us=1143, ds=13367
Mar 21 09:35:07 daemon pppd[788]: PPPoE: Terminating on signal 15.
Mar 21 09:35:07 daemon pppd[788]: Clear IP addresses. Connection DOWN.
Mar 21 09:35:07 daemon pppd[788]: Clear IP addresses.
Mar 21 09:35:07 daemon pppd[788]: Couldn't increase MTU to 1500.
Mar 21 09:35:13 daemon pppd[788]: Connection terminated.
Mar 21 09:35:13 daemon pppd[788]: Connect time 2925.9 minutes.
Mar 21 09:35:13 daemon pppd[788]: Sent 291913163 bytes, received 1255578384 bytes.
Mar 21 09:35:13 user kernel: dev_shutdown, dec ppp device refcnt, dev->refcnt=4
Mar 21 09:35:14 user kernel: unregister_netdevice: waiting for ppp_0_0_38_1 to become free. Usage count = -1
Mar 21 09:35:14 user kernel: dev->name = ppp_0_0_38_1, dev->refcnt=-1
Mar 21 09:35:14 user kernel: after reset to 0, dev->refcnt=0
Mar 21 09:35:14 daemon pppd[788]: Exit.
Mar 21 09:35:14 daemon pppd[26963]: PPPoATM setdevname_pppoatm
Mar 21 09:35:14 daemon pppd[26963]: PPPoATM setdevname_pppoatm - SUCCESS
Mar 21 09:35:14 daemon pppd[26963]: pppd 2.4.1 started by admin, uid 0
Mar 21 09:35:14 daemon pppd[26963]: PPP: Start to connect ...
Mar 21 09:35:14 daemon pppd[26963]: Using interface ppp0_0_38_1
Mar 21 09:35:14 daemon pppd[26963]: Connect: ppp_0_0_38_1 <-->
Mar 21 09:35:14 daemon pppd[26963]: Couldn't increase MTU to 1500.
Mar 21 09:35:14 daemon pppd[26963]: PPP LCP UP.
Mar 21 09:35:14 daemon pppd[26963]: Couldn't increase MTU to 1500.
Mar 21 09:35:14 daemon pppd[26963]: PPP LCP UP.
Mar 21 09:35:14 daemon pppd[26963]: Remote message: CHAP authentication success, unit 848
Mar 21 09:35:14 daemon pppd[26963]: local IP address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: remote IP address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: primary DNS address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: secondary DNS address (deleted)
Mar 21 09:35:15 daemon dnsmasq[59]: using nameserver (deleted)
Mar 21 09:35:15 daemon dnsmasq[59]: using nameserver (deleted)
Mar 21 09:35:15 daemon pppd[26963]: Received valid IP address from server. Connection UP.
Mar 21 09:35:17 user syslog: begin: interface: ppp_0_0_38_1 go to up
Mar 21 09:35:19 user syslog: end: interface: ppp_0_0_38_1 go to up
Can you tell from the logs which end terminated the connection? The connection dropped after being stable @ 6db SNR margin for a while and has now reconnected at 9db - I was getting quite a lot of CRC errors (400 per hour) but apart from that it all looked good.
Mar 21 05:48:23 syslog -- MARK --
Mar 21 06:48:23 syslog -- MARK --
Mar 21 07:48:23 syslog -- MARK --
Mar 21 08:48:23 syslog -- MARK --
Mar 21 09:34:18 user kernel: Line 0: ADSL link down
Mar 21 09:34:19 user syslog: tc qdisc del dev ppp_0_0_38_1 root 2>/dev/null
Mar 21 09:34:20 user kernel: Line 0: xDSL G.994 training
Mar 21 09:34:22 daemon pppd[788]: Terminating connection due to link down.
Mar 21 09:34:29 user kernel: Line 0: ADSL G.992 started
Mar 21 09:34:34 user kernel: Line 0: ADSL G.992 channel analysis
Mar 21 09:34:40 user kernel: Line 0: ADSL G.992 message exchange
Mar 21 09:34:41 user kernel: Line 0: ADSL link down
Mar 21 09:34:46 user kernel: Line 0: xDSL G.994 training
Mar 21 09:34:55 user kernel: Line 0: ADSL G.992 started
Mar 21 09:34:59 user kernel: Line 0: ADSL G.992 channel analysis
Mar 21 09:35:06 user kernel: Line 0: ADSL link up, Path 0, us=1143, ds=13367
Mar 21 09:35:07 daemon pppd[788]: PPPoE: Terminating on signal 15.
Mar 21 09:35:07 daemon pppd[788]: Clear IP addresses. Connection DOWN.
Mar 21 09:35:07 daemon pppd[788]: Clear IP addresses.
Mar 21 09:35:07 daemon pppd[788]: Couldn't increase MTU to 1500.
Mar 21 09:35:13 daemon pppd[788]: Connection terminated.
Mar 21 09:35:13 daemon pppd[788]: Connect time 2925.9 minutes.
Mar 21 09:35:13 daemon pppd[788]: Sent 291913163 bytes, received 1255578384 bytes.
Mar 21 09:35:13 user kernel: dev_shutdown, dec ppp device refcnt, dev->refcnt=4
Mar 21 09:35:14 user kernel: unregister_netdevice: waiting for ppp_0_0_38_1 to become free. Usage count = -1
Mar 21 09:35:14 user kernel: dev->name = ppp_0_0_38_1, dev->refcnt=-1
Mar 21 09:35:14 user kernel: after reset to 0, dev->refcnt=0
Mar 21 09:35:14 daemon pppd[788]: Exit.
Mar 21 09:35:14 daemon pppd[26963]: PPPoATM setdevname_pppoatm
Mar 21 09:35:14 daemon pppd[26963]: PPPoATM setdevname_pppoatm - SUCCESS
Mar 21 09:35:14 daemon pppd[26963]: pppd 2.4.1 started by admin, uid 0
Mar 21 09:35:14 daemon pppd[26963]: PPP: Start to connect ...
Mar 21 09:35:14 daemon pppd[26963]: Using interface ppp0_0_38_1
Mar 21 09:35:14 daemon pppd[26963]: Connect: ppp_0_0_38_1 <-->
Mar 21 09:35:14 daemon pppd[26963]: Couldn't increase MTU to 1500.
Mar 21 09:35:14 daemon pppd[26963]: PPP LCP UP.
Mar 21 09:35:14 daemon pppd[26963]: Couldn't increase MTU to 1500.
Mar 21 09:35:14 daemon pppd[26963]: PPP LCP UP.
Mar 21 09:35:14 daemon pppd[26963]: Remote message: CHAP authentication success, unit 848
Mar 21 09:35:14 daemon pppd[26963]: local IP address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: remote IP address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: primary DNS address (deleted)
Mar 21 09:35:14 daemon pppd[26963]: secondary DNS address (deleted)
Mar 21 09:35:15 daemon dnsmasq[59]: using nameserver (deleted)
Mar 21 09:35:15 daemon dnsmasq[59]: using nameserver (deleted)
Mar 21 09:35:15 daemon pppd[26963]: Received valid IP address from server. Connection UP.
Mar 21 09:35:17 user syslog: begin: interface: ppp_0_0_38_1 go to up
Mar 21 09:35:19 user syslog: end: interface: ppp_0_0_38_1 go to up