z-way-server shuts down in the middle of the night

Discussions about RaZberry - Z-Wave board for Raspberry computer
AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 07 Mar 2017 02:18

Okay, I replaced the *.so file, hopefully my thermostats heating up in the morning, because the server is still running, as it should. Guess my wife kills me, if not. :lol:

AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 07 Mar 2017 13:55

Hi again,

I'm still alive, but the z-way-server wasn't in the morning, though it was running with the new modzwave.so file. :roll:

EDIT: Just checked back and maybe it's rights were 644, instead of 755. :oops:

Good thing of the latest 4.9 Raspberry kernel release is, that syslog and messages telling much more details, I'm not that kind of a pro, so maybe it's helpful for you:

messages

Code: Select all

Mar  7 03:04:06 raspberrypi kernel: [63772.576972] kworker/3:0     D    0 20286      2 0x00000000
Mar  7 03:04:09 raspberrypi kernel: [63772.577015] Workqueue: events_freezable mmc_rescan
Mar  7 03:04:09 raspberrypi kernel: [63772.577063] [<80713318>] (__schedule) from [<8071383c>] (schedule+0x50/0xa8)
Mar  7 03:04:09 raspberrypi kernel: [63772.577090] [<8071383c>] (schedule) from [<805b5e74>] (__mmc_claim_host+0xb8/0x1cc)
Mar  7 03:04:09 raspberrypi kernel: [63772.577115] [<805b5e74>] (__mmc_claim_host) from [<805b5fb8>] (mmc_get_card+0x30/0x34)
Mar  7 03:04:09 raspberrypi kernel: [63772.577140] [<805b5fb8>] (mmc_get_card) from [<805bea48>] (mmc_sd_detect+0x2c/0x80)
Mar  7 03:04:09 raspberrypi kernel: [63772.577165] [<805bea48>] (mmc_sd_detect) from [<805b8c7c>] (mmc_rescan+0x1c8/0x38c)
Mar  7 03:04:09 raspberrypi kernel: [63772.577196] [<805b8c7c>] (mmc_rescan) from [<80136568>] (process_one_work+0x158/0x454)
Mar  7 03:04:09 raspberrypi kernel: [63772.577225] [<80136568>] (process_one_work) from [<801368c8>] (worker_thread+0x64/0x568)
Mar  7 03:04:09 raspberrypi kernel: [63772.577251] [<801368c8>] (worker_thread) from [<8013c7b0>] (kthread+0xec/0x104)
Mar  7 03:04:09 raspberrypi kernel: [63772.577278] [<8013c7b0>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Mar  7 03:06:14 raspberrypi kernel: [63905.895243] kthreadd invoked oom-killer: gfp_mask=0x27000c0(GFP_KERNEL_ACCOUNT|__GFP_NOTRACK), nodemask=0, order=1, oom_score_adj=0
Mar  7 03:06:15 raspberrypi kernel: [63905.895275] kthreadd cpuset=/ mems_allowed=0
Mar  7 03:06:15 raspberrypi kernel: [63905.895310] CPU: 2 PID: 2 Comm: kthreadd Not tainted 4.9.13-v7+ #974
Mar  7 03:06:15 raspberrypi kernel: [63905.895322] Hardware name: BCM2835
Mar  7 03:06:15 raspberrypi kernel: [63905.895367] [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Mar  7 03:06:15 raspberrypi kernel: [63905.895395] [<8010c058>] (show_stack) from [<80454680>] (dump_stack+0xd4/0x118)
Mar  7 03:06:15 raspberrypi kernel: [63905.895423] [<80454680>] (dump_stack) from [<8026c8cc>] (dump_header+0x9c/0x1f4)
Mar  7 03:06:15 raspberrypi kernel: [63905.895450] [<8026c8cc>] (dump_header) from [<80210228>] (oom_kill_process+0x3e0/0x4e4)
Mar  7 03:06:15 raspberrypi kernel: [63905.895478] [<80210228>] (oom_kill_process) from [<80210690>] (out_of_memory+0x124/0x334)
Mar  7 03:06:15 raspberrypi kernel: [63905.895505] [<80210690>] (out_of_memory) from [<802157b0>] (__alloc_pages_nodemask+0xcf4/0xdd0)
Mar  7 03:06:15 raspberrypi kernel: [63905.895536] [<802157b0>] (__alloc_pages_nodemask) from [<8011aaf4>] (copy_process.part.5+0xec/0x1770)
Mar  7 03:06:15 raspberrypi kernel: [63905.895563] [<8011aaf4>] (copy_process.part.5) from [<8011c308>] (_do_fork+0xc8/0x408)
Mar  7 03:06:15 raspberrypi kernel: [63905.895588] [<8011c308>] (_do_fork) from [<8011c6b8>] (kernel_thread+0x40/0x48)
Mar  7 03:06:15 raspberrypi kernel: [63905.895615] [<8011c6b8>] (kernel_thread) from [<8013d7ec>] (kthreadd+0x1cc/0x254)
Mar  7 03:06:15 raspberrypi kernel: [63905.895643] [<8013d7ec>] (kthreadd) from [<80108148>] (ret_from_fork+0x14/0x2c)
Mar  7 03:06:15 raspberrypi kernel: [63905.895655] Mem-Info:
Mar  7 03:06:15 raspberrypi kernel: [63905.895686] active_anon:112295 inactive_anon:112298 isolated_anon:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  active_file:452 inactive_file:522 isolated_file:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  unevictable:0 dirty:1 writeback:1 unstable:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  slab_reclaimable:2376 slab_unreclaimable:3148
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  mapped:3765 shmem:3545 pagetables:1674 bounce:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  free:1764 free_pcp:0 free_cma:8
Mar  7 03:06:15 raspberrypi kernel: [63905.895735] Node 0 active_anon:449180kB inactive_anon:449192kB active_file:1808kB inactive_file:2088kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:15060kB dirty:4kB writeback:4kB shmem:14180kB writeback_tmp:0kB unstable:0kB pages_scanned:6223 all_unreclaimable? yes
Mar  7 03:06:15 raspberrypi kernel: [63905.895774] Normal free:7056kB min:3868kB low:4832kB high:5796kB active_anon:449180kB inactive_anon:449192kB active_file:1808kB inactive_file:2088kB unevictable:0kB writepending:8kB present:966656kB managed:945524kB mlocked:0kB slab_reclaimable:9504kB slab_unreclaimable:12592kB kernel_stack:1784kB pagetables:6696kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:32kB
Mar  7 03:06:15 raspberrypi kernel: lowmem_reserve[]: 0 0
Mar  7 03:06:15 raspberrypi kernel: [63905.895807] Normal: 573*4kB (UMEHC) 80*8kB (UMEH) 7*16kB (UME) 2*32kB (H) 2*64kB (H) 2*128kB (H) 2*256kB (H) 0*512kB 1*1024kB (H) 1*2048kB (H) 0*4096kB = 7076kB
Mar  7 03:06:15 raspberrypi kernel: 4989 total pagecache pages
Mar  7 03:06:15 raspberrypi kernel: [63905.895930] 387 pages in swap cache
Mar  7 03:06:15 raspberrypi kernel: [63905.895944] Swap cache stats: add 27075, delete 26688, find 176172/176509
Mar  7 03:06:15 raspberrypi kernel: [63905.895954] Free swap  = 0kB
Mar  7 03:06:15 raspberrypi kernel: [63905.895964] Total swap = 102396kB
Mar  7 03:06:15 raspberrypi kernel: [63905.895975] 241664 pages RAM
Mar  7 03:06:15 raspberrypi kernel: [63905.895985] 0 pages HighMem/MovableOnly
Mar  7 03:06:15 raspberrypi kernel: [63905.895995] 5283 pages reserved
Mar  7 03:06:15 raspberrypi kernel: [63905.896005] 2048 pages cma reserved
Mar  7 03:06:15 raspberrypi kernel: [63905.896016] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
Mar  7 03:06:15 raspberrypi kernel: [63905.896063] [  145]     0   145     2483      346       9       0       36             0 systemd-journal
Mar  7 03:06:15 raspberrypi kernel: [63905.896083] [  150]     0   150     2993        2       9       0      191         -1000 systemd-udevd
Mar  7 03:06:15 raspberrypi kernel: [63905.896104] [  450]     0   450    79991     5966      37       0     2263             0 java
Mar  7 03:06:15 raspberrypi kernel: [63905.896124] [  451]   110   451     1001       61       7       0       21             0 avahi-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896143] [  453]     0   453     1966       31       8       0       84         -1000 sshd
Mar  7 03:06:15 raspberrypi kernel: [63905.896162] [  454]     0   454     8036       73      11       0      114             0 rsyslogd
Mar  7 03:06:15 raspberrypi kernel: [63905.896182] [  455]     0   455     1275       15       6       0       41             0 cron
Mar  7 03:06:15 raspberrypi kernel: [63905.896201] [  458]   119   458     2425      103       9       0       90             0 freshclam
Mar  7 03:06:15 raspberrypi kernel: [63905.896220] [  460]     0   460      963       34       6       0       33             0 systemd-logind
Mar  7 03:06:15 raspberrypi kernel: [63905.896240] [  468]   104   468     1442       75       7       0       51          -900 dbus-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896259] [  498]   110   498      969       13       6       0       43             0 avahi-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896279] [  563]     0   563      472        7       5       0       16             0 ifplugd
Mar  7 03:06:15 raspberrypi kernel: [63905.896298] [  614]     0   614    10129       82      15       0      129             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896317] [  624] 65534   624      573        4       6       0       27             0 thd
Mar  7 03:06:15 raspberrypi kernel: [63905.896336] [  629]   102   629     1444       38       7       0       71             0 ntpd
Mar  7 03:06:15 raspberrypi kernel: [63905.896355] [  631]     0   631     1013        2       7       0       31             0 agetty
Mar  7 03:06:15 raspberrypi kernel: [63905.896374] [  662]   108   662     1452        1       6       0       72             0 xrdp
Mar  7 03:06:15 raspberrypi kernel: [63905.896393] [  663]     0   663     1330       69       7       0       45             0 zbw_connect
Mar  7 03:06:15 raspberrypi kernel: [63905.896413] [  672]     0   672     1624        0       7       0       75             0 xrdp-sesman
Mar  7 03:06:15 raspberrypi kernel: [63905.896432] [  751]     0   751      478        1       5       0       29             0 mysqld_safe
Mar  7 03:06:15 raspberrypi kernel: [63905.896451] [  798]     0   798    30397       36      46       0     1908             0 Xorg
Mar  7 03:06:15 raspberrypi kernel: [63905.896540] [ 1138]   107  1138    81864     9745      61       0     6983             0 mysqld
Mar  7 03:06:15 raspberrypi kernel: [63905.896561] [ 1162]     0  1162     7285      456      13       0      886             0 wicd
Mar  7 03:06:15 raspberrypi kernel: [63905.896580] [ 1219]     0  1219     4769      277      14       0      816             0 wicd-monitor
Mar  7 03:06:15 raspberrypi kernel: [63905.896615] [ 1238]     0  1238    15012      533      17       0      667             0 fail2ban-server
Mar  7 03:06:15 raspberrypi kernel: [63905.896639] [ 1262]     0  1262     8137       38      13       0      178             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896659] [ 1286]   106  1286     1244       48       7       0       55             0 systemd
Mar  7 03:06:15 raspberrypi kernel: [63905.896679] [ 1289]   106  1289     1770       20       8       0      359             0 (sd-pam)
Mar  7 03:06:15 raspberrypi kernel: [63905.896699] [ 1292]   106  1292    32895      191      44       0     3238             0 lightdm-gtk-gre
Mar  7 03:06:15 raspberrypi kernel: [63905.896719] [ 1329]   106  1329      923        0       5       0       66             0 dbus-launch
Mar  7 03:06:15 raspberrypi kernel: [63905.896739] [ 1344]   106  1344     1373        1       6       0       69             0 dbus-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896758] [ 1362]     0  1362     5616       48      14       0      254             0 nmbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896797] [ 1409]     0  1409     8909       50      21       0      373             0 smbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896834] [ 1414]     0  1414     8909       49      20       0      373             0 smbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896865] [ 1501]     0  1501     2332        2       9       0      114             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896903] [ 6874]     0  6874    44801     1490      33       0      906             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.896937] [ 8749]    33  8749    46510     4039      40       0      710             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.896974] [ 8767]    33  8767    46940     4376      41       0      758             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897012] [ 8769]    33  8769    46482     4055      40       0      664             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897044] [ 8770]    33  8770    46615     4174      40       0      670             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897103] [ 8785]    33  8785    46866     4312      41       0      688             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897158] [ 8810]    33  8810    46869     4439      41       0      635             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897195] [14976]    33 14976    46868     4400      41       0      661             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897228] [14980]    33 14980    46868     4394      41       0      676             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897261] [14982]    33 14982    46867     4365      41       0      624             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897294] [14983]    33 14983    46884     4381      41       0      705             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897328] [16162]  1000 16162     1244        1       8       0       98             0 systemd
Mar  7 03:06:15 raspberrypi kernel: [63905.897360] [16165]  1000 16165     1770      131       8       0      253             0 (sd-pam)
Mar  7 03:06:15 raspberrypi kernel: [63905.897393] [16462]     0 16462   221413   187199     704       0     1083             0 z-way-server
Mar  7 03:06:15 raspberrypi kernel: [63905.897421] [20609]     0 20609     1792      131       7       0        0             0 ssh
Mar  7 03:06:15 raspberrypi kernel: [63905.897450] [20613]     0 20613     8909       85      20       0      337             0 smbd
syslog

