Kogan NBN - Intermittent Dropouts, Don't Know if Its The Service or My Modem?

misterpotatomato on 09/03/2019 - 18:56
Last edited 09/03/2019 - 19:48

Hi all,

I went to Kogan NBN recently and I've been having weird dropouts (every 10 mins or so, I would say). These are my router logs…

This is a shorter, less verbose history. You can see every 10 minutes it loses eth3 (WAN) connectivity.

Mar 9 18:08:58 [RC]: Wan(eth3) Disconnect
Mar 9 18:08:58 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:08:58 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.73
Mar 9 18:09:02 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:09:02 [DNSMASQ]: failed to create listening socket for port 53: Address already in use
Mar 9 18:09:02 [DNSMASQ]: FAILED to start up
Mar 9 18:09:02 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:09:02
Mar 9 18:09:06 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
Mar 9 18:10:01 [RC]: Wan(eth3) Disconnect
Mar 9 18:10:01 [DNSMASQ]: failed to bind DHCP server socket: Address already in use
Mar 9 18:10:01 [DNSMASQ]: FAILED to start up
Mar 9 18:10:01 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:10:01 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:11:00 [RC]: Wan(eth3) Disconnect
Mar 9 18:11:00 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:11:00 [RC]: Current Wan interface is eth3 , mode is dhcp
Mar 9 18:11:00 [RC]: Wan(eth3) Disconnect
Mar 9 18:11:00 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:11:01 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.73
Mar 9 18:11:04 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:11:04 [DNSMASQ]: failed to bind DHCP server socket: Address already in use
Mar 9 18:11:04 [DNSMASQ]: FAILED to start up
Mar 9 18:11:05 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:11:05
Mar 9 18:11:10 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
Mar 9 18:12:45 [RC]: Wan(eth3) Disconnect
Mar 9 18:12:45 [RC]: Wan(eth3) Disconnect
Mar 9 18:12:45 [DNSMASQ]: failed to bind DHCP server socket: Address already in use
Mar 9 18:12:45 [DNSMASQ]: FAILED to start up
Mar 9 18:12:45 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:12:45 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:12:47 [RC]: Current Wan interface is eth3 , mode is static
Mar 9 18:12:48 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.73
Mar 9 18:12:51 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:12:51 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:12:51
Mar 9 18:22:50 [RC]: Wan(eth3) Disconnect
Mar 9 18:22:50 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:22:53 [RC]: Current Wan interface is eth3 , mode is dhcp
Mar 9 18:22:53 [RC]: Wan(eth3) Disconnect
Mar 9 18:22:53 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:22:54 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.218
Mar 9 18:22:57 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:22:57 [DNSMASQ]: failed to bind DHCP server socket: Address already in use
Mar 9 18:22:57 [DNSMASQ]: FAILED to start up
Mar 9 18:23:02 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
Mar 9 18:23:02 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:23:02
Mar 9 18:32:52 [RC]: Wan(eth3) Disconnect
Mar 9 18:32:52 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:32:53 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.218
Mar 9 18:32:56 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:32:56 [DNSMASQ]: failed to create listening socket for port 53: Address already in use
Mar 9 18:32:56 [DNSMASQ]: FAILED to start up
Mar 9 18:33:00 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
Mar 9 18:33:01 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:33:01
Mar 9 18:42:52 [RC]: Wan(eth3) Disconnect
Mar 9 18:42:52 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:42:54 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.218
Mar 9 18:42:57 ipsec_setup: …Openswan IPsec stopped
Mar 9 18:42:57 [DNSMASQ]: failed to bind DHCP server socket: Address already in use
Mar 9 18:42:57 [DNSMASQ]: FAILED to start up
Mar 9 18:42:58 [RC]: SNTP SYN success ! Current timer is 2019-03-09 18:42:58
Mar 9 18:43:02 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory

This is a longer, more verbose history

