Sql - error Error: connect ETIMEDOUT

nieIP

hallo

was bedeutet folgende Fehlermeldung im Log ??

sql.0	2018-06-25 20:16:29.352	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.350	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.349	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.347	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.345	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.342	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.341	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.339	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.337	error	Error: connect ETIMEDOUT
sql.0	2018-06-25 20:16:29.335	error	Error: connect ETIMEDOUT

apollon77

Connection Timeout zur Datenbank?

Netzwerkprobleme? Datenbankprozess weg?

nieIP

drei mal Nein, jedenfalls nicht von Datenbankseite.

ausserdem ist etwas mit dem RasPi Adapter nicht i.O. Statt Werten werden dort Scriptzeilen zurück geliefert. Ich schalte den erst mal aus

1282_screenshot_2018-06-25_23.56.28.png

apollon77

Was hast du vorher getan irgendwelche Updates?

Wenn du rpi2 auf debug log stellst was kommt so raus?

Gesendet vom Handy …

nieIP

Hallo

ich habe Admin und sql auf die aktuelle Version gebracht.

nieIP

2018-06-26 00:25:12.089 - info: host.raspi-3 object change system.adapter.rpi2.0
2018-06-26 00:25:28.653 - info: host.raspi-3 object change system.adapter.rpi2.0
2018-06-26 00:25:28.656 - info: host.raspi-3 "system.adapter.rpi2.0" enabled
2018-06-26 00:25:28.734 - info: host.raspi-3 instance system.adapter.rpi2.0 started with pid 23191
2018-06-26 00:25:29.994 - debug: rpi2.0 objectDB connected
2018-06-26 00:25:30.110 - debug: rpi2.0 statesDB connected
2018-06-26 00:25:30.249 - info: rpi2.0 starting. Version 0.3.2 in /opt/iobroker/node_modules/iobroker.rpi2, node: v6.13.0
2018-06-26 00:25:35.590 - debug: rpi2.0 received all objects
2018-06-26 00:25:35.592 - debug: rpi2.0 NODE Version = v6.13.0, we need new execSync
2018-06-26 00:25:35.597 - debug: rpi2.0 start parsing
2018-06-26 00:25:35.598 - debug: rpi2.0 PARSING: c_cpu
2018-06-26 00:25:35.598 - debug: rpi2.0 PARSING: c_raspberry
2018-06-26 00:25:35.598 - debug: rpi2.0 PARSING: c_memory
2018-06-26 00:25:35.599 - debug: rpi2.0 PARSING: c_network
2018-06-26 00:25:35.599 - debug: rpi2.0 PARSING: c_sdcard
2018-06-26 00:25:35.599 - debug: rpi2.0 PARSING: c_swap
2018-06-26 00:25:35.599 - debug: rpi2.0 PARSING: c_temperature
2018-06-26 00:25:35.600 - debug: rpi2.0 PARSING: c_uptime
2018-06-26 00:25:35.600 - debug: rpi2.0 PARSING: c_wlan
2018-06-26 00:25:35.600 - debug: rpi2.0 PARSING: interval
2018-06-26 00:25:35.600 - debug: rpi2.0 PARSING: cpu
2018-06-26 00:25:35.601 - debug: rpi2.0 PARSING: cpu_frequency
2018-06-26 00:25:35.601 - debug: rpi2.0 ---> cat /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq
2018-06-26 00:25:35.631 - debug: rpi2.0 ------------- 1200000
2018-06-26 00:25:35.633 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:25:35.633 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.634 - debug: rpi2.0 ---> POST: $1/1000
2018-06-26 00:25:35.634 - debug: rpi2.0 PARSING: load1,load5,load15
2018-06-26 00:25:35.635 - debug: rpi2.0 ---> cat /proc/loadavg
2018-06-26 00:25:35.657 - debug: rpi2.0 ------------- 0.28 0.65 1.67 1/173 23204
2018-06-26 00:25:35.658 - debug: rpi2.0 ---> REGEXP: /^(\S+)\s(\S+)\s(\S+)/
2018-06-26 00:25:35.658 - debug: rpi2.0 GROUPS: 4
2018-06-26 00:25:35.659 - debug: rpi2.0 MATCHING: 0.28
2018-06-26 00:25:35.660 - debug: rpi2.0 NAME: load1, VALULE: 0.28
2018-06-26 00:25:35.661 - debug: rpi2.0 MATCHING: 0.65
2018-06-26 00:25:35.661 - debug: rpi2.0 NAME: load5, VALULE: 0.65
2018-06-26 00:25:35.661 - debug: rpi2.0 MATCHING: 1.67
2018-06-26 00:25:35.662 - debug: rpi2.0 NAME: load15, VALULE: 1.67
2018-06-26 00:25:35.663 - debug: rpi2.0 PARSING: scaling_governor
2018-06-26 00:25:35.663 - debug: rpi2.0 ---> cat /sys/devices/system/cpu/cpu0/cpufreq/scaling_governor
2018-06-26 00:25:35.684 - debug: rpi2.0 ------------- ondemand
2018-06-26 00:25:35.685 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:25:35.685 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.685 - debug: rpi2.0 ---> POST:
2018-06-26 00:25:35.686 - debug: rpi2.0 PARSING: raspberry
2018-06-26 00:25:35.688 - debug: rpi2.0 PARSING: memory
2018-06-26 00:25:35.689 - debug: rpi2.0 PARSING: memory_total
2018-06-26 00:25:35.690 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:25:35.713 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 460664 kB
MemAvailable: 496656 kB
Buffers: 18064 kB
Cached: 59000 kB
SwapCached: 2468 kB
Active: 380448 kB
Inactive: 70856 kB
Active(anon): 335184 kB
Inactive(anon): 39776 kB
Active(file): 45264 kB
Inactive(file): 31080 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 52 kB
Writeback: 0 kB
AnonPages: 372940 kB
Mapped: 24044 kB
Shmem: 720 kB
Slab: 22788 kB
SReclaimable: 9404 kB
SUnreclaim: 13384 kB
KernelStack: 1432 kB
PageTables: 3244 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1010072 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:25:35.716 - debug: rpi2.0 ---> REGEXP: /MemTotal:\s+(\d+)/
2018-06-26 00:25:35.718 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.720 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.721 - debug: rpi2.0 PARSING: memory_free
2018-06-26 00:25:35.722 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:25:35.743 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 460832 kB
MemAvailable: 496824 kB
Buffers: 18064 kB
Cached: 59000 kB
SwapCached: 2468 kB
Active: 380540 kB
Inactive: 70824 kB
Active(anon): 335244 kB
Inactive(anon): 39776 kB
Active(file): 45296 kB
Inactive(file): 31048 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 52 kB
Writeback: 0 kB
AnonPages: 373024 kB
Mapped: 24044 kB
Shmem: 720 kB
Slab: 22796 kB
SReclaimable: 9404 kB
SUnreclaim: 13392 kB
KernelStack: 1416 kB
PageTables: 3124 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1010424 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:25:35.744 - debug: rpi2.0 ---> REGEXP: /MemFree:\s+(\d+)/
2018-06-26 00:25:35.745 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.748 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.749 - debug: rpi2.0 PARSING: memory_available
2018-06-26 00:25:35.749 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:25:35.772 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 460636 kB
MemAvailable: 496628 kB
Buffers: 18064 kB
Cached: 59000 kB
SwapCached: 2468 kB
Active: 380644 kB
Inactive: 70816 kB
Active(anon): 335340 kB
Inactive(anon): 39776 kB
Active(file): 45304 kB
Inactive(file): 31040 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 52 kB
Writeback: 0 kB
AnonPages: 373032 kB
Mapped: 24044 kB
Shmem: 720 kB
Slab: 22804 kB
SReclaimable: 9404 kB
SUnreclaim: 13400 kB
KernelStack: 1416 kB
PageTables: 3152 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1010776 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:25:35.773 - debug: rpi2.0 ---> REGEXP: /MemAvailable:\s+(\d+)/m
2018-06-26 00:25:35.773 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.774 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.775 - debug: rpi2.0 PARSING: network
2018-06-26 00:25:35.775 - debug: rpi2.0 PARSING: net_received
2018-06-26 00:25:35.776 - debug: rpi2.0 ---> cat /sys/class/net/eth0/statistics/rx_bytes
2018-06-26 00:25:35.797 - debug: rpi2.0 ------------- 5830989407
2018-06-26 00:25:35.798 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:25:35.799 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.800 - debug: rpi2.0 ---> POST: $1*-1
2018-06-26 00:25:35.800 - debug: rpi2.0 PARSING: net_send
2018-06-26 00:25:35.800 - debug: rpi2.0 ---> cat /sys/class/net/eth0/statistics/tx_bytes
2018-06-26 00:25:35.822 - debug: rpi2.0 ------------- 1610544677
2018-06-26 00:25:35.822 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:25:35.823 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.825 - debug: rpi2.0 ---> POST:
2018-06-26 00:25:35.826 - debug: rpi2.0 PARSING: sdcard
2018-06-26 00:25:35.826 - debug: rpi2.0 PARSING: sdcard_root_total
2018-06-26 00:25:35.827 - debug: rpi2.0 ---> df /
2018-06-26 00:25:35.883 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/root 30645636 6260036 23086032 22% /
2018-06-26 00:25:35.884 - debug: rpi2.0 ---> REGEXP: /\S+\s+(\d+).*\/$/m
2018-06-26 00:25:35.884 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.884 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.885 - debug: rpi2.0 PARSING: sdcard_boot_total
2018-06-26 00:25:35.885 - debug: rpi2.0 ---> df /boot
2018-06-26 00:25:35.907 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/mmcblk0p1 64456 22832 41624 36% /boot
2018-06-26 00:25:35.908 - debug: rpi2.0 ---> REGEXP: /\S+\s+(\d+).*\/boot$/m
2018-06-26 00:25:35.909 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.909 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.910 - debug: rpi2.0 PARSING: sdcard_root_used
2018-06-26 00:25:35.910 - debug: rpi2.0 ---> df /
2018-06-26 00:25:35.932 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/root 30645636 6260036 23086032 22% /
2018-06-26 00:25:35.934 - debug: rpi2.0 ---> REGEXP: /\S+\s+\d+\s+(\d+).*\/$/m
2018-06-26 00:25:35.934 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.934 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.935 - debug: rpi2.0 PARSING: sdcard_boot_used
2018-06-26 00:25:35.935 - debug: rpi2.0 ---> df /boot
2018-06-26 00:25:35.957 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/mmcblk0p1 64456 22832 41624 36% /boot
2018-06-26 00:25:35.958 - debug: rpi2.0 ---> REGEXP: /\S+\s+\d+\s+(\d+).*\/boot$/m
2018-06-26 00:25:35.959 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.959 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.959 - debug: rpi2.0 PARSING: swap
2018-06-26 00:25:35.960 - debug: rpi2.0 PARSING: swap_total
2018-06-26 00:25:35.960 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:25:35.990 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 459328 kB
MemAvailable: 495968 kB
Buffers: 18312 kB
Cached: 59400 kB
SwapCached: 2468 kB
Active: 381600 kB
Inactive: 70900 kB
Active(anon): 335732 kB
Inactive(anon): 39776 kB
Active(file): 45868 kB
Inactive(file): 31124 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 52 kB
Writeback: 0 kB
AnonPages: 373508 kB
Mapped: 24064 kB
Shmem: 720 kB
Slab: 22888 kB
SReclaimable: 9408 kB
SUnreclaim: 13480 kB
KernelStack: 1440 kB
PageTables: 3108 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1010776 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:25:35.993 - debug: rpi2.0 ---> REGEXP: /SwapTotal:\s+(\d+)/m
2018-06-26 00:25:35.994 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:35.995 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:25:35.995 - debug: rpi2.0 PARSING: swap_used
2018-06-26 00:25:35.996 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:25:36.017 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 459340 kB
MemAvailable: 495980 kB
Buffers: 18312 kB
Cached: 59400 kB
SwapCached: 2468 kB
Active: 381692 kB
Inactive: 70900 kB
Active(anon): 335824 kB
Inactive(anon): 39776 kB
Active(file): 45868 kB
Inactive(file): 31124 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 52 kB
Writeback: 0 kB
AnonPages: 373388 kB
Mapped: 24064 kB
Shmem: 720 kB
Slab: 22892 kB
SReclaimable: 9408 kB
SUnreclaim: 13484 kB
KernelStack: 1440 kB
PageTables: 3152 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1010072 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:25:36.018 - debug: rpi2.0 ---> REGEXP: /SwapFree:\s+(\d+)/m
2018-06-26 00:25:36.019 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:36.019 - debug: rpi2.0 ---> POST: (rpi.swap_total - $1)/1024
2018-06-26 00:25:36.020 - debug: rpi2.0 PARSING: temperature
2018-06-26 00:25:36.021 - debug: rpi2.0 PARSING: soc_temp
2018-06-26 00:25:36.021 - debug: rpi2.0 ---> cat /sys/devices/virtual/thermal/thermal_zone0/temp
2018-06-26 00:25:36.045 - debug: rpi2.0 ------------- 56920
2018-06-26 00:25:36.046 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:25:36.047 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:36.048 - debug: rpi2.0 ---> POST: $1/1000
2018-06-26 00:25:36.049 - debug: rpi2.0 PARSING: uptime
2018-06-26 00:25:36.049 - debug: rpi2.0 PARSING: uptime
2018-06-26 00:25:36.050 - debug: rpi2.0 ---> cat /proc/uptime
2018-06-26 00:25:36.071 - debug: rpi2.0 ------------- 2094844.42 7870980.39
2018-06-26 00:25:36.072 - debug: rpi2.0 ---> REGEXP: /(^\S+)/
2018-06-26 00:25:36.076 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:25:36.077 - debug: rpi2.0 ---> POST:
2018-06-26 00:25:36.078 - debug: rpi2.0 PARSING: wlan
2018-06-26 00:25:36.079 - debug: rpi2.0 PARSING: gpios
2018-06-26 00:25:36.082 - debug: rpi2.0 CURRENT = c_cpu undefined
2018-06-26 00:25:36.087 - debug: rpi2.0 0
2018-06-26 00:25:36.088 - debug: rpi2.0 CURRENT = c_raspberry undefined
2018-06-26 00:25:36.088 - debug: rpi2.0 0
2018-06-26 00:25:36.090 - debug: rpi2.0 CURRENT = c_memory undefined
2018-06-26 00:25:36.093 - debug: rpi2.0 0
2018-06-26 00:25:36.094 - debug: rpi2.0 CURRENT = c_network undefined
2018-06-26 00:25:36.096 - debug: rpi2.0 0
2018-06-26 00:25:36.096 - debug: rpi2.0 CURRENT = c_sdcard undefined
2018-06-26 00:25:36.097 - debug: rpi2.0 0
2018-06-26 00:25:36.097 - debug: rpi2.0 CURRENT = c_swap undefined
2018-06-26 00:25:36.097 - debug: rpi2.0 0
2018-06-26 00:25:36.097 - debug: rpi2.0 CURRENT = c_temperature undefined
2018-06-26 00:25:36.097 - debug: rpi2.0 0
2018-06-26 00:25:36.099 - debug: rpi2.0 CURRENT = c_uptime undefined
2018-06-26 00:25:36.099 - debug: rpi2.0 0
2018-06-26 00:25:36.100 - debug: rpi2.0 CURRENT = c_wlan undefined
2018-06-26 00:25:36.101 - debug: rpi2.0 0
2018-06-26 00:25:36.101 - debug: rpi2.0 CURRENT = interval undefined
2018-06-26 00:25:36.101 - debug: rpi2.0 -1
2018-06-26 00:25:36.101 - debug: rpi2.0 CURRENT = cpu true
2018-06-26 00:25:36.102 - debug: rpi2.0 -1
2018-06-26 00:25:36.106 - debug: rpi2.0 ---> POST: $1/1000 for cpu_frequency in [object Object]
2018-06-26 00:25:36.107 - debug: rpi2.0 VALUE: 1200000 POST: $1/1000
2018-06-26 00:25:36.111 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.cpu_frequency VALUE = 1200
2018-06-26 00:25:36.114 - debug: rpi2.0 ---> POST: for load1,load5,load15 in [object Object]
2018-06-26 00:25:36.115 - debug: rpi2.0 MATCHING: 0.28
2018-06-26 00:25:36.116 - debug: rpi2.0 NAME: load1 VALULE: 0.28
2018-06-26 00:25:36.116 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load1 VALUE = 0.28
2018-06-26 00:25:36.117 - debug: rpi2.0 MATCHING: 0.65
2018-06-26 00:25:36.118 - debug: rpi2.0 NAME: load5 VALULE: 0.65
2018-06-26 00:25:36.118 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load5 VALUE = 0.65
2018-06-26 00:25:36.119 - debug: rpi2.0 MATCHING: 1.67
2018-06-26 00:25:36.119 - debug: rpi2.0 NAME: load15 VALULE: 1.67
2018-06-26 00:25:36.120 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load15 VALUE = 1.67
2018-06-26 00:25:36.121 - debug: rpi2.0 ---> POST: for scaling_governor in [object Object]
2018-06-26 00:25:36.121 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.scaling_governor VALUE = ondemand
2018-06-26 00:25:36.122 - debug: rpi2.0 CURRENT = raspberry false
2018-06-26 00:25:36.122 - debug: rpi2.0 -1
2018-06-26 00:25:36.122 - debug: rpi2.0 CURRENT = memory true
2018-06-26 00:25:36.122 - debug: rpi2.0 -1
2018-06-26 00:25:36.124 - debug: rpi2.0 ---> POST: $1/1024 for memory_total in [object Object]
2018-06-26 00:25:36.124 - debug: rpi2.0 VALUE: 949476 POST: $1/1024
2018-06-26 00:25:36.125 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_total VALUE = 927.22
2018-06-26 00:25:36.126 - debug: rpi2.0 ---> POST: $1/1024 for memory_free in [object Object]
2018-06-26 00:25:36.126 - debug: rpi2.0 VALUE: 460832 POST: $1/1024
2018-06-26 00:25:36.126 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_free VALUE = 450.03
2018-06-26 00:25:36.127 - debug: rpi2.0 ---> POST: $1/1024 for memory_available in [object Object]
2018-06-26 00:25:36.127 - debug: rpi2.0 VALUE: 496628 POST: $1/1024
2018-06-26 00:25:36.129 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_available VALUE = 484.99
2018-06-26 00:25:36.129 - debug: rpi2.0 CURRENT = network true
2018-06-26 00:25:36.129 - debug: rpi2.0 -1
2018-06-26 00:25:36.130 - debug: rpi2.0 ---> POST: $1*-1 for net_received in [object Object]
2018-06-26 00:25:36.130 - debug: rpi2.0 VALUE: 5830989407 POST: $1*-1
2018-06-26 00:25:36.131 - debug: rpi2.0 SETSTATE FOR rpi2.0.network.net_received VALUE = -5830989407
2018-06-26 00:25:36.131 - debug: rpi2.0 ---> POST: for net_send in [object Object]
2018-06-26 00:25:36.132 - debug: rpi2.0 SETSTATE FOR rpi2.0.network.net_send VALUE = 1610544677
2018-06-26 00:25:36.132 - debug: rpi2.0 CURRENT = sdcard true
2018-06-26 00:25:36.132 - debug: rpi2.0 -1
2018-06-26 00:25:36.133 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_root_total in [object Object]
2018-06-26 00:25:36.133 - debug: rpi2.0 VALUE: 30645636 POST: $1/1024
2018-06-26 00:25:36.134 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_root_total VALUE = 29927.38
2018-06-26 00:25:36.135 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_boot_total in [object Object]
2018-06-26 00:25:36.135 - debug: rpi2.0 VALUE: 64456 POST: $1/1024
2018-06-26 00:25:36.136 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_boot_total VALUE = 62.95
2018-06-26 00:25:36.136 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_root_used in [object Object]
2018-06-26 00:25:36.137 - debug: rpi2.0 VALUE: 6260036 POST: $1/1024
2018-06-26 00:25:36.137 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_root_used VALUE = 6113.32
2018-06-26 00:25:36.138 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_boot_used in [object Object]
2018-06-26 00:25:36.138 - debug: rpi2.0 VALUE: 22832 POST: $1/1024
2018-06-26 00:25:36.138 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_boot_used VALUE = 22.30
2018-06-26 00:25:36.139 - debug: rpi2.0 CURRENT = swap true
2018-06-26 00:25:36.139 - debug: rpi2.0 -1
2018-06-26 00:25:36.140 - debug: rpi2.0 ---> POST: $1/1024 for swap_total in [object Object]
2018-06-26 00:25:36.140 - debug: rpi2.0 VALUE: 102396 POST: $1/1024
2018-06-26 00:25:36.141 - debug: rpi2.0 SETSTATE FOR rpi2.0.swap.swap_total VALUE = 100.00
2018-06-26 00:25:36.142 - debug: rpi2.0 ---> POST: (rpi.swap_total - $1)/1024 for swap_used in [object Object]
2018-06-26 00:25:36.142 - debug: rpi2.0 VALUE: 59344 POST: (rpi.swap_total - $1)/1024
2018-06-26 00:25:36.143 - debug: rpi2.0 SETSTATE FOR rpi2.0.swap.swap_used VALUE = 42.04
2018-06-26 00:25:36.144 - debug: rpi2.0 CURRENT = temperature true
2018-06-26 00:25:36.144 - debug: rpi2.0 -1
2018-06-26 00:25:36.145 - debug: rpi2.0 ---> POST: $1/1000 for soc_temp in [object Object]
2018-06-26 00:25:36.145 - debug: rpi2.0 VALUE: 56920 POST: $1/1000
2018-06-26 00:25:36.146 - debug: rpi2.0 SETSTATE FOR rpi2.0.temperature.soc_temp VALUE = 56.92
2018-06-26 00:25:36.146 - debug: rpi2.0 CURRENT = uptime true
2018-06-26 00:25:36.146 - debug: rpi2.0 -1
2018-06-26 00:25:36.147 - debug: rpi2.0 ---> POST: for uptime in [object Object]
2018-06-26 00:25:36.147 - debug: rpi2.0 SETSTATE FOR rpi2.0.uptime.uptime VALUE = 2094844.42
2018-06-26 00:25:36.147 - debug: rpi2.0 CURRENT = wlan false
2018-06-26 00:25:36.148 - debug: rpi2.0 -1
2018-06-26 00:25:36.148 - debug: rpi2.0 CURRENT = gpios undefined
2018-06-26 00:25:36.149 - debug: rpi2.0 -1
2018-06-26 00:25:36.200 - debug: rpi2.0 stateChange for rpi2.0.cpu.cpu_frequency found state = {"val":1200,"ack":true,"ts":1529965536114,"q":0,"from":"system.adapter.rpi2.0","lc":1529963879783}
2018-06-26 00:25:36.201 - debug: rpi2.0 stateChange for rpi2.0.cpu.load1 found state = {"val":"0.28","ack":true,"ts":1529965536178,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536178}
2018-06-26 00:25:36.203 - debug: rpi2.0 stateChange for rpi2.0.cpu.load5 found state = {"val":"0.65","ack":true,"ts":1529965536182,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536182}
2018-06-26 00:25:36.204 - debug: rpi2.0 stateChange for rpi2.0.cpu.load15 found state = {"val":"1.67","ack":true,"ts":1529965536183,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536183}
2018-06-26 00:25:36.206 - debug: rpi2.0 stateChange for rpi2.0.cpu.scaling_governor found state = {"val":"ondemand","ack":true,"ts":1529965536184,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991334}
2018-06-26 00:25:36.207 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_total found state = {"val":"927.22","ack":true,"ts":1529965536184,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991334}
2018-06-26 00:25:36.228 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_free found state = {"val":"450.03","ack":true,"ts":1529965536200,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536200}
2018-06-26 00:25:36.229 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_available found state = {"val":"484.99","ack":true,"ts":1529965536203,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536203}
2018-06-26 00:25:36.231 - debug: rpi2.0 stateChange for rpi2.0.network.net_received found state = {"val":-5830989407,"ack":true,"ts":1529965536203,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536203}
2018-06-26 00:25:36.232 - debug: rpi2.0 stateChange for rpi2.0.network.net_send found state = {"val":1610544677,"ack":true,"ts":1529965536204,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536204}
2018-06-26 00:25:36.233 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_root_total found state = {"val":"29927.38","ack":true,"ts":1529965536204,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991336}
2018-06-26 00:25:36.233 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_boot_total found state = {"val":"62.95","ack":true,"ts":1529965536205,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991336}
2018-06-26 00:25:36.234 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_root_used found state = {"val":"6113.32","ack":true,"ts":1529965536205,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536205}
2018-06-26 00:25:36.236 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_boot_used found state = {"val":"22.30","ack":true,"ts":1529965536206,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991337}
2018-06-26 00:25:36.237 - debug: rpi2.0 stateChange for rpi2.0.swap.swap_total found state = {"val":"100.00","ack":true,"ts":1529965536206,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991337}
2018-06-26 00:25:36.237 - debug: rpi2.0 stateChange for rpi2.0.swap.swap_used found state = {"val":"42.04","ack":true,"ts":1529965536207,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536207}
2018-06-26 00:25:36.238 - debug: rpi2.0 stateChange for rpi2.0.temperature.soc_temp found state = {"val":"56.92","ack":true,"ts":1529965536207,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536207}
2018-06-26 00:25:36.239 - debug: rpi2.0 stateChange for rpi2.0.uptime.uptime found state = {"val":"2094844.42","ack":true,"ts":1529965536208,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536208}
2018-06-26 00:26:35.599 - debug: rpi2.0 start parsing
2018-06-26 00:26:35.601 - debug: rpi2.0 PARSING: c_cpu
2018-06-26 00:26:35.601 - debug: rpi2.0 PARSING: c_raspberry
2018-06-26 00:26:35.602 - debug: rpi2.0 PARSING: c_memory
2018-06-26 00:26:35.602 - debug: rpi2.0 PARSING: c_network
2018-06-26 00:26:35.603 - debug: rpi2.0 PARSING: c_sdcard
2018-06-26 00:26:35.603 - debug: rpi2.0 PARSING: c_swap
2018-06-26 00:26:35.611 - debug: rpi2.0 PARSING: c_temperature
2018-06-26 00:26:35.612 - debug: rpi2.0 PARSING: c_uptime
2018-06-26 00:26:35.612 - debug: rpi2.0 PARSING: c_wlan
2018-06-26 00:26:35.613 - debug: rpi2.0 PARSING: interval
2018-06-26 00:26:35.614 - debug: rpi2.0 PARSING: cpu
2018-06-26 00:26:35.616 - debug: rpi2.0 PARSING: cpu_frequency
2018-06-26 00:26:35.617 - debug: rpi2.0 ---> cat /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq
2018-06-26 00:26:35.651 - debug: rpi2.0 ------------- 600000
2018-06-26 00:26:35.652 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:26:35.653 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.715 - debug: rpi2.0 ---> POST: $1/1000
2018-06-26 00:26:35.717 - debug: rpi2.0 PARSING: load1,load5,load15
2018-06-26 00:26:35.718 - debug: rpi2.0 ---> cat /proc/loadavg
2018-06-26 00:26:35.762 - debug: rpi2.0 ------------- 0.35 0.59 1.59 1/173 23237
2018-06-26 00:26:35.762 - debug: rpi2.0 ---> REGEXP: /^(\S+)\s(\S+)\s(\S+)/
2018-06-26 00:26:35.762 - debug: rpi2.0 GROUPS: 4
2018-06-26 00:26:35.763 - debug: rpi2.0 MATCHING: 0.35
2018-06-26 00:26:35.763 - debug: rpi2.0 NAME: load1, VALULE: 0.35
2018-06-26 00:26:35.763 - debug: rpi2.0 MATCHING: 0.59
2018-06-26 00:26:35.765 - debug: rpi2.0 NAME: load5, VALULE: 0.59
2018-06-26 00:26:35.765 - debug: rpi2.0 MATCHING: 1.59
2018-06-26 00:26:35.766 - debug: rpi2.0 NAME: load15, VALULE: 1.59
2018-06-26 00:26:35.766 - debug: rpi2.0 PARSING: scaling_governor
2018-06-26 00:26:35.766 - debug: rpi2.0 ---> cat /sys/devices/system/cpu/cpu0/cpufreq/scaling_governor
2018-06-26 00:26:35.786 - debug: rpi2.0 ------------- ondemand
2018-06-26 00:26:35.786 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:26:35.786 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.786 - debug: rpi2.0 ---> POST:
2018-06-26 00:26:35.787 - debug: rpi2.0 PARSING: raspberry
2018-06-26 00:26:35.787 - debug: rpi2.0 PARSING: memory
2018-06-26 00:26:35.787 - debug: rpi2.0 PARSING: memory_total
2018-06-26 00:26:35.787 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:26:35.809 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 467348 kB
MemAvailable: 504020 kB
Buffers: 18352 kB
Cached: 59428 kB
SwapCached: 2468 kB
Active: 374332 kB
Inactive: 70828 kB
Active(anon): 328324 kB
Inactive(anon): 39776 kB
Active(file): 46008 kB
Inactive(file): 31052 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 24 kB
Writeback: 0 kB
AnonPages: 366144 kB
Mapped: 24052 kB
Shmem: 720 kB
Slab: 22876 kB
SReclaimable: 9336 kB
SUnreclaim: 13540 kB
KernelStack: 1440 kB
PageTables: 3148 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1005120 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:26:35.810 - debug: rpi2.0 ---> REGEXP: /MemTotal:\s+(\d+)/
2018-06-26 00:26:35.810 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.810 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.810 - debug: rpi2.0 PARSING: memory_free
2018-06-26 00:26:35.811 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:26:35.830 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 467472 kB
MemAvailable: 504144 kB
Buffers: 18352 kB
Cached: 59428 kB
SwapCached: 2468 kB
Active: 374360 kB
Inactive: 70828 kB
Active(anon): 328352 kB
Inactive(anon): 39776 kB
Active(file): 46008 kB
Inactive(file): 31052 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 24 kB
Writeback: 0 kB
AnonPages: 366128 kB
Mapped: 24052 kB
Shmem: 720 kB
Slab: 22876 kB
SReclaimable: 9336 kB
SUnreclaim: 13540 kB
KernelStack: 1424 kB
PageTables: 3172 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1004420 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:26:35.830 - debug: rpi2.0 ---> REGEXP: /MemFree:\s+(\d+)/
2018-06-26 00:26:35.831 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.831 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.831 - debug: rpi2.0 PARSING: memory_available
2018-06-26 00:26:35.831 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:26:35.851 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 467324 kB
MemAvailable: 503996 kB
Buffers: 18352 kB
Cached: 59428 kB
SwapCached: 2468 kB
Active: 374288 kB
Inactive: 70828 kB
Active(anon): 328280 kB
Inactive(anon): 39776 kB
Active(file): 46008 kB
Inactive(file): 31052 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 24 kB
Writeback: 0 kB
AnonPages: 366156 kB
Mapped: 24064 kB
Shmem: 720 kB
Slab: 22860 kB
SReclaimable: 9336 kB
SUnreclaim: 13524 kB
KernelStack: 1432 kB
PageTables: 3184 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1004772 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:26:35.851 - debug: rpi2.0 ---> REGEXP: /MemAvailable:\s+(\d+)/m
2018-06-26 00:26:35.852 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.852 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.852 - debug: rpi2.0 PARSING: network
2018-06-26 00:26:35.852 - debug: rpi2.0 PARSING: net_received
2018-06-26 00:26:35.853 - debug: rpi2.0 ---> cat /sys/class/net/eth0/statistics/rx_bytes
2018-06-26 00:26:35.872 - debug: rpi2.0 ------------- 5831114772
2018-06-26 00:26:35.873 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:26:35.873 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.873 - debug: rpi2.0 ---> POST: $1*-1
2018-06-26 00:26:35.873 - debug: rpi2.0 PARSING: net_send
2018-06-26 00:26:35.874 - debug: rpi2.0 ---> cat /sys/class/net/eth0/statistics/tx_bytes
2018-06-26 00:26:35.893 - debug: rpi2.0 ------------- 1610644115
2018-06-26 00:26:35.893 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:26:35.894 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.894 - debug: rpi2.0 ---> POST:
2018-06-26 00:26:35.894 - debug: rpi2.0 PARSING: sdcard
2018-06-26 00:26:35.895 - debug: rpi2.0 PARSING: sdcard_root_total
2018-06-26 00:26:35.895 - debug: rpi2.0 ---> df /
2018-06-26 00:26:35.915 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/root 30645636 6260060 23086008 22% /
2018-06-26 00:26:35.916 - debug: rpi2.0 ---> REGEXP: /\S+\s+(\d+).*\/$/m
2018-06-26 00:26:35.916 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.916 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.916 - debug: rpi2.0 PARSING: sdcard_boot_total
2018-06-26 00:26:35.917 - debug: rpi2.0 ---> df /boot
2018-06-26 00:26:35.937 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/mmcblk0p1 64456 22832 41624 36% /boot
2018-06-26 00:26:35.938 - debug: rpi2.0 ---> REGEXP: /\S+\s+(\d+).*\/boot$/m
2018-06-26 00:26:35.938 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.938 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.938 - debug: rpi2.0 PARSING: sdcard_root_used
2018-06-26 00:26:35.939 - debug: rpi2.0 ---> df /
2018-06-26 00:26:35.959 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/root 30645636 6260060 23086008 22% /
2018-06-26 00:26:35.960 - debug: rpi2.0 ---> REGEXP: /\S+\s+\d+\s+(\d+).*\/$/m
2018-06-26 00:26:35.960 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.960 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.961 - debug: rpi2.0 PARSING: sdcard_boot_used
2018-06-26 00:26:35.961 - debug: rpi2.0 ---> df /boot
2018-06-26 00:26:35.981 - debug: rpi2.0 ------------- Dateisystem 1K-Blöcke Benutzt Verfügbar Verw% Eingehängt auf
/dev/mmcblk0p1 64456 22832 41624 36% /boot
2018-06-26 00:26:35.982 - debug: rpi2.0 ---> REGEXP: /\S+\s+\d+\s+(\d+).*\/boot$/m
2018-06-26 00:26:35.982 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:35.982 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:35.984 - debug: rpi2.0 PARSING: swap
2018-06-26 00:26:35.985 - debug: rpi2.0 PARSING: swap_total
2018-06-26 00:26:35.986 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:26:36.008 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 467076 kB
MemAvailable: 503748 kB
Buffers: 18352 kB
Cached: 59428 kB
SwapCached: 2468 kB
Active: 374400 kB
Inactive: 70828 kB
Active(anon): 328392 kB
Inactive(anon): 39776 kB
Active(file): 46008 kB
Inactive(file): 31052 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 24 kB
Writeback: 0 kB
AnonPages: 366172 kB
Mapped: 24056 kB
Shmem: 720 kB
Slab: 22860 kB
SReclaimable: 9336 kB
SUnreclaim: 13524 kB
KernelStack: 1448 kB
PageTables: 3128 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1004424 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:26:36.008 - debug: rpi2.0 ---> REGEXP: /SwapTotal:\s+(\d+)/m
2018-06-26 00:26:36.009 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:36.009 - debug: rpi2.0 ---> POST: $1/1024
2018-06-26 00:26:36.009 - debug: rpi2.0 PARSING: swap_used
2018-06-26 00:26:36.009 - debug: rpi2.0 ---> cat /proc/meminfo
2018-06-26 00:26:36.029 - debug: rpi2.0 ------------- MemTotal: 949476 kB
MemFree: 466928 kB
MemAvailable: 503600 kB
Buffers: 18352 kB
Cached: 59428 kB
SwapCached: 2468 kB
Active: 374300 kB
Inactive: 70828 kB
Active(anon): 328292 kB
Inactive(anon): 39776 kB
Active(file): 46008 kB
Inactive(file): 31052 kB
Unevictable: 0 kB
Mlocked: 0 kB
SwapTotal: 102396 kB
SwapFree: 59344 kB
Dirty: 24 kB
Writeback: 0 kB
AnonPages: 366120 kB
Mapped: 24156 kB
Shmem: 720 kB
Slab: 22860 kB
SReclaimable: 9336 kB
SUnreclaim: 13524 kB
KernelStack: 1456 kB
PageTables: 3148 kB
NFS_Unstable: 0 kB
Bounce: 0 kB
WritebackTmp: 0 kB
CommitLimit: 577132 kB
Committed_AS: 1004776 kB
VmallocTotal: 1114112 kB
VmallocUsed: 0 kB
VmallocChunk: 0 kB
CmaTotal: 8192 kB
CmaFree: 5940 kB
2018-06-26 00:26:36.030 - debug: rpi2.0 ---> REGEXP: /SwapFree:\s+(\d+)/m
2018-06-26 00:26:36.030 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:36.031 - debug: rpi2.0 ---> POST: (rpi.swap_total - $1)/1024
2018-06-26 00:26:36.031 - debug: rpi2.0 PARSING: temperature
2018-06-26 00:26:36.031 - debug: rpi2.0 PARSING: soc_temp
2018-06-26 00:26:36.031 - debug: rpi2.0 ---> cat /sys/devices/virtual/thermal/thermal_zone0/temp
2018-06-26 00:26:36.053 - debug: rpi2.0 ------------- 51540
2018-06-26 00:26:36.054 - debug: rpi2.0 ---> REGEXP: /(.*)/
2018-06-26 00:26:36.054 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:36.055 - debug: rpi2.0 ---> POST: $1/1000
2018-06-26 00:26:36.056 - debug: rpi2.0 PARSING: uptime
2018-06-26 00:26:36.056 - debug: rpi2.0 PARSING: uptime
2018-06-26 00:26:36.057 - debug: rpi2.0 ---> cat /proc/uptime
2018-06-26 00:26:36.077 - debug: rpi2.0 ------------- 2094904.43 7871214.07
2018-06-26 00:26:36.078 - debug: rpi2.0 ---> REGEXP: /(^\S+)/
2018-06-26 00:26:36.078 - debug: rpi2.0 GROUPS: 2
2018-06-26 00:26:36.078 - debug: rpi2.0 ---> POST:
2018-06-26 00:26:36.078 - debug: rpi2.0 PARSING: wlan
2018-06-26 00:26:36.079 - debug: rpi2.0 PARSING: gpios
2018-06-26 00:26:36.079 - debug: rpi2.0 CURRENT = c_cpu undefined
2018-06-26 00:26:36.079 - debug: rpi2.0 0
2018-06-26 00:26:36.079 - debug: rpi2.0 CURRENT = c_raspberry undefined
2018-06-26 00:26:36.079 - debug: rpi2.0 0
2018-06-26 00:26:36.080 - debug: rpi2.0 CURRENT = c_memory undefined
2018-06-26 00:26:36.080 - debug: rpi2.0 0
2018-06-26 00:26:36.080 - debug: rpi2.0 CURRENT = c_network undefined
2018-06-26 00:26:36.080 - debug: rpi2.0 0
2018-06-26 00:26:36.080 - debug: rpi2.0 CURRENT = c_sdcard undefined
2018-06-26 00:26:36.080 - debug: rpi2.0 0
2018-06-26 00:26:36.081 - debug: rpi2.0 CURRENT = c_swap undefined
2018-06-26 00:26:36.081 - debug: rpi2.0 0
2018-06-26 00:26:36.081 - debug: rpi2.0 CURRENT = c_temperature undefined
2018-06-26 00:26:36.081 - debug: rpi2.0 0
2018-06-26 00:26:36.082 - debug: rpi2.0 CURRENT = c_uptime undefined
2018-06-26 00:26:36.082 - debug: rpi2.0 0
2018-06-26 00:26:36.082 - debug: rpi2.0 CURRENT = c_wlan undefined
2018-06-26 00:26:36.082 - debug: rpi2.0 0
2018-06-26 00:26:36.082 - debug: rpi2.0 CURRENT = interval undefined
2018-06-26 00:26:36.083 - debug: rpi2.0 -1
2018-06-26 00:26:36.083 - debug: rpi2.0 CURRENT = cpu true
2018-06-26 00:26:36.083 - debug: rpi2.0 -1
2018-06-26 00:26:36.085 - debug: rpi2.0 ---> POST: $1/1000 for cpu_frequency in [object Object]
2018-06-26 00:26:36.085 - debug: rpi2.0 VALUE: 600000 POST: $1/1000
2018-06-26 00:26:36.087 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.cpu_frequency VALUE = 600
2018-06-26 00:26:36.089 - debug: rpi2.0 ---> POST: for load1,load5,load15 in [object Object]
2018-06-26 00:26:36.089 - debug: rpi2.0 MATCHING: 0.35
2018-06-26 00:26:36.090 - debug: rpi2.0 NAME: load1 VALULE: 0.35
2018-06-26 00:26:36.090 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load1 VALUE = 0.35
2018-06-26 00:26:36.091 - debug: rpi2.0 MATCHING: 0.59
2018-06-26 00:26:36.092 - debug: rpi2.0 NAME: load5 VALULE: 0.59
2018-06-26 00:26:36.092 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load5 VALUE = 0.59
2018-06-26 00:26:36.093 - debug: rpi2.0 MATCHING: 1.59
2018-06-26 00:26:36.093 - debug: rpi2.0 NAME: load15 VALULE: 1.59
2018-06-26 00:26:36.103 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.load15 VALUE = 1.59
2018-06-26 00:26:36.106 - debug: rpi2.0 ---> POST: for scaling_governor in [object Object]
2018-06-26 00:26:36.107 - debug: rpi2.0 SETSTATE FOR rpi2.0.cpu.scaling_governor VALUE = ondemand
2018-06-26 00:26:36.109 - debug: rpi2.0 CURRENT = raspberry false
2018-06-26 00:26:36.110 - debug: rpi2.0 -1
2018-06-26 00:26:36.110 - debug: rpi2.0 CURRENT = memory true
2018-06-26 00:26:36.111 - debug: rpi2.0 -1
2018-06-26 00:26:36.111 - debug: rpi2.0 ---> POST: $1/1024 for memory_total in [object Object]
2018-06-26 00:26:36.111 - debug: rpi2.0 VALUE: 949476 POST: $1/1024
2018-06-26 00:26:36.112 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_total VALUE = 927.22
2018-06-26 00:26:36.113 - debug: rpi2.0 ---> POST: $1/1024 for memory_free in [object Object]
2018-06-26 00:26:36.113 - debug: rpi2.0 VALUE: 467472 POST: $1/1024
2018-06-26 00:26:36.114 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_free VALUE = 456.52
2018-06-26 00:26:36.114 - debug: rpi2.0 ---> POST: $1/1024 for memory_available in [object Object]
2018-06-26 00:26:36.115 - debug: rpi2.0 VALUE: 503996 POST: $1/1024
2018-06-26 00:26:36.116 - debug: rpi2.0 SETSTATE FOR rpi2.0.memory.memory_available VALUE = 492.18
2018-06-26 00:26:36.116 - debug: rpi2.0 CURRENT = network true
2018-06-26 00:26:36.116 - debug: rpi2.0 -1
2018-06-26 00:26:36.118 - debug: rpi2.0 ---> POST: $1*-1 for net_received in [object Object]
2018-06-26 00:26:36.118 - debug: rpi2.0 VALUE: 5831114772 POST: $1*-1
2018-06-26 00:26:36.119 - debug: rpi2.0 SETSTATE FOR rpi2.0.network.net_received VALUE = -5831114772
2018-06-26 00:26:36.122 - debug: rpi2.0 ---> POST: for net_send in [object Object]
2018-06-26 00:26:36.122 - debug: rpi2.0 SETSTATE FOR rpi2.0.network.net_send VALUE = 1610644115
2018-06-26 00:26:36.122 - debug: rpi2.0 CURRENT = sdcard true
2018-06-26 00:26:36.123 - debug: rpi2.0 -1
2018-06-26 00:26:36.123 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_root_total in [object Object]
2018-06-26 00:26:36.123 - debug: rpi2.0 VALUE: 30645636 POST: $1/1024
2018-06-26 00:26:36.124 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_root_total VALUE = 29927.38
2018-06-26 00:26:36.124 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_boot_total in [object Object]
2018-06-26 00:26:36.124 - debug: rpi2.0 VALUE: 64456 POST: $1/1024
2018-06-26 00:26:36.125 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_boot_total VALUE = 62.95
2018-06-26 00:26:36.125 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_root_used in [object Object]
2018-06-26 00:26:36.126 - debug: rpi2.0 VALUE: 6260060 POST: $1/1024
2018-06-26 00:26:36.126 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_root_used VALUE = 6113.34
2018-06-26 00:26:36.126 - debug: rpi2.0 ---> POST: $1/1024 for sdcard_boot_used in [object Object]
2018-06-26 00:26:36.127 - debug: rpi2.0 VALUE: 22832 POST: $1/1024
2018-06-26 00:26:36.128 - debug: rpi2.0 SETSTATE FOR rpi2.0.sdcard.sdcard_boot_used VALUE = 22.30
2018-06-26 00:26:36.129 - debug: rpi2.0 CURRENT = swap true
2018-06-26 00:26:36.129 - debug: rpi2.0 -1
2018-06-26 00:26:36.129 - debug: rpi2.0 ---> POST: $1/1024 for swap_total in [object Object]
2018-06-26 00:26:36.130 - debug: rpi2.0 VALUE: 102396 POST: $1/1024
2018-06-26 00:26:36.130 - debug: rpi2.0 SETSTATE FOR rpi2.0.swap.swap_total VALUE = 100.00
2018-06-26 00:26:36.131 - debug: rpi2.0 ---> POST: (rpi.swap_total - $1)/1024 for swap_used in [object Object]
2018-06-26 00:26:36.131 - debug: rpi2.0 VALUE: 59344 POST: (rpi.swap_total - $1)/1024
2018-06-26 00:26:36.131 - debug: rpi2.0 SETSTATE FOR rpi2.0.swap.swap_used VALUE = 42.04
2018-06-26 00:26:36.133 - debug: rpi2.0 CURRENT = temperature true
2018-06-26 00:26:36.134 - debug: rpi2.0 -1
2018-06-26 00:26:36.136 - debug: rpi2.0 ---> POST: $1/1000 for soc_temp in [object Object]
2018-06-26 00:26:36.137 - debug: rpi2.0 VALUE: 51540 POST: $1/1000
2018-06-26 00:26:36.137 - debug: rpi2.0 SETSTATE FOR rpi2.0.temperature.soc_temp VALUE = 51.54
2018-06-26 00:26:36.138 - debug: rpi2.0 CURRENT = uptime true
2018-06-26 00:26:36.138 - debug: rpi2.0 -1
2018-06-26 00:26:36.138 - debug: rpi2.0 ---> POST: for uptime in [object Object]
2018-06-26 00:26:36.138 - debug: rpi2.0 SETSTATE FOR rpi2.0.uptime.uptime VALUE = 2094904.43
2018-06-26 00:26:36.139 - debug: rpi2.0 CURRENT = wlan false
2018-06-26 00:26:36.139 - debug: rpi2.0 -1
2018-06-26 00:26:36.139 - debug: rpi2.0 CURRENT = gpios undefined
2018-06-26 00:26:36.139 - debug: rpi2.0 -1
2018-06-26 00:26:36.153 - debug: rpi2.0 stateChange for rpi2.0.cpu.cpu_frequency found state = {"val":600,"ack":true,"ts":1529965596090,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596090}
2018-06-26 00:26:36.236 - debug: rpi2.0 stateChange for rpi2.0.cpu.load1 found state = {"val":"0.35","ack":true,"ts":1529965596172,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596172}
2018-06-26 00:26:36.239 - debug: rpi2.0 stateChange for rpi2.0.cpu.load5 found state = {"val":"0.59","ack":true,"ts":1529965596176,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596176}
2018-06-26 00:26:36.241 - debug: rpi2.0 stateChange for rpi2.0.cpu.load15 found state = {"val":"1.59","ack":true,"ts":1529965596177,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596177}
2018-06-26 00:26:36.243 - debug: rpi2.0 stateChange for rpi2.0.cpu.scaling_governor found state = {"val":"ondemand","ack":true,"ts":1529965596177,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991334}
2018-06-26 00:26:36.244 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_total found state = {"val":"927.22","ack":true,"ts":1529965596178,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991334}
2018-06-26 00:26:36.246 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_free found state = {"val":"456.52","ack":true,"ts":1529965596178,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596178}
2018-06-26 00:26:36.253 - debug: rpi2.0 stateChange for rpi2.0.memory.memory_available found state = {"val":"492.18","ack":true,"ts":1529965596178,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596178}
2018-06-26 00:26:36.255 - debug: rpi2.0 stateChange for rpi2.0.network.net_received found state = {"val":-5831114772,"ack":true,"ts":1529965596179,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596179}
2018-06-26 00:26:36.256 - debug: rpi2.0 stateChange for rpi2.0.network.net_send found state = {"val":1610644115,"ack":true,"ts":1529965596179,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596179}
2018-06-26 00:26:36.257 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_root_total found state = {"val":"29927.38","ack":true,"ts":1529965596180,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991336}
2018-06-26 00:26:36.258 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_boot_total found state = {"val":"62.95","ack":true,"ts":1529965596180,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991336}
2018-06-26 00:26:36.259 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_root_used found state = {"val":"6113.34","ack":true,"ts":1529965596181,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596181}
2018-06-26 00:26:36.261 - debug: rpi2.0 stateChange for rpi2.0.sdcard.sdcard_boot_used found state = {"val":"22.30","ack":true,"ts":1529965596181,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991337}
2018-06-26 00:26:36.262 - debug: rpi2.0 stateChange for rpi2.0.swap.swap_total found state = {"val":"100.00","ack":true,"ts":1529965596182,"q":0,"from":"system.adapter.rpi2.0","lc":1525812991337}
2018-06-26 00:26:36.263 - debug: rpi2.0 stateChange for rpi2.0.swap.swap_used found state = {"val":"42.04","ack":true,"ts":1529965596182,"q":0,"from":"system.adapter.rpi2.0","lc":1529965536207}
2018-06-26 00:26:36.264 - debug: rpi2.0 stateChange for rpi2.0.temperature.soc_temp found state = {"val":"51.54","ack":true,"ts":1529965596183,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596183}
2018-06-26 00:26:36.265 - debug: rpi2.0 stateChange for rpi2.0.uptime.uptime found state = {"val":"2094904.43","ack":true,"ts":1529965596184,"q":0,"from":"system.adapter.rpi2.0","lc":1529965596184}
2018-06-26 00:26:49.542 - info: host.raspi-3 object change system.adapter.rpi2.0
2018-06-26 00:26:49.549 - info: host.raspi-3 stopInstance system.adapter.rpi2.0
2018-06-26 00:26:49.550 - info: host.raspi-3 stopInstance system.adapter.rpi2.0 killing pid 23191
2018-06-26 00:26:49.571 - info: rpi2.0 terminating
2018-06-26 00:26:49.688 - info: host.raspi-3 instance system.adapter.rpi2.0 terminated with code 0 (OK)

