Hi, getting disconnects in the middle of the night - wondering if anyone had any ideas why? Here is my log.
I don't think it's a disconnect but rather a retrain thats not able to complete the timing is a clue 03:49 that's when the DLM would do it's thing in the wee small hours either to increase or decrease your interleaving depending on your errored second count over 24 hours.
Jul 23 03:45:42 daemon notice syslog: pppd:Couldn't increase MTU to 1500
Thanks for the reply,
So is it a problem with my router or line?
It did it again last night and now I am syncing at an even lower speed (dropped from 40mbps to 32mbps in past week)
Log:
Jul 25 04:01:09 daemon crit kernel: Line 0: VDSL2 link down
Jul 25 04:01:09 daemon warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN, Service Support = PTM
Jul 25 04:01:09 daemon warn kernel: HOST XTM tx ch 0 disabled.
Jul 25 04:01:09 daemon warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0
Jul 25 04:01:09 daemon warn kernel: TxLineRateTimer=0
Jul 25 04:01:09 daemon warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 0
Jul 25 04:01:09 daemon warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Jul 25 04:01:11 daemon crit kernel: Line 0: xDSL G.994 training
Jul 25 04:01:12 daemon notice syslog: pppd:Terminating on signal 20.
Jul 25 04:01:12 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Jul 25 04:01:12 daemon crit syslog: Clear IP addresses. Connection DOWN.
Jul 25 04:01:12 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:01:12 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:01:17 daemon info kernel: device ptm0 left promiscuous mode
Jul 25 04:01:17 daemon info kernel: device ptm0 entered promiscuous mode
Jul 25 04:01:17 daemon info kernel: device ptm0 left promiscuous mode
Jul 25 04:01:19 daemon crit kernel: Line 0: VDSL G.993 started
Jul 25 04:01:36 daemon crit kernel: Line 0: VDSL2 link up, Bearer 0, us=10767, ds=32400
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP, Service Support = PTM
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 2
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Jul 25 04:01:36 daemon warn kernel: TxLineRateTimer=2972
Jul 25 04:01:36 daemon warn kernel: bcmxtmrt: MAC address: 60 03 47 0d 63 0f
Jul 25 04:01:36 daemon warn kernel: [DoCreateDeviceReq.3087]: register_netdev
Jul 25 04:01:36 daemon warn kernel: [DoCreateDeviceReq.3089]: register_netdev done
Jul 25 04:01:36 daemon warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Reserve PTM vcid=0 ptmPri=1 port=0 bondingPort=4
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Reserve PTM vcid=1 ptmPri=2 port=0 bondingPort=4
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Reserve TxQueueIdx=0 for vcid 0
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Reserve MP group=0 priority=0 weight=1
Jul 25 04:01:36 daemon warn kernel: XTM Init: Ch:0 - 400 tx BDs at 0xa3000000
Jul 25 04:01:36 daemon warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=10767000, DS=32400000
Jul 25 04:01:36 daemon warn kernel: [FAP1] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Jul 25 04:01:36 daemon warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0
Jul 25 04:01:36 daemon warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 0
Jul 25 04:01:36 daemon warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 1
Jul 25 04:01:36 daemon warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 1
Jul 25 04:01:36 daemon warn kernel: ptm0.1 MAC address set to 60:03:47:0D:63:10
Jul 25 04:01:36 daemon warn kernel: netdev path : ptm0.1 -> ptm0
Jul 25 04:01:36 daemon warn kernel: BCMVLAN : ptm0 mode was set to RG
Jul 25 04:01:36 daemon info kernel: device ptm0 entered promiscuous mode
Jul 25 04:01:36 daemon notice syslog: pppd:cms logging initialized.
Jul 25 04:01:36 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Jul 25 04:01:36 daemon notice syslog: PPP: Start to connect ...
Jul 25 04:02:03 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Jul 25 04:02:03 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Jul 25 04:02:03 daemon notice syslog: pppd:Doing disconnect
Jul 25 04:02:06 daemon notice syslog: PPP: Start to connect ...
Jul 25 04:02:10 daemon crit syslog: PPP server detected.
Jul 25 04:02:11 daemon crit syslog: PPP session established.
Jul 25 04:02:11 daemon warn kernel: netdev path : ppp1.1 -> ptm0.1 -> ptm0
Jul 25 04:02:11 daemon notice syslog: pppd:Using interface ppp1.1
Jul 25 04:02:11 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Jul 25 04:02:11 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:02:11 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:02:11 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:02:11 daemon crit syslog: PPP LCP UP.
Jul 25 04:02:11 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:02:11 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:02:11 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:02:11 daemon crit syslog: PPP LCP UP.
Jul 25 04:02:12 daemon notice syslog: pppd:local IP address 195.166.144.83
Jul 25 04:02:12 daemon notice syslog: pppd:remote IP address 195.166.130.138
Jul 25 04:02:12 daemon notice syslog: pppd:primary DNS address 212.159.6.10
Jul 25 04:02:12 daemon notice syslog: pppd:secondary DNS address 212.159.6.9
Jul 25 04:02:12 daemon crit syslog: Received valid IP address from server. Connection UP.
Jul 25 04:02:15 daemon warn kernel: ^[[0;36;44mBroadcom Packet Flow Cache flushing the flows^[[0m
Jul 25 04:45:27 daemon notice syslog: pppd:LCP terminated by peer
Jul 25 04:45:27 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Jul 25 04:45:27 daemon crit syslog: Clear IP addresses. Connection DOWN.
Jul 25 04:45:27 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:27 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:30 daemon notice syslog: pppd:Connection terminated.
Jul 25 04:45:30 daemon notice syslog: pppd:Connect time 43.3 minutes.
Jul 25 04:45:30 daemon notice syslog: pppd:Sent 67865 bytes, received 1576661 bytes.
Jul 25 04:45:30 daemon notice syslog: pppd:Doing disconnect
Jul 25 04:45:33 daemon notice syslog: PPP: Start to connect ...
Jul 25 04:45:37 daemon crit syslog: PPP server detected.
Jul 25 04:45:37 daemon crit syslog: PPP session established.
Jul 25 04:45:37 daemon warn kernel: netdev path : ppp1.1 -> ptm0.1 -> ptm0
Jul 25 04:45:37 daemon notice syslog: pppd:Using interface ppp1.1
Jul 25 04:45:37 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Jul 25 04:45:37 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:37 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:37 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:37 daemon crit syslog: PPP LCP UP.
Jul 25 04:45:38 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:38 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:38 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:38 daemon crit syslog: PPP LCP UP.
Jul 25 04:45:48 daemon notice syslog: pppd:IPCP: timeout sending Config-Requests
Jul 25 04:45:48 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:48 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:48 daemon notice syslog: pppd:Connection terminated.
Jul 25 04:45:48 daemon notice syslog: pppd:Doing disconnect
Jul 25 04:45:51 daemon notice syslog: PPP: Start to connect ...
Jul 25 04:45:55 daemon crit syslog: PPP server detected.
Jul 25 04:45:55 daemon crit syslog: PPP session established.
Jul 25 04:45:55 daemon warn kernel: netdev path : ppp1.1 -> ptm0.1 -> ptm0
Jul 25 04:45:55 daemon notice syslog: pppd:Using interface ppp1.1
Jul 25 04:45:55 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Jul 25 04:45:55 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:55 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:55 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:55 daemon crit syslog: PPP LCP UP.
Jul 25 04:45:56 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Jul 25 04:45:56 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:56 daemon err syslog: pppd:Couldn't increase MRU to 1500
Jul 25 04:45:56 daemon crit syslog: PPP LCP UP.
Jul 25 04:45:57 daemon notice syslog: pppd:local IP address 46.208.116.32
Jul 25 04:45:57 daemon notice syslog: pppd:remote IP address 195.166.128.191
Jul 25 04:45:57 daemon notice syslog: pppd:primary DNS address 212.159.6.10
Jul 25 04:45:57 daemon notice syslog: pppd:secondary DNS address 212.159.6.9
Jul 25 04:45:57 daemon crit syslog: Received valid IP address from server. Connection UP.
Jul 25 04:46:00 daemon warn kernel: ^[[0;36;44mBroadcom Packet Flow Cache flushing the flows^[[0m
Jul 25 04:46:00 daemon err syslog: dhcpd:udhcp server (v0.9.6) started
Jul 25 07:35:45 daemon err syslog: dhcpd:sending ACK to 192.168.1.103
Jul 25 08:37:09 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:37:48 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:37:55 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:38:12 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:38:35 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:38:46 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:38:46 daemon err syslog: dhcpd:sending OFFER of 192.168.1.107
Jul 25 08:38:46 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:38:48 daemon err syslog: dhcpd:sending ACK to 192.168.1.107
Jul 25 08:55:15 daemon err syslog: dhcpd:sending ACK to 192.168.1.105
Jul 25 09:01:03 daemon err syslog: dhcpd:sending OFFER of 192.168.1.104
Jul 25 09:01:03 daemon err syslog: dhcpd:sending ACK to 192.168.1.104
Jul 25 09:29:32 daemon crit kernel: eth3 (switch port: 1) Link UP 1000 mbps full duplex
Jul 25 09:29:32 daemon info kernel: br0: bridge group port 4(eth3) entering forwarding state
Jul 25 09:33:37 daemon err syslog: dhcpd:sending ACK to 192.168.1.101
Jul 25 10:12:06 daemon err syslog: dhcpd:sending ACK to 192.168.1.101