2019-03-09 17:10:42 udhcpc: Entering renew state
2019-03-09 17:10:42 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:10:42 udhcpc: adding option 0x35
2019-03-09 17:10:42 udhcpc: adding option 0x3d
2019-03-09 17:10:42 udhcpc: adding option 0x3c
2019-03-09 17:10:42 udhcpc: Sending renew…
2019-03-09 17:10:42 udhcpc: timeout=89293 udhcpc_main 446
2019-03-09 17:10:42 udhcpc: timeout=89293 udhcpc_main 344
2019-03-09 17:10:42 udhcpc: tv.tv_sec=112 udhcpc_main 347
2019-03-09 17:10:42 udhcpc: Opening listen socket on 0x00000000:68 eth3
2019-03-09 17:10:42 udhcpc: Waiting on select…
2019-03-09 17:12:34 udhcpc: now=89293 udhcpc_main 372
2019-03-09 17:12:34 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:12:34 udhcpc: adding option 0x35
2019-03-09 17:12:34 udhcpc: adding option 0x3d
2019-03-09 17:12:34 udhcpc: adding option 0x3c
2019-03-09 17:12:34 udhcpc: Sending renew…
2019-03-09 17:12:34 udhcpc: timeout=89349 udhcpc_main 446
2019-03-09 17:12:34 udhcpc: timeout=89349 udhcpc_main 344
2019-03-09 17:12:34 udhcpc: tv.tv_sec=56 udhcpc_main 347
2019-03-09 17:12:34 udhcpc: Waiting on select…
2019-03-09 17:13:30 udhcpc: now=89349 udhcpc_main 372
2019-03-09 17:13:30 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:13:30 udhcpc: adding option 0x35
2019-03-09 17:13:30 udhcpc: adding option 0x3d
2019-03-09 17:13:30 udhcpc: adding option 0x3c
2019-03-09 17:13:30 udhcpc: Sending renew…
2019-03-09 17:13:30 udhcpc: timeout=89377 udhcpc_main 446
2019-03-09 17:13:30 udhcpc: timeout=89377 udhcpc_main 344
2019-03-09 17:13:30 udhcpc: tv.tv_sec=28 udhcpc_main 347
2019-03-09 17:13:30 udhcpc: Waiting on select…
2019-03-09 17:13:58 udhcpc: now=89377 udhcpc_main 372
2019-03-09 17:13:58 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:13:58 udhcpc: adding option 0x35
2019-03-09 17:13:58 udhcpc: adding option 0x3d
2019-03-09 17:13:58 udhcpc: adding option 0x3c
2019-03-09 17:13:58 udhcpc: Sending renew…
2019-03-09 17:13:58 udhcpc: timeout=89391 udhcpc_main 446
2019-03-09 17:13:58 udhcpc: timeout=89391 udhcpc_main 344
2019-03-09 17:13:58 udhcpc: tv.tv_sec=14 udhcpc_main 347
2019-03-09 17:13:58 udhcpc: Waiting on select…
2019-03-09 17:14:12 udhcpc: now=89391 udhcpc_main 372
2019-03-09 17:14:12 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:14:12 udhcpc: adding option 0x35
2019-03-09 17:14:12 udhcpc: adding option 0x3d
2019-03-09 17:14:12 udhcpc: adding option 0x3c
2019-03-09 17:14:12 udhcpc: Sending renew…
2019-03-09 17:14:12 udhcpc: timeout=89398 udhcpc_main 446
2019-03-09 17:14:12 udhcpc: timeout=89398 udhcpc_main 344
2019-03-09 17:14:12 udhcpc: tv.tv_sec=7 udhcpc_main 347
2019-03-09 17:14:12 udhcpc: Waiting on select…
2019-03-09 17:14:19 udhcpc: now=89398 udhcpc_main 372
2019-03-09 17:14:19 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:14:19 udhcpc: adding option 0x35
2019-03-09 17:14:19 udhcpc: adding option 0x3d
2019-03-09 17:14:19 udhcpc: adding option 0x3c
2019-03-09 17:14:19 udhcpc: Sending renew…
2019-03-09 17:14:19 udhcpc: timeout=89402 udhcpc_main 446
2019-03-09 17:14:19 udhcpc: timeout=89402 udhcpc_main 344
2019-03-09 17:14:19 udhcpc: tv.tv_sec=4 udhcpc_main 347
2019-03-09 17:14:19 udhcpc: Waiting on select…
2019-03-09 17:14:23 udhcpc: now=89402 udhcpc_main 372
2019-03-09 17:14:23 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:14:23 udhcpc: adding option 0x35
2019-03-09 17:14:23 udhcpc: adding option 0x3d
2019-03-09 17:14:23 udhcpc: adding option 0x3c
2019-03-09 17:14:23 udhcpc: Sending renew…
2019-03-09 17:14:23 udhcpc: timeout=89404 udhcpc_main 446
2019-03-09 17:14:23 udhcpc: timeout=89404 udhcpc_main 344
2019-03-09 17:14:23 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:14:23 udhcpc: Waiting on select…
2019-03-09 17:14:25 udhcpc: now=89404 udhcpc_main 372
2019-03-09 17:14:25 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:14:25 udhcpc: adding option 0x35
2019-03-09 17:14:25 udhcpc: adding option 0x3d
2019-03-09 17:14:25 udhcpc: adding option 0x3c
2019-03-09 17:14:25 udhcpc: Sending renew…
2019-03-09 17:14:25 udhcpc: timeout=89405 udhcpc_main 446
2019-03-09 17:14:25 udhcpc: timeout=89405 udhcpc_main 344
2019-03-09 17:14:25 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:14:25 udhcpc: Waiting on select…
2019-03-09 17:14:26 udhcpc: now=89405 udhcpc_main 372
2019-03-09 17:14:26 udhcpc: timeout=89406 udhcpc_main 437
2019-03-09 17:14:26 udhcpc: Entering rebinding state
2019-03-09 17:14:26 udhcpc: timeout=89406 udhcpc_main 344
2019-03-09 17:14:26 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:14:26 udhcpc: Waiting on select…
2019-03-09 17:14:27 udhcpc: now=89406 udhcpc_main 372
2019-03-09 17:14:27 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:14:27 udhcpc: adding option 0x35
2019-03-09 17:14:27 udhcpc: adding option 0x3d
2019-03-09 17:14:27 udhcpc: adding option 0x3c
2019-03-09 17:14:27 udhcpc: Sending renew…
2019-03-09 17:14:27 udhcpc: timeout=89443 udhcpc_main 466
2019-03-09 17:14:27 udhcpc: timeout=89443 udhcpc_main 344
2019-03-09 17:14:27 udhcpc: tv.tv_sec=37 udhcpc_main 347
2019-03-09 17:14:27 udhcpc: Waiting on select…
2019-03-09 17:15:04 udhcpc: now=89443 udhcpc_main 372
2019-03-09 17:15:04 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:04 udhcpc: adding option 0x35
2019-03-09 17:15:04 udhcpc: adding option 0x3d
2019-03-09 17:15:04 udhcpc: adding option 0x3c
2019-03-09 17:15:04 udhcpc: Sending renew…
2019-03-09 17:15:04 udhcpc: timeout=89462 udhcpc_main 466
2019-03-09 17:15:04 udhcpc: timeout=89462 udhcpc_main 344
2019-03-09 17:15:04 udhcpc: tv.tv_sec=19 udhcpc_main 347
2019-03-09 17:15:04 udhcpc: Waiting on select…
2019-03-09 17:15:23 udhcpc: now=89462 udhcpc_main 372
2019-03-09 17:15:23 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:23 udhcpc: adding option 0x35
2019-03-09 17:15:23 udhcpc: adding option 0x3d
2019-03-09 17:15:23 udhcpc: adding option 0x3c
2019-03-09 17:15:23 udhcpc: Sending renew…
2019-03-09 17:15:23 udhcpc: timeout=89471 udhcpc_main 466
2019-03-09 17:15:23 udhcpc: timeout=89471 udhcpc_main 344
2019-03-09 17:15:23 udhcpc: tv.tv_sec=8 udhcpc_main 347
2019-03-09 17:15:23 udhcpc: Waiting on select…
2019-03-09 17:15:31 udhcpc: now=89471 udhcpc_main 372
2019-03-09 17:15:31 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:31 udhcpc: adding option 0x35
2019-03-09 17:15:31 udhcpc: adding option 0x3d
2019-03-09 17:15:31 udhcpc: adding option 0x3c
2019-03-09 17:15:31 udhcpc: Sending renew…
2019-03-09 17:15:31 udhcpc: timeout=89476 udhcpc_main 466
2019-03-09 17:15:31 udhcpc: timeout=89476 udhcpc_main 344
2019-03-09 17:15:31 udhcpc: tv.tv_sec=5 udhcpc_main 347
2019-03-09 17:15:31 udhcpc: Waiting on select…
2019-03-09 17:15:36 udhcpc: now=89476 udhcpc_main 372
2019-03-09 17:15:36 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:36 udhcpc: adding option 0x35
2019-03-09 17:15:36 udhcpc: adding option 0x3d
2019-03-09 17:15:36 udhcpc: adding option 0x3c
2019-03-09 17:15:36 udhcpc: Sending renew…
2019-03-09 17:15:36 udhcpc: timeout=89478 udhcpc_main 466
2019-03-09 17:15:36 udhcpc: timeout=89478 udhcpc_main 344
2019-03-09 17:15:36 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:15:36 udhcpc: Waiting on select…
2019-03-09 17:15:38 udhcpc: now=89478 udhcpc_main 372
2019-03-09 17:15:38 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:38 udhcpc: adding option 0x35
2019-03-09 17:15:38 udhcpc: adding option 0x3d
2019-03-09 17:15:38 udhcpc: adding option 0x3c
2019-03-09 17:15:38 udhcpc: Sending renew…
2019-03-09 17:15:38 udhcpc: timeout=89479 udhcpc_main 466
2019-03-09 17:15:38 udhcpc: timeout=89479 udhcpc_main 344
2019-03-09 17:15:38 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:15:38 udhcpc: Waiting on select…
2019-03-09 17:15:39 udhcpc: now=89479 udhcpc_main 372
2019-03-09 17:15:39 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:15:39 udhcpc: adding option 0x35
2019-03-09 17:15:39 udhcpc: adding option 0x3d
2019-03-09 17:15:39 udhcpc: adding option 0x3c
2019-03-09 17:15:39 udhcpc: Sending renew…
2019-03-09 17:15:39 udhcpc: timeout=89480 udhcpc_main 466
2019-03-09 17:15:39 udhcpc: timeout=89480 udhcpc_main 344
2019-03-09 17:15:39 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:15:39 udhcpc: Waiting on select…
2019-03-09 17:15:40 udhcpc: now=89480 udhcpc_main 372
2019-03-09 17:15:40 udhcpc: Lease lost, entering init state
2019-03-09 17:15:40 udhcpc: execle'ing /tmp/wan_udhcpc
2019-03-09 17:15:40 [RC]: Wan(eth3) Disconnect
2019-03-09 17:15:41 ipsec_setup: …Openswan IPsec stopped
2019-03-09 17:15:41 udhcpc: entering raw listen mode
2019-03-09 17:15:41 udhcpc: timeout=89480 udhcpc_main 344
2019-03-09 17:15:41 udhcpc: tv.tv_sec=0 udhcpc_main 347
2019-03-09 17:15:41 udhcpc: Opening raw socket on ifindex 9
2019-03-09 17:15:41 udhcpc: now=89480 udhcpc_main 372
2019-03-09 17:15:41 udhcpc: sending broadcast discover udhcpc_main 381
2019-03-09 17:15:41 udhcpc: adding option 0x35
2019-03-09 17:15:41 udhcpc: adding option 0x3d
2019-03-09 17:15:41 udhcpc: adding option 0x3c
2019-03-09 17:15:41 udhcpc: adding option 0x32
2019-03-09 17:15:41 udhcpc: timeout=89482 udhcpc_main 385
2019-03-09 17:15:41 udhcpc: timeout=89482 udhcpc_main 344
2019-03-09 17:15:41 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:15:41 udhcpc: Waiting on select…
2019-03-09 17:15:41 udhcpc: now=89480 udhcpc_main 372
2019-03-09 17:15:41 udhcpc: oooooh!!! got some!
2019-03-09 17:15:41 udhcpc: recieve messagetype=2 udhcpc_main 498
2019-03-09 17:15:41 udhcpc: timeout=89480 udhcpc_main 511
2019-03-09 17:15:41 udhcpc: timeout=89480 udhcpc_main 344
2019-03-09 17:15:41 udhcpc: tv.tv_sec=0 udhcpc_main 347
2019-03-09 17:15:41 udhcpc: now=89480 udhcpc_main 372
2019-03-09 17:15:41 udhcpc: adding option 0x35
2019-03-09 17:15:41 udhcpc: adding option 0x3d
2019-03-09 17:15:41 udhcpc: adding option 0x3c
2019-03-09 17:15:41 udhcpc: adding option 0x32
2019-03-09 17:15:41 udhcpc: adding option 0x36
2019-03-09 17:15:41 udhcpc: Sending select for x.x.x.x…
2019-03-09 17:15:41 udhcpc: timeout=89482 udhcpc_main 413
2019-03-09 17:15:41 udhcpc: timeout=89482 udhcpc_main 344
2019-03-09 17:15:41 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:15:41 udhcpc: Waiting on select…
2019-03-09 17:15:41 udhcpc: now=89480 udhcpc_main 372
2019-03-09 17:15:41 udhcpc: oooooh!!! got some!
2019-03-09 17:15:41 udhcpc: recieve messagetype=5 udhcpc_main 498
2019-03-09 17:15:41 udhcpc: Lease of x.x.x.x obtained, lease time 600
2019-03-09 17:15:41 udhcpc: timeout=89780 udhcpc_main 587
2019-03-09 17:15:41 udhcpc: execle'ing /tmp/wan_udhcpc
2019-03-09 17:15:41 [RC]: Wan(eth3) connect Success , Current Wan ip is x.x.x.x
2019-03-09 17:15:45 ipsec_setup: …Openswan IPsec stopped
2019-03-09 17:15:45 udhcpc: entering none listen mode
2019-03-09 17:15:45 udhcpc: timeout=89780 udhcpc_main 344
2019-03-09 17:15:45 udhcpc: tv.tv_sec=296 udhcpc_main 347
2019-03-09 17:15:45 udhcpc: Waiting on select…
2019-03-09 17:15:45 [RC]: SNTP SYN success ! Current timer is 2019-03-09 17:15:45
2019-03-09 17:15:48 [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
2019-03-09 17:20:41 udhcpc: now=89780 udhcpc_main 372
2019-03-09 17:20:41 udhcpc: entering kernel listen mode
2019-03-09 17:20:41 udhcpc: Entering renew state
2019-03-09 17:20:41 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:20:41 udhcpc: adding option 0x35
2019-03-09 17:20:41 udhcpc: adding option 0x3d
2019-03-09 17:20:41 udhcpc: adding option 0x3c
2019-03-09 17:20:41 udhcpc: Sending renew…
2019-03-09 17:20:41 udhcpc: timeout=89892 udhcpc_main 446
2019-03-09 17:20:41 udhcpc: timeout=89892 udhcpc_main 344
2019-03-09 17:20:41 udhcpc: tv.tv_sec=112 udhcpc_main 347
2019-03-09 17:20:41 udhcpc: Opening listen socket on 0x00000000:68 eth3
2019-03-09 17:20:41 udhcpc: Waiting on select…
2019-03-09 17:22:33 udhcpc: now=89892 udhcpc_main 372
2019-03-09 17:22:33 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:22:33 udhcpc: adding option 0x35
2019-03-09 17:22:33 udhcpc: adding option 0x3d
2019-03-09 17:22:33 udhcpc: adding option 0x3c
2019-03-09 17:22:33 udhcpc: Sending renew…
2019-03-09 17:22:33 udhcpc: timeout=89948 udhcpc_main 446
2019-03-09 17:22:33 udhcpc: timeout=89948 udhcpc_main 344
2019-03-09 17:22:33 udhcpc: tv.tv_sec=56 udhcpc_main 347
2019-03-09 17:22:33 udhcpc: Waiting on select…
2019-03-09 17:23:29 udhcpc: now=89948 udhcpc_main 372
2019-03-09 17:23:29 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:23:29 udhcpc: adding option 0x35
2019-03-09 17:23:29 udhcpc: adding option 0x3d
2019-03-09 17:23:29 udhcpc: adding option 0x3c
2019-03-09 17:23:29 udhcpc: Sending renew…
2019-03-09 17:23:29 udhcpc: timeout=89976 udhcpc_main 446
2019-03-09 17:23:29 udhcpc: timeout=89976 udhcpc_main 344
2019-03-09 17:23:29 udhcpc: tv.tv_sec=28 udhcpc_main 347
2019-03-09 17:23:29 udhcpc: Waiting on select…
2019-03-09 17:23:57 udhcpc: now=89976 udhcpc_main 372
2019-03-09 17:23:57 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:23:57 udhcpc: adding option 0x35
2019-03-09 17:23:57 udhcpc: adding option 0x3d
2019-03-09 17:23:57 udhcpc: adding option 0x3c
2019-03-09 17:23:57 udhcpc: Sending renew…
2019-03-09 17:23:57 udhcpc: timeout=89990 udhcpc_main 446
2019-03-09 17:23:57 udhcpc: timeout=89990 udhcpc_main 344
2019-03-09 17:23:57 udhcpc: tv.tv_sec=14 udhcpc_main 347
2019-03-09 17:23:57 udhcpc: Waiting on select…
2019-03-09 17:24:11 udhcpc: now=89991 udhcpc_main 372
2019-03-09 17:24:11 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:24:11 udhcpc: adding option 0x35
2019-03-09 17:24:11 udhcpc: adding option 0x3d
2019-03-09 17:24:11 udhcpc: adding option 0x3c
2019-03-09 17:24:11 udhcpc: Sending renew…
2019-03-09 17:24:11 udhcpc: timeout=89997 udhcpc_main 446
2019-03-09 17:24:11 udhcpc: timeout=89997 udhcpc_main 344
2019-03-09 17:24:11 udhcpc: tv.tv_sec=6 udhcpc_main 347
2019-03-09 17:24:11 udhcpc: Waiting on select…
2019-03-09 17:24:17 udhcpc: now=89997 udhcpc_main 372
2019-03-09 17:24:17 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:24:17 udhcpc: adding option 0x35
2019-03-09 17:24:17 udhcpc: adding option 0x3d
2019-03-09 17:24:17 udhcpc: adding option 0x3c
2019-03-09 17:24:17 udhcpc: Sending renew…
2019-03-09 17:24:17 udhcpc: timeout=90001 udhcpc_main 446
2019-03-09 17:24:17 udhcpc: timeout=90001 udhcpc_main 344
2019-03-09 17:24:17 udhcpc: tv.tv_sec=4 udhcpc_main 347
2019-03-09 17:24:17 udhcpc: Waiting on select…
2019-03-09 17:24:21 udhcpc: now=90001 udhcpc_main 372
2019-03-09 17:24:21 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:24:21 udhcpc: adding option 0x35
2019-03-09 17:24:21 udhcpc: adding option 0x3d
2019-03-09 17:24:21 udhcpc: adding option 0x3c
2019-03-09 17:24:21 udhcpc: Sending renew…
2019-03-09 17:24:21 udhcpc: timeout=90003 udhcpc_main 446
2019-03-09 17:24:21 udhcpc: timeout=90003 udhcpc_main 344
2019-03-09 17:24:21 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:24:21 udhcpc: Waiting on select…
2019-03-09 17:24:23 udhcpc: now=90003 udhcpc_main 372
2019-03-09 17:24:23 udhcpc: sending unicast renew udhcpc_main 441
2019-03-09 17:24:23 udhcpc: adding option 0x35
2019-03-09 17:24:23 udhcpc: adding option 0x3d
2019-03-09 17:24:23 udhcpc: adding option 0x3c
2019-03-09 17:24:23 udhcpc: Sending renew…
2019-03-09 17:24:23 udhcpc: timeout=90004 udhcpc_main 446
2019-03-09 17:24:23 udhcpc: timeout=90004 udhcpc_main 344
2019-03-09 17:24:23 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:24:23 udhcpc: Waiting on select…
2019-03-09 17:24:24 udhcpc: now=90004 udhcpc_main 372
2019-03-09 17:24:24 udhcpc: timeout=90005 udhcpc_main 437
2019-03-09 17:24:24 udhcpc: Entering rebinding state
2019-03-09 17:24:24 udhcpc: timeout=90005 udhcpc_main 344
2019-03-09 17:24:24 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:24:24 udhcpc: Waiting on select…
2019-03-09 17:24:25 udhcpc: now=90005 udhcpc_main 372
2019-03-09 17:24:25 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:24:25 udhcpc: adding option 0x35
2019-03-09 17:24:25 udhcpc: adding option 0x3d
2019-03-09 17:24:25 udhcpc: adding option 0x3c
2019-03-09 17:24:25 udhcpc: Sending renew…
2019-03-09 17:24:25 udhcpc: timeout=90042 udhcpc_main 466
2019-03-09 17:24:25 udhcpc: timeout=90042 udhcpc_main 344
2019-03-09 17:24:25 udhcpc: tv.tv_sec=37 udhcpc_main 347
2019-03-09 17:24:25 udhcpc: Waiting on select…
2019-03-09 17:25:02 udhcpc: now=90042 udhcpc_main 372
2019-03-09 17:25:02 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:02 udhcpc: adding option 0x35
2019-03-09 17:25:02 udhcpc: adding option 0x3d
2019-03-09 17:25:02 udhcpc: adding option 0x3c
2019-03-09 17:25:02 udhcpc: Sending renew…
2019-03-09 17:25:02 udhcpc: timeout=90061 udhcpc_main 466
2019-03-09 17:25:02 udhcpc: timeout=90061 udhcpc_main 344
2019-03-09 17:25:02 udhcpc: tv.tv_sec=19 udhcpc_main 347
2019-03-09 17:25:02 udhcpc: Waiting on select…
2019-03-09 17:25:21 udhcpc: now=90061 udhcpc_main 372
2019-03-09 17:25:21 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:21 udhcpc: adding option 0x35
2019-03-09 17:25:21 udhcpc: adding option 0x3d
2019-03-09 17:25:21 udhcpc: adding option 0x3c
2019-03-09 17:25:21 udhcpc: Sending renew…
2019-03-09 17:25:21 udhcpc: timeout=90070 udhcpc_main 466
2019-03-09 17:25:21 udhcpc: timeout=90070 udhcpc_main 344
2019-03-09 17:25:21 udhcpc: tv.tv_sec=9 udhcpc_main 347
2019-03-09 17:25:21 udhcpc: Waiting on select…
2019-03-09 17:25:30 udhcpc: now=90070 udhcpc_main 372
2019-03-09 17:25:30 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:30 udhcpc: adding option 0x35
2019-03-09 17:25:30 udhcpc: adding option 0x3d
2019-03-09 17:25:30 udhcpc: adding option 0x3c
2019-03-09 17:25:30 udhcpc: Sending renew…
2019-03-09 17:25:30 udhcpc: timeout=90075 udhcpc_main 466
2019-03-09 17:25:30 udhcpc: timeout=90075 udhcpc_main 344
2019-03-09 17:25:30 udhcpc: tv.tv_sec=5 udhcpc_main 347
2019-03-09 17:25:30 udhcpc: Waiting on select…
2019-03-09 17:25:35 udhcpc: now=90075 udhcpc_main 372
2019-03-09 17:25:35 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:35 udhcpc: adding option 0x35
2019-03-09 17:25:35 udhcpc: adding option 0x3d
2019-03-09 17:25:35 udhcpc: adding option 0x3c
2019-03-09 17:25:35 udhcpc: Sending renew…
2019-03-09 17:25:36 udhcpc: timeout=90077 udhcpc_main 466
2019-03-09 17:25:36 udhcpc: timeout=90077 udhcpc_main 344
2019-03-09 17:25:36 udhcpc: tv.tv_sec=2 udhcpc_main 347
2019-03-09 17:25:36 udhcpc: Waiting on select…
2019-03-09 17:25:38 udhcpc: now=90077 udhcpc_main 372
2019-03-09 17:25:38 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:38 udhcpc: adding option 0x35
2019-03-09 17:25:38 udhcpc: adding option 0x3d
2019-03-09 17:25:38 udhcpc: adding option 0x3c
2019-03-09 17:25:38 udhcpc: Sending renew…
2019-03-09 17:25:38 udhcpc: timeout=90078 udhcpc_main 466
2019-03-09 17:25:38 udhcpc: timeout=90078 udhcpc_main 344
2019-03-09 17:25:38 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:25:38 udhcpc: Waiting on select…
2019-03-09 17:25:39 udhcpc: now=90078 udhcpc_main 372
2019-03-09 17:25:39 udhcpc: sending broadcast renew udhcpc_main 461
2019-03-09 17:25:39 udhcpc: adding option 0x35
2019-03-09 17:25:39 udhcpc: adding option 0x3d
2019-03-09 17:25:39 udhcpc: adding option 0x3c
2019-03-09 17:25:39 udhcpc: Sending renew…
2019-03-09 17:25:39 udhcpc: timeout=90079 udhcpc_main 466
2019-03-09 17:25:39 udhcpc: timeout=90079 udhcpc_main 344
2019-03-09 17:25:39 udhcpc: tv.tv_sec=1 udhcpc_main 347
2019-03-09 17:25:39 udhcpc: Waiting on select…
2019-03-09 17:25:40 udhcpc: now=90079 udhcpc_main 372
2019-03-09 17:25:40 udhcpc: Lease lost, entering init state
2019-03-09 17:25:40 udhcpc: execle'ing /tmp/wan_udhcpc
2019-03-09 17:25:40 [RC]: Wan(eth3) Disconnect

Some points:

The lease time on the IP address is "600" (I assume, seconds… so 6 minutes? Is this a little short?)
It looks like when my router loses the lease it disconnects the WAN port, or something?

The main reason I notice is because I'll be watching Stan or playing Xbox and it will just disconnect and then reconnect ~10 seconds later. It boots me out of xbox online games which is pretty frustrating…

Any thoughts or suggestions?

Internet Broadband nbn

Related Stores

Kogan Internet

Comments

altomic on 09/03/2019 - 19:14

+2

needs a TL:DR.

but seriously, NFI.

have you logged the issue with kogan?

what model is your router?
- misterpotatomato on 09/03/2019 - 19:16
  
  +1
  
  DLINK DIR-882
  
  Edit: haven't logged with Kogan yet. I can't imagine trying to explain "My DHCP lease is short" to their support team…
  - Diji1 on 09/03/2019 - 22:35
    
    You can change this yourself assuming it isn't blocked somehow.
    
    Google for the model and how to change DHCP lease. If you cannot find the exact model similar models from same brand will usually be the same.
[Deactivated] on 09/03/2019 - 19:18

Does your phone line cable move much? Mine seems to disconnect if the wire is moved just a tiny bit.
- misterpotatomato on 09/03/2019 - 19:18
  
  HFC connected, I would say its fairly static.
Trung on 09/03/2019 - 21:46

+1

Your lease is 10 minutes, so your router tries to renew after 5 minutes, but seems to timeout too quickly.
Are you running the latest firmware?
The latest "Improve interoperability with VDSL modems", maybe that'll help.
Cartman2530 on 09/03/2019 - 21:52

+1

Check all connections first as a matter of course. Disconnect every network device except the PC you are using to diagnose, including all wifi devices.

"[DNSMASQ]: FAILED to start up"
"[DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory"

These lines are of interest, maybe a factory reset of the router may be in order as there may be something wrong or corrupted with the configuration. Restore the router to factory FIRST, then check for any firmware updates and install them if there are.

If its FTTN you may need to save your VDSL config settings first before doing anything. Hopefully Kogan has a web page that will be able to guide you through which settings you need.
- Trung on 09/03/2019 - 22:48
  
  Sorry to contradict, and please correct me if I'm wrong.
  
  [DNSMASQ]: FAILED to start up
  
  This is the error the DNS forwarder throws after the DHCP lease ended.
  It then runs fine after connection restored.
  
  [DHCPS-6]: dhcp6_ctl_authinit: failed to open /var/etc/dhcp6sctlkey: No such file or directory
  
  This is because the router cannot obtain IPv6 from NBN modem.
  Most likely because the modem does not assign IPv6.
  Setting IPv6 to Local Only should stop this from showing up in log at connection and every 30mins after.
  - Cartman2530 on 10/03/2019 - 15:47
    
    Fair enough, but…
    
    The first a DHCP lease config problem, the second a IPv6 config problem. Do you agree that some settings must be changed to fix it? That's what i was getting at and hopefully a firmware reset/upgrade would fix that. Thats usually been my way to fix router problems with disconnects and its worked for me unless there is a fault with the hardware itself.
    Your first thread post above mine seems to agree with me on that as well.
    
    Can you tell the OP what settings must be changed to fix the problem then?
    - Trung on 10/03/2019 - 21:41
      
      Yeah, absolutely agree with your way of troubleshooting.
      
      I just meant to point out that those 2 log entries are quite.. routine (not sure if that's the best word)
      
      I read in some forums that changing IPv6 to Local Only does help with stability.
      OP can try that if they ever want to switch back to the DIR-882.
      (But why go back to it when they got an Orbi.)
      
      But because I've seen a few D-Link routers running perfectly fine with all those IPv6 stuff in the log, I didn't think it would help OP in this case.
      
      My best guess was firmware, it seems to not able to renew DHCP lease, which I've only ever been able to fix by updating firmware.
OZB Al on 10/03/2019 - 09:57

ipsec_setup: …Openswan IPsec stopped

Are you using a VPN?

When your HFC was installed did they use an existing lead in cable and wall socket? This is what happened to me and I had three months of arguing with nbn co before they got the lead in cable replaced. The guy who replaced it showed me the three cm section of the old cable where the outer insulation was gone. I was getting regular drop outs before the cable was replaced.
- misterpotatomato on 10/03/2019 - 12:02
  
  How long would your drop out last?
  
  Originally it was fine. Then I did two things in fairly close succession…
  
  Changed to Kogan NBN (from Aussie BB)
  Upgraded my router firmware
  
  It could be Kogan and shorter DHCP routers messing it up. I really want to find a good deal on an rbk50 Netgear orbi but they are really expensive at the moment ☹️
misterpotatomato on 10/03/2019 - 20:02

+2

I switched my DLink DIR 882 router for a Netgear Orbi and now its rock solid. I think the D-Link was rubbish to be honest.

Kogan NBN - Intermittent Dropouts, Don't Know if Its The Service or My Modem?

Related Stores

Comments

New Forum Topics