PrinzEisenherz1

Ich habe hin und wieder auch diese Meldung im Log

host.orangepiplus2e	2018-07-01 19:44:54.442	info	Restart adapter system.adapter.sql.0 because enabled
host.orangepiplus2e	2018-07-01 19:44:54.442	error	instance system.adapter.sql.0 terminated with code 6 (uncaught exception)
Caught	2018-07-01 19:44:54.441	error	by controller[1]: timeout: 30000 }
Caught	2018-07-01 19:44:54.440	error	by controller[1]: fatal: true,
Caught	2018-07-01 19:44:54.440	error	by controller[1]: code: 'PROTOCOL_SEQUENCE_TIMEOUT',
Caught	2018-07-01 19:44:54.440	error	by controller[1]: at Timer.listOnTimeout (timers.js:214:5)
Caught	2018-07-01 19:44:54.439	error	by controller[1]: at tryOnTimeout (timers.js:250:5)
Caught	2018-07-01 19:44:54.439	error	by controller[1]: at ontimeout (timers.js:386:11)
Caught	2018-07-01 19:44:54.438	error	by controller[1]: at Quit._onTimeout (/opt/iobroker/node_modules/iobroker.sql/node_modules/mysql/lib/protocol/sequences/Sequence.js:129:8)
Caught	2018-07-01 19:44:54.438	error	by controller[1]: at Quit.emit (events.js:185:7)
Caught	2018-07-01 19:44:54.437	error	by controller[1]: at emitNone (events.js:86:13)
Caught	2018-07-01 19:44:54.437	error	by controller[1]: at Quit. <anonymous>(/opt/iobroker/node_modules/iobroker.sql/node_modules/mysql/lib/protocol/Protocol.js:164:17)
Caught	2018-07-01 19:44:54.435	error	by controller[1]: { Error: Quit inactivity timeout
sql.0	2018-07-01 19:44:53.282	warn	Exception: Error: Quit inactivity timeout
sql.0	2018-07-01 19:44:53.264	error	at Timer.listOnTimeout (timers.js:214:5)
sql.0	2018-07-01 19:44:53.264	error	at tryOnTimeout (timers.js:250:5)
sql.0	2018-07-01 19:44:53.264	error	at ontimeout (timers.js:386:11)
sql.0	2018-07-01 19:44:53.264	error	at Quit._onTimeout (/opt/iobroker/node_modules/iobroker.sql/node_modules/mysql/lib/protocol/sequences/Sequence.js:129:8)
sql.0	2018-07-01 19:44:53.264	error	at Quit.emit (events.js:185:7)
sql.0	2018-07-01 19:44:53.264	error	at emitNone (events.js:86:13)
sql.0	2018-07-01 19:44:53.264	error	at Quit. <anonymous>(/opt/iobroker/node_modules/iobroker.sql/node_modules/mysql/lib/protocol/Protocol.js:164:17)
sql.0	2018-07-01 19:44:53.264	error	Error: Quit inactivity timeout
sql.0	2018-07-01 19:44:53.263	error	uncaught exception: Quit inactivity timeout
sql.0	2018-07-01 19:44:47.112	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:46.854	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:46.846	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:46.726	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.204	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.182	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.175	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.169	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.161	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.144	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.138	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.130	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:44:21.121	error	Error: connect ETIMEDOUT
host.orangepiplus2e	2018-07-01 19:44:13.200	info	instance system.adapter.daswetter.0 terminated with code 0 (OK)
host.orangepiplus2e	2018-07-01 19:44:00.197	info	instance system.adapter.daswetter.0 started with pid 3815
sql.0	2018-07-01 19:43:17.200	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:43:16.364	error	Error: connect ETIMEDOUT
nut.0	2018-07-01 19:42:55.562	error	Error happend: Error: connect ETIMEDOUT 192.168.178.100:3493
telegram.1	2018-07-01 19:42:45.822	error	polling_error:EFATAL, EFATAL: Error: getaddrinfo ENOTFOUND api.telegram.org api.telegram.org:443
sql.0	2018-07-01 19:41:07.816	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:41:07.785	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:41:06.527	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:41:06.523	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:41:06.512	error	Error: connect ETIMEDOUT
sql.0	2018-07-01 19:41:06.261	error	Error: connect ETIMEDOUT
host.orangepiplus2e	2018-07-01 19:40:10.457	info	instance system.adapter.tankerkoenig.0 terminated with code 0 (OK)</anonymous></anonymous>

Weiß auch nicht warum.

Netzwerkverbindung möchte ich bei mir mal ausschließen

apollon77

Also wenn ich das so sehe und auch das es nicht nur SQL ist sondern auch nut und telegram und alles im Zeitraum 19:41:06-19:44:53 bin ich aber genau bei Netzwerkproblemen (kann ja auch am OrangePi liegen und nicht am Netzwerk allgemein!!).

Timeout zu SQL-DB
Timeout zu nut-Server
DNS Lookup Fehler zu Telegram.

Gibt denn ggf das Router Log aufschluss?

Sagt ggf /var/log/syslog irgendwas im genannten Zeitraum

PrinzEisenherz1

Hmmm…eventuell muss ich echt mal über n neues switch nachdenken.

Mittlerweile hab ich aber auch die Befürchtung das es am DSM update liegt weil ich irgendwie die ganzen Probleme erst seit dem update auf DSM 6.3 habe und ich meinen Redis und SQL-Server darauf betreibe

Gruß

Johnny

Community Forum

NEWS

Sql - error Error: connect ETIMEDOUT

Support us

773

32.5k

81.7k

1.3m