RT-AC87U Losing Connection

[Cain]

I have an RTAC87U Asus Wifi router. I'm running Merlin 384.5. I have a ARRIS SB8200 cable modem.

I'm losing my internet intermittently.

This is my syslog for the last period of time it lost connection, any info on what's causing this please?? I can provided extended log time periods if helpful.

Jun 16 02:25:38 WAN_Connection: ISP's DHCP did not function properly.
Jun 16 02:25:38 DualWAN: skip single wan wan_led_control - WANRED off
Jun 16 02:25:39 nat: apply redirect rules
Jun 16 02:25:43 WAN_Connection: Ethernet link up.
Jun 16 02:25:43 rc_service: wanduck 219:notify_rc restart_wan_if 0
Jun 16 02:25:43 wan: [deconfig] udhcpc done[286]
Jun 16 02:25:43 kernel: et1: et_mvlan_netdev_event: event 9 for vlan2 mvlan_en 0
Jun 16 02:25:43 kernel: et1: et_mvlan_netdev_event: event 2 for vlan2 mvlan_en 0
Jun 16 02:25:43 kernel: et1: et_mvlan_netdev_event: event 13 for vlan2 mvlan_en 0
Jun 16 02:25:43 kernel: et1: et_mvlan_netdev_event: event 1 for vlan2 mvlan_en 0
Jun 16 02:25:43 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 16 02:25:44 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 16 02:25:46 wan: mac clone: [wan0_hwaddr] == [a4:5e:60:4b:79:25]
Jun 16 02:25:46 wan: [deconfig] udhcpc done[286]
Jun 16 02:26:07 WAN_Connection: Ethernet link down.
Jun 16 02:26:23 WAN_Connection: Ethernet link up.
Jun 16 02:26:23 rc_service: wanduck 219:notify_rc restart_wan_if 0
Jun 16 02:26:23 wan: [deconfig] udhcpc done[286]
Jun 16 02:26:23 kernel: et1: et_mvlan_netdev_event: event 9 for vlan2 mvlan_en 0
Jun 16 02:26:24 kernel: et1: et_mvlan_netdev_event: event 2 for vlan2 mvlan_en 0
Jun 16 02:26:24 kernel: et1: et_mvlan_netdev_event: event 13 for vlan2 mvlan_en 0
Jun 16 02:26:24 kernel: et1: et_mvlan_netdev_event: event 1 for vlan2 mvlan_en 0
Jun 16 02:26:24 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 16 02:26:24 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 16 02:26:26 wan: mac clone: [wan0_hwaddr] == [a4:5e:60:4b:79:25]
Jun 16 02:26:26 wan: [deconfig] udhcpc done[286]
Jun 16 02:26:28 WAN_Connection: ISP's DHCP did not function properly.
Jun 16 02:26:28 rc_service: udhcpc 28328:notify_rc start_firewall
Jun 16 02:26:29 nat: apply nat rules (/tmp/nat_rules_vlan2_vlan2)
Jun 16 02:26:29 wan: finish adding multi routes
Jun 16 02:26:30 rc_service: udhcpc 28328:notify_rc stop_upnp
Jun 16 02:26:30 rc_service: udhcpc 28328:notify_rc start_upnp
Jun 16 02:26:30 rc_service: waitting "stop_upnp" via udhcpc ...
Jun 16 02:26:31 dhcp_client: bound 192.168.100.11 via 192.168.100.1 during 20 seconds.
Jun 16 02:26:35 WAN_Connection: WAN was restored.
Jun 16 02:27:13 wan: [deconfig] udhcpc done[286]
Jun 16 02:27:13 WAN_Connection: ISP's DHCP did not function properly.
Jun 16 02:27:14 DualWAN: skip single wan wan_led_control - WANRED off
Jun 16 02:27:14 nat: apply redirect rules
Jun 16 02:27:15 rc_service: udhcpc 28403:notify_rc start_firewall
Jun 16 02:27:16 wan: finish adding multi routes
Jun 16 02:27:16 nat: apply nat rules (/tmp/nat_rules_vlan2_vlan2)
Jun 16 02:27:16 rc_service: udhcpc 28403:notify_rc stop_upnp
Jun 16 02:27:16 rc_service: udhcpc 28403:notify_rc start_upnp
Jun 16 02:27:16 rc_service: waitting "stop_upnp" via udhcpc ...
Jun 16 02:27:18 dhcp_client: bound xxx.75.151.xxx via xxx.75.144.1 during 79249 seconds.
Jun 16 02:27:19 WAN_Connection: WAN was restored.


