Sat May 9 04:44:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 149 avg_overall: 152 change: 2% Sat May 9 04:44:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 87 avg_overall: 86 change: -1% Sat May 9 04:45:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 132 avg_overall: 152 change: 15% Sat May 9 04:45:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 86 avg_overall: 86 change: 0% Sat May 9 04:45:04 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): restoring userdata '/www/cgi-bin/db_backup.tgz' from monitoring server Sat May 9 04:45:05 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ wget_do(): [ERR] returning 1 after fetching 'http://intercity-vpn.de/networks/liszt28/vds/db_backup.tgz_ed290d6615a7047cb23fad502a968ac2' Sat May 9 04:45:05 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): [ERR] download '/www/cgi-bin/db_backup.tgz' from 'http://intercity-vpn.de/networks/liszt28/vds/db_backup.tgz_ed290d6615a7047cb23fad502a968ac2' failed Sat May 9 04:45:05 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): [ERR] restoring/download failed, try 2 in /tmp/backup_userdata_restore_counter Sat May 9 04:45:06 2015 daemon.info kalua: /bin/sh stopwatch_stop(): task 'olsr_build_overview_static:build_tables' lasts 0.01 seconds Sat May 9 04:45:11 2015 daemon.info kalua: /bin/sh stopwatch_stop(): task 'olsr_build_overview_static:cgi-bin-status.sh' lasts 4.37 seconds Sat May 9 04:45:11 2015 daemon.info kalua: /usr/sbin/cron.check_inet_gw_fff+ net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 04:45:15 2015 daemon.info kalua: /usr/sbin/cron.monitoring net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 04:45:16 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): counting olsr-traff for 30 sec Sat May 9 04:45:46 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): in: 212240 = 7074 bytes/s out: 41392 = 1379 bytes/s Sat May 9 04:45:47 2015 daemon.info kalua: /usr/sbin/cron.monitoring heartbeat(): sending message: 'http://intercity-vpn.de/networks/liszt28/meshrdf/ [...]' Sat May 9 04:45:51 2015 authpriv.info dropbear[25280]: Child connection from 127.0.0.1:53883 Sat May 9 04:45:51 2015 authpriv.info dropbear[25280]: Exit before auth: Exited normally Sat May 9 04:45:54 2015 daemon.info kalua: /bin/sh watch_sshd_or_telnet(): [OK] dropbear seems to run: 'SSH-2.0-dropbear_2015.67 ' Sat May 9 04:45:55 2015 daemon.info kalua: /bin/sh registrator_petting(): [truncated] answer was 'OK' (question was: 'http://intercity-vpn.de/networks/liszt28/registrator/?WIFIMAC=647002d3240b&SSHPUBKEYFP=aed30835a60147faa3cb9d80310b1573&SSHPUBKEY=7373682d647373204141414142334e7a6143316b63334d4141414342414c6556553069647979456b67636e30305538524349487468494f677a6c744664626c4a6b5766682f4e4a514a4546504542625930514a654753656b446d374e743650562b68592b4730454245416478526a745137676b566e5155516f70784d67422f2b334a4aSat May 9 04:45:57 2015 daemon.alert kalua: /bin/sh registrator(): [ERR] somebody has your number '222' or your pass/sshkey-fingerprint has changed Sat May 9 04:45:59 2015 daemon.info kalua: /bin/sh error(): new number invalid: '' Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: time 1431139559 Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: cache size 1000, 0/9 cache insertions re-used unexpired cache entries. Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: queries forwarded 31, queries answered locally 83 Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: server 8.8.8.8#53: queries sent 31, retried or failed 0 Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: read /etc/hosts - 1 addresses Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: read /tmp/hosts/dhcp - 1 addresses Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: read /etc/local.hosts - 66 addresses Sat May 9 04:45:59 2015 daemon.info dnsmasq[4197]: read /var/run/hosts_olsr - 473 addresses Sat May 9 04:45:59 2015 daemon.info dnsmasq-dhcp[4197]: read /etc/ethers - 0 addresses Sat May 9 04:45:59 2015 daemon.info kalua: /etc/dhcp-script.d/10dhcpscript dhcp_cron-watchdog(): '00:00:00:00:00:00' is from 'lo' = loopback-DEV HOST: lo-alias Sat May 9 04:46:00 2015 daemon.info kalua: /usr/sbin/cron.reverse_ssh_tunnel net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 04:46:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 142 avg_overall: 151 change: 6% Sat May 9 04:46:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 86 change: 1% Sat May 9 04:46:07 2015 daemon.info kalua: /bin/sh scheduler_run(): [OK] aborting run, age: 66 sec Sat May 9 04:47:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 151 avg_overall: 150 change: 0% Sat May 9 04:47:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 83 avg_overall: 86 change: 3% Sat May 9 04:47:01 2015 daemon.info kalua: /bin/sh incoming_wifi_activity(): [ERR] timediff > 60 sec = 120 Sat May 9 04:48:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 160 avg_overall: 149 change: -6% Sat May 9 04:48:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 04:49:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 154 avg_overall: 149 change: -3% Sat May 9 04:49:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 04:50:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 162 avg_overall: 150 change: -7% Sat May 9 04:50:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 86 avg_overall: 85 change: -1% Sat May 9 04:51:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 167 avg_overall: 151 change: -9% Sat May 9 04:51:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 86 avg_overall: 85 change: -1% Sat May 9 04:52:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 171 avg_overall: 152 change: -11% Sat May 9 04:52:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 04:53:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 164 avg_overall: 153 change: -6% Sat May 9 04:53:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 04:54:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 170 avg_overall: 153 change: -10% Sat May 9 04:54:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 04:55:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 180 avg_overall: 154 change: -14% Sat May 9 04:55:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 84 avg_overall: 85 change: 1% Sat May 9 04:56:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 179 avg_overall: 156 change: -12% Sat May 9 04:56:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 82 avg_overall: 85 change: 3% Sat May 9 04:57:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 179 avg_overall: 157 change: -12% Sat May 9 04:57:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 84 change: -1% Sat May 9 04:58:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 186 avg_overall: 160 change: -13% Sat May 9 04:58:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 84 change: -1% Sat May 9 04:59:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 187 avg_overall: 163 change: -12% Sat May 9 04:59:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 87 avg_overall: 84 change: -3% Sat May 9 05:00:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 183 avg_overall: 165 change: -9% Sat May 9 05:00:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 86 avg_overall: 84 change: -2% Sat May 9 05:00:07 2015 daemon.info kalua: /usr/sbin/cron.monitoring heartbeat(): public IP changed: 'enforce_recheck' -> '87.171.23.114' Sat May 9 05:00:07 2015 daemon.info kalua: /usr/sbin/cron.monitoring net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:00:07 2015 daemon.info kalua: /usr/sbin/cron.monitoring net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:00:08 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): counting olsr-traff for 30 sec Sat May 9 05:00:38 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): in: 236680 = 7889 bytes/s out: 45316 = 1510 bytes/s Sat May 9 05:00:38 2015 daemon.info kalua: /usr/sbin/cron.monitoring heartbeat(): sending message: 'http://intercity-vpn.de/networks/liszt28/meshrdf/ [...]' Sat May 9 05:00:39 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): restoring userdata '/www/cgi-bin/db_backup.tgz' from monitoring server Sat May 9 05:00:40 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ wget_do(): [ERR] returning 1 after fetching 'http://intercity-vpn.de/networks/liszt28/vds/db_backup.tgz_ed290d6615a7047cb23fad502a968ac2' Sat May 9 05:00:40 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): [ERR] download '/www/cgi-bin/db_backup.tgz' from 'http://intercity-vpn.de/networks/liszt28/vds/db_backup.tgz_ed290d6615a7047cb23fad502a968ac2' failed Sat May 9 05:00:40 2015 daemon.info kalua: /usr/sbin/cron.evaluate_ulogd_syslogemu_fff+ backup_userdata(): [ERR] restoring/download failed, try 3 in /tmp/backup_userdata_restore_counter Sat May 9 05:00:41 2015 daemon.info kalua: /bin/sh stopwatch_stop(): task 'olsr_build_overview_static:build_tables' lasts 0.01 seconds Sat May 9 05:00:46 2015 daemon.info kalua: /bin/sh stopwatch_stop(): task 'olsr_build_overview_static:cgi-bin-status.sh' lasts 4.28 seconds Sat May 9 05:00:46 2015 daemon.info kalua: /usr/sbin/cron.check_inet_gw_fff+ net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:00:52 2015 daemon.info kalua: /usr/sbin/cron.monitoring heartbeat(): public IP changed: 'enforce_recheck' -> '87.171.23.114' Sat May 9 05:00:52 2015 daemon.info kalua: /usr/sbin/cron.monitoring net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:00:52 2015 daemon.info kalua: /usr/sbin/cron.monitoring net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:00:53 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): counting olsr-traff for 30 sec Sat May 9 05:01:23 2015 daemon.info kalua: /usr/sbin/cron.monitoring incoming_and_outgoing_olsr_traff(): in: 329884 = 10996 bytes/s out: 57152 = 1905 bytes/s Sat May 9 05:01:23 2015 daemon.info kalua: /usr/sbin/cron.monitoring heartbeat(): sending message: 'http://intercity-vpn.de/networks/liszt28/meshrdf/ [...]' Sat May 9 05:01:24 2015 authpriv.info dropbear[1885]: Child connection from 127.0.0.1:47748 Sat May 9 05:01:24 2015 authpriv.info dropbear[1885]: Exit before auth: Exited normally Sat May 9 05:01:27 2015 daemon.info kalua: /bin/sh watch_sshd_or_telnet(): [OK] dropbear seems to run: 'SSH-2.0-dropbear_2015.67 ' Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: time 1431140488 Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: cache size 1000, 0/9 cache insertions re-used unexpired cache entries. Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: queries forwarded 40, queries answered locally 107 Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: server 8.8.8.8#53: queries sent 40, retried or failed 0 Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: read /etc/hosts - 1 addresses Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: read /tmp/hosts/dhcp - 1 addresses Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: read /etc/local.hosts - 66 addresses Sat May 9 05:01:28 2015 daemon.info dnsmasq[4197]: read /var/run/hosts_olsr - 473 addresses Sat May 9 05:01:28 2015 daemon.info dnsmasq-dhcp[4197]: read /etc/ethers - 0 addresses Sat May 9 05:01:28 2015 daemon.info kalua: /etc/dhcp-script.d/10dhcpscript dhcp_cron-watchdog(): '00:00:00:00:00:00' is from 'lo' = loopback-DEV HOST: lo-alias Sat May 9 05:01:28 2015 daemon.info kalua: /usr/sbin/cron.reverse_ssh_tunnel net_local_inet_offer(): no local default-gateway found, only 2 hops away 8-) Sat May 9 05:01:29 2015 daemon.info kalua: /bin/sh scheduler_run(): [OK] aborting run, age: 88 sec Sat May 9 05:02:01 2015 user.notice root: debug: phy: phy0 interval: 120 avg: 183 avg_overall: 169 change: -7% Sat May 9 05:02:01 2015 user.notice root: debug: phy: phy1 interval: 120 avg: 86 avg_overall: 84 change: -2% Sat May 9 05:02:01 2015 daemon.info kalua: /bin/sh incoming_wifi_activity(): [ERR] timediff > 60 sec = 120 Sat May 9 05:03:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 179 avg_overall: 171 change: -4% Sat May 9 05:03:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 87 avg_overall: 85 change: -2% Sat May 9 05:04:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 182 avg_overall: 173 change: -4% Sat May 9 05:04:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 87 avg_overall: 85 change: -2% Sat May 9 05:04:14 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:05:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 183 avg_overall: 175 change: -4% Sat May 9 05:05:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 86 avg_overall: 85 change: -1% Sat May 9 05:06:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 186 avg_overall: 177 change: -4% Sat May 9 05:06:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 85 avg_overall: 85 change: 0% Sat May 9 05:06:14 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:07:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 181 avg_overall: 178 change: -1% Sat May 9 05:07:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 87 avg_overall: 85 change: -2% Sat May 9 05:07:14 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:07:28 2015 kern.info kernel: [ 3471.330000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down Sat May 9 05:07:58 2015 kern.info kernel: [ 3501.330000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up Sat May 9 05:08:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 174 avg_overall: 179 change: 2% Sat May 9 05:08:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 84 avg_overall: 85 change: 1% Sat May 9 05:08:05 2015 daemon.info kalua: /bin/sh net_ip_reachable(): arping: 10.63.6.125 dev: eth0.2 list: 'eth0.2' line: '10.63.6.125 dev eth0.2 lladdr 10:6f:3f:0e:31:90 PERMANENT' try: 2 Sat May 9 05:08:07 2015 daemon.info kalua: /bin/sh net_ip_reachable(): arping: 10.63.6.125 dev: eth0.2 list: 'eth0.2' line: '10.63.6.125 dev eth0.2 lladdr 10:6f:3f:0e:31:90 PERMANENT' try: 1 Sat May 9 05:08:08 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): unstable WAN: 10.63.222.61 10.63.6.125 0.00 0.000 1.000 INFINITE Sat May 9 05:08:08 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:08:09 2015 daemon.info kalua: /bin/sh scheduler_wait_till_cpuload_is_low(): PID: 5235 - load: 87 - waiting 31 sec for '_olsr_force_static_arp 10.63.6.65' Sat May 9 05:08:26 2015 kern.info kernel: [ 3529.330000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is down Sat May 9 05:08:42 2015 kern.info kernel: [ 3545.330000] Atheros AR8216/AR8236/AR8316 ag71xx-mdio.0:00: Port 1 is up Sat May 9 05:08:43 2015 daemon.info kalua: /bin/sh wget_do(): [ERR] returning 1 after fetching 'http://10.63.6.65/cgi-bin-tool.sh?OPT=ip2mac' Sat May 9 05:08:43 2015 daemon.info kalua: /bin/sh wget_do(): error: 'wget: can't connect to remote host (10.63.6.65): No route to host' Sat May 9 05:08:43 2015 daemon.info kalua: /bin/sh wget_do(): [ERR] returning 1 after fetching 'http://10.63.6.65/cgi-bin/luci/freifunk/status' Sat May 9 05:08:43 2015 daemon.info kalua: /bin/sh wget_do(): [ERR] returning 1 after fetching 'http://10.63.6.65/cgi-bin-status.html' Sat May 9 05:09:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 177 avg_overall: 179 change: 1% Sat May 9 05:09:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 84 avg_overall: 85 change: 1% Sat May 9 05:09:13 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): unstable WAN: 10.63.222.61 10.63.6.125 0.00 1.000 0.271 3.695 Sat May 9 05:09:14 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:10:01 2015 user.notice root: debug: phy: phy0 interval: 60 avg: 183 avg_overall: 180 change: -1% Sat May 9 05:10:01 2015 user.notice root: debug: phy: phy1 interval: 60 avg: 84 avg_overall: 85 change: 1% Sat May 9 05:10:14 2015 daemon.alert kalua: /bin/sh olsr_build_tables(): wan-ping failed Sat May 9 05:10:14 2015 daemon.info kalua: /bin/sh watch_counter(): [/tmp/error_wanping_olsr_build_tables] max: 5 >= 5