Jun 30 16:04:55 [ DBG] REDIRECT[1211]: final_state - enable: false, old_state: true, new_state: false
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch true -> false, set Action = STOP.
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p udp -j REDIRECT -m udp --dport 53|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p tcp -j REDIRECT -m tcp --dport 53|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Rule #-19997 UNLOADED
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch true -> false, set Action = STOP.
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p tcp -j REDIRECT --to-port 4445 ! -d 192.168.0.1/32 -m tcp --dport 443|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Rule #-19996 UNLOADED
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch true -> false, set Action = STOP.
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t filter -D z3to2 -p tcp -j DROP -i br0 -m tcp -m multiport --dports 80,443 -d 192.0.2.1/32|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Rule #-19995 UNLOADED
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch true -> false, set Action = STOP.
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p tcp -j REDIRECT --to-port 81 ! -d 192.168.0.1/32 -m tcp --dport 80|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Rule #-19994 UNLOADED
Jun 30 16:04:55 [ DBG] handler_update_dnsmasq[1211]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:55 [ DBG] default_stop[1228]: PID 773 does not exists
Jun 30 16:04:55 [ DBG] default_stop[1228]: PID 776 does not exists
Jun 30 16:04:55 [ DBG] delete_dnsmasq_file[1229]: Delete file: /tmp/dnsmasq/dnsmasq30.conf
Jun 30 16:04:55 [ DBG] delete_dnsmasq_file[1229]: Delete file: /tmp/dnsmasq/hosts30.conf
Jun 30 16:04:55 [ DBG] delete_dnsmasq_file[1229]: Delete file: /tmp/dnsmasq/resolv30.conf
Jun 30 16:04:55 [ DBG] check_diff_args[857]: dnsmasq@30(restart): arguments are equal, nothing to do
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch true -> false, set Action = STOP.
Jun 30 16:04:55 [ DBG] set_nat_chain_jump[1211]: Deleting: |-t nat -D PREROUTING -i br0 -j ^R_br0_redir|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p udp -j REDIRECT -m udp --dport 53|
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Deleting |-t nat -D ^R_br0_redir -p tcp -j REDIRECT -m tcp --dport 53|
Jun 30 16:04:55 [WARN] DNSMASQ_SCRIPT[1233]: Too small arguments
Jun 30 16:04:55 [ DBG] unload_firewall_rule[1211]: Rule #9 UNLOADED
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Enable switch false -> true, set Action = START.
Jun 30 16:04:55 [ DBG] handler_set_fwrule[1211]: determine_action: Current status Disabled, set Action = START.
Jun 30 16:04:55 [ DBG] load_firewall_rule[1211]: Creating chain ^R_br0_redir in table nat
Jun 30 16:04:55 [ DBG] set_nat_chain_jump[1211]: Creating: |-t nat -A PREROUTING -i br0 -j ^R_br0_redir|
Jun 30 16:04:55 [ DBG] load_firewall_rule[1211]: Creating |-t nat -A ^R_br0_redir -p udp -j REDIRECT -m udp --dport 53|
Jun 30 16:04:55 [ DBG] load_firewall_rule[1211]: Creating |-t nat -A ^R_br0_redir -p tcp -j REDIRECT -m tcp --dport 53|
Jun 30 16:04:55 [ DBG] load_firewall_rule[1211]: Rule #9 LOADED
Jun 30 16:04:56 [ DBG] handler_telnetd[1258]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_telnetd[1258]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] handler_update_ssh[1260]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_update_ssh[1260]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] handler_anweb[1263]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_anweb[1263]: determine_action: Status switch Enabled -> Enabling, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_ntp[1266]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_ntp[1266]: determine_action: Status switch Enabled -> Enabling, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_ipsec[1268]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_ipsec[1268]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] handler_tr069[1270]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_tr069[1270]: determine_action: Start update requested.
Jun 30 16:04:56 [ DBG] handler_tr069[1270]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:56 [WARN] d_task_update_pidfile[1272]: Another instance is already running: [1513].
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1270]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1270]: determine_action: Start update requested.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1270]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1270]: Failed to parse rule Device.Firewall.IPv4.Rules.10.
Jun 30 16:04:56 [ DBG] handler_roamd[1274]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_roamd[1274]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: ROUTE Args: Interface=Device.Network.Interface.Cellular.1. Origin=Device.Network.Connection.DHCP.9. Src IP=Device.Network.IP.7.IPv4Address.1.  Start=1
Jun 30 16:04:56 [ DBG] handler_update_route[1275]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_update_route[1275]: determine_action: Status switch Enabled -> Enabling, set Action = 0.
Jun 30 16:04:56 [ DBG] update_route[1276]: ip route del unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 258 metric 410: OK
Jun 30 16:04:56 [ DBG] update_route[1276]: ip route del unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 257 metric 410: OK
Jun 30 16:04:56 [ DBG] update_route[1276]: ip route del unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 254 metric 410: OK
Jun 30 16:04:56 [ DBG] update_route[1278]: ip route add unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 258 metric 410: OK
Jun 30 16:04:56 [ DBG] update_route[1278]: ip route add unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 257 metric 410: OK
Jun 30 16:04:56 [ DBG] update_route[1278]: ip route add unspec 0.0.0.0/0 dev unet0 via 10.0.0.1 table 254 metric 410: OK
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: ROUTE Args: Interface=Device.Network.Interface.Cellular.1. Origin=Device.Network.Connection.DHCP.9. Src IP=Device.Network.IP.7.IPv4Address.1.  Start=1
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: GRE ip link = Device.Network.IP.7.IPv4Address.1.
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: IGMPX: interface Device.Network.Interface.Cellular.1.
Jun 30 16:04:56 [INFO] check_group_wan[1206]: The master connection is started
Jun 30 16:04:56 [ DBG] handler_autoupdate[1279]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_autoupdate[1279]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] handler_autoprovision[1280]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_autoprovision[1280]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: PPP* ip link = Device.Network.IP.7.IPv4Address.1.
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: PPP* ifname = unet0
Jun 30 16:04:56 [ DBG] dwatcher[wrkr][1206]: Check IPsec: IP = 10.0.0.100/8 IP_Link = Device.Network.IP.7.IPv4Address.1., Start 1
Jun 30 16:04:56 [ DBG] handler_iperf3_set[1281]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:56 [ DBG] handler_iperf3_set[1281]: determine_action: nothing to do.
Jun 30 16:04:56 [ DBG] SchedRestartService[1206]: set rule DDNS
Jun 30 16:04:56 [ DBG] SchedRestartService[1206]: set rule DynPortMap
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1282]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1282]: determine_action: Stop update requested.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1282]: determine_action: Status switch Enabled -> Disabling, set Action = STOP.
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1282]: Deleting |-t filter -D z3to2 -p udp -j DROP -m udp --dport 1701|
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1282]: Rule #0 UNLOADED
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1286]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1286]: determine_action: Stop update requested.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1286]: determine_action: Status switch Enabled -> Disabling, set Action = STOP.
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1286]: Deleting |-t filter -D z3to2 -p tcp -j DROP -m tcp --dport 1723|
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1286]: Rule #1 UNLOADED
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1290]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1290]: determine_action: Stop update requested.
Jun 30 16:04:56 [ DBG] handler_set_fwrule[1290]: determine_action: Status switch Enabled -> Disabling, set Action = STOP.
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1290]: Deleting |-t filter -D z3to2 -p udp -j DROP -m udp -m multiport --dports 500,4500|
Jun 30 16:04:56 [ DBG] unload_firewall_rule[1290]: Rule #2 UNLOADED
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1294]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1294]: determine_action: nothing to do.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1295]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1295]: determine_action: nothing to do.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1296]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1296]: determine_action: nothing to do.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1297]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1297]: determine_action: nothing to do.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1298]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1298]: determine_action: Status switch Disabled -> Disabling, set Action = 0.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1298]: Failed to parse rule Device.Firewall.IPv4.Rules.8.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1299]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1299]: determine_action: Stop update requested.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1299]: determine_action: Status switch Enabled -> Disabling, set Action = STOP.
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1299]: Deleting |-t filter -D z2to1 -p udp -j ACCEPT -m udp --sport 67 -m udp --dport 68 -s 10.0.0.1/32 -d 10.0.0.100/32|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1299]: Rule #10 UNLOADED
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1303]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1303]: determine_action: Stop update requested.
Jun 30 16:04:57 [ DBG] handler_set_fwrule[1303]: determine_action: Status switch Enabled -> Disabling, set Action = STOP.
Jun 30 16:04:57 [ DBG] set_nat_chain_jump[1303]: Deleting: |-t nat -D POSTROUTING -o unet0 -j unet0_masq|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1303]: Deleting |-t nat -D unet0_masq -s 192.168.0.1/24 -j br0_unet0|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1303]: Deleting |-t nat -D br0_unet0  -j MASQUERADE|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1303]: Rule #10001 UNLOADED
Jun 30 16:04:57 [ DBG] REDIRECT[1313]: Device.Services.Redirect.1.
Jun 30 16:04:57 [ DBG] REDIRECT[1313]: cable - enable: true, old_state: false, new_state: false
Jun 30 16:04:57 [ DBG] REDIRECT[1313]: factory - enable: true, old_state: false, new_state: false
Jun 30 16:04:57 [ DBG] REDIRECT[1313]: connections - enable: true, old_state: false, new_state: false
Jun 30 16:04:57 [ DBG] REDIRECT[1313]: final_state - enable: false, old_state: false, new_state: false
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1323]: Process action: RestartService
Jun 30 16:04:57 [ DBG] process_restart_service[1323]: Device.Services.DDNS.Instances. is multiinstance, getting all instances
Jun 30 16:04:57 [ DBG] process_restart_service[1323]: Device.Services.DDNS.Instances. is empty, skip
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1326]: Process action: RestartService
Jun 30 16:04:57 [ DBG] DynPortMap[1327]: could not open lease file: /tmp/miniupnpd/1.lease
Jun 30 16:04:57 [ DBG] process_restart_service[1326]: Device.Services.DynamicPortMapping. is multiinstance, getting all instances
Jun 30 16:04:57 [ DBG] process_restart_service[1326]: Restart Service: Device.Services.DynamicPortMapping.1.
Jun 30 16:04:57 [ DBG] handler_update_dynportmap[1329]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:57 [ DBG] handler_update_dynportmap[1329]: determine_action: Status switch Enabled -> Enabling, set Action = 0.
Jun 30 16:04:57 [ DBG] DynPortMap[1329]: WANGroup set, auto mode
Jun 30 16:04:57 [ DBG] DynPortMap[1329]: Checking other instances.
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1329]: Deleting |-t nat -D PREROUTING -i unet0 -j MINIUPNPD1|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1329]: Deleting |-t nat -D POSTROUTING -o unet0 -j POSTROUTING-MINIUPNPD1|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1329]: Deleting |-t filter -D z2to3 -i unet0 -o br0 -j MINIUPNPD1|
Jun 30 16:04:57 [ DBG] unload_firewall_rule[1329]: Rule #-10001 UNLOADED
Jun 30 16:04:57 [ DBG] check_diff_args[857]: miniupnpd@42(restart): arguments are equal, nothing to do
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Creating chain MINIUPNPD1 in table nat
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Creating chain POSTROUTING-MINIUPNPD1 in table nat
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Creating chain MINIUPNPD1 in table filter
Jun 30 16:04:57 [ DBG] loa
d_firewall_rule[1329]: Creating |-t nat -I PREROUTING 1 -i unet0 -j MINIUPNPD1|
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Creating |-t nat -I POSTROUTING 1 -o unet0 -j POSTROUTING-MINIUPNPD1|
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Creating |-t filter -I z2to3 3 -i unet0 -o br0 -j MINIUPNPD1|
Jun 30 16:04:57 [ DBG] load_firewall_rule[1329]: Rule #-10001 LOADED
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1350]: Process action: FirewallRelationChanged
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1352]: Process action: RouteUp
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1352]: PPP* ip link = Device.Routing.IPv4.Routes.15.
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1352]: PPP* ifname = unet0
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1355]: Process action: RouteDown
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1357]: Process action: RouteUp
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1359]: Process action: RouteDown
Jun 30 16:04:57 [ DBG] dwatcher[wrkr][1361]: Process action: RouteUp
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1363]: Process action: DnsUpdate
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1370]: Process action: ConnectionStatusChanged
Jun 30 16:04:58 [ DBG] DynPortMap[1371]: could not open lease file: /tmp/miniupnpd/1.lease
Jun 30 16:04:58 [ DBG] d_gpio_role_toggle[1370]: Invalid role 10 and state 1
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1375]: Process action: DnsmasqDown
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1376]: Process action: DnsmasqUp
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1377]: Process action: RouteDown
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1377]: PPP* ip link = Device.Routing.IPv4.Routes.15.
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1377]: PPP* ifname = unet0
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1384]: Process action: RouteUp
Jun 30 16:04:58 [ DBG] REDIRECT[1385]: Device.Services.Redirect.1.
Jun 30 16:04:58 [ DBG] REDIRECT[1385]: cable - enable: true, old_state: false, new_state: false
Jun 30 16:04:58 [ DBG] REDIRECT[1385]: factory - enable: true, old_state: false, new_state: false
Jun 30 16:04:58 [ DBG] REDIRECT[1385]: connections - enable: true, old_state: false, new_state: false
Jun 30 16:04:58 [ DBG] REDIRECT[1385]: final_state - enable: false, old_state: false, new_state: false
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1384]: PPP* ip link = Device.Routing.IPv4.Routes.15.
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1384]: PPP* ifname = unet0
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1389]: Process action: FirewallRelationChanged
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1391]: Process action: DnsmasqReload
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1392]: Process action: RestartService
Jun 30 16:04:58 [ DBG] process_restart_service[1392]: Device.Services.DDNS.Instances. is multiinstance, getting all instances
Jun 30 16:04:58 [ DBG] process_restart_service[1392]: Device.Services.DDNS.Instances. is empty, skip
Jun 30 16:04:58 [ DBG] dwatcher[wrkr][1394]: Process action: RestartService
Jun 30 16:04:58 [ DBG] DynPortMap[1395]: could not open lease file: /tmp/miniupnpd/1.lease
Jun 30 16:04:58 [ DBG] process_restart_service[1394]: Device.Services.DynamicPortMapping. is multiinstance, getting all instances
Jun 30 16:04:58 [ DBG] process_restart_service[1394]: Restart Service: Device.Services.DynamicPortMapping.1.
Jun 30 16:04:58 [ DBG] handler_update_dynportmap[1396]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:58 [ DBG] handler_update_dynportmap[1396]: determine_action: Status switch Enabled -> Enabling, set Action = 0.
Jun 30 16:04:58 [ DBG] DynPortMap[1396]: WANGroup set, auto mode
Jun 30 16:04:58 [ DBG] DynPortMap[1396]: Checking other instances.
Jun 30 16:04:58 [ DBG] unload_firewall_rule[1396]: Deleting |-t nat -D PREROUTING -i unet0 -j MINIUPNPD1|
Jun 30 16:04:58 [ DBG] unload_firewall_rule[1396]: Deleting |-t nat -D POSTROUTING -o unet0 -j POSTROUTING-MINIUPNPD1|
Jun 30 16:04:58 [ DBG] unload_firewall_rule[1396]: Deleting |-t filter -D z2to3 -i unet0 -o br0 -j MINIUPNPD1|
Jun 30 16:04:58 [ DBG] unload_firewall_rule[1396]: Rule #-10001 UNLOADED
Jun 30 16:04:58 [ DBG] check_diff_args[857]: miniupnpd@42(restart): arguments are equal, nothing to do
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating chain MINIUPNPD1 in table nat
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating chain POSTROUTING-MINIUPNPD1 in table nat
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating chain MINIUPNPD1 in table filter
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating |-t nat -I PREROUTING 1 -i unet0 -j MINIUPNPD1|
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating |-t nat -I POSTROUTING 1 -o unet0 -j POSTROUTING-MINIUPNPD1|
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Creating |-t filter -I z2to3 3 -i unet0 -o br0 -j MINIUPNPD1|
Jun 30 16:04:58 [ DBG] load_firewall_rule[1396]: Rule #-10001 LOADED
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t filter -I z3to2 3 -p udp -j DROP -m udp --dport 1701|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Rule #0 LOADED
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t filter -I z3to2 4 -p tcp -j DROP -m tcp --dport 1723|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Rule #1 LOADED
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t filter -I z3to2 5 -p udp -j DROP -m udp -m multiport --dports 500,4500|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Rule #2 LOADED
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: nothing to do.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: nothing to do.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: nothing to do.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable false, set Action = 0.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: nothing to do.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: Failed to parse rule Device.Firewall.IPv4.Rules.8.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t filter -I z2to1 3 -p udp -j ACCEPT -m udp --sport 67 -m udp --dport 68 -s 10.0.0.1/32 -d 10.0.0.100/32|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Rule #10 LOADED
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Enable true, set Action = STOP | START.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Start update requested.
Jun 30 16:04:59 [ DBG] handler_set_fwrule[1422]: determine_action: Status switch Disabled -> Enabling, set Action = START.
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating chain br0_unet0 in table nat
Jun 30 16:04:59 [ DBG] dwatcher[wrkr][1435]: Process action: FirewallRelationChanged
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating chain unet0_masq in table nat
Jun 30 16:04:59 [ DBG] set_nat_chain_jump[1422]: Creating: |-t nat -A POSTROUTING -o unet0 -j unet0_masq|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t nat -A unet0_masq -s 192.168.0.1/24 -j br0_unet0|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Creating |-t nat -A br0_unet0  -j MASQUERADE|
Jun 30 16:04:59 [ DBG] load_firewall_rule[1422]: Rule #10001 LOADED
Jun 30 16:05:02 [ ERR] d_modules_get_ip_link_by_iface[1459]: IP object not found.
Jun 30 16:05:02 [INFO] d_modules_get_ipaddr_by_iface[1459]: No available IPPrefix.
Jun 30 16:05:13 [ ERR] d_modules_get_ip_link_by_iface[1491]: IP object not found.
Jun 30 16:05:13 [INFO] d_modules_get_ipaddr_by_iface[1491]: No available IPPrefix.
Jun 30 16:05:22 [ ERR] d_modules_get_ip_link_by_iface[1536]: IP object not found.
Jun 30 16:05:22 [INFO] d_modules_get_ipaddr_by_iface[1536]: No available IPPrefix.
Jun 30 16:05:22 [ DBG] check_health[1536]: rx counter for unet0 has changed (2349 -> 3970)
Jun 30 16:05:33 [ ERR] d_modules_get_ip_link_by_iface[1570]: IP object not found.
Jun 30 16:05:33 [INFO] d_modules_get_ipaddr_by_iface[1570]: No available IPPrefix.
Jun 30 16:05:42 [ ERR] d_modules_get_ip_link_by_iface[1602]: IP object not found.
Jun 30 16:05:42 [INFO] d_modules_get_ipaddr_by_iface[1602]: No available IPPrefix.
Jun 30 16:05:53 [ ERR] d_modules_get_ip_link_by_iface[1634]: IP object not found.
Jun 30 16:05:53 [INFO] d_modules_get_ipaddr_by_iface[1634]: No available IPPrefix.
Jun 30 16:06:02 [ ERR] d_modules_get_ip_link_by_iface[1668]: IP object not found.
Jun 30 16:06:02 [INFO] d_modules_get_ipaddr_by_iface[1668]: No available IPPrefix.
Jun 30 16:06:13 [ ERR] d_modules_get_ip_link_by_iface[1700]: IP object not found.
Jun 30 16:06:13 [INFO] d_modules_get_ipaddr_by_iface[1700]: No available IPPrefix.
Jun 30 16:06:13 [ DBG] check_health[1700]: rx counter for unet0 has changed (6587 -> 7635)
Jun 30 16:06:22 [ ERR] d_modules_get_ip_link_by_iface[1732]: IP object not found.
Jun 30 16:06:22 [INFO] d_modules_get_ipaddr_by_iface[1732]: No available IPPrefix.
Jun 30 16:06:33 [ ERR] d_modules_get_ip_link_by_iface[1766]: IP object not found.
Jun 30 16:06:33 [INFO] d_modules_get_ipaddr_by_iface[1766]: No available IPPrefix.
Jun 30 16:06:42 [ ERR] d_modules_get_ip_link_by_iface[1798]: IP object not found.
Jun 30 16:06:42 [INFO] d_modules_get_ipaddr_by_iface[1798]: No available IPPrefix.
Jun 30 16:06:53 [ ERR] d_modules_get_ip_link_by_iface[1830]: IP object not found.
Jun 30 16:06:53 [INFO] d_modules_get_ipaddr_by_iface[1830]: No available IPPrefix.
Jun 30 16:07:02 [ ERR] d_modules_get_ip_link_by_iface[1864]: IP object not found.
Jun 30 16:07:02 [INFO] d_modules_get_ipaddr_by_iface[1864]: No available IPPrefix.
Jun 30 16:07:13 [ ERR] d_modules_get_ip_link_by_iface[1896]: IP object not found.
Jun 30 16:07:13 [INFO] d_modules_get_ipaddr_by_iface[1896]: No available IPPrefix.
Jun 30 16:07:15 [NOTE] [AUTH][857]: Deauthentification failure: malformed request
Jun 30 16:07:15 [NOTE] AUTH[857]: Attempt to proceed a RPC with invalid token: d7964deb-0bb0-4236-b3e6-b6f5c4192493
Jun 30 16:07:22 [ ERR] d_modules_get_ip_link_by_iface[1930]: IP object not found.
Jun 30 16:07:22 [INFO] d_modules_get_ipaddr_by_iface[1930]: No available IPPrefix.
Jun 30 16:07:33 [ ERR] d_modules_get_ip_link_by_iface[1964]: IP object not found.
Jun 30 16:07:33 [INFO] d_modules_get_ipaddr_by_iface[1964]: No available IPPrefix.
Jun 30 16:07:42 [ ERR] d_modules_get_ip_link_by_iface[1996]: IP object not found.
Jun 30 16:07:42 [INFO] d_modules_get_ipaddr_by_iface[1996]: No available IPPrefix.
Jun 30 16:07:53 [ ERR] d_modules_get_ip_link_by_iface[2028]: IP object not found.
Jun 30 16:07:53 [INFO] d_modules_get_ipaddr_by_iface[2028]: No available IPPrefix.
Jun 30 16:08:02 [ ERR] d_modules_get_ip_link_by_iface[2062]: IP object not found.
Jun 30 16:08:02 [INFO] d_modules_get_ipaddr_by_iface[2062]: No available IPPrefix.
Jun 30 16:08:13 [ ERR] d_modules_get_ip_link_by_iface[2094]: IP object not found.
Jun 30 16:08:13 [INFO] d_modules_get_ipaddr_by_iface[2094]: No available IPPrefix.
Jun 30 16:08:22 [ ERR] d_modules_get_ip_link_by_iface[2126]: IP object not found.
Jun 30 16:08:22 [INFO] d_modules_get_ipaddr_by_iface[2126]: No available IPPrefix.
Jun 30 16:08:33 [ ERR] d_modules_get_ip_link_by_iface[2160]: IP object not found.
Jun 30 16:08:33 [INFO] d_modules_get_ipaddr_by_iface[2160]: No available IPPrefix.
Jun 30 16:08:42 [ ERR] d_modules_get_ip_link_by_iface[2192]: IP object not found.
Jun 30 16:08:42 [INFO] d_modules_get_ipaddr_by_iface[2192]: No available IPPrefix.
Jun 30 16:08:53 [ ERR] d_modules_get_ip_link_by_iface[2224]: IP object not found.
Jun 30 16:08:53 [INFO] d_modules_get_ipaddr_by_iface[2224]: No available IPPrefix.
Jun 30 16:09:02 [ ERR] d_modules_get_ip_link_by_iface[2258]: IP object not found.
Jun 30 16:09:02 [INFO] d_modules_get_ipaddr_by_iface[2258]: No available IPPrefix.
Jun 30 16:09:13 [ ERR] d_modules_get_ip_link_by_iface[2290]: IP object not found.
Jun 30 16:09:13 [INFO] d_modules_get_ipaddr_by_iface[2290]: No available IPPrefix.
Jun 30 16:09:22 [ ERR] d_modules_get_ip_link_by_iface[2322]: IP object not found.
Jun 30 16:09:22 [INFO] d_modules_get_ipaddr_by_iface[2322]: No available IPPrefix.
Jun 30 16:09:33 [ ERR] d_modules_get_ip_link_by_iface[2356]: IP object not found.
Jun 30 16:09:33 [INFO] d_modules_get_ipaddr_by_iface[2356]: No available IPPrefix.
Jun 30 16:09:39 [NOTE] AUTH[857]: Attempt to proceed a RPC with invalid token: b74442a2-155b-44a9-8d3b-3502a6c332a5
Jun 30 16:09:39 [NOTE] AUTH[857]: Attempt to proceed a RPC with invalid token: b74442a2-155b-44a9-8d3b-3502a6c332a5
Jun 30 16:09:42 [ ERR] d_modules_get_ip_link_by_iface[2390]: IP object not found.
Jun 30 16:09:42 [INFO] d_modules_get_ipaddr_by_iface[2390]: No available IPPrefix.
Jun 30 16:09:53 [ ERR] d_modules_get_ip_link_by_iface[2422]: IP object not found.
Jun 30 16:09:53 [INFO] d_modules_get_ipaddr_by_iface[2422]: No available IPPrefix.
Jun 30 16:10:02 [ ERR] d_modules_get_ip_link_by_iface[2456]: IP object not found.
Jun 30 16:10:02 [INFO] d_modules_get_ipaddr_by_iface[2456]: No available IPPrefix.
Jun 30 16:10:13 [ ERR] d_modules_get_ip_link_by_iface[2488]: IP object not found.
Jun 30 16:10:13 [INFO] d_modules_get_ipaddr_by_iface[2488]: No available IPPrefix.
Jun 30 16:10:22 [ ERR] d_modules_get_ip_link_by_iface[2520]: IP object not found.
Jun 30 16:10:22 [INFO] d_modules_get_ipaddr_by_iface[2520]: No available IPPrefix.
Jun 30 16:10:33 [ ERR] d_modules_get_ip_link_by_iface[2554]: IP object not found.
Jun 30 16:10:33 [INFO] d_modules_get_ipaddr_by_iface[2554]: No available IPPrefix.
Jun 30 16:10:42 [ ERR] d_modules_get_ip_link_by_iface[2586]: IP object not found.
Jun 30 16:10:42 [INFO] d_modules_get_ipaddr_by_iface[2586]: No available IPPrefix.
Jun 30 16:10:53 [ ERR] d_modules_get_ip_link_by_iface[2618]: IP object not found.
Jun 30 16:10:53 [INFO] d_modules_get_ipaddr_by_iface[2618]: No available IPPrefix.
Jun 30 16:11:02 [ ERR] d_modules_get_ip_link_by_iface[2652]: IP object not found.
Jun 30 16:11:02 [INFO] d_modules_get_ipaddr_by_iface[2652]: No available IPPrefix.
Jun 30 16:11:13 [ ERR] d_modules_get_ip_link_by_iface[2684]: IP object not found.
Jun 30 16:11:13 [INFO] d_modules_get_ipaddr_by_iface[2684]: No available IPPrefix.
Jun 30 16:11:22 [ ERR] d_modules_get_ip_link_by_iface[2716]: IP object not found.
Jun 30 16:11:22 [INFO] d_modules_get_ipaddr_by_iface[2716]: No available IPPrefix.
Jun 30 16:11:33 [ ERR] d_modules_get_ip_link_by_iface[2750]: IP object not found.
Jun 30 16:11:33 [INFO] d_modules_get_ipaddr_by_iface[2750]: No available IPPrefix.
Jun 30 16:11:42 [ ERR] d_modules_get_ip_link_by_iface[2782]: IP object not found.
Jun 30 16:11:42 [INFO] d_modules_get_ipaddr_by_iface[2782]: No available IPPrefix.
Jun 30 16:11:53 [ ERR] d_modules_get_ip_link_by_iface[2814]: IP object not found.
Jun 30 16:11:53 [INFO] d_modules_get_ipaddr_by_iface[2814]: No available IPPrefix.
Jun 30 16:12:02 [ ERR] d_modules_get_ip_link_by_iface[2848]: IP object not found.
Jun 30 16:12:02 [INFO] d_modules_get_ipaddr_by_iface[2848]: No available IPPrefix.
Jun 30 16:12:13 [ ERR] d_modules_get_ip_link_by_iface[2880]: IP object not found.
Jun 30 16:12:13 [INFO] d_modules_get_ipaddr_by_iface[2880]: No available IPPrefix.
Jun 30 16:12:14 [NOTE] [AUTH][857]: Deauthentification failure: malformed request
Jun 30 16:12:14 [NOTE] AUTH[857]: Attempt to proceed a RPC with invalid token: d7964deb-0bb0-4236-b3e6-b6f5c4192493
Jun 30 16:12:22 [ ERR] d_modules_get_ip_link_by_iface[2914]: IP object not found.
Jun 30 16:12:22 [INFO] d_modules_get_ipaddr_by_iface[2914]: No available IPPrefix.
Jun 30 16:12:33 [ ERR] d_modules_get_ip_link_by_iface[2948]: IP object not found.
Jun 30 16:12:33 [INFO] d_modules_get_ipaddr_by_iface[2948]: No available IPPrefix.
Jun 30 16:12:42 [ ERR] d_modules_get_ip_link_by_iface[2980]: IP object not found.
Jun 30 16:12:42 [INFO] d_modules_get_ipaddr_by_iface[2980]: No available IPPrefix.
Jun 30 16:12:52 [ ERR] d_modules_get_ip_link_by_iface[3012]: IP object not found.
Jun 30 16:12:52 [INFO] d_modules_get_ipaddr_by_iface[3012]: No available IPPrefix.
Jun 30 16:13:02 [ ERR] d_modules_get_ip_link_by_iface[3075]: IP object not found.
Jun 30 16:13:02 [INFO] d_modules_get_ipaddr_by_iface[3075]: No available IPPrefix.
Jun 30 16:13:13 [ ERR] d_modules_get_ip_link_by_iface[3156]: IP object not found.
Jun 30 16:13:13 [INFO] d_modules_get_ipaddr_by_iface[3156]: No available IPPrefix.
Jun 30 16:13:16 [ ERR] d_url_parse[3193]: Can't parse scheme in url=;
Jun 30 16:13:21 [ ERR] d_url_parse[3212]: Can't parse scheme in url=;