Code: Select all

Mar  7 03:04:02 raspberrypi kernel: [63772.576912] INFO: task kworker/3:0:20286 blocked for more than 120 seconds.
Mar  7 03:04:03 raspberrypi kernel: [63772.576947]       Not tainted 4.9.13-v7+ #974
Mar  7 03:04:05 raspberrypi kernel: [63772.576959] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Mar  7 03:04:06 raspberrypi kernel: [63772.576972] kworker/3:0     D    0 20286      2 0x00000000
Mar  7 03:04:09 raspberrypi kernel: [63772.577015] Workqueue: events_freezable mmc_rescan
Mar  7 03:04:09 raspberrypi kernel: [63772.577063] [<80713318>] (__schedule) from [<8071383c>] (schedule+0x50/0xa8)
Mar  7 03:04:09 raspberrypi kernel: [63772.577090] [<8071383c>] (schedule) from [<805b5e74>] (__mmc_claim_host+0xb8/0x1cc)
Mar  7 03:04:09 raspberrypi kernel: [63772.577115] [<805b5e74>] (__mmc_claim_host) from [<805b5fb8>] (mmc_get_card+0x30/0x34)
Mar  7 03:04:09 raspberrypi kernel: [63772.577140] [<805b5fb8>] (mmc_get_card) from [<805bea48>] (mmc_sd_detect+0x2c/0x80)
Mar  7 03:04:09 raspberrypi kernel: [63772.577165] [<805bea48>] (mmc_sd_detect) from [<805b8c7c>] (mmc_rescan+0x1c8/0x38c)
Mar  7 03:04:09 raspberrypi kernel: [63772.577196] [<805b8c7c>] (mmc_rescan) from [<80136568>] (process_one_work+0x158/0x454)
Mar  7 03:04:09 raspberrypi kernel: [63772.577225] [<80136568>] (process_one_work) from [<801368c8>] (worker_thread+0x64/0x568)
Mar  7 03:04:09 raspberrypi kernel: [63772.577251] [<801368c8>] (worker_thread) from [<8013c7b0>] (kthread+0xec/0x104)
Mar  7 03:04:09 raspberrypi kernel: [63772.577278] [<8013c7b0>] (kthread) from [<80108148>] (ret_from_fork+0x14/0x2c)
Mar  7 03:06:14 raspberrypi kernel: [63905.895243] kthreadd invoked oom-killer: gfp_mask=0x27000c0(GFP_KERNEL_ACCOUNT|__GFP_NOTRACK), nodemask=0, order=1, oom_score_adj=0
Mar  7 03:06:15 raspberrypi kernel: [63905.895275] kthreadd cpuset=/ mems_allowed=0
Mar  7 03:06:15 raspberrypi kernel: [63905.895310] CPU: 2 PID: 2 Comm: kthreadd Not tainted 4.9.13-v7+ #974
Mar  7 03:06:15 raspberrypi kernel: [63905.895322] Hardware name: BCM2835
Mar  7 03:06:15 raspberrypi kernel: [63905.895367] [<8010fb3c>] (unwind_backtrace) from [<8010c058>] (show_stack+0x20/0x24)
Mar  7 03:06:15 raspberrypi kernel: [63905.895395] [<8010c058>] (show_stack) from [<80454680>] (dump_stack+0xd4/0x118)
Mar  7 03:06:15 raspberrypi kernel: [63905.895423] [<80454680>] (dump_stack) from [<8026c8cc>] (dump_header+0x9c/0x1f4)
Mar  7 03:06:15 raspberrypi kernel: [63905.895450] [<8026c8cc>] (dump_header) from [<80210228>] (oom_kill_process+0x3e0/0x4e4)
Mar  7 03:06:15 raspberrypi kernel: [63905.895478] [<80210228>] (oom_kill_process) from [<80210690>] (out_of_memory+0x124/0x334)
Mar  7 03:06:15 raspberrypi kernel: [63905.895505] [<80210690>] (out_of_memory) from [<802157b0>] (__alloc_pages_nodemask+0xcf4/0xdd0)
Mar  7 03:06:15 raspberrypi kernel: [63905.895536] [<802157b0>] (__alloc_pages_nodemask) from [<8011aaf4>] (copy_process.part.5+0xec/0x1770)
Mar  7 03:06:15 raspberrypi kernel: [63905.895563] [<8011aaf4>] (copy_process.part.5) from [<8011c308>] (_do_fork+0xc8/0x408)
Mar  7 03:06:15 raspberrypi kernel: [63905.895588] [<8011c308>] (_do_fork) from [<8011c6b8>] (kernel_thread+0x40/0x48)
Mar  7 03:06:15 raspberrypi kernel: [63905.895615] [<8011c6b8>] (kernel_thread) from [<8013d7ec>] (kthreadd+0x1cc/0x254)
Mar  7 03:06:15 raspberrypi kernel: [63905.895643] [<8013d7ec>] (kthreadd) from [<80108148>] (ret_from_fork+0x14/0x2c)
Mar  7 03:06:15 raspberrypi kernel: [63905.895655] Mem-Info:
Mar  7 03:06:15 raspberrypi kernel: [63905.895686] active_anon:112295 inactive_anon:112298 isolated_anon:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  active_file:452 inactive_file:522 isolated_file:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  unevictable:0 dirty:1 writeback:1 unstable:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  slab_reclaimable:2376 slab_unreclaimable:3148
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  mapped:3765 shmem:3545 pagetables:1674 bounce:0
Mar  7 03:06:15 raspberrypi kernel: [63905.895686]  free:1764 free_pcp:0 free_cma:8
Mar  7 03:06:15 raspberrypi kernel: [63905.895735] Node 0 active_anon:449180kB inactive_anon:449192kB active_file:1808kB inactive_file:2088kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:15060kB dirty:4kB writeback:4kB shmem:14180kB writeback_tmp:0kB unstable:0kB pages_scanned:6223 all_unreclaimable? yes
Mar  7 03:06:15 raspberrypi kernel: [63905.895774] Normal free:7056kB min:3868kB low:4832kB high:5796kB active_anon:449180kB inactive_anon:449192kB active_file:1808kB inactive_file:2088kB unevictable:0kB writepending:8kB present:966656kB managed:945524kB mlocked:0kB slab_reclaimable:9504kB slab_unreclaimable:12592kB kernel_stack:1784kB pagetables:6696kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:32kB
Mar  7 03:06:15 raspberrypi kernel: lowmem_reserve[]: 0 0
Mar  7 03:06:15 raspberrypi kernel: [63905.895807] Normal: 573*4kB (UMEHC) 80*8kB (UMEH) 7*16kB (UME) 2*32kB (H) 2*64kB (H) 2*128kB (H) 2*256kB (H) 0*512kB 1*1024kB (H) 1*2048kB (H) 0*4096kB = 7076kB
Mar  7 03:06:15 raspberrypi kernel: 4989 total pagecache pages
Mar  7 03:06:15 raspberrypi kernel: [63905.895930] 387 pages in swap cache
Mar  7 03:06:15 raspberrypi kernel: [63905.895944] Swap cache stats: add 27075, delete 26688, find 176172/176509
Mar  7 03:06:15 raspberrypi kernel: [63905.895954] Free swap  = 0kB
Mar  7 03:06:15 raspberrypi kernel: [63905.895964] Total swap = 102396kB
Mar  7 03:06:15 raspberrypi kernel: [63905.895975] 241664 pages RAM
Mar  7 03:06:15 raspberrypi kernel: [63905.895985] 0 pages HighMem/MovableOnly
Mar  7 03:06:15 raspberrypi kernel: [63905.895995] 5283 pages reserved
Mar  7 03:06:15 raspberrypi kernel: [63905.896005] 2048 pages cma reserved
Mar  7 03:06:15 raspberrypi kernel: [63905.896016] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
Mar  7 03:06:15 raspberrypi kernel: [63905.896063] [  145]     0   145     2483      346       9       0       36             0 systemd-journal
Mar  7 03:06:15 raspberrypi kernel: [63905.896083] [  150]     0   150     2993        2       9       0      191         -1000 systemd-udevd
Mar  7 03:06:15 raspberrypi kernel: [63905.896104] [  450]     0   450    79991     5966      37       0     2263             0 java
Mar  7 03:06:15 raspberrypi kernel: [63905.896124] [  451]   110   451     1001       61       7       0       21             0 avahi-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896143] [  453]     0   453     1966       31       8       0       84         -1000 sshd
Mar  7 03:06:15 raspberrypi kernel: [63905.896162] [  454]     0   454     8036       73      11       0      114             0 rsyslogd
Mar  7 03:06:15 raspberrypi kernel: [63905.896182] [  455]     0   455     1275       15       6       0       41             0 cron
Mar  7 03:06:15 raspberrypi kernel: [63905.896201] [  458]   119   458     2425      103       9       0       90             0 freshclam
Mar  7 03:06:15 raspberrypi kernel: [63905.896220] [  460]     0   460      963       34       6       0       33             0 systemd-logind
Mar  7 03:06:15 raspberrypi kernel: [63905.896240] [  468]   104   468     1442       75       7       0       51          -900 dbus-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896259] [  498]   110   498      969       13       6       0       43             0 avahi-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896279] [  563]     0   563      472        7       5       0       16             0 ifplugd
Mar  7 03:06:15 raspberrypi kernel: [63905.896298] [  614]     0   614    10129       82      15       0      129             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896317] [  624] 65534   624      573        4       6       0       27             0 thd
Mar  7 03:06:15 raspberrypi kernel: [63905.896336] [  629]   102   629     1444       38       7       0       71             0 ntpd
Mar  7 03:06:15 raspberrypi kernel: [63905.896355] [  631]     0   631     1013        2       7       0       31             0 agetty
Mar  7 03:06:15 raspberrypi kernel: [63905.896374] [  662]   108   662     1452        1       6       0       72             0 xrdp
Mar  7 03:06:15 raspberrypi kernel: [63905.896393] [  663]     0   663     1330       69       7       0       45             0 zbw_connect
Mar  7 03:06:15 raspberrypi kernel: [63905.896413] [  672]     0   672     1624        0       7       0       75             0 xrdp-sesman
Mar  7 03:06:15 raspberrypi kernel: [63905.896432] [  751]     0   751      478        1       5       0       29             0 mysqld_safe
Mar  7 03:06:15 raspberrypi kernel: [63905.896451] [  798]     0   798    30397       36      46       0     1908             0 Xorg
Mar  7 03:06:15 raspberrypi kernel: [63905.896540] [ 1138]   107  1138    81864     9745      61       0     6983             0 mysqld
Mar  7 03:06:15 raspberrypi kernel: [63905.896561] [ 1162]     0  1162     7285      456      13       0      886             0 wicd
Mar  7 03:06:15 raspberrypi kernel: [63905.896580] [ 1219]     0  1219     4769      277      14       0      816             0 wicd-monitor
Mar  7 03:06:15 raspberrypi kernel: [63905.896615] [ 1238]     0  1238    15012      533      17       0      667             0 fail2ban-server
Mar  7 03:06:15 raspberrypi kernel: [63905.896639] [ 1262]     0  1262     8137       38      13       0      178             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896659] [ 1286]   106  1286     1244       48       7       0       55             0 systemd
Mar  7 03:06:15 raspberrypi kernel: [63905.896679] [ 1289]   106  1289     1770       20       8       0      359             0 (sd-pam)
Mar  7 03:06:15 raspberrypi kernel: [63905.896699] [ 1292]   106  1292    32895      191      44       0     3238             0 lightdm-gtk-gre
Mar  7 03:06:15 raspberrypi kernel: [63905.896719] [ 1329]   106  1329      923        0       5       0       66             0 dbus-launch
Mar  7 03:06:15 raspberrypi kernel: [63905.896739] [ 1344]   106  1344     1373        1       6       0       69             0 dbus-daemon
Mar  7 03:06:15 raspberrypi kernel: [63905.896758] [ 1362]     0  1362     5616       48      14       0      254             0 nmbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896797] [ 1409]     0  1409     8909       50      21       0      373             0 smbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896834] [ 1414]     0  1414     8909       49      20       0      373             0 smbd
Mar  7 03:06:15 raspberrypi kernel: [63905.896865] [ 1501]     0  1501     2332        2       9       0      114             0 lightdm
Mar  7 03:06:15 raspberrypi kernel: [63905.896903] [ 6874]     0  6874    44801     1490      33       0      906             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.896937] [ 8749]    33  8749    46510     4039      40       0      710             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.896974] [ 8767]    33  8767    46940     4376      41       0      758             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897012] [ 8769]    33  8769    46482     4055      40       0      664             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897044] [ 8770]    33  8770    46615     4174      40       0      670             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897103] [ 8785]    33  8785    46866     4312      41       0      688             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897158] [ 8810]    33  8810    46869     4439      41       0      635             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897195] [14976]    33 14976    46868     4400      41       0      661             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897228] [14980]    33 14980    46868     4394      41       0      676             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897261] [14982]    33 14982    46867     4365      41       0      624             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897294] [14983]    33 14983    46884     4381      41       0      705             0 apache2
Mar  7 03:06:15 raspberrypi kernel: [63905.897328] [16162]  1000 16162     1244        1       8       0       98             0 systemd
Mar  7 03:06:15 raspberrypi kernel: [63905.897360] [16165]  1000 16165     1770      131       8       0      253             0 (sd-pam)
Mar  7 03:06:15 raspberrypi kernel: [63905.897393] [16462]     0 16462   221413   187199     704       0     1083             0 z-way-server
Mar  7 03:06:15 raspberrypi kernel: [63905.897421] [20609]     0 20609     1792      131       7       0        0             0 ssh
Mar  7 03:06:15 raspberrypi kernel: [63905.897450] [20613]     0 20613     8909       85      20       0      337             0 smbd
Mar  7 03:06:15 raspberrypi kernel: [63905.897483] Out of memory: Kill process 16462 (z-way-server) score 699 or sacrifice child
Mar  7 03:06:15 raspberrypi kernel: [63905.897574] Killed process 16462 (z-way-server) total-vm:885652kB, anon-rss:748796kB, file-rss:0kB, shmem-rss:0kB
Mar  7 03:06:15 raspberrypi systemd[1]: Stopping User Manager for UID 1000...
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopping Default.
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopped target Default.
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopping Basic System.
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopped target Basic System.
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopping Paths.
Mar  7 03:06:15 raspberrypi systemd[16162]: Stopped target Paths.
Mar  7 03:06:16 raspberrypi systemd[16162]: Stopping Timers.
Mar  7 03:06:16 raspberrypi systemd[16162]: Stopped target Timers.
Mar  7 03:06:16 raspberrypi systemd[16162]: Stopping Sockets.
Mar  7 03:06:16 raspberrypi systemd[16162]: Stopped target Sockets.
Mar  7 03:06:16 raspberrypi systemd[16162]: Starting Shutdown.
Mar  7 03:06:16 raspberrypi systemd[16162]: Reached target Shutdown.
Mar  7 03:06:16 raspberrypi systemd[16162]: Starting Exit the Session...
Mar  7 03:06:16 raspberrypi systemd[16162]: Received SIGRTMIN+24 from PID 20619 (kill).
Mar  7 03:06:16 raspberrypi systemd[1]: Stopped User Manager for UID 1000.
Mar  7 03:06:16 raspberrypi systemd[1]: Stopping user-1000.slice.
Mar  7 03:06:16 raspberrypi systemd[1]: Removed slice user-1000.slice.
And as allways: z-way log shows nothing special.

