[Problem] iobroker nicht mehr erreichbar
-
Timeouts? Bitte mal log Posten.
-
nein, du bist nicht alleine. genau diesen effekt hatte ich heute morgen auch gehabt, 4:17 laut log. in den logs stand nix auffälliges drin.
zuvor vor heute auch vor einigen wochen schon 2 / 3 mal.
weder admin noch vis sind erreichbar.
Black
-
Also irgendwas muss im iobroker logfile stehen. Was in so einem Fall „auffällig“ ist ist so eine Sache. Postet doch mal Logs bitte.
Steht in /var/log/syslog irgendwas von „OOM Killer“?
-
Mache ich heute Abend. Bin dienstlich unterwegs grad
-
Hallo,
anbei das Log zumindest der entscheidende Teil wie ich vermute:
2018-05-02 11:54:45.612 - [32minfo[39m: sonoff.0 Client [Steckdose1] closed 2018-05-02 11:54:05.225 - [32minfo[39m: hm-rpc.0 Disconnected 2018-05-02 11:54:50.786 - [32minfo[39m: hm-rpc.0 Connected 2018-05-02 11:54:52.683 - [32minfo[39m: sonoff.0 Client [Steckdose1] connected 2018-05-02 11:54:53.583 - [32minfo[39m: sonoff.0 Client [Steckdose1] reconnected 2018-05-02 11:54:55.184 - [32minfo[39m: sonoff.0 Client [Steckdose1] reconnected 2018-05-02 11:54:56.582 - [32minfo[39m: sonoff.0 Client [Steckdose1] closed 2018-05-02 11:55:03.797 - [32minfo[39m: javascript.0 script.js.Ping_Ossi_Email: true 2018-05-02 11:55:08.268 - [32minfo[39m: sonoff.0 Client [Steckdose1] connected 2018-05-02 11:56:06.253 - [31merror[39m: uncaught exception: spawn ENOMEM 2018-05-02 11:56:32.929 - [32minfo[39m: admin.0 terminating http server on port 8081 2018-05-02 11:56:50.183 - [32minfo[39m: discovery.0 terminating with timeout 2018-05-02 11:56:55.703 - [32minfo[39m: web.0 terminating http server on port 8082 2018-05-02 11:57:08.068 - [32minfo[39m: simple-api.0 terminating http server on port 8087 2018-05-02 11:56:58.320 - [32minfo[39m: web.0 terminated http server on port 8082 2018-05-02 11:57:09.136 - [32minfo[39m: amazon-dash.0 cleaned everything up... 2018-05-02 11:57:09.387 - [32minfo[39m: openhab.0 cleaned everything up... 2018-05-02 11:57:09.744 - [32minfo[39m: email.0 terminating with timeout 2018-05-02 11:57:10.019 - [32minfo[39m: fullcalendar.0 terminating with timeout 2018-05-02 11:57:10.033 - [32minfo[39m: sonoff.0 terminating with timeout 2018-05-02 11:57:10.201 - [32minfo[39m: enigma2.2 terminating with timeout 2018-05-02 11:57:10.205 - [32minfo[39m: enigma2.0 terminating with timeout 2018-05-02 11:57:10.225 - [31merror[39m: daswetter.0 no connection to objects DB 2018-05-02 11:57:10.594 - [32minfo[39m: enigma2.1 terminating with timeout 2018-05-02 11:57:10.641 - [32minfo[39m: amazon-dash.0 terminating with timeout 2018-05-02 11:57:10.890 - [32minfo[39m: openhab.0 terminating with timeout 2018-05-02 12:00:00.806 - [32minfo[39m: javascript.0 script.js.Ping_Ossi_Email: truenun die grep io….
ps -ef |grep io root 10 2 0 Apr28 ? 00:00:33 [migration/0] root 13 2 0 Apr28 ? 00:00:30 [migration/1] root 18 2 0 Apr28 ? 00:00:27 [migration/2] root 23 2 0 Apr28 ? 00:00:24 [migration/3] root 40 2 0 Apr28 ? 00:00:00 [rpciod] root 41 2 0 Apr28 ? 00:00:00 [xprtiod] root 45 2 0 Apr28 ? 00:00:00 [nfsiod] root 58 2 0 Apr28 ? 00:00:00 [DWC Notificatio] message+ 315 1 0 Apr28 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 547 388 0 Apr28 ? 00:00:00 lightdm --session-child 14 17 pi 563 547 0 Apr28 ? 00:00:00 /usr/bin/lxsession -s LXDE-pi -e LXDE pi 572 555 0 Apr28 ? 00:00:00 /usr/bin/dbus-daemon --session --address=systemd: --nofork --nopidfile --systemd-activation pi 620 563 0 Apr28 ? 00:00:02 /usr/bin/ssh-agent x-session-manager root 8666 1 0 Mai02 ? 00:07:25 io.hm-rpc.0 root 8684 1 0 Mai02 ? 00:05:57 io.simple-api.0 root 8699 1 0 Mai02 ? 00:14:38 io.tr-064.0 root 8711 1 0 Mai02 ? 00:06:54 io.hm-rega.0 root 8716 1 0 Mai02 ? 00:06:12 io.web.0 root 8728 1 0 Mai02 ? 00:11:44 io.javascript.0 root 18512 1 0 Mai02 ? 00:03:06 /usr/bin/node /opt/iobroker/node_modules/iobroker.daswetter/daswetter.js 0 debug pi 22814 666 15 15:46 ? 00:00:51 /usr/lib/chromium-browser/chromium-browser --disable-quic --enable-fast-unload --enable-tcp-fast-open --disable-gpu-compositing --ppapi-flash-version=29.0.0.140 --enable-pinch --ppapi-flash-path=/usr/lib/chromium-browser/libpepflashplayer.so --ppapi-flash-args=enable_stagevideo_auto=0 pi 22833 22814 0 15:46 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser --type=zygote --ppapi-flash-path=/usr/lib/chromium-browser/libpepflashplayer.so --ppapi-flash-version=29.0.0.140 pi 22835 22833 0 15:46 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser --type=zygote --ppapi-flash-path=/usr/lib/chromium-browser/libpepflashplayer.so --ppapi-flash-version=29.0.0.140 pi 23173 22835 2 15:46 ? 00:00:07 /usr/lib/chromium-browser/chromium-browser --type=renderer --disable-gpu-compositing --enable-pinch --field-trial-handle=8456407416145069719,163419441469812308,131072 --service-pipe-token=50754194C5A12B3052EB836411959AAC --lang=de --extension-process --enable-offline-auto-reload --enable-offline-auto-reload-visible-only --blink-settings=disallowFetchForDocWrittenScriptsInMainFrame=false,disallowFetchForDocWrittenScriptsInMainFrameOnSlowConnections=false,cssExternalScannerNoPreload=false,cssExternalScannerPreload=true --ppapi-flash-args=enable_stagevideo_auto=0 --ppapi-flash-path=/usr/lib/chromium-browser/libpepflashplayer.so --ppapi-flash-version=29.0.0.140 --enable-pinch --num-raster-threads=2 --enable-main-frame-before-activation --content-image-texture-target=0,0,3553;0,1,3553;0,2,3553;0,3,3553;0,4,3553;0,5,3553;0,6,3553;0,7,3553;0,8,3553;0,9,3553;0,10,3553;0,11,3553;0,12,3553;0,13,3553;0,14,3553;0,15,3553;0,16,3553;1,0,3553;1,1,3553;1,2,3553;1,3,3553;1,4,3553;1,5,3553;1,6,3553;1,7,3553;1,8,3553;1,9,3553;1,10,3553;1,11,3553;1,12,3553;1,13,3553;1,14,3553;1,15,3553;1,16,3553;2,0,3553;2,1,3553;2,2,3553;2,3,3553;2,4,3553;2,5,3553;2,6,3553;2,7,3553;2,8,3553;2,9,3553;2,10,3553;2,11,3553;2,12,3553;2,13,3553;2,14,3553;2,15,3553;2,16,3553;3,0,3553;3,1,3553;3,2,3553;3,3,3553;3,4,3553;3,5,3553;3,6,3553;3,7,3553;3,8,3553;3,9,3553;3,10,3553;3,11,3553;3,12,3553;3,13,3553;3,14,3553;3,15,3553;3,16,3553;4,0,3553;4,1,3553;4,2,3553;4,3,3553;4,4,3553;4,5,3553;4,6,3553;4,7,3553;4,8,3553;4,9,3553;4,10,3553;4,11,3553;4,12,3553;4,13,3553;4,14,3553;4,15,3553;4,16,3553 --disable-accelerated-video-decode --disable-gpu-compositing --service-request-channel-token=50754194C5A12B3052EB836411959AAC --renderer-client-id=2 --shared-files=v8_natives_data:100,v8_snapshot_data:101 pi 23196 22835 30 15:46 ? 00:01:40 /usr/lib/chromium-browser/chromium-browser --type=renderer --disable-gpu-compositing --enable-pinch --field-trial-handle=8456407416145069719,163419441469812308,131072 --service-pipe-token=A9346BAF904CD2F9603A9E88A2850A8D --lang=de --enable-offline-auto-reload --enable-offline-auto-reload-visible-only --blink-settings=disallowFetchForDocWrittenScriptsInMainFrame=false,disallowFetchForDocWrittenScriptsInMainFrameOnSlowConnections=false,cssExternalScannerNoPreload=false,cssExternalScannerPreload=true --ppapi-flash-args=enable_stagevideo_auto=0 --ppapi-flash-path=/usr/lib/chromium-browser/libpepflashplayer.so --ppapi-flash-version=29.0.0.140 --enable-pinch --num-raster-threads=2 --enable-main-frame-before-activation --content-image-texture-target=0,0,3553;0,1,3553;0,2,3553;0,3,3553;0,4,3553;0,5,3553;0,6,3553;0,7,3553;0,8,3553;0,9,3553;0,10,3553;0,11,3553;0,12,3553;0,13,3553;0,14,3553;0,15,3553;0,16,3553;1,0,3553;1,1,3553;1,2,3553;1,3,3553;1,4,3553;1,5,3553;1,6,3553;1,7,3553;1,8,3553;1,9,3553;1,10,3553;1,11,3553;1,12,3553;1,13,3553;1,14,3553;1,15,3553;1,16,3553;2,0,3553;2,1,3553;2,2,3553;2,3,3553;2,4,3553;2,5,3553;2,6,3553;2,7,3553;2,8,3553;2,9,3553;2,10,3553;2,11,3553;2,12,3553;2,13,3553;2,14,3553;2,15,3553;2,16,3553;3,0,3553;3,1,3553;3,2,3553;3,3,3553;3,4,3553;3,5,3553;3,6,3553;3,7,3553;3,8,3553;3,9,3553;3,10,3553;3,11,3553;3,12,3553;3,13,3553;3,14,3553;3,15,3553;3,16,3553;4,0,3553;4,1,3553;4,2,3553;4,3,3553;4,4,3553;4,5,3553;4,6,3553;4,7,3553;4,8,3553;4,9,3553;4,10,3553;4,11,3553;4,12,3553;4,13,3553;4,14,3553;4,15,3553;4,16,3553 --disable-accelerated-video-decode --disable-gpu-compositing --service-request-channel-token=A9346BAF904CD2F9603A9E88A2850A8D --renderer-client-id=5 --shared-files=v8_natives_data:100,v8_snapshot_data:101 pi 23211 22814 0 15:46 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser --type=gpu-process --field-trial-handle=8456407416145069719,163419441469812308,131072 --supports-dual-gpus=false --gpu-driver-bug-workarounds=7,8,10,24,30,69,77 --disable-gl-extensions=GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent --gpu-vendor-id=0x0000 --gpu-device-id=0x0000 --gpu-driver-vendor=Mesa --gpu-driver-version=13.0.6 --gpu-driver-date --service-request-channel-token=97054480C0B600B98FA7DA2BF08F1B00 pi 23385 668 4 15:49 ? 00:00:07 leafpad /opt/iobroker/log/iobroker.2018-05-02.log pi 23411 23400 0 15:52 pts/0 00:00:00 grep --color=auto iotop:
top top - 15:54:00 up 5 days, 1:06, 1 user, load average: 0,61, 0,54, 0,28 Tasks: 137 total, 1 running, 92 sleeping, 0 stopped, 1 zombie %Cpu(s): 4,6 us, 0,9 sy, 0,0 ni, 94,5 id, 0,0 wa, 0,0 hi, 0,0 si, 0,0 st KiB Mem : 949452 total, 33276 free, 670928 used, 245248 buff/cache KiB Swap: 102396 total, 0 free, 102396 used. 207228 avail Mem PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 458 root 20 0 225940 42680 13580 S 10,5 4,5 3:56.43 Xorg 23398 pi 20 0 48784 19596 16104 S 8,2 2,1 0:02.90 lxterminal 23414 pi 20 0 8644 3232 2696 R 1,6 0,3 0:00.39 top 8716 root 20 0 132788 26080 5540 S 1,3 2,7 6:12.56 io.web.0 666 pi 20 0 162764 28940 9196 S 0,3 3,0 21:01.73 lxpanel 828 unifi 20 0 1511404 164296 0 S 0,3 17,3 163:07.83 java 1775 unifi 20 0 311148 12560 672 S 0,3 1,3 32:42.91 mongod 8699 root 20 0 137448 30628 6320 S 0,3 3,2 14:39.30 io.tr-064.0 1 root 20 0 27000 2284 1568 S 0,0 0,2 0:12.61 systemd 2 root 20 0 0 0 0 S 0,0 0,0 0:00.62 kthreadd 4 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kworker/0:0H 6 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 mm_percpu_wq 7 root 20 0 0 0 0 S 0,0 0,0 0:55.89 ksoftirqd/0 8 root 20 0 0 0 0 I 0,0 0,0 3:24.89 rcu_sched 9 root 20 0 0 0 0 I 0,0 0,0 0:00.00 rcu_bh 10 root rt 0 0 0 0 S 0,0 0,0 0:33.72 migration/0 11 root 20 0 0 0 0 S 0,0 0,0 0:00.00 cpuhp/0 12 root 20 0 0 0 0 S 0,0 0,0 0:00.00 cpuhp/1 13 root rt 0 0 0 0 S 0,0 0,0 0:30.35 migration/1 14 root 20 0 0 0 0 S 0,0 0,0 0:12.85 ksoftirqd/1 16 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kworker/1:0H 17 root 20 0 0 0 0 S 0,0 0,0 0:00.00 cpuhp/2 18 root rt 0 0 0 0 S 0,0 0,0 0:27.42 migration/2 19 root 20 0 0 0 0 S 0,0 0,0 0:02.57 ksoftirqd/2 21 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kworker/2:0H 22 root 20 0 0 0 0 S 0,0 0,0 0:00.00 cpuhp/3 23 root rt 0 0 0 0 S 0,0 0,0 0:24.84 migration/3 24 root 20 0 0 0 0 S 0,0 0,0 0:02.33 ksoftirqd/3 26 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kworker/3:0H 27 root 20 0 0 0 0 S 0,0 0,0 0:00.00 kdevtmpfs 28 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 netns 33 root 20 0 0 0 0 S 0,0 0,0 0:01.85 khungtaskd 34 root 20 0 0 0 0 S 0,0 0,0 0:00.00 oom_reaper 35 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 writeback 36 root 20 0 0 0 0 S 0,0 0,0 0:00.02 kcompactd0 37 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 crypto 38 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kblockd 39 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 watchdogd 40 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 rpciod 41 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 xprtiod 44 root 20 0 0 0 0 S 0,0 0,0 10:13.25 kswapd0 45 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 nfsiod 55 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 kthrotld 56 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 iscsi_eh 57 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 dwc_otg 58 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 DWC Notificatio 60 root 1 -19 0 0 0 S 0,0 0,0 0:00.00 vchiq-slot/0 61 root 1 -19 0 0 0 S 0,0 0,0 0:00.00 vchiq-recy/0 62 root 0 -20 0 0 0 S 0,0 0,0 0:00.00 vchiq-sync/0 63 root 20 0 0 0 0 S 0,0 0,0 0:00.00 vchiq-keep/0 64 root 10 -10 0 0 0 S 0,0 0,0 0:00.00 SMIO 65 root -51 0 0 0 0 S 0,0 0,0 0:19.02 irq/92-mmc1 68 root 20 0 0 0 0 S 0,0 0,0 3:00.04 mmcqd/0 69 root 20 0 0 0 0 S 0,0 0,0 0:15.36 jbd2/mmcblk0p2- 70 root 0 -20 0 0 0 I 0,0 0,0 0:00.00 ext4-rsv-conversyslog:
ay 2 06:27:42 raspberrypi systemd[1]: Starting Daily apt upgrade and clean activities... May 2 06:27:45 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities. May 2 06:27:45 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 3min 39.231115s random time. May 2 06:27:45 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 57min 18.501814s random time. May 2 06:58:15 raspberrypi kernel: [317431.738562] Under-voltage detected! (0x00050005) May 2 06:58:21 raspberrypi kernel: [317437.978619] Voltage normalised (0x00000000) May 2 07:17:01 raspberrypi CRON[6985]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 07:44:46 raspberrypi kernel: [320223.121938] Under-voltage detected! (0x00050005) May 2 07:44:51 raspberrypi kernel: [320227.281886] Voltage normalised (0x00000000) May 2 08:05:47 raspberrypi kernel: [321483.613887] Under-voltage detected! (0x00050005) May 2 08:05:51 raspberrypi kernel: [321487.773907] Voltage normalised (0x00000000) May 2 08:17:01 raspberrypi CRON[10278]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 09:17:01 raspberrypi CRON[12671]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 10:17:01 raspberrypi CRON[15067]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 11:17:01 raspberrypi CRON[17471]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 11:57:10 raspberrypi kernel: [335367.194871] device eth0 left promiscuous mode May 2 12:17:02 raspberrypi CRON[18649]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 13:17:01 raspberrypi CRON[18758]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 14:17:01 raspberrypi CRON[18865]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 15:06:42 raspberrypi systemd[1]: Starting Cleanup of Temporary Directories... May 2 15:06:42 raspberrypi systemd[1]: Started Cleanup of Temporary Directories. May 2 15:17:01 raspberrypi CRON[18975]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 16:17:01 raspberrypi CRON[19081]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 16:17:42 raspberrypi systemd[1]: Starting Daily apt download activities... May 2 16:17:45 raspberrypi systemd[1]: Started Daily apt download activities. May 2 16:17:45 raspberrypi systemd[1]: apt-daily.timer: Adding 11h 46min 27.967310s random time. May 2 16:17:45 raspberrypi systemd[1]: apt-daily.timer: Adding 9h 59min 20.575898s random time. May 2 17:17:01 raspberrypi CRON[19233]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 17:45:12 raspberrypi kernel: [356249.063537] Under-voltage detected! (0x00050005) May 2 17:45:16 raspberrypi kernel: [356253.223553] Voltage normalised (0x00000000) May 2 18:17:01 raspberrypi CRON[19345]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 19:17:01 raspberrypi CRON[19472]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 20:17:01 raspberrypi CRON[19583]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 21:17:01 raspberrypi CRON[19686]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 22:17:01 raspberrypi CRON[19794]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 2 23:17:01 raspberrypi CRON[19902]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 00:17:02 raspberrypi CRON[20010]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 01:17:01 raspberrypi CRON[20117]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 02:17:01 raspberrypi CRON[20226]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 03:17:01 raspberrypi CRON[20332]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 03:59:42 raspberrypi systemd[1]: Starting Daily apt download activities... May 3 03:59:44 raspberrypi systemd[1]: Started Daily apt download activities. May 3 03:59:44 raspberrypi systemd[1]: apt-daily.timer: Adding 2h 58min 36.136101s random time. May 3 03:59:44 raspberrypi systemd[1]: apt-daily.timer: Adding 8h 58min 59.238554s random time. May 3 04:17:01 raspberrypi CRON[20482]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 05:17:01 raspberrypi CRON[20591]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 06:17:01 raspberrypi CRON[21352]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 06:25:01 raspberrypi CRON[21384]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily )) May 3 06:25:02 raspberrypi liblogging-stdlog: [origin software="rsyslogd" swVersion="8.24.0" x-pid="341" x-info="http://www.rsyslog.com"] rsyslogd was HUPed May 3 06:57:42 raspberrypi systemd[1]: Starting Daily apt upgrade and clean activities... May 3 06:57:45 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities. May 3 06:57:45 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 15min 32.306324s random time. May 3 06:57:45 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 2min 35.027214s random time. May 3 07:17:01 raspberrypi CRON[21621]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 08:17:01 raspberrypi CRON[21756]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 09:17:01 raspberrypi CRON[21887]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 10:17:01 raspberrypi CRON[22020]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 11:17:01 raspberrypi CRON[22149]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 12:17:01 raspberrypi CRON[22280]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 13:17:01 raspberrypi CRON[22416]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 14:17:01 raspberrypi CRON[22549]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 14:59:42 raspberrypi systemd[1]: Starting Daily apt download activities... May 3 14:59:44 raspberrypi systemd[1]: Started Daily apt download activities. May 3 14:59:44 raspberrypi systemd[1]: apt-daily.timer: Adding 1h 27min 49.295669s random time. May 3 14:59:44 raspberrypi systemd[1]: apt-daily.timer: Adding 51min 7.020473s random time. May 3 15:07:32 raspberrypi systemd[1]: Starting Cleanup of Temporary Directories... May 3 15:07:32 raspberrypi systemd[1]: Started Cleanup of Temporary Directories. May 3 15:17:01 raspberrypi CRON[22746]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) May 3 15:47:13 raspberrypi kernel: [435570.621499] Under-voltage detected! (0x00050005) May 3 15:47:17 raspberrypi kernel: [435574.781561] Voltage normalised (0x00000000) May 3 15:53:13 raspberrypi kernel: [435930.465096] Under-voltage detected! (0x00050005) May 3 15:53:17 raspberrypi kernel: [435934.625004] Voltage normalised (0x00000000)ich hoffe ihr könnt etwas mit anfangen.
mir fällt nur auf, dass er nach und nach die http-server terminiert, aber warum….
Danke
Gruß Jens
-
Hi,
es gibt zwei Hinweise in deinen Logs die mir auf die schnelle aufgefallen sind:
A:````
spawn ENOMEMB:```` May 2 07:44:46 raspberrypi kernel: [320223.121938] Under-voltage detected! (0x00050005) May 2 07:44:51 raspberrypi kernel: [320227.281886] Voltage normalised (0x00000000) May 2 08:05:47 raspberrypi kernel: [321483.613887] Under-voltage detected! (0x00050005) May 2 08:05:51 raspberrypi kernel: [321487.773907] Voltage normalised (0x00000000)Du solltest mal die Stromversorgung tauschen. Sicher stellen das dein Netzteil 2,5A liefern kann.
-
Ich bin auch bei dem "ENOMEN" == "Out of Memory als ein neuer prozess gestartet werden sollte". und danach wurden alle beendet.
Ergo: Was sagt denn so die Speicherauslastung?
-
aha…
das klingt ja nicht grad nach einer einfachen Lösung, weil beim Pi kann man ja schlecht den Speicher erweitern.
ich denke Mal Ihr meint bestimmt die Anzeige im Admin oben...
Insgesamt RAM verbraucht: 672 MB / Frei: 37 MB (4%) [Host: raspberrypi - 17 Prozesse]ich würde mal so als Leihe sagen das sieht nicht gut aus.
Nun ist die Frage, wie kann ich das reduzieren ohne Instanzen abzuschalten?
Javascript hat 68MB ohne die Scripte wird es auch nicht laufen….
Stromversorgung könnte sein, ist aber eigentlich ein gutes Netzteil wo auch nur einer dran hängt.
aber das könnte man ja Problemlos tauschen gegen ein 3A.
Habe Ihr Tipps wie man den Memory frei schaufeln kann?
oder ist ioBroker zu groß für einen Pi?
Danke
Gruß Jens
-
Vergiss die ANzeige im ioBroker.
Was sagt "top" auf der Konsole?
-
na das kommt schon ziemlich genau hin,
zeitweise 45600 KB
-
Bei mir läuft iobroker auf einem opi+2e mit 2 GByte. Ich kam heute morgen auch nicht per ssh drauf. Benutzer und pw konnte ich eingeben mehr nicht.
-
Dann am an einem Rechner ne ssh Session mit top laufen lassen und warten bis es wieder passiert. Top nach Speicher sortieren lassen. Dann sehr ihr den Grund.
Das kann alles sein
-
Bei mir läuft iobroker auf einem opi+2e mit 2 GByte. Ich kam heute morgen auch nicht per ssh drauf. Benutzer und pw konnte ich eingeben mehr nicht. `
Keine weitere Eingabe von irgendetwas möglich gewesen?
Hatte ich auch mal: viewtopic.php?f=8&t=11333&p=120279&hilit=putty#p120279
Vielleicht mal Wetty und/oder Terminal installieren in der Hoffnung das man damit im Problemfall noch die logs lesen kann.
-
ok, nu bin ich auch wieder am rechner.
das ist der entscheidende Part meines logs. wenn ichs richtig interpretiere, startet da der jscontroller und der admin neu ?
! 2018-05-03 04:30:03.576 - [32minfo[39m: weatherunderground.0 starting. Version 1.1.2 in /opt/iobroker/node_modules/iobroker.weatherunderground, node: v6.14.1
! 2018-05-03 04:30:08.776 - [32minfo[39m: host.OPI instance system.adapter.weatherunderground.0 terminated with code 0 (OK)
! 2018-05-03 04:36:45.913 - [32minfo[39m: javascript.0 script.js.Licht.Licht_Haustür_V2: 11
! 2018-05-03 04:36:45.917 - [32minfo[39m: javascript.0 script.js.Licht.Licht_Haustür_V2: verzögert an: 120000
! 2018-05-03 04:40:00.196 - [32minfo[39m: host.OPI instance system.adapter.weatherunderground.0 started with pid 11590
! 2018-05-03 04:40:03.556 - [32minfo[39m: weatherunderground.0 starting. Version 1.1.2 in /opt/iobroker/node_modules/iobroker.weatherunderground, node: v6.14.1
! 2018-05-03 04:40:16.323 - [32minfo[39m: host.OPI instance system.adapter.weatherunderground.0 terminated with code 0 (OK)
! 2018-05-03 04:17:12.656 - [32minfo[39m: host.OPI iobroker.js-controller version 1.4.2 js-controller starting
! 2018-05-03 04:17:12.676 - [32minfo[39m: host.OPI Copyright
2014-2018 bluefox, 2014 hobbyquaker
! 2018-05-03 04:17:12.679 - [32minfo[39m: host.OPI hostname: OPI, node: v6.14.1
! 2018-05-03 04:17:12.682 - [32minfo[39m: host.OPI ip addresses: 192.168.2.5 2003:c9:1f28:f200:81:eeff:fec5:f86 fe80::81:eeff:fec5:f86
! 2018-05-03 04:17:13.096 - [32minfo[39m: host.OPI inMem-states listening on port 9000
! 2018-05-03 04:17:13.974 - [32minfo[39m: host.OPI inMem-objects listening on port 9001
! 2018-05-03 04:17:14.014 - [32minfo[39m: host.OPI InMemoryDB connected
! 2018-05-03 04:17:14.089 - [32minfo[39m: host.OPI 30 instances found
! 2018-05-03 04:17:14.204 - [32minfo[39m: host.OPI starting 18 instances
! 2018-05-03 04:17:14.357 - [32minfo[39m: host.OPI instance system.adapter.admin.0 started with pid 1026
! 2018-05-03 06:23:01.113 - [32minfo[39m: host.OPI instance system.adapter.web.0 started with pid 1051[/spoiler]syslog beginnt erst ab dem neustart
in dieser Uhrzeit warf dann auch die CCU mit errors, als die RPC Adapter im IoBroker ihren Dienst einstellten
top in laufen System ist avolut harmlos, speicher sollte satt und genug sein und die CPU dümpelt bei 480MHz rum (auf einem oPI+2e)
! top - 19:25:34 up 13:02, 1 user, load average: 0,25, 0,17, 0,17
! Tasks: 120 total, 1 running, 75 sleeping, 0 stopped, 0 zombie
! %Cpu(s): 2,4 us, 0,7 sy, 0,0 ni, 96,9 id, 0,0 wa, 0,0 hi, 0,0 si, 0,0 st
! KiB Mem : 2062388 total, 1263028 free, 569196 used, 230164 buff/cache
! KiB Swap: 131068 total, 131068 free, 0 used. 1424384 avail Mem
! PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
! 1014 root 20 0 189820 91448 17380 S 4,9 4,4 63:03.59 iobroker.js-con
! 22225 root 20 0 8832 3068 2624 R 2,3 0,1 0:00.71 top
! 1383 root 20 0 125856 36712 17136 S 2,0 1,8 21:16.88 io.history.0
! 1100 root 20 0 124624 32956 17024 S 1,3 1,6 7:13.12 io.simple-api.0
! 781 root 20 0 0 0 0 D 0,7 0,0 1:07.93 RTW_CMD_THREAD
! 1108 root 20 0 180084 80632 17856 S 0,7 3,9 16:12.52 io.javascript.0
! 17799 root 20 0 0 0 0 I 0,7 0,0 0:39.82 kworker/1:0
! 493 message+ 20 0 5316 2780 2408 S 0,3 0,1 0:12.43 dbus-daemon
! 503 root 20 0 88608 12904 11072 S 0,3 0,6 0:38.31 NetworkManager
! 1026 root 20 0 154936 53656 17136 S 0,3 2,6 1:23.93 io.admin.0
! 1236 root 20 0 128128 35984 16944 S 0,3 1,7 1:05.16 io.lightify.0
! 1318 root 20 0 139756 42328 17076 S 0,3 2,1 4:49.50 io.hm-rega.1
! 1348 root 20 0 126168 37472 17068 S 0,3 1,8 1:45.46 io.hm-rpc.3
! 21775 root 20 0 0 0 0 I 0,3 0,0 0:01.40 kworker/0:0
! 22091 root 20 0 0 0 0 I 0,3 0,0 0:00.18 kworker/3:1
! 1 root 20 0 25840 5444 3864 S 0,0 0,3 0:07.08 systemd
! …..........Black
-
na bei mir vermute ich mal der Speicherräuber der unifi Controller mit 163644 KB immerhin 17,%
Dann muss ich den wohl woanders unterbringen entweder auf der synology oder auf nen anderen Pi.
-
syslog beginnt erst ab dem neustart `
Meinst du das iobroker log?
Du musst mal das Linux SYSLOG checken.
-
so, ichhatte das heute früh nochmal…
da sah das ganze dann so aus unter top
! top - 06:42:05 up 9 min, 1 user, load average: 5,32, 4,46, 2,32
! Tasks: 116 total, 4 running, 71 sleeping, 0 stopped, 0 zombie
! %Cpu(s): 57,4 us, 2,0 sy, 0,0 ni, 40,5 id, 0,0 wa, 0,0 hi, 0,1 si, 0,0 st
! KiB Mem : 2062388 total, 1264036 free, 584876 used, 213476 buff/cache
! KiB Swap: 131068 total, 131068 free, 0 used. 1411036 avail Mem
! PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
! 1857 root 20 0 110632 55476 16160 R 100,3 2,7 0:14.94 node
! 911 root 20 0 271928 187912 17324 R 99,4 9,1 7:41.35 iobroker.js-con
! 1771 root 20 0 122372 30680 17096 S 7,7 1,5 0:16.47 io.js2fs.0
! 1735 root 20 0 135016 49040 16964 S 6,1 2,4 0:42.66 io.hm-rega.1
! 1753 root 20 0 116524 29804 16916 S 5,1 1,4 0:15.54 io.web.0
! 1741 root 20 0 134584 40732 17084 S 4,8 2,0 0:22.53 io.admin.0
! 1819 root 20 0 8664 2920 2468 R 3,5 0,1 0:02.67 top
! 1759 root 20 0 115644 28044 17060 S 2,9 1,4 0:13.11 io.simple-api.0
! 1538 root 20 0 161344 63316 17140 S 1,9 3,1 0:32.73 io.javascript.2
! 1617 root 20 0 123288 35272 16996 R 1,3 1,7 0:15.39 io.lightify.0
! 1765 root 20 0 117316 29660 17120 S 1,3 1,4 0:14.97 io.hm-rpc.3 -
Hm … ein „Node“ Prozess mit 100% cpu und einmal js-Controller mit 99% ...
Bei sowas nochmal mach mal ein „ps auxww|grep node“ weil die Frage ists was das für ein Prozess ist.
Ansonsten load 5 heist erstmal nur das alles langsamer ist
-
Hallo Zusammen,
was mich etwas stutzig macht, ich denke mal bei mir war die Ursache, dass der Speicher des RasPi nicht
reichte, somit habe ich jetzt extra den unifi Controller (160MB Speichernutzung) vom RasPi verband.
Fazit: Gestern satte 20% round about 200MB anstatt vorher um die 40MB frei.
Heute schaue ich rein, überhaupt nichts gemacht und es sind nur noch 10% also 100MB frei.
Wie kann dass denn sein?
Da hat die Aktion ja irgendwie nicht viel gebracht
Gruß Jens
-
Entscheidend ist der verfügbare Speicher (available)
Hier siehst du mal wie krass der Unterschied sein kannGruß Rainer
