Thu Jan 20 17:26:08 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:27:08 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:27:38 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 17:27:38 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 17:27:54 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:28:08 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:29:09 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:29:59 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:30:09 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:31:09 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:31:49 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:32:09 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:32:29 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 17:32:29 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 17:33:10 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:33:46 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:34:10 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:35:10 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:35:53 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:36:11 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:36:56 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 17:36:56 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 17:37:11 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:37:50 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:38:11 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:39:11 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:39:45 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:40:12 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:41:12 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:41:17 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 17:41:17 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 17:41:42 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 17:41:42 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 17:41:54 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:42:12 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:43:12 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:44:02 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:44:13 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:45:13 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:45:56 2022 daemon.err odhcp6c[5462]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:46:13 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:47:13 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:47:20 2022 daemon.info dnsmasq-dhcp[5677]: DHCPINFORM(br-lan) 10.0.0.141 [redacted] Thu Jan 20 17:47:20 2022 daemon.info dnsmasq-dhcp[5677]: DHCPACK(br-lan) 10.0.0.141 [redacted] Thu Jan 20 17:47:27 2022 kern.crit kernel: [ 3036.961000] Line 0: xDSL link down Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.964000] bcmxtmcfg: AutoSenseATM is not enabled. Link Traffic type from PHY=1 Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.972000] bcmxtmcfg: XTM Link Information, port = 0, State = DOWN(2), Service Support = ATM(1) Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.981000] HOST XTM tx ch 0 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.985000] HOST XTM tx ch 1 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.989000] HOST XTM tx ch 2 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.993000] HOST XTM tx ch 3 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3036.997000] HOST XTM tx ch 4 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.000000] HOST XTM tx ch 5 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.004000] HOST XTM tx ch 6 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.008000] HOST XTM tx ch 7 disabled. Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.012000] bcmxtmcfg: Connection DOWN, LinkActiveStatus=0x0 Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.018000] bcmxtmcfg: ReconfigureSAR port 0 traffictype 0 Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.024000] bcmxtmcfg: Normal(XTM/PTM) Mode enabled Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.029000] bcmxtmcfg: DS xDSL G.inp Mode = DISABLED Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.034000] bcmxtmcfg: xDSL G.Fast Mode = DISABLED Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.039000] bcmxtmcfg: ATM Bonding configured in system. Fallback mode = Enabled Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.047000] bcmxtmcfg: Bonding State is DATA_IDLE Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.052000] bcmxtmcfg: SID MODE SET to 12 BIT MODE Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.057000] bcmxtmcfg: ATM Bonding Mgmt Log Area = 883b4514 Thu Jan 20 17:47:27 2022 kern.warn kernel: [ 3037.062000] bcmxtmcfg: gulXtmMonitorValid(0xc0cf9dc4) = 1 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on internet:green Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:green with action none Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action none Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on runFunc Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:47:27 2022 daemon.notice netifd: Network device 'atm0' link is down Thu Jan 20 17:47:27 2022 daemon.notice netifd: Interface 'wan' has link connectivity loss Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action netdev Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting device_name to eth4 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting mode to link Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on power:orange Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:orange with action none Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on power:red Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:red with action none Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on power:blue Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:blue with action none Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: applying action on power:green Thu Jan 20 17:47:27 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:green with action none Thu Jan 20 17:47:28 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server Thu Jan 20 17:47:28 2022 daemon.err cwmpd[4357]: APP_TRACE: bad address 'nld-acs.com' Thu Jan 20 17:47:28 2022 daemon.crit cwmpd[4357]: CONNECTION: Failed to resolve. Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 1 Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 5 Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 10 Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 9 Thu Jan 20 17:47:28 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:47:28 2022 daemon.notice netifd: Interface 'wan6' is now down Thu Jan 20 17:47:28 2022 daemon.notice netifd: Interface 'wan6' is disabled Thu Jan 20 17:47:28 2022 daemon.notice netifd: Network alias '' link is down Thu Jan 20 17:47:28 2022 daemon.notice netifd: Interface 'wan6' has link connectivity loss Thu Jan 20 17:47:28 2022 daemon.warn dnsmasq[5677]: no servers found in /tmp/resolv.conf.auto, will retry Thu Jan 20 17:47:28 2022 daemon.info pppd[5388]: Terminating on signal 15 Thu Jan 20 17:47:28 2022 daemon.notice pppd[5388]: Connection state changed to disconnecting (phase 9) Thu Jan 20 17:47:28 2022 daemon.info pppd[5388]: Connect time 47.6 minutes. Thu Jan 20 17:47:28 2022 daemon.info pppd[5388]: Sent 39749232 bytes, received 178839863 bytes. Thu Jan 20 17:47:28 2022 daemon.notice netifd: Network device 'pppoa-wan' link is down Thu Jan 20 17:47:29 2022 user.notice intercept: [7888] setup (action=ifchanged) Thu Jan 20 17:47:29 2022 user.notice intercept: [7888] an interface changed, test default route via observed interface Thu Jan 20 17:47:30 2022 user.notice intercept: [7888] WAN connectivity 0 Thu Jan 20 17:47:30 2022 user.notice intercept: [7888] intercept_set_state(1) Thu Jan 20 17:47:30 2022 user.notice intercept: [7888] reconfigure active from 0 to 1 Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.560000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.565000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.572000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.578000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.591000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.621000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 user.notice intercept: [7888] flushed nointercept[46] ipsets Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.633000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.639000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.655000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.696000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.715000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 kern.warn kernel: [ 3039.727000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:47:30 2022 user.notice intercept: [7888] populated nointercept[46] ipsets Thu Jan 20 17:47:31 2022 user.notice pppoe-relay-hotplug: Interface wan ifdown Thu Jan 20 17:47:31 2022 daemon.info dnsmasq[5677]: exiting on receipt of SIGTERM Thu Jan 20 17:47:33 2022 user.notice intercept: [7888] completely handled 'ifchanged' Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: started, version 2.78 cachesize 150 Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP conntrack dns-filterno-ipset no-auth no-DNSSEC no-ID loop-detect inotify Thu Jan 20 17:47:33 2022 daemon.info dnsmasq-dhcp[8093]: DHCP, IP range 10.0.0.201 -- 10.0.0.220, lease time 12h Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain test Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain onion Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain localhost Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain local Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain invalid Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.net Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.org Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.com Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: using 3 more local addresses Thu Jan 20 17:47:33 2022 daemon.warn dnsmasq[8093]: no servers found in /tmp/resolv.conf.auto, will retry Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: read /etc/hosts - 1 addresses Thu Jan 20 17:47:33 2022 daemon.info dnsmasq[8093]: read /tmp/hosts/dhcp.cfg02411c - 2 addresses Thu Jan 20 17:47:33 2022 daemon.info dnsmasq-dhcp[8093]: read /etc/ethers - 0 addresses Thu Jan 20 17:47:34 2022 kern.crit kernel: [ 3043.317000] Line 0: xDSL G.994 training Thu Jan 20 17:47:34 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:47:34 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action none Thu Jan 20 17:47:34 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 1. Thu Jan 20 17:47:37 2022 daemon.err cwmpd[4357]: UBUS_CLIENT: Failed to retrieve external IP address Thu Jan 20 17:47:37 2022 daemon.err cwmpd[4357]: SOCK_TRACE: cwmp_createClientSocket - no ip to bind to on interface wan - fail Thu Jan 20 17:47:37 2022 daemon.crit cwmpd[4357]: CONNECTION: Failed to create socket. Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 2 Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 10 Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 20 Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 19 Thu Jan 20 17:47:37 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:47:45 2022 kern.crit kernel: [ 3054.325000] Line 0: ADSL G.992 started Thu Jan 20 17:47:45 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:47:45 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action timer Thu Jan 20 17:47:45 2022 daemon.debug ledfw[1276]: setting delay_on to 125 Thu Jan 20 17:47:45 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:47:45 2022 daemon.debug ledfw[1276]: setting delay_off to 125 Thu Jan 20 17:47:49 2022 kern.crit kernel: [ 3058.331000] Line 0: ADSL G.992 channel analysis Thu Jan 20 17:47:50 2022 kern.crit kernel: [ 3059.335000] Line 0: xDSL link down Thu Jan 20 17:47:50 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:47:50 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action netdev Thu Jan 20 17:47:50 2022 daemon.debug ledfw[1276]: setting device_name to eth4 Thu Jan 20 17:47:50 2022 daemon.debug ledfw[1276]: setting mode to link Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 2. Thu Jan 20 17:47:56 2022 daemon.err cwmpd[4357]: UBUS_CLIENT: Failed to retrieve external IP address Thu Jan 20 17:47:56 2022 daemon.err cwmpd[4357]: SOCK_TRACE: cwmp_createClientSocket - no ip to bind to on interface wan - fail Thu Jan 20 17:47:56 2022 daemon.crit cwmpd[4357]: CONNECTION: Failed to create socket. Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 3 Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 20 Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 40 Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 27 Thu Jan 20 17:47:56 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:47:58 2022 daemon.warn nginx: 2022/01/20 17:47:57 [warn] 4527#0: *44 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.141, server: localhost, request: "GET /httpi.lp?url=x1.c.lencr.org HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:10 2022 daemon.warn nginx: 2022/01/20 17:48:10 [warn] 4527#0: *67 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:12 2022 daemon.warn nginx: 2022/01/20 17:48:12 [warn] 4527#0: *68 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:12 2022 daemon.warn nginx: 2022/01/20 17:48:12 [warn] 4527#0: *69 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:12 2022 daemon.warn nginx: 2022/01/20 17:48:12 [warn] 4527#0: *68 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=www.msftconnecttest.com HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:14 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:48:16 2022 kern.crit kernel: [ 3085.350000] Line 0: xDSL G.994 training Thu Jan 20 17:48:16 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:48:16 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action none Thu Jan 20 17:48:16 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:48:20 2022 daemon.warn nginx: 2022/01/20 17:48:20 [warn] 4527#0: *67 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:20 2022 daemon.warn nginx: 2022/01/20 17:48:20 [warn] 4527#0: *74 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:20 2022 daemon.warn nginx: 2022/01/20 17:48:20 [warn] 4527#0: *69 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 3. Thu Jan 20 17:48:23 2022 daemon.err cwmpd[4357]: UBUS_CLIENT: Failed to retrieve external IP address Thu Jan 20 17:48:23 2022 daemon.err cwmpd[4357]: SOCK_TRACE: cwmp_createClientSocket - no ip to bind to on interface wan - fail Thu Jan 20 17:48:23 2022 daemon.crit cwmpd[4357]: CONNECTION: Failed to create socket. Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 4 Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 40 Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 80 Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 59 Thu Jan 20 17:48:23 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:48:26 2022 kern.crit kernel: [ 3095.357000] Line 0: ADSL G.992 started Thu Jan 20 17:48:26 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:48:26 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action timer Thu Jan 20 17:48:26 2022 daemon.debug ledfw[1276]: setting delay_on to 125 Thu Jan 20 17:48:26 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:48:26 2022 daemon.debug ledfw[1276]: setting delay_off to 125 Thu Jan 20 17:48:31 2022 kern.crit kernel: [ 3100.364000] Line 0: ADSL G.992 channel analysis Thu Jan 20 17:48:38 2022 kern.crit kernel: [ 3107.374000] Line 0: xDSL link down Thu Jan 20 17:48:38 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:48:38 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action netdev Thu Jan 20 17:48:38 2022 daemon.debug ledfw[1276]: setting device_name to eth4 Thu Jan 20 17:48:38 2022 daemon.debug ledfw[1276]: setting mode to link Thu Jan 20 17:48:45 2022 kern.crit kernel: [ 3114.380000] Line 0: xDSL G.994 training Thu Jan 20 17:48:45 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:48:45 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action none Thu Jan 20 17:48:45 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:48:54 2022 daemon.warn nginx: 2022/01/20 17:48:54 [warn] 4527#0: *74 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.215, server: localhost, request: "GET /httpi.lp?url=wpad HTTP/1.1", host: "10.0.0.1" Thu Jan 20 17:48:56 2022 kern.crit kernel: [ 3125.389000] Line 0: ADSL G.992 started Thu Jan 20 17:48:56 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:48:56 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action timer Thu Jan 20 17:48:56 2022 daemon.debug ledfw[1276]: setting delay_on to 125 Thu Jan 20 17:48:56 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:48:56 2022 daemon.debug ledfw[1276]: setting delay_off to 125 Thu Jan 20 17:49:01 2022 kern.crit kernel: [ 3130.395000] Line 0: ADSL G.992 channel analysis Thu Jan 20 17:49:07 2022 kern.crit kernel: [ 3136.404000] Line 0: xDSL link down Thu Jan 20 17:49:07 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:49:07 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action netdev Thu Jan 20 17:49:07 2022 daemon.debug ledfw[1276]: setting device_name to eth4 Thu Jan 20 17:49:07 2022 daemon.debug ledfw[1276]: setting mode to link Thu Jan 20 17:49:12 2022 kern.crit kernel: [ 3141.408000] Line 0: xDSL G.994 training Thu Jan 20 17:49:12 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:49:12 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action none Thu Jan 20 17:49:12 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:14 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:49:14 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 17:49:14 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 4. Thu Jan 20 17:49:22 2022 daemon.err cwmpd[4357]: UBUS_CLIENT: Failed to retrieve external IP address Thu Jan 20 17:49:22 2022 daemon.err cwmpd[4357]: SOCK_TRACE: cwmp_createClientSocket - no ip to bind to on interface wan - fail Thu Jan 20 17:49:22 2022 daemon.crit cwmpd[4357]: CONNECTION: Failed to create socket. Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 5 Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 80 Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 160 Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 148 Thu Jan 20 17:49:22 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:49:23 2022 kern.crit kernel: [ 3152.412000] Line 0: ADSL G.992 started Thu Jan 20 17:49:23 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:49:23 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action timer Thu Jan 20 17:49:23 2022 daemon.debug ledfw[1276]: setting delay_on to 125 Thu Jan 20 17:49:23 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:23 2022 daemon.debug ledfw[1276]: setting delay_off to 125 Thu Jan 20 17:49:27 2022 kern.crit kernel: [ 3156.416000] Line 0: ADSL G.992 channel analysis Thu Jan 20 17:49:34 2022 kern.crit kernel: [ 3163.424000] Line 0: ADSL G.992 message exchange Thu Jan 20 17:49:34 2022 daemon.notice netifd: Network device 'atm0' link is up Thu Jan 20 17:49:34 2022 daemon.notice netifd: Interface 'wan' has link connectivity Thu Jan 20 17:49:34 2022 daemon.notice netifd: Interface 'wan' is setting up now Thu Jan 20 17:49:34 2022 kern.crit kernel: [ 3163.538000] Line 0: ADSL link up, Bearer 0, us=1173, ds=12375 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.543000] bcmxtmcfg: DS xDSL G.inp Mode = DISABLED Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.548000] bcmxtmcfg: xDSL G.Fast Mode = DISABLED Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.553000] bcmxtmcfg: AutoSenseATM is not enabled. Link Traffic type from PHY=1 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.561000] bcmxtmcfg: XTM Link Information, port = 0, State = UP(1), Service Support = ATM(1) Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.570000] bcmxtmcfg: ReconfigureSAR port 0 traffictype 1 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.575000] bcmxtmcfg: Normal(XTM/PTM) Mode enabled Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.581000] bcmxtmcfg: Reserve TxQueueIdx=0 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.586000] bcmxtmcfg: Reserve TxQueueIdx=1 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.591000] bcmxtmcfg: Reserve TxQueueIdx=2 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.596000] bcmxtmcfg: Reserve TxQueueIdx=3 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.602000] bcmxtmcfg: Reserve TxQueueIdx=4 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.607000] bcmxtmcfg: Reserve TxQueueIdx=5 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.612000] bcmxtmcfg: Reserve TxQueueIdx=6 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.618000] bcmxtmcfg: Reserve TxQueueIdx=7 for vcid 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.624000] XTM Init: Ch:0 - 400 tx BDs at 0xaf868000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.631000] XTM Init: Ch:1 - 400 tx BDs at 0xa7fac000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.638000] XTM Init: Ch:2 - 400 tx BDs at 0xa40c4000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.645000] XTM Init: Ch:3 - 400 tx BDs at 0xa7318000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.652000] XTM Init: Ch:4 - 400 tx BDs at 0xa7054000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.659000] XTM Init: Ch:5 - 400 tx BDs at 0xa7330000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.667000] XTM Init: Ch:6 - 400 tx BDs at 0xaf16c000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.674000] XTM Init: Ch:7 - 400 tx BDs at 0xaf854000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.682000] bcmxtmcfg: Connection UP, LinkActiveStatus=0x1, US=1173000, DS=12375000 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.689000] [FAP1] xtmLinkUp : devId 1, matchId 0 Thu Jan 20 17:49:34 2022 kern.warn kernel: [ 3163.694000] [FAP0] xtmLinkUp : devId 1, matchId 0 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: applying action on internet:green Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:green with action none Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action timer Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting delay_on to 498 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting delay_off to 502 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action timer Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting delay_on to 499 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting delay_off to 501 Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: applying action on broadband:green Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/broadband:green with action none Thu Jan 20 17:49:34 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:34 2022 daemon.info pppd[8410]: Plugin connstate.so loaded. Thu Jan 20 17:49:34 2022 daemon.info pppd[8410]: Plugin pppoatm-brcm.so loaded. Thu Jan 20 17:49:34 2022 daemon.info pppd[8410]: PPPoATM plugin_init Thu Jan 20 17:49:34 2022 daemon.info pppd[8410]: PPPoATM setdevname_pppoatm - SUCCESS:atm0 Thu Jan 20 17:49:34 2022 daemon.notice pppd[8410]: pppd 2.4.7 started by root, uid 0 Thu Jan 20 17:49:34 2022 daemon.notice pppd[8410]: Connection state changed to connecting (phase 2) Thu Jan 20 17:49:35 2022 kern.warn kernel: [ 3164.138000] netdev path : ppp0 -> atm0 Thu Jan 20 17:49:35 2022 daemon.err pppd[8410]: Couldn't attach to PPP unit 0: Invalid argument Thu Jan 20 17:49:35 2022 daemon.notice pppd[8410]: Connection state changed to disconnecting (phase 10) Thu Jan 20 17:49:35 2022 daemon.info pppd[8410]: PPPoATM disconnect_pppoatm - SUCCESS Thu Jan 20 17:49:35 2022 daemon.notice pppd[8410]: Connection state changed to disconnected (phase 0) Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: applying action on internet:green Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:green with action none Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action none Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:35 2022 daemon.debug ledfw[1276]: applying action on runFunc Thu Jan 20 17:49:35 2022 daemon.info pppd[8410]: Exit. Thu Jan 20 17:49:35 2022 daemon.notice netifd: Interface 'wan' is now down Thu Jan 20 17:49:35 2022 kern.info kernel: [ 3164.721000] ADDRCONF(NETDEV_UP): atm0: link is not ready Thu Jan 20 17:49:35 2022 daemon.notice netifd: Interface 'wan' is disabled Thu Jan 20 17:49:35 2022 daemon.notice netifd: Interface 'wan' is enabled Thu Jan 20 17:49:35 2022 daemon.notice netifd: Interface 'wan' is setting up now Thu Jan 20 17:49:36 2022 daemon.info pppd[8513]: Plugin connstate.so loaded. Thu Jan 20 17:49:36 2022 daemon.info pppd[8513]: Plugin pppoatm-brcm.so loaded. Thu Jan 20 17:49:36 2022 daemon.info pppd[8513]: PPPoATM plugin_init Thu Jan 20 17:49:36 2022 daemon.info pppd[8513]: PPPoATM setdevname_pppoatm - SUCCESS:atm0 Thu Jan 20 17:49:36 2022 daemon.notice pppd[8513]: pppd 2.4.7 started by root, uid 0 Thu Jan 20 17:49:36 2022 daemon.notice pppd[8513]: Connection state changed to connecting (phase 2) Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: applying action on internet:green Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:green with action none Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action timer Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting delay_on to 498 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting delay_off to 502 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action timer Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting delay_on to 499 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:36 2022 daemon.debug ledfw[1276]: setting delay_off to 501 Thu Jan 20 17:49:36 2022 kern.warn kernel: [ 3165.493000] netdev path : ppp0 -> atm0 Thu Jan 20 17:49:36 2022 daemon.info pppd[8513]: Using interface pppoa-wan Thu Jan 20 17:49:36 2022 daemon.notice pppd[8513]: Connect: pppoa-wan <--> atm0 Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: Connection state changed to authenticating (phase 5) Thu Jan 20 17:49:42 2022 daemon.info pppd[8513]: CHAP authentication succeeded: CHAP authentication success Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: CHAP authentication succeeded Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: Connection state changed to networking (phase 7) Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: local IP address [redacted] Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: remote IP address [redacted] Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: primary DNS address [redacted] Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: secondary DNS address [redacted] Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 user.err syslog: ILibGetLocalIPAddressList :292>No matching interface Thu Jan 20 17:49:42 2022 daemon.notice pppd[8513]: Connection state changed to connected (phase 8) Thu Jan 20 17:49:42 2022 daemon.notice netifd: Network device 'pppoa-wan' link is up Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on power:orange Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:orange with action none Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on power:red Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:red with action none Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on power:blue Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:blue with action none Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on power:green Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/power:green with action none Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting brightness to 255 Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on internet:green Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:green with action netdev Thu Jan 20 17:49:42 2022 daemon.notice netifd: Interface 'wan6' is enabled Thu Jan 20 17:49:42 2022 daemon.notice netifd: Network alias 'pppoa-wan' link is up Thu Jan 20 17:49:42 2022 daemon.notice netifd: Interface 'wan6' has link connectivity Thu Jan 20 17:49:42 2022 daemon.notice netifd: Interface 'wan6' is setting up now Thu Jan 20 17:49:42 2022 daemon.notice netifd: Interface 'wan' is now up Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: reading /tmp/resolv.conf.auto Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain test Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain onion Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain localhost Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain local Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain invalid Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.net Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.org Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using local addresses only for domain example.com Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using nameserver 212.159.6.10#53 [0] Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using nameserver 212.159.6.9#53 [0] Thu Jan 20 17:49:42 2022 daemon.info dnsmasq[8093]: using 3 more local addresses Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting device_name to pppoa-wan Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting mode to link tx rx Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on internet:red Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: writing to /sys/class/leds/internet:red with action none Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: setting brightness to 0 Thu Jan 20 17:49:42 2022 daemon.debug ledfw[1276]: applying action on runFunc Thu Jan 20 17:49:42 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:49:42 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 5. Thu Jan 20 17:49:42 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 17:49:43 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:49:44 2022 user.notice intercept: [8642] setup (action=ifchanged) Thu Jan 20 17:49:44 2022 user.notice intercept: [8642] an interface changed, test default route via observed interface Thu Jan 20 17:49:45 2022 user.notice intercept: [8642] WAN connectivity 1 Thu Jan 20 17:49:45 2022 user.notice intercept: [8642] intercept_set_state(0) Thu Jan 20 17:49:45 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.221000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.227000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.234000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.243000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.256000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 user.notice intercept: [8642] reconfigure active from 1 to 0 Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.288000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.301000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.307000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.319000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.339000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.356000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 kern.warn kernel: [ 3174.368000] netlink: 20 bytes leftover after parsing attributes. Thu Jan 20 17:49:45 2022 user.notice intercept: [8642] flushed nointercept[46] ipsets Thu Jan 20 17:49:45 2022 user.notice firewall: Reloading firewall due to ifup of wan (pppoa-wan) Thu Jan 20 17:49:46 2022 daemon.info dnsmasq[8093]: exiting on receipt of SIGTERM Thu Jan 20 17:49:47 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: started, version 2.78 cachesize 150 Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP conntrack dns-filterno-ipset no-auth no-DNSSEC no-ID loop-detect inotify Thu Jan 20 17:49:48 2022 daemon.info dnsmasq-dhcp[8795]: DHCP, IP range 10.0.0.201 -- 10.0.0.220, lease time 12h Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain test Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain onion Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain localhost Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain local Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain invalid Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.net Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.org Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.com Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using 3 more local addresses Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: reading /tmp/resolv.conf.auto Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain test Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain onion Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain localhost Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain local Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain invalid Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.net Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.org Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using local addresses only for domain example.com Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using nameserver 212.159.6.10#53 [0] Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using nameserver 212.159.6.9#53 [0] Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: using 3 more local addresses Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: read /etc/hosts - 1 addresses Thu Jan 20 17:49:48 2022 daemon.info dnsmasq[8795]: read /tmp/hosts/dhcp.cfg02411c - 2 addresses Thu Jan 20 17:49:48 2022 daemon.info dnsmasq-dhcp[8795]: read /etc/ethers - 0 addresses Thu Jan 20 17:49:50 2022 user.notice intercept: [8642] completely handled 'ifchanged' Thu Jan 20 17:49:51 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:49:54 2022 user.notice pppoe-relay-hotplug: Interface wan ifup Thu Jan 20 17:49:56 2022 user.notice xdsl: xDSL linestate up @Thu Jan 20 17:49:34 2022 (G.992.5_Annex_A; downstream: 12375 kbit/s, upstream: 1173 kbit/s; output Power Down: 12.8 dBm, Up: 19.2 dBm; line Attenuation Down: 37.0 dB, Up: 20.0 dB; snr Margin Down: 3.1 dB, Up: 6.6 dB) Thu Jan 20 17:49:58 2022 user.notice ddns-scripts[9143]: myddns_ipv4: PID '9143' started at 2022-01-20 17:49 Thu Jan 20 17:49:59 2022 user.warn ddns-scripts[9143]: myddns_ipv4: Service section disabled! - TERMINATE Thu Jan 20 17:49:59 2022 user.warn ddns-scripts[9143]: myddns_ipv4: PID '9143' exit WITH ERROR '1' at 2022-01-20 17:49 Thu Jan 20 17:50:01 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:50:12 2022 daemon.err cwmpd[4357]: CONNECTION: Failed to connect to server. Thu Jan 20 17:50:12 2022 daemon.info cwmpd[4357]: PROT_TRACE: Nb of retries 6 Thu Jan 20 17:50:12 2022 daemon.info cwmpd[4357]: PROT_TRACE: Min retry wait time 160 Thu Jan 20 17:50:12 2022 daemon.info cwmpd[4357]: PROT_TRACE: Max retry wait time 320 Thu Jan 20 17:50:12 2022 daemon.info cwmpd[4357]: PROT_TRACE: Effective retry wait time 187 Thu Jan 20 17:50:12 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:50:14 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:50:15 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 17:50:15 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 17:50:19 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:50:56 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:51:14 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:52:11 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:52:15 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server retry 6. Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: CONNECTION: Connected to server, starting transaction. Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: ConnectionRequestURL updated to http://9[redacted] Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: > Inform (4 VALUE CHANGE) Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: < InformResponse Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: > Empty Post Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: Transaction succeeded, closing connection. Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: APP_TRACE: Saveall action is set Thu Jan 20 17:52:50 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 17:53:15 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:54:15 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:54:19 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:54:50 2022 daemon.info dnsmasq-dhcp[8795]: DHCPINFORM(br-lan) 10.0.0.141 [redacted] Thu Jan 20 17:54:50 2022 daemon.info dnsmasq-dhcp[8795]: DHCPACK(br-lan) 10.0.0.141 [redacted][redacted] Thu Jan 20 17:55:16 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:55:22 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 17:55:22 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 17:56:16 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:56:20 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:57:16 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:58:16 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 17:58:21 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 17:58:38 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 17:58:38 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 17:59:17 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:00:16 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:00:17 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:00:42 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:00:42 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:00:43 2022 daemon.info dnsmasq-dhcp[8795]: DHCPREQUEST(br-lan) 10.0.0.205 [redacted] Thu Jan 20 18:00:43 2022 daemon.info dnsmasq-dhcp[8795]: DHCPACK(br-lan) 10.0.0.205 [redacted] Thu Jan 20 18:01:17 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:02:06 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:02:17 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:03:18 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:04:05 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:04:18 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:05:18 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:05:45 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:05:45 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:06:10 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:06:10 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:06:16 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:06:19 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:07:19 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:08:19 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:08:19 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:09:19 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:10:20 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:10:22 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:11:20 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:11:23 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:11:23 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:12:11 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:12:20 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:13:20 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:14:05 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:14:21 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:15:05 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:15:05 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:15:21 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:16:04 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:16:21 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:17:21 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:17:59 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:18:09 2022 daemon.info dnsmasq-dhcp[8795]: DHCPINFORM(br-lan) 10.0.0.141 [redacted] Thu Jan 20 18:18:09 2022 daemon.info dnsmasq-dhcp[8795]: DHCPACK(br-lan) 10.0.0.141 [redacted] Thu Jan 20 18:18:22 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:19:22 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:20:00 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:20:22 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:20:23 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:20:23 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:21:22 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:21:58 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:22:23 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:23:22 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:23:22 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:23:23 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:24:05 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:24:23 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:24:39 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:24:39 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:25:23 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:25:56 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:26:24 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:27:03 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:27:03 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:27:24 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:27:58 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:28:24 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:29:25 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:29:32 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:29:32 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:29:57 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:30:25 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:31:25 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:31:49 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:32:25 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:33:26 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:33:46 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:34:26 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:35:15 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:35:15 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:35:26 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:35:34 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:36:26 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:36:37 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:36:37 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:37:27 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:37:36 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:38:27 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:39:27 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:39:46 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:40:27 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:41:28 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:41:41 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:42:28 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:43:03 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:43:03 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:43:28 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:43:38 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:44:29 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:44:33 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:44:33 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:45:29 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:45:34 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:46:29 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:47:24 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:47:29 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:48:09 2022 daemon.info dnsmasq-dhcp[8795]: DHCPINFORM(br-lan) 10.0.0.141 [redacted] Thu Jan 20 18:48:09 2022 daemon.info dnsmasq-dhcp[8795]: DHCPACK(br-lan) 10.0.0.141 [redacted] Thu Jan 20 18:48:28 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:48:28 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:48:30 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:49:30 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:49:36 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:50:30 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:51:18 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:51:18 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:51:31 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:51:47 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:52:31 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:53:31 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:53:42 2022 daemon.info dnsmasq-dhcp[8795]: DHCPREQUEST(br-lan) 10.0.0.215 [redacted] Thu Jan 20 18:53:42 2022 daemon.warn dnsmasq-dhcp[8795]: Ignoring domain [redacted] for DHCP host name [redacted] Thu Jan 20 18:53:42 2022 daemon.info dnsmasq-dhcp[8795]: DHCPACK(br-lan) 10.0.0.215 [redacted][redacted] Thu Jan 20 18:53:47 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:54:31 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:55:27 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 18:55:27 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 18:55:32 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:55:44 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:56:32 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:56:50 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 18:56:50 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 18:57:32 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:57:54 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 18:58:32 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:59:33 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 18:59:46 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:00:33 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:01:33 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:01:50 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:02:34 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:03:34 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:03:41 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:04:34 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:05:04 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 19:05:04 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 19:05:34 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:05:35 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:06:18 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 19:06:18 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 19:06:35 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:07:35 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:07:45 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:08:35 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:09:35 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:09:40 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:10:36 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:11:36 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:11:36 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:12:36 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:13:32 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:13:36 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:14:18 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-lan Thu Jan 20 19:14:18 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-lan (Operation not permitted) Thu Jan 20 19:14:37 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:15:14 2022 daemon.info odhcpd[2962]: Using a RA lifetime of 0 seconds on br-guest Thu Jan 20 19:15:14 2022 daemon.notice odhcpd[2962]: Failed to send to ff02::1%br-guest (Operation not permitted) Thu Jan 20 19:15:20 2022 daemon.err odhcp6c[8595]: Failed to send DHCPV6 message to ff02::1:2 (Operation not permitted) Thu Jan 20 19:15:37 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:16:01 2022 daemon.warn nginx: 2022/01/20 19:16:01 [warn] 4527#0: *90 [lua] session.lua:343: new(): new session for guest, client: 10.0.0.141, server: localhost, request: "GET / HTTP/1.1", host: "10.0.0.1" Thu Jan 20 19:16:12 2022 daemon.err nginx: 2022/01/20 19:16:12 [error] 4527#0: *93 [lua] sessionmgr.lua:231: redirectIfNotAuthorized(): Unauthorized request, client: 10.0.0.141, server: localhost, request: "GET /modals/usermgr-modal.lp HTTP/1.1", host: "10.0.0.1", referrer: "http://10.0.0.1/" Thu Jan 20 19:16:18 2022 daemon.warn nginx: 2022/01/20 19:16:18 [warn] 4527#0: *94 [lua] session.lua:103: changeUser(): changing user to admin, client: 10.0.0.141, server: localhost, request: "POST /authenticate HTTP/1.1", host: "10.0.0.1", referrer: "http://10.0.0.1/login.lp" Thu Jan 20 19:16:37 2022 daemon.notice [4235]: [mobiled] (WaitingForDevice) runs WaitingForDevice-Main.check(timeout, 1) Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: Events are waiting, need to contact ACS Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: CONNECTION: Connecting to server Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: CONNECTION: Connected to server, starting transaction. Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: ConnectionRequestURL updated to http://[redacted] Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: UBUS_CLIENT: external IP address is [redacted] Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: > Inform (2 PERIODIC) Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: < InformResponse Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: > Empty Post Thu Jan 20 19:16:38 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:39 2022 daemon.err cwmpd[4357]: PROT_TRACE: > GetParameterValues Fault Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: < SetParameterValues Request Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: > SetParameterValues Response Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:39 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:40 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:42 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:43 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:43 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:44 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:44 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:45 2022 daemon.info cwmpd[4357]: PROT_TRACE: > GetParameterValues Response Thu Jan 20 19:16:45 2022 daemon.info cwmpd[4357]: PROT_TRACE: < GetParameterValues Request Thu Jan 20 19:16:45 2022 daemon.err cwmpd[4357]: PROT_TRACE: > GetParameterValues Fault Thu Jan 20 19:16:45 2022 daemon.info cwmpd[4357]: PROT_TRACE: Transaction succeeded, closing connection. Thu Jan 20 19:16:45 2022 daemon.info cwmpd[4357]: APP_TRACE: Saveall action is set Thu Jan 20 19:16:45 2022 daemon.info cwmpd[4357]: PROT_TRACE: LAST STATE: Thu Jan 20 19:16:45 2022 daemon.info transformer[3419]: async run: /etc/init.d/cwmpd reload Thu Jan 20 19:16:45 2022 user.notice cwmpd: Reloading cwmpd