After all it's not the point, to give cron or a script a restart order, but I'm somewhat a fan of a clean solution. ;)
Can't imagine that it should not be possible to sort this out.

User avatar
PoltoS
Posts: 4744
Joined: 26 Jan 2011 19:36

Re: z-way-server shuts down in the middle of the night

Post by PoltoS » 08 Mar 2017 02:47

sure you have not kept the original file (under any name) in the folder? if so, move it out! Z-Way loads all files in /modules (even if not .so)

AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 08 Mar 2017 02:49

Tellling me now! :shock: Sure, I hold modzwave.so.bak there! :lol:

AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 08 Mar 2017 11:10

"Upps...I(t) did it again." With r(e)m(oved) old .so file and new one in place. :?

This time I woke up nearly at 3am, so I watched the "event" live. I looked into htop and exactly 3am it started swamping my RAM, with z-way-server using nearly 80% of RAM htop stopped updating and hung a long while and was back at work after z-way-server crashed. So it seems not to be related to your memory leaking. Is it a possible option, there is a device sending a faulty package, that causes trouble?

I made some screenshots, from my tablet. Maybe it's useful, I've found that zbw did something around the time of hanging?! And is there a way to check Z-Way apps for using cron, or something like a Z-Way crontab?

My next idea would be to deactivate nearly all apps and functions, back to the basics. It's a pitty it's time related, so it's hard to reproduce, but I don't want to do experiments with wrong clock settings, due to paradoxa like future files. ;)