Any help most appreciated.

 

[ColinTaylor]

Looks like your cable modem (or whatever it is that's plugged into your router's WAN port) rebooted itself. Either that or you've got a faulty WAN Ethernet cable.

 

[Cain]

Looks like your cable modem (or whatever it is that's plugged into your router's WAN port) rebooted itself. Either that or you've got a faulty WAN Ethernet cable.

I was afraid of that! Thank you for the reply and help.

 

[Cain]

Replaced the cable, it lost internet connection again twice about the same time in the very early morning.

=======================================
6/16/2018 7:08:27 PM Log Start
---------------------------------------
Failure Start Length
6/17/2018 2:55:15 AM 0:00:10
6/17/2018 3:47:33 AM 0:02:56



I had changed the logging level, perhaps I need more detailed logging, but this is all the ASUS captured:

Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 9 for vlan2 mvlan_en 0
Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 2 for vlan2 mvlan_en 0
Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 13 for vlan2 mvlan_en 0
Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 1 for vlan2 mvlan_en 0
Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 16 17:45:21 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 17 02:48:33 kernel: et1: et_mvlan_netdev_event: event 9 for vlan2 mvlan_en 0
Jun 17 02:48:33 kernel: et1: et_mvlan_netdev_event: event 2 for vlan2 mvlan_en 0
Jun 17 02:48:33 kernel: et1: et_mvlan_netdev_event: event 13 for vlan2 mvlan_en 0
Jun 17 02:48:33 kernel: et1: et_mvlan_netdev_event: event 1 for vlan2 mvlan_en 0
Jun 17 02:48:33 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 17 02:48:34 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 9 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 2 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 13 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 1 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0
Jun 17 02:49:14 kernel: et1: et_mvlan_netdev_event: event 4 for vlan2 mvlan_en 0

--------------------

1) What logging levels should I set on my ASUS router to figure out what is happening? What Syslog level will give me the most information and detail?

2) Any thoughts why it reboots twice in between 2AM and 4AM each night??

Thx!

 

[ColinTaylor]

I think you need to investigate the cable modem not the router. Check the cable modem logs. If it's only happening in the middle of the night I'd say it sounds like your ISP is performing maintenance. Try asking your ISP.

 

[Cain]

Aha!! Very interesting and helpful, thank you for the suggestion and theory.

Can you tell me the highest detail log level for my Asus router, so I can set that?


Edit update, see my cable modem logs below, I think you may be correct about maintenance:

6-17-2018, 2:50:3 Notice(6) "TLV-11 - unrecognized OID;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"
6-17-2018, 2:50:2 Error(4) "Missing BP Configuration Setting TLV Type: 17.9;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"
6-17-2018, 2:50:2 Error(4) "Missing BP Configuration Setting TLV Type: 17.8;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"

6-17-2018, 2:48:21 Critical(3) "Received Response to Broadcast Maintenance Request, But no Unicast Maintenance opportunities received - T4 time out;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"
6-17-2018, 2:48:21 Critical(3) "No Ranging Response received - T3 time-out;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"
6-17-2018, 2:48:21 Critical(3) "Received Response to Broadcast Maintenance Request, But no Unicast Maintenance opportunities received - T4 time out;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"
6-17-2018, 2:47:49 Critical(3) "Started Unicast Maintenance Ranging - No Response received - T3 time-out;CM-MAC=bc:2e:48:fe:d5:99;CMTS-MAC=00:17:10:88:d4:e3;CM-QOS=1.1;CM-VER=3.1;"

 

[ColinTaylor]

Can you tell me the highest detail log level for my Asus router, so I can set that?

Debug.