Page 1 of 1
8800AXL R2 unstable after months of stability
Posted: Mon Feb 12, 2018 10:26 am
by evansnp
My router has dropped the VDSL connection around 5 times in the last week, after months of being rock solid. As a result, DLM has kicked in, and my speed dropped from 79999 to 74000, with attainable rate dropping from 125000 ish, down to 87000 ish. I'm currently on the 'd5' firmware, as 'd7' didn't seem to support vectoring (didn't manage to perform the 'clean' test that you asked me to do Billion_Fan). Below is the log extract.
Code: Select all
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SILI Start - UtopiaCfg - 10
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN(2), Service Support = PTM(2)
Feb 11 02:25:28 kern warn kernel: HOST XTM tx ch 0 disabled.
Feb 11 02:25:28 kern warn kernel: W1B -2102494241
Feb 11 02:25:28 kern warn kernel: W1E -2102494239
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SILI End - UtopiaCfg - 0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: DS xDSL G.inp Mode = DISABLED
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: xDSL G.Fast Mode = DISABLED
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ATM Bonding configured in system. Fallback mode = Enabled
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Bonding State is DATA_IDLE
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SID MODE SET to 12 BIT MODE
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ATM Bonding Mgmt Log Area = 82fb9d14
Feb 11 02:25:29 kern info kernel: device ptm0.2 left promiscuous mode
Feb 11 02:25:29 kern info kernel: br1: port 2(ptm0.2) entered disabled state
Feb 11 02:25:32 daemon notice syslog: pppd:Terminating on signal 20.
Feb 11 02:25:33 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Feb 11 02:25:33 daemon crit syslog: Clear IP addresses. Connection DOWN.
Feb 11 02:25:33 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 11 02:25:33 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:25:33 kern warn kernel: mcpd_nl_process_igmp_snoop_entry: source device ppp1.1 is not up
Feb 11 02:25:37 kern info kernel: device ptm0 left promiscuous mode
Feb 11 02:25:37 kern info kernel: device ptm0 entered promiscuous mode
Feb 11 02:25:37 kern info kernel: device ptm0 left promiscuous mode
Feb 11 02:27:32 kern crit kernel: Line 0: VDSL G.993 channel analysis
Feb 11 02:27:33 kern crit kernel: Line 0: VDSL2 link up, Bearer 0, us=19999, ds=73998
Feb 11 02:27:33 kern crit kernel: Line 0: VDSL2 link up, Bearer 1, us=0, ds=0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: SILI Start - UtopiaCfg - 0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: DS xDSL G.inp Mode = ENABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: xDSL G.Fast Mode = DISABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: xDSL ADSL Mode = DISABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP(1), Service Support = PTM(2)
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 2
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: SILI End - UtopiaCfg - 10
Feb 11 02:27:33 kern warn kernel: bcmxtmrt: TxPAF Status = Disabled
Feb 11 02:27:33 kern warn kernel: bcmxtmrt: MAC address: 60 03 47 39 7d ed
Feb 11 02:27:33 kern warn kernel: [DoCreateDeviceReq.775]: register_netdev
Feb 11 02:27:33 kern warn kernel: [DoCreateDeviceReq.777]: register_netdev done
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Reserve TxQueueIdx=0 for vcid 0
Feb 11 02:27:33 kern warn kernel: NON-CO4 DSLAM based operations for PTM CRC & ENET FCS.
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 0
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 1
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 1
Feb 11 02:27:33 kern warn kernel: XTM Init: Ch:0 - 400 tx BDs at 0xa2f74000
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=19999000, DS=73998000
Feb 11 02:27:33 kern warn kernel: netdev path : ptm0.2 -> ptm0
Feb 11 02:27:33 kern warn kernel: BCMVLAN : ptm0 mode was set to RG
Feb 11 02:27:34 kern info kernel: device ptm0.2 entered promiscuous mode
Feb 11 02:27:34 kern info kernel: device ptm0 entered promiscuous mode
Feb 11 02:27:34 kern info kernel: br1: port 2(ptm0.2) entered forwarding state
Feb 11 02:27:34 kern info kernel: br1: port 2(ptm0.2) entered forwarding state
Feb 11 02:27:35 kern warn kernel: ptm0.1 MAC address set to 60:03:47:39:7D:EE
Feb 11 02:27:35 kern warn kernel: netdev path : ptm0.1 -> ptm0
Feb 11 02:27:35 kern warn kernel: BCMVLAN : ptm0 mode was set to RG
Feb 11 02:27:35 daemon notice syslog: pppd:cms logging initialized.
Feb 11 02:27:35 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Feb 11 02:27:35 daemon notice syslog: PPP: Start to connect ...
Feb 11 02:27:35 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/07ca
Feb 11 02:27:56 kern warn kernel: dev = ptm0(0x82F8A800) macAddr=60:3:47:39:7D:ED
Feb 11 02:27:56 kern warn kernel: Line0: VCE macAddr=0:10:FC:20:0:0, VTU-R macAddr=60:3:47:39:7D:ED
Feb 11 02:28:03 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 11 02:28:03 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 11 02:28:03 daemon notice syslog: pppd:Doing disconnect
Feb 11 02:28:06 daemon notice syslog: PPP: Start to connect ...
Feb 11 02:28:06 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/07ca
Feb 11 02:28:10 daemon crit syslog: PPP server detected.
Feb 11 02:28:10 daemon crit syslog: PPP session established.
Feb 11 02:28:10 kern warn kernel: netdev path : ppp1.1
Feb 11 02:28:10 kern info kernel: -> ptm0.1 ->
Feb 11 02:28:10 kern info kernel: ptm0
Feb 11 02:28:10 daemon notice syslog: pppd:Using interface ppp1.1
Feb 11 02:28:10 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Feb 11 02:28:10 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 11 02:28:10 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:28:10 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:28:10 daemon crit syslog: PPP LCP UP.
Feb 11 02:28:10 daemon notice syslog: pppd:Warning - secret file /etc/ppp/chap-secrets has world and/or group access
Feb 11 02:28:10 daemon notice syslog: pppd:No CHAP secret found for authenticating us to acc-aln7.bm-erd
Feb 11 02:28:10 daemon notice syslog: pppd:Remote message: CHAP authentication success
Feb 11 02:28:10 daemon notice syslog: pppd:local IP address xxxxxxxxxx
Feb 11 02:28:10 daemon notice syslog: pppd:remote IP address 172.16.12.164
Feb 11 02:28:10 daemon notice syslog: pppd:primary DNS address 81.139.57.100
Feb 11 02:28:10 daemon notice syslog: pppd:secondary DNS address 81.139.56.100
Feb 11 02:28:10 daemon crit syslog: Received valid IP address from server. Connection UP.
Feb 11 05:18:29 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 11 05:18:29 kern info kernel: br0: port 2(eth2) entered disabled state
Feb 11 05:18:34 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 11 05:18:34 kern info kernel: br0: port 2(eth2) entered forwarding state
Feb 11 05:18:34 kern info kernel: br0: port 2(eth2) entered forwarding state
Re: 8800AXL R2 unstable after months of stability
Posted: Mon Feb 12, 2018 10:35 am
by billion_fan
evansnp wrote: ↑Mon Feb 12, 2018 10:26 am
My router has dropped the VDSL connection around 5 times in the last week, after months of being rock solid. As a result, DLM has kicked in, and my speed dropped from 79999 to 74000, with attainable rate dropping from 125000 ish, down to 87000 ish. I'm currently on the 'd5' firmware, as 'd7' didn't seem to support vectoring (didn't manage to perform the 'clean' test that you asked me to do Billion_Fan). Below is the log extract.
Code: Select all
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SILI Start - UtopiaCfg - 10
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = DOWN(2), Service Support = PTM(2)
Feb 11 02:25:28 kern warn kernel: HOST XTM tx ch 0 disabled.
Feb 11 02:25:28 kern warn kernel: W1B -2102494241
Feb 11 02:25:28 kern warn kernel: W1E -2102494239
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SILI End - UtopiaCfg - 0
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: DS xDSL G.inp Mode = DISABLED
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: xDSL G.Fast Mode = DISABLED
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ATM Bonding configured in system. Fallback mode = Enabled
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Bonding State is DATA_IDLE
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: SID MODE SET to 12 BIT MODE
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: ATM Bonding Mgmt Log Area = 82fb9d14
Feb 11 02:25:29 kern info kernel: device ptm0.2 left promiscuous mode
Feb 11 02:25:29 kern info kernel: br1: port 2(ptm0.2) entered disabled state
Feb 11 02:25:32 daemon notice syslog: pppd:Terminating on signal 20.
Feb 11 02:25:33 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Feb 11 02:25:33 daemon crit syslog: Clear IP addresses. Connection DOWN.
Feb 11 02:25:33 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 11 02:25:33 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:25:33 kern warn kernel: mcpd_nl_process_igmp_snoop_entry: source device ppp1.1 is not up
Feb 11 02:25:37 kern info kernel: device ptm0 left promiscuous mode
Feb 11 02:25:37 kern info kernel: device ptm0 entered promiscuous mode
Feb 11 02:25:37 kern info kernel: device ptm0 left promiscuous mode
Feb 11 02:27:32 kern crit kernel: Line 0: VDSL G.993 channel analysis
Feb 11 02:27:33 kern crit kernel: Line 0: VDSL2 link up, Bearer 0, us=19999, ds=73998
Feb 11 02:27:33 kern crit kernel: Line 0: VDSL2 link up, Bearer 1, us=0, ds=0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: SILI Start - UtopiaCfg - 0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: DS xDSL G.inp Mode = ENABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: xDSL G.Fast Mode = DISABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: xDSL ADSL Mode = DISABLED
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: XTM Link Information, port = 0, State = UP(1), Service Support = PTM(2)
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: ReconfigureSAR port 0 traffictype 2
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Normal(XTM/PTM) Mode enabled
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: SILI End - UtopiaCfg - 10
Feb 11 02:27:33 kern warn kernel: bcmxtmrt: TxPAF Status = Disabled
Feb 11 02:27:33 kern warn kernel: bcmxtmrt: MAC address: 60 03 47 39 7d ed
Feb 11 02:27:33 kern warn kernel: [DoCreateDeviceReq.775]: register_netdev
Feb 11 02:27:33 kern warn kernel: [DoCreateDeviceReq.777]: register_netdev done
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmCreateDevice : devId 0, encapType 0, headerLen 0
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Reserve TxQueueIdx=0 for vcid 0
Feb 11 02:27:33 kern warn kernel: NON-CO4 DSLAM based operations for PTM CRC & ENET FCS.
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 0
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 0
Feb 11 02:27:33 kern warn kernel: [FAP0] xtmLinkUp : devId 0, matchId 1
Feb 11 02:27:33 kern warn kernel: [FAP1] xtmLinkUp : devId 0, matchId 1
Feb 11 02:27:33 kern warn kernel: XTM Init: Ch:0 - 400 tx BDs at 0xa2f74000
Feb 11 02:27:33 kern warn kernel: bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=19999000, DS=73998000
Feb 11 02:27:33 kern warn kernel: netdev path : ptm0.2 -> ptm0
Feb 11 02:27:33 kern warn kernel: BCMVLAN : ptm0 mode was set to RG
Feb 11 02:27:34 kern info kernel: device ptm0.2 entered promiscuous mode
Feb 11 02:27:34 kern info kernel: device ptm0 entered promiscuous mode
Feb 11 02:27:34 kern info kernel: br1: port 2(ptm0.2) entered forwarding state
Feb 11 02:27:34 kern info kernel: br1: port 2(ptm0.2) entered forwarding state
Feb 11 02:27:35 kern warn kernel: ptm0.1 MAC address set to 60:03:47:39:7D:EE
Feb 11 02:27:35 kern warn kernel: netdev path : ptm0.1 -> ptm0
Feb 11 02:27:35 kern warn kernel: BCMVLAN : ptm0 mode was set to RG
Feb 11 02:27:35 daemon notice syslog: pppd:cms logging initialized.
Feb 11 02:27:35 daemon notice syslog: pppd 2.4.1 started by admin, uid 0
Feb 11 02:27:35 daemon notice syslog: PPP: Start to connect ...
Feb 11 02:27:35 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/07ca
Feb 11 02:27:56 kern warn kernel: dev = ptm0(0x82F8A800) macAddr=60:3:47:39:7D:ED
Feb 11 02:27:56 kern warn kernel: Line0: VCE macAddr=0:10:FC:20:0:0, VTU-R macAddr=60:3:47:39:7D:ED
Feb 11 02:28:03 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 11 02:28:03 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 11 02:28:03 daemon notice syslog: pppd:Doing disconnect
Feb 11 02:28:06 daemon notice syslog: PPP: Start to connect ...
Feb 11 02:28:06 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/07ca
Feb 11 02:28:10 daemon crit syslog: PPP server detected.
Feb 11 02:28:10 daemon crit syslog: PPP session established.
Feb 11 02:28:10 kern warn kernel: netdev path : ppp1.1
Feb 11 02:28:10 kern info kernel: -> ptm0.1 ->
Feb 11 02:28:10 kern info kernel: ptm0
Feb 11 02:28:10 daemon notice syslog: pppd:Using interface ppp1.1
Feb 11 02:28:10 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Feb 11 02:28:10 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 11 02:28:10 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:28:10 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 11 02:28:10 daemon crit syslog: PPP LCP UP.
Feb 11 02:28:10 daemon notice syslog: pppd:Warning - secret file /etc/ppp/chap-secrets has world and/or group access
Feb 11 02:28:10 daemon notice syslog: pppd:No CHAP secret found for authenticating us to acc-aln7.bm-erd
Feb 11 02:28:10 daemon notice syslog: pppd:Remote message: CHAP authentication success
Feb 11 02:28:10 daemon notice syslog: pppd:local IP address xxxxxxxxxx
Feb 11 02:28:10 daemon notice syslog: pppd:remote IP address 172.16.12.164
Feb 11 02:28:10 daemon notice syslog: pppd:primary DNS address 81.139.57.100
Feb 11 02:28:10 daemon notice syslog: pppd:secondary DNS address 81.139.56.100
Feb 11 02:28:10 daemon crit syslog: Received valid IP address from server. Connection UP.
Feb 11 05:18:29 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 11 05:18:29 kern info kernel: br0: port 2(eth2) entered disabled state
Feb 11 05:18:34 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 11 05:18:34 kern info kernel: br0: port 2(eth2) entered forwarding state
Feb 11 05:18:34 kern info kernel: br0: port 2(eth2) entered forwarding state
There must be something going with the line or exchange if the router was working fine on the same firmware for months. (routers don't normally start dropping out unless there is something wrong with the line or at the exchange) The system log does state much unfortunately.
You can try using the latest firmware 2.52.d10
http://www.billion.uk.com/esupport/inde ... ion-252d10
Re: 8800AXL R2 unstable after months of stability
Posted: Mon Feb 12, 2018 12:58 pm
by evansnp
Updated to 'd10', factory reset, and re-applied config. Let's see what happens
Re: 8800AXL R2 unstable after months of stability
Posted: Mon Feb 12, 2018 1:21 pm
by evansnp
Note for Billion_Fan: This version is showing "1 VECT FULL" under vectoring status.
Re: 8800AXL R2 unstable after months of stability
Posted: Mon Feb 12, 2018 2:18 pm
by billion_fan
evansnp wrote: ↑Mon Feb 12, 2018 1:21 pm
Note for Billion_Fan: This version is showing "1 VECT FULL" under vectoring status.
That's great news
Re: 8800AXL R2 unstable after months of stability
Posted: Fri Feb 16, 2018 4:26 pm
by evansnp
Unfortunately, the 'd10' firmware hasn't fixed the issue. How can I find out whether it's a modem problem, or cabinet / exchange problem?
Logs:
Code: Select all
Feb 16 03:15:09 daemon notice syslog: pppd:LCP terminated by peer
Feb 16 03:15:09 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Feb 16 03:15:09 daemon crit syslog: Clear IP addresses. Connection DOWN.
Feb 16 03:15:09 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 16 03:15:09 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:15:12 daemon notice syslog: pppd:Connection terminated.
Feb 16 03:15:12 daemon notice syslog: pppd:Connect time 5199.9 minutes.
Feb 16 03:15:12 daemon notice syslog: pppd:Sent 2637212471 bytes, received 291489009 bytes.
Feb 16 03:15:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:15:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:15:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:15:42 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:15:42 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:15:42 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:15:45 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:15:45 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:16:12 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:16:12 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:16:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:16:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:16:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:16:42 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:16:42 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:16:42 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:16:45 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:16:45 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:17:12 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:17:12 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:17:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:17:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:17:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:17:34 daemon crit syslog: PPP server detected.
Feb 16 03:17:34 daemon crit syslog: PPP session established.
Feb 16 03:17:34 kern warn kernel: netdev path : ppp1.1
Feb 16 03:17:34 kern info kernel: -> ptm0.1
Feb 16 03:17:34 kern info kernel: -> ptm0
Feb 16 03:17:34 daemon notice syslog: pppd:Using interface ppp1.1
Feb 16 03:17:34 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Feb 16 03:17:34 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 16 03:17:34 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:17:34 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:17:34 daemon crit syslog: PPP LCP UP.
Feb 16 03:17:34 daemon notice syslog: pppd:Warning - secret file /etc/ppp/chap-secrets has world and/or group access
Feb 16 03:17:34 daemon notice syslog: pppd:No CHAP secret found for authenticating us to acc-aln7.bm-erd
Feb 16 03:17:35 daemon notice syslog: pppd:Remote message: CHAP authentication success
Feb 16 03:17:35 daemon notice syslog: pppd:local IP address xxxxxxxxxx
Feb 16 03:17:35 daemon notice syslog: pppd:remote IP address 172.16.12.164
Feb 16 03:17:35 daemon notice syslog: pppd:primary DNS address 81.139.56.100
Feb 16 03:17:35 daemon notice syslog: pppd:secondary DNS address 81.139.57.100
Feb 16 03:17:35 daemon crit syslog: Received valid IP address from server. Connection UP.
Feb 16 03:17:37 user info syslog: ddnsd: evansnp.uk (ppp1.1) IP has changed from 86.159.134.26 to 86.155.220.1
Feb 16 03:17:37 user info syslog: ddnsd: update evansnp.uk request successfully
Feb 16 12:51:35 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:51:35 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:51:40 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 100 mbps full duplex
Feb 16 12:51:40 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:40 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:57 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:51:57 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:51:59 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 16 12:51:59 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:59 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:04 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:53:04 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:53:09 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 100 mbps full duplex
Feb 16 12:53:09 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:09 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:24 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:53:24 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:53:27 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 16 12:53:27 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:27 kern info kernel: br0: port 3(eth2) entered forwarding state
Re: 8800AXL R2 unstable after months of stability
Posted: Fri Feb 16, 2018 4:51 pm
by billion_fan
evansnp wrote: ↑Fri Feb 16, 2018 4:26 pm
Unfortunately, the 'd10' firmware hasn't fixed the issue. How can I find out whether it's a modem problem, or cabinet / exchange problem?
Logs:
Code: Select all
Feb 16 03:15:09 daemon notice syslog: pppd:LCP terminated by peer
Feb 16 03:15:09 daemon crit syslog: Clear IP addresses. PPP connection DOWN.
Feb 16 03:15:09 daemon crit syslog: Clear IP addresses. Connection DOWN.
Feb 16 03:15:09 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 16 03:15:09 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:15:12 daemon notice syslog: pppd:Connection terminated.
Feb 16 03:15:12 daemon notice syslog: pppd:Connect time 5199.9 minutes.
Feb 16 03:15:12 daemon notice syslog: pppd:Sent 2637212471 bytes, received 291489009 bytes.
Feb 16 03:15:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:15:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:15:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:15:42 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:15:42 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:15:42 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:15:45 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:15:45 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:16:12 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:16:12 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:16:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:16:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:16:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:16:42 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:16:42 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:16:42 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:16:45 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:16:45 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:17:12 daemon err syslog: pppd:Couldn't get channel number: Transport endpoint is not connected
Feb 16 03:17:12 daemon notice syslog: pppd:Couldn't reset tty to normal line discipline: Inappropriate ioctl for device
Feb 16 03:17:12 daemon notice syslog: pppd:Doing disconnect
Feb 16 03:17:15 daemon notice syslog: PPP: Start to connect ...
Feb 16 03:17:15 daemon crit syslog: session disconnect pppoe.oldsession=0ca402d182b3/0959
Feb 16 03:17:34 daemon crit syslog: PPP server detected.
Feb 16 03:17:34 daemon crit syslog: PPP session established.
Feb 16 03:17:34 kern warn kernel: netdev path : ppp1.1
Feb 16 03:17:34 kern info kernel: -> ptm0.1
Feb 16 03:17:34 kern info kernel: -> ptm0
Feb 16 03:17:34 daemon notice syslog: pppd:Using interface ppp1.1
Feb 16 03:17:34 daemon notice syslog: pppd:Connect: ppp1.1 <--> ptm0.1
Feb 16 03:17:34 daemon notice syslog: pppd:Couldn't increase MTU to 1500.
Feb 16 03:17:34 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:17:34 daemon err syslog: pppd:Couldn't increase MRU to 1500
Feb 16 03:17:34 daemon crit syslog: PPP LCP UP.
Feb 16 03:17:34 daemon notice syslog: pppd:Warning - secret file /etc/ppp/chap-secrets has world and/or group access
Feb 16 03:17:34 daemon notice syslog: pppd:No CHAP secret found for authenticating us to acc-aln7.bm-erd
Feb 16 03:17:35 daemon notice syslog: pppd:Remote message: CHAP authentication success
Feb 16 03:17:35 daemon notice syslog: pppd:local IP address xxxxxxxxxx
Feb 16 03:17:35 daemon notice syslog: pppd:remote IP address 172.16.12.164
Feb 16 03:17:35 daemon notice syslog: pppd:primary DNS address 81.139.56.100
Feb 16 03:17:35 daemon notice syslog: pppd:secondary DNS address 81.139.57.100
Feb 16 03:17:35 daemon crit syslog: Received valid IP address from server. Connection UP.
Feb 16 03:17:37 user info syslog: ddnsd: evansnp.uk (ppp1.1) IP has changed from 86.159.134.26 to 86.155.220.1
Feb 16 03:17:37 user info syslog: ddnsd: update evansnp.uk request successfully
Feb 16 12:51:35 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:51:35 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:51:40 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 100 mbps full duplex
Feb 16 12:51:40 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:40 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:57 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:51:57 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:51:59 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 16 12:51:59 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:51:59 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:04 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:53:04 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:53:09 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 100 mbps full duplex
Feb 16 12:53:09 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:09 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:24 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link DOWN.
Feb 16 12:53:24 kern info kernel: br0: port 3(eth2) entered disabled state
Feb 16 12:53:27 kern crit kernel: eth2 (Ext switch port: 3) (Logical Port: 11) Link UP 10 mbps full duplex
Feb 16 12:53:27 kern info kernel: br0: port 3(eth2) entered forwarding state
Feb 16 12:53:27 kern info kernel: br0: port 3(eth2) entered forwarding state
Well from the first log we saw
Feb 11 02:25:28 kern warn kernel: bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0 = this means the line dropped out
Feb 11 02:27:33 kern crit kernel: Line 0: VDSL2 link up, Bearer 0, us=19999, ds=73998 = means the device resynced
On the second log we don't see the same messages which will indicate a drop in the PPP connection only
Re: 8800AXL R2 unstable after months of stability
Posted: Fri Feb 16, 2018 5:06 pm
by evansnp
Hi Billion_Fan,
On the second log we don't see the same messages which will indicate a drop in the PPP connection only
What's the implication of this? Is it more likely to be the exchange?
Re: 8800AXL R2 unstable after months of stability
Posted: Fri Feb 16, 2018 5:21 pm
by billion_fan
evansnp wrote: ↑Fri Feb 16, 2018 5:06 pm
Hi Billion_Fan,
On the second log we don't see the same messages which will indicate a drop in the PPP connection only
What's the implication of this? Is it more likely to be the exchange?
Yep, the router won't disconnect the PPP without intervention, so it might be a problem at the exchange, keep a eye on it (the next time it happens try to check to the logs to look for a line re-sync), as it seems they might have fixed the line drop, but still have issues with the PPP dropping