Screenshots:
3:02 | https://goo.gl/photos/mK48FubZLcyxLJ7Q8
3:04 | https://goo.gl/photos/aZGCz6Q3SyrgHbZG8
3:11 | https://goo.gl/photos/phB2dQkSyf9VJsiv6

User avatar
PoltoS
Posts: 4744
Joined: 26 Jan 2011 19:36

Re: z-way-server shuts down in the middle of the night

Post by PoltoS » 08 Mar 2017 19:29

I don't see z-way-server in htop. Sure it is eating the memory? Which thread is allocating it?

AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 08 Mar 2017 19:45

The First line, the colored, because selected one. :-)

User avatar
PoltoS
Posts: 4744
Joined: 26 Jan 2011 19:36

Re: z-way-server shuts down in the middle of the night

Post by PoltoS » 08 Mar 2017 19:53

Indeed (skipped it as a header ;)

Ok, if it goes so fast, this is not a leak but intentional allocation. Certainly by JS code. Please enable JS debugger and look what is going on. You can change time and restart Z-Way to look what happens at 3:00. Also you can search for tasks scheduled for 3:00

AlphaX2
Posts: 110
Joined: 25 Jul 2015 15:03

Re: z-way-server shuts down in the middle of the night

Post by AlphaX2 » 08 Mar 2017 20:33

Would you be please so kind, to tell me, how to debug the Javascript part? I know, that I possibly could change the time, but don't want to, because I don't know how it will affect ownCloud for example. Default and root user don't have any cron jobs at 3am, is there a way to see if and how the Z-Way apps using it?

User avatar
PoltoS
Posts: 4744
Joined: 26 Jan 2011 19:36

Re: z-way-server shuts down in the middle of the night

Post by PoltoS » 08 Mar 2017 21:19

In Z-Way there is a Cron app - some modules can use it.

This should help.

In brief:
1. edit config.xml to set debug port to 8183, restart Z-Way
2. run node inspector (I suggest to run it on your machine and use ssh port-forward as described - on Pi it might be slower; you can try on Pi too): # node-inspector --debug-port 8183
3. navigate to the page http://127.0.0.1:8080/debug?port=8183
4. here you can pause, set breakpoints, ... on the left you have (by default folded) list of files

Please note that new node-inspector is not compatible anymore. We use v0.7.4!!

Post Reply