Z-way server goes down spontaneously

Discussions about Z-Way software and Z-Wave technology in general
User avatar
GokMasE
Posts: 59
Joined: 13 Mar 2016 01:04
Location: Sweden

Re: Z-way server goes down spontaneously

Post by GokMasE »

Wikibear wrote:
17 Jan 2020 13:04
I can't install this deb cause i will redirect to main page.
I had the exact same problem with the direct link to 3.0.3-13-package but found a link to the index of the z-way-server and from there it would download and install OK. Try this: https://storage.z-wave.me/z-way-server/

Hope that helps.

EDIT: I notice that there is now also a 3.0.4-package available but it is not mentioned in the change.log (yet).
Last edited by GokMasE on 22 Jan 2020 13:30, edited 1 time in total.
User avatar
GokMasE
Posts: 59
Joined: 13 Mar 2016 01:04
Location: Sweden

Re: Z-way server goes down spontaneously

Post by GokMasE »

PoltoS wrote:
14 Jan 2020 02:02
Hm... every time it is after the HTTP request.

Can you please show me the module configuration (that produce this HTTP) and the what is replied to http://192.168.1.3:1025/ZWave?Cmd=CBck& ... &Lev=19.93 so I can reproduce it (may be a wireshark/tcpdump dump)
Ok, I apologize in advance - I am very new to tcpdump so not sure if I am getting the information needed out of this, please let me know if I need to refine the way data is retrieved and/or stored to ensure the necessary data and format is achieved.
Todays crash log was however a bit different in that the crash was not immediately following an HTTP request.

Here is the end snippet of the gdb text output:

Code: Select all

[New Thread 0x6e9fe450 (LWP 9824)]
[New Thread 0x6e1fd450 (LWP 9825)]
[Thread 0x6e1fd450 (LWP 9825) exited]
[Thread 0x6e9fe450 (LWP 9824) exited]
[New Thread 0x6e9fe450 (LWP 9826)]
[Thread 0x6e9fe450 (LWP 9826) exited]
[New Thread 0x6e9fe450 (LWP 9827)]
[Thread 0x6e9fe450 (LWP 9827) exited]
[New Thread 0x6e9fe450 (LWP 9828)]
[Thread 0x6e9fe450 (LWP 9828) exited]
[New Thread 0x6e9fe450 (LWP 9829)]
[Thread 0x6e9fe450 (LWP 9829) exited]
[New Thread 0x6e9fe450 (LWP 9830)]
[Thread 0x6e9fe450 (LWP 9830) exited]
[New Thread 0x6e9fe450 (LWP 9831)]
[New Thread 0x6e1fd450 (LWP 9832)]
[Thread 0x6e9fe450 (LWP 9831) exited]
[New Thread 0x6e9fe450 (LWP 9833)]
[Thread 0x6e1fd450 (LWP 9832) exited]
[Thread 0x6e9fe450 (LWP 9833) exited]
[New Thread 0x6e9fe450 (LWP 9834)]
[Thread 0x6e9fe450 (LWP 9834) exited]
[New Thread 0x6e9fe450 (LWP 9836)]
[Thread 0x6e9fe450 (LWP 9836) exited]
[New Thread 0x6e9fe450 (LWP 9837)]
[Thread 0x6e9fe450 (LWP 9837) exited]
[New Thread 0x6e9fe450 (LWP 9838)]
[New Thread 0x6e1fd450 (LWP 9839)]
[Thread 0x6e1fd450 (LWP 9839) exited]
[Thread 0x6e9fe450 (LWP 9838) exited]
[New Thread 0x6e9fe450 (LWP 9840)]
[Thread 0x6e9fe450 (LWP 9840) exited]
[New Thread 0x6e9fe450 (LWP 9841)]
[Thread 0x6e9fe450 (LWP 9841) exited]

Thread 7 "zway/core" received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x733ff450 (LWP 26459)]
0x76c03e3c in v8::internal::GlobalHandles::Create(v8::internal::Object*) ()
   from libs/libv8.so
(gdb) i thr
  Id   Target Id         Frame
  1    Thread 0x76ff6140 (LWP 26450) "z-way-server" 0x76350030 in nanosleep ()
    at ../sysdeps/unix/syscall-template.S:84
  2    Thread 0x73cff450 (LWP 26454) "OptimizingCompi" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x59ea4)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  3    Thread 0x7405b450 (LWP 26455) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5a004)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  4    Thread 0x73d6d450 (LWP 26456) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5a124)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  5    Thread 0x73d5c450 (LWP 26457) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5a244)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  6    Thread 0x73d4b450 (LWP 26458) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5a364)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
* 7    Thread 0x733ff450 (LWP 26459) "zway/core" 0x76c03e3c in v8::internal::GlobalHandles::Create(v8::internal::Object*) () from libs/libv8.so
  8    Thread 0x727ff450 (LWP 26462) "zway/webserver" 0x76350030 in nanosleep
    () at ../sysdeps/unix/syscall-template.S:84
  9    Thread 0x71ffe450 (LWP 26463) "zway/timers" 0x76350030 in nanosleep ()
---Type <return> to continue, or q <return> to quit---
    at ../sysdeps/unix/syscall-template.S:84
  10   Thread 0x713ff450 (LWP 26464) "zway/core" 0x765f6f10 in __lll_lock_wait
    (futex=futex@entry=0x72a06f90, private=0) at lowlevellock.c:46
(gdb) bt
#0  0x76c03e3c in v8::internal::GlobalHandles::Create(v8::internal::Object*) ()
   from libs/libv8.so
#1  0x76b50c1c in v8::V8::GlobalizeReference(v8::internal::Isolate*, v8::internal::Object**) () from libs/libv8.so
#2  0x74109cb0 in zwjs::DHWrapper::Setup(v8::Isolate*, v8::Handle<v8::Object>)
    () from ./modules/moddhs.so
#3  0x740feb38 in zwjs::DataHolder::New(zwjs::Environment*, zwjs::DHWrapper*)
    () from ./modules/moddhs.so
#4  0x740fe180 in zwjs::DataHolder::New(zwjs::Environment*, _ZDataHolder*) ()
   from ./modules/moddhs.so
#5  0x740fdfb0 in zwjs::DataHolder::Constructor(v8::FunctionCallbackInfo<v8::Value> const&) () from ./modules/moddhs.so
#6  0x76b70bc0 in v8::internal::FunctionCallbackArguments::Call(void (*)(v8::FunctionCallbackInfo<v8::Value> const&)) () from libs/libv8.so
#7  0x76b8c4a4 in v8::internal::Builtin_HandleApiCall(int, v8::internal::Object**, v8::internal::Isolate*) () from libs/libv8.so
#8  0x4f30a3a8 in ?? ()
Backtrace stopped: previous frame identical to this frame (corrupt stack?)
(gdb)
Here is the end snippet of the tcpdump text output: (With some lines with my embedded ZWay password censored out)

Code: Select all

17:39:58.142866 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42162 > 192.168.1.3.1025: Flags [.], cksum 0xc94a (correct), seq 131, ack 90, win 29200, length 0
        0x0000:  4500 0028 0000 4000 4006 b6b9 c0a8 01c3  E..(..@.@.......
        0x0010:  c0a8 0103 a4b2 0401 e6b7 2fd1 60d1 d054  ........../.`..T
        0x0020:  5010 7210 c94a 0000                      P.r..J..
17:39:58.977056 IP (tos 0x0, ttl 64, id 38202, offset 0, flags [DF], proto TCP (6), length 60)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [S], cksum 0x8445 (incorrect -> 0x47cd), seq 2309655229, win 29200, options [mss 1460,sackOK,TS val 4013448627 ecr 0,nop,wsc                                                  ale 7], length 0
        0x0000:  4500 003c 953a 4000 4006 216b c0a8 01c3  E..<.:@.@.!k....
        0x0010:  c0a8 0103 a4b6 0401 89aa 8abd 0000 0000  ................
        0x0020:  a002 7210 8445 0000 0204 05b4 0402 080a  ..r..E..........
        0x0030:  ef38 5db3 0000 0000 0103 0307            .8].........
17:39:58.982106 IP (tos 0x0, ttl 255, id 51949, offset 0, flags [none], proto TCP (6), length 44)
    192.168.1.3.1025 > 192.168.1.195.42166: Flags [S.], cksum 0x0d29 (correct), seq 1624365000, ack 2309655230, win 4380, options [mss 1460], length 0
        0x0000:  4500 002c caed 0000 ff06 6cc7 c0a8 0103  E..,......l.....
        0x0010:  c0a8 01c3 0401 a4b6 60d1 d7c8 89aa 8abe  ........`.......
        0x0020:  6012 111c 0d29 0000 0204 05b4 0000       `....)........
17:39:58.982274 IP (tos 0x0, ttl 64, id 38203, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [.], cksum 0x8431 (incorrect -> 0xc3f1), seq 1, ack 1, win 29200, length 0
        0x0000:  4500 0028 953b 4000 4006 217e c0a8 01c3  E..(.;@.@.!~....
        0x0010:  c0a8 0103 a4b6 0401 89aa 8abe 60d1 d7c9  ............`...
        0x0020:  5010 7210 8431 0000                      P.r..1..
17:39:58.982652 IP (tos 0x0, ttl 64, id 38204, offset 0, flags [DF], proto TCP (6), length 172)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [P.], cksum 0x84b5 (incorrect -> 0xfde7), seq 1:133, ack 1, win 29200, length 132
        0x0000:  4500 00ac 953c 4000 4006 20f9 c0a8 01c3  E....<@.@.......
        0x0010:  c0a8 0103 a4b6 0401 89aa 8abe 60d1 d7c9  ............`...
        0x0020:  5018 7210 84b5 0000 4745 5420 2f5a 5761  P.r.....GET./ZWa
        0x0030:  7665 3f43 6d64 3d43 4263 6b26 4465 763d  ve?Cmd=CBck&Dev=
        0x0040:  5a57 6179 5644 6576 5f7a 7761 795f 362d  ZWayVDev_zway_6-
        0x0050:  312d 3530 2d32 264c 6576 3d30 2e36 2048  1-50-2&Lev=0.6.H
        0x0060:  5454 502f 312e 310d 0a48 6f73 743a 2031  TTP/1.1..Host:.1
        0x0070:  3932 2e31 3638 2e31 2e33 3a31 3032 350d  92.168.1.3:1025.
        0x0080:  0a55 7365 722d 4167 656e 743a 2058 4d4c  .User-Agent:.XML
        0x0090:  4874 7470 5265 7175 6573 740d 0a41 6363  HttpRequest..Acc
        0x00a0:  6570 743a 202a 2f2a 0d0a 0d0a            ept:.*/*....
17:39:58.996894 IP (tos 0x0, ttl 255, id 51950, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42166: Flags [.], cksum 0x24e6 (correct), seq 1, ack 133, win 4248, length 0
        0x0000:  4500 0028 caee 0000 ff06 6cca c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b6 60d1 d7c9 89aa 8b42  ........`......B
        0x0020:  5010 1098 24e6 0000 8abe 60d1 d7c9       P...$.....`...
17:39:59.243622 IP (tos 0x0, ttl 255, id 51951, offset 0, flags [none], proto TCP (6), length 128)
    192.168.1.3.1025 > 192.168.1.195.42166: Flags [P.], cksum 0x106a (correct), seq 1:89, ack 133, win 4380, length 88
        0x0000:  4500 0080 caef 0000 ff06 6c71 c0a8 0103  E.........lq....
        0x0010:  c0a8 01c3 0401 a4b6 60d1 d7c9 89aa 8b42  ........`......B
        0x0020:  5018 111c 106a 0000 4854 5450 2f31 2e31  P....j..HTTP/1.1
        0x0030:  2032 3030 204f 4b0d 0a43 6f6e 7465 6e74  .200.OK..Content
        0x0040:  2d6c 656e 6774 683a 2036 0d0a 436f 6e6e  -length:.6..Conn
        0x0050:  6563 7469 6f6e 3a20 636c 6f73 650d 0a43  ection:.close..C
        0x0060:  6f6e 7465 6e74 2d74 7970 653a 2074 6578  ontent-type:.tex
        0x0070:  742f 6874 6d6c 0d0a 0d0a 3230 3020 4f4b  t/html....200.OK
17:39:59.243724 IP (tos 0x0, ttl 64, id 38205, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [.], cksum 0x8431 (incorrect -> 0xc315), seq 133, ack 89, win 29200, length 0
        0x0000:  4500 0028 953d 4000 4006 217c c0a8 01c3  E..(.=@.@.!|....
        0x0010:  c0a8 0103 a4b6 0401 89aa 8b42 60d1 d821  ...........B`..!
        0x0020:  5010 7210 8431 0000                      P.r..1..
17:39:59.244181 IP (tos 0x0, ttl 64, id 38206, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [F.], cksum 0x8431 (incorrect -> 0xc314), seq 133, ack 89, win 29200, length 0
        0x0000:  4500 0028 953e 4000 4006 217b c0a8 01c3  E..(.>@.@.!{....
        0x0010:  c0a8 0103 a4b6 0401 89aa 8b42 60d1 d821  ...........B`..!
        0x0020:  5011 7210 8431 0000                      P.r..1..
17:39:59.253029 IP (tos 0x0, ttl 255, id 51952, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42166: Flags [.], cksum 0x2409 (correct), seq 89, ack 134, win 4380, length 0
        0x0000:  4500 0028 caf0 0000 ff06 6cc8 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b6 60d1 d821 89aa 8b43  ........`..!...C
        0x0020:  5010 111c 2409 0000 8b42 60d1 d821       P...$....B`..!
17:39:59.261212 IP (tos 0x0, ttl 255, id 51953, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42166: Flags [F.], cksum 0x2408 (correct), seq 89, ack 134, win 4380, length 0
        0x0000:  4500 0028 caf1 0000 ff06 6cc7 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b6 60d1 d821 89aa 8b43  ........`..!...C
        0x0020:  5011 111c 2408 0000 8b42 60d1 d821       P...$....B`..!
17:39:59.261297 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42166 > 192.168.1.3.1025: Flags [.], cksum 0xc313 (correct), seq 134, ack 90, win 29200, length 0
        0x0000:  4500 0028 0000 4000 4006 b6b9 c0a8 01c3  E..(..@.@.......
        0x0010:  c0a8 0103 a4b6 0401 89aa 8b43 60d1 d822  ...........C`.."
        0x0020:  5010 7210 c313 0000                      P.r.....
17:40:00.411681 IP (tos 0x0, ttl 64, id 1611, offset 0, flags [DF], proto TCP (6), length 60)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [S], cksum 0x8445 (incorrect -> 0x2a20), seq 102508125, win 29200, options [mss 1460,sackOK,TS val 4013450061 ecr 0,nop,wsca                                                  le 7], length 0
        0x0000:  4500 003c 064b 4000 4006 b05a c0a8 01c3  E..<.K@.@..Z....
        0x0010:  c0a8 0103 a4b8 0401 061c 265d 0000 0000  ..........&]....
        0x0020:  a002 7210 8445 0000 0204 05b4 0402 080a  ..r..E..........
        0x0030:  ef38 634d 0000 0000 0103 0307            .8cM........
17:40:00.417120 IP (tos 0x0, ttl 255, id 51954, offset 0, flags [none], proto TCP (6), length 44)
    192.168.1.3.1025 > 192.168.1.195.42168: Flags [S.], cksum 0xf12e (correct), seq 1624365999, ack 102508126, win 4380, options [mss 1460], length 0
        0x0000:  4500 002c caf2 0000 ff06 6cc2 c0a8 0103  E..,......l.....
        0x0010:  c0a8 01c3 0401 a4b8 60d1 dbaf 061c 265e  ........`.....&^
        0x0020:  6012 111c f12e 0000 0204 05b4 0000       `.............
17:40:00.417285 IP (tos 0x0, ttl 64, id 1612, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [.], cksum 0x8431 (incorrect -> 0xa7f7), seq 1, ack 1, win 29200, length 0
        0x0000:  4500 0028 064c 4000 4006 b06d c0a8 01c3  E..(.L@.@..m....
        0x0010:  c0a8 0103 a4b8 0401 061c 265e 60d1 dbb0  ..........&^`...
        0x0020:  5010 7210 8431 0000                      P.r..1..
17:40:00.417927 IP (tos 0x0, ttl 64, id 1613, offset 0, flags [DF], proto TCP (6), length 172)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [P.], cksum 0x84b5 (incorrect -> 0x7bd8), seq 1:133, ack 1, win 29200, length 132
        0x0000:  4500 00ac 064d 4000 4006 afe8 c0a8 01c3  E....M@.@.......
        0x0010:  c0a8 0103 a4b8 0401 061c 265e 60d1 dbb0  ..........&^`...
        0x0020:  5018 7210 84b5 0000 4745 5420 2f5a 5761  P.r.....GET./ZWa
        0x0030:  7665 3f43 6d64 3d43 4263 6b26 4465 763d  ve?Cmd=CBck&Dev=
        0x0040:  5a57 6179 5644 6576 5f7a 7761 795f 3131  ZWayVDev_zway_11
        0x0050:  2d31 2d34 382d 3126 4c65 763d 6f6e 2048  -1-48-1&Lev=on.H
        0x0060:  5454 502f 312e 310d 0a48 6f73 743a 2031  TTP/1.1..Host:.1
        0x0070:  3932 2e31 3638 2e31 2e33 3a31 3032 350d  92.168.1.3:1025.
        0x0080:  0a55 7365 722d 4167 656e 743a 2058 4d4c  .User-Agent:.XML
        0x0090:  4874 7470 5265 7175 6573 740d 0a41 6363  HttpRequest..Acc
        0x00a0:  6570 743a 202a 2f2a 0d0a 0d0a            ept:.*/*....
17:40:00.436900 IP (tos 0x0, ttl 255, id 51955, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42168: Flags [.], cksum 0x08ec (correct), seq 1, ack 133, win 4248, length 0
        0x0000:  4500 0028 caf3 0000 ff06 6cc5 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b8 60d1 dbb0 061c 26e2  ........`.....&.
        0x0020:  5010 1098 08ec 0000 265e 60d1 dbb0       P.......&^`...
17:40:00.464251 IP (tos 0x0, ttl 255, id 51956, offset 0, flags [none], proto TCP (6), length 128)
    192.168.1.3.1025 > 192.168.1.195.42168: Flags [P.], cksum 0xf46f (correct), seq 1:89, ack 133, win 4380, length 88
        0x0000:  4500 0080 caf4 0000 ff06 6c6c c0a8 0103  E.........ll....
        0x0010:  c0a8 01c3 0401 a4b8 60d1 dbb0 061c 26e2  ........`.....&.
        0x0020:  5018 111c f46f 0000 4854 5450 2f31 2e31  P....o..HTTP/1.1
        0x0030:  2032 3030 204f 4b0d 0a43 6f6e 7465 6e74  .200.OK..Content
        0x0040:  2d6c 656e 6774 683a 2036 0d0a 436f 6e6e  -length:.6..Conn
        0x0050:  6563 7469 6f6e 3a20 636c 6f73 650d 0a43  ection:.close..C
        0x0060:  6f6e 7465 6e74 2d74 7970 653a 2074 6578  ontent-type:.tex
        0x0070:  742f 6874 6d6c 0d0a 0d0a 3230 3020 4f4b  t/html....200.OK
17:40:00.464377 IP (tos 0x0, ttl 64, id 1614, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [.], cksum 0x8431 (incorrect -> 0xa71b), seq 133, ack 89, win 29200, length 0
        0x0000:  4500 0028 064e 4000 4006 b06b c0a8 01c3  E..(.N@.@..k....
        0x0010:  c0a8 0103 a4b8 0401 061c 26e2 60d1 dc08  ..........&.`...
        0x0020:  5010 7210 8431 0000                      P.r..1..
17:40:00.465437 IP (tos 0x0, ttl 64, id 1615, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [F.], cksum 0x8431 (incorrect -> 0xa71a), seq 133, ack 89, win 29200, length 0
        0x0000:  4500 0028 064f 4000 4006 b06a c0a8 01c3  E..(.O@.@..j....
        0x0010:  c0a8 0103 a4b8 0401 061c 26e2 60d1 dc08  ..........&.`...
        0x0020:  5011 7210 8431 0000                      P.r..1..
17:40:00.473605 IP (tos 0x0, ttl 255, id 51957, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42168: Flags [.], cksum 0x080f (correct), seq 89, ack 134, win 4380, length 0
        0x0000:  4500 0028 caf5 0000 ff06 6cc3 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b8 60d1 dc08 061c 26e3  ........`.....&.
        0x0020:  5010 111c 080f 0000 26e2 60d1 dc08       P.......&.`...
17:40:00.481899 IP (tos 0x0, ttl 255, id 51958, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.1025 > 192.168.1.195.42168: Flags [F.], cksum 0x080e (correct), seq 89, ack 134, win 4380, length 0
        0x0000:  4500 0028 caf6 0000 ff06 6cc2 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 0401 a4b8 60d1 dc08 061c 26e3  ........`.....&.
        0x0020:  5011 111c 080e 0000 26e2 60d1 dc08       P.......&.`...
17:40:00.481985 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.42168 > 192.168.1.3.1025: Flags [.], cksum 0xa719 (correct), seq 134, ack 90, win 29200, length 0
        0x0000:  4500 0028 0000 4000 4006 b6b9 c0a8 01c3  E..(..@.@.......
        0x0010:  c0a8 0103 a4b8 0401 061c 26e3 60d1 dc09  ..........&.`...
        0x0020:  5010 7210 a719 0000                      P.r.....
17:40:00.686025 IP (tos 0x0, ttl 255, id 51959, offset 0, flags [none], proto TCP (6), length 44)
    192.168.1.3.4161 > 192.168.1.195.8083: Flags [S], cksum 0x92b7 (correct), seq 1624366998, win 4380, options [mss 1460], length 0
        0x0000:  4500 002c caf7 0000 ff06 6cbd c0a8 0103  E..,......l.....
        0x0010:  c0a8 01c3 1041 1f93 60d1 df96 0000 0000  .....A..`.......
        0x0020:  6002 111c 92b7 0000 0204 05b4 dc08       `.............
17:40:00.686119 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 44)
    192.168.1.195.8083 > 192.168.1.3.4161: Flags [S.], cksum 0x8435 (incorrect -> 0x2240), seq 3456188784, ack 1624366999, win 29200, options [mss 1460], length 0
        0x0000:  4500 002c 0000 4000 4006 b6b5 c0a8 01c3  E..,..@.@.......
        0x0010:  c0a8 0103 1f93 1041 ce01 4170 60d1 df97  .......A..Ap`...
        0x0020:  6012 7210 8435 0000 0204 05b4            `.r..5......
17:40:00.687004 IP (tos 0x0, ttl 255, id 51960, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4161 > 192.168.1.195.8083: Flags [.], cksum 0x9af1 (correct), seq 1, ack 1, win 4380, length 0
        0x0000:  4500 0028 caf8 0000 ff06 6cc0 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1041 1f93 60d1 df97 ce01 4171  .....A..`.....Aq
        0x0020:  5010 111c 9af1 0000 4171 60d1 df97       P.......Aq`...
17:40:00.698082 IP (tos 0x0, ttl 255, id 51961, offset 0, flags [none], proto TCP (6), length 240)
    192.168.1.3.4161 > 192.168.1.195.8083: Flags [P.], cksum 0x8920 (correct), seq 1:201, ack 1, win 4380, length 200
        0x0000:  4500 00f0 caf9 0000 ff06 6bf7 c0a8 0103  E.........k.....
        0x0010:  c0a8 01c3 1041 1f93 60d1 df97 ce01 4171  .....A..`.....Aq
        0x0020:  5018 111c 8920 0000 4745 5420 2f5a 4175  P.......GET./ZAu
        0x0030:  746f 6d61 7469 6f6e 2f61 7069 2f76 312f  tomation/api/v1/
        0x0040:  6465 7669 6365 733f 7369 6e63 653d 3135  devices?since=15
        0x0050:  3739 3533 3833 3935 2048 5454 502f 312e  79538395.HTTP/1.
        0x0060:  300d 0a41 7574 686f 7269 7a61 7469 6f6e  0..Authorization
        0x0070:  (Censored)
        0x0080:  (Censored)
        0x0090:  (Censored)
        0x00a0:  3136 382e 312e 3139 350d 0a41 6363 6570  168.1.195..Accep
        0x00b0:  743a 202a 2f2a 0d0a 5573 6572 2d41 6765  t:.*/*..User-Age
        0x00c0:  6e74 3a20 4d6f 7a69 6c6c 612f 322e 3020  nt:.Mozilla/2.0.
        0x00d0:  2863 6f6d 7061 7469 626c 653b 2047 5a57  (compatible;.GZW
        0x00e0:  2d43 6c69 656e 7420 302e 3229 0d0a 0d0a  -Client.0.2)....
17:40:00.698123 IP (tos 0x0, ttl 64, id 24899, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4161: Flags [.], cksum 0x8431 (incorrect -> 0x3605), seq 1, ack 201, win 30016, length 0
        0x0000:  4500 0028 6143 4000 4006 5576 c0a8 01c3  E..(aC@.@.Uv....
        0x0010:  c0a8 0103 1f93 1041 ce01 4171 60d1 e05f  .......A..Aq`.._
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:06.704250 IP (tos 0x0, ttl 255, id 51962, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4161 > 192.168.1.195.8083: Flags [F.], cksum 0x9a28 (correct), seq 201, ack 1, win 4380, length 0
        0x0000:  4500 0028 cafa 0000 ff06 6cbe c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1041 1f93 60d1 e05f ce01 4171  .....A..`.._..Aq
        0x0020:  5011 111c 9a28 0000 0000 0000 0000       P....(........
17:40:06.744473 IP (tos 0x0, ttl 64, id 24900, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4161: Flags [.], cksum 0x8431 (incorrect -> 0x3604), seq 1, ack 202, win 30016, length 0
        0x0000:  4500 0028 6144 4000 4006 5575 c0a8 01c3  E..(aD@.@.Uu....
        0x0010:  c0a8 0103 1f93 1041 ce01 4171 60d1 e060  .......A..Aq`..`
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:07.636397 IP (tos 0x0, ttl 255, id 51963, offset 0, flags [none], proto TCP (6), length 44)
    192.168.1.3.4162 > 192.168.1.195.8083: Flags [S], cksum 0x8ecf (correct), seq 1624367997, win 4380, options [mss 1460], length 0
        0x0000:  4500 002c cafb 0000 ff06 6cb9 c0a8 0103  E..,......l.....
        0x0010:  c0a8 01c3 1042 1f93 60d1 e37d 0000 0000  .....B..`..}....
        0x0020:  6002 111c 8ecf 0000 0204 05b4 e060       `............`
17:40:07.636542 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 44)
    192.168.1.195.8083 > 192.168.1.3.4162: Flags [S.], cksum 0x8435 (incorrect -> 0x6506), seq 4101297230, ack 1624367998, win 29200, options [mss 1460], length 0
        0x0000:  4500 002c 0000 4000 4006 b6b5 c0a8 01c3  E..,..@.@.......
        0x0010:  c0a8 0103 1f93 1042 f474 d44e 60d1 e37e  .......B.t.N`..~
        0x0020:  6012 7210 8435 0000 0204 05b4            `.r..5......
17:40:07.642029 IP (tos 0x0, ttl 255, id 51964, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4162 > 192.168.1.195.8083: Flags [.], cksum 0xddb7 (correct), seq 1, ack 1, win 4380, length 0
        0x0000:  4500 0028 cafc 0000 ff06 6cbc c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1042 1f93 60d1 e37e f474 d44f  .....B..`..~.t.O
        0x0020:  5010 111c ddb7 0000 d44f 60d1 e37e       P........O`..~
17:40:07.657884 IP (tos 0x0, ttl 255, id 51965, offset 0, flags [none], proto TCP (6), length 256)
    192.168.1.3.4162 > 192.168.1.195.8083: Flags [P.], cksum 0xb436 (correct), seq 1:217, ack 1, win 4380, length 216
        0x0000:  4500 0100 cafd 0000 ff06 6be3 c0a8 0103  E.........k.....
        0x0010:  c0a8 01c3 1042 1f93 60d1 e37e f474 d44f  .....B..`..~.t.O
        0x0020:  5018 111c b436 0000 4745 5420 2f5a 4175  P....6..GET./ZAu
        0x0030:  746f 6d61 7469 6f6e 2f61 7069 2f76 312f  tomation/api/v1/
        0x0040:  6465 7669 6365 732f 5a57 6179 5644 6576  devices/ZWayVDev
        0x0050:  5f7a 7761 795f 322d 302d 3337 2f63 6f6d  _zway_2-0-37/com
        0x0060:  6d61 6e64 2f6f 6666 2048 5454 502f 312e  mand/off.HTTP/1.
        0x0070:  300d 0a41 7574 686f 7269 7a61 7469 6f6e  0..Authorization
        0x0080:  (Censored)
        0x0090:  (Censored)
        0x00a0:  (Censored)
        0x00b0:  3136 382e 312e 3139 350d 0a41 6363 6570  168.1.195..Accep
        0x00c0:  743a 202a 2f2a 0d0a 5573 6572 2d41 6765  t:.*/*..User-Age
        0x00d0:  6e74 3a20 4d6f 7a69 6c6c 612f 322e 3020  nt:.Mozilla/2.0.
        0x00e0:  2863 6f6d 7061 7469 626c 653b 2047 5a57  (compatible;.GZW
        0x00f0:  2d43 6c69 656e 7420 302e 3229 0d0a 0d0a  -Client.0.2)....
17:40:07.657928 IP (tos 0x0, ttl 64, id 63142, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4162: Flags [.], cksum 0x8431 (incorrect -> 0x78bb), seq 1, ack 217, win 30016, length 0
        0x0000:  4500 0028 f6a6 4000 4006 c012 c0a8 01c3  E..(..@.@.......
        0x0010:  c0a8 0103 1f93 1042 f474 d44f 60d1 e456  .......B.t.O`..V
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:13.664261 IP (tos 0x0, ttl 255, id 51966, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4162 > 192.168.1.195.8083: Flags [F.], cksum 0xdcde (correct), seq 217, ack 1, win 4380, length 0
        0x0000:  4500 0028 cafe 0000 ff06 6cba c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1042 1f93 60d1 e456 f474 d44f  .....B..`..V.t.O
        0x0020:  5011 111c dcde 0000 c8b1 4b41 4e4e       P.........KANN
17:40:13.704473 IP (tos 0x0, ttl 64, id 63143, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4162: Flags [.], cksum 0x8431 (incorrect -> 0x78ba), seq 1, ack 218, win 30016, length 0
        0x0000:  4500 0028 f6a7 4000 4006 c011 c0a8 01c3  E..(..@.@.......
        0x0010:  c0a8 0103 1f93 1042 f474 d44f 60d1 e457  .......B.t.O`..W
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:17.786398 IP (tos 0x0, ttl 255, id 51967, offset 0, flags [none], proto TCP (6), length 44)
    192.168.1.3.4163 > 192.168.1.195.8083: Flags [S], cksum 0x8ae7 (correct), seq 1624368996, win 4380, options [mss 1460], length 0
        0x0000:  4500 002c caff 0000 ff06 6cb5 c0a8 0103  E..,......l.....
        0x0010:  c0a8 01c3 1043 1f93 60d1 e764 0000 0000  .....C..`..d....
        0x0020:  6002 111c 8ae7 0000 0204 05b4 0000       `.............
17:40:17.786544 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto TCP (6), length 44)
    192.168.1.195.8083 > 192.168.1.3.4163: Flags [S.], cksum 0x8435 (incorrect -> 0xfddf), seq 1755169636, ack 1624368997, win 29200, options [mss 1460], length 0
        0x0000:  4500 002c 0000 4000 4006 b6b5 c0a8 01c3  E..,..@.@.......
        0x0010:  c0a8 0103 1f93 1043 689d c364 60d1 e765  .......Ch..d`..e
        0x0020:  6012 7210 8435 0000 0204 05b4            `.r..5......
17:40:17.797945 IP (tos 0x0, ttl 255, id 51968, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4163 > 192.168.1.195.8083: Flags [.], cksum 0x7691 (correct), seq 1, ack 1, win 4380, length 0
        0x0000:  4500 0028 cb00 0000 ff06 6cb8 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1043 1f93 60d1 e765 689d c365  .....C..`..eh..e
        0x0020:  5010 111c 7691 0000 c365 60d1 e765       P...v....e`..e
17:40:17.812552 IP (tos 0x0, ttl 255, id 51969, offset 0, flags [none], proto TCP (6), length 223)
    192.168.1.3.4163 > 192.168.1.195.8083: Flags [P.], cksum 0xc718 (correct), seq 1:184, ack 1, win 4380, length 183
        0x0000:  4500 00df cb01 0000 ff06 6c00 c0a8 0103  E.........l.....
        0x0010:  c0a8 01c3 1043 1f93 60d1 e765 689d c365  .....C..`..eh..e
        0x0020:  5018 111c c718 0000 4745 5420 2f5a 4175  P.......GET./ZAu
        0x0030:  746f 6d61 7469 6f6e 2f61 7069 2f76 312f  tomation/api/v1/
        0x0040:  6465 7669 6365 7320 4854 5450 2f31 2e30  devices.HTTP/1.0
        0x0050:  0d0a 4175 7468 6f72 697a 6174 696f 6e3a  ..Authorization:
        0x0060:  (Censored)
        0x0070:  (Censored)
        0x0080:  (Censored)
        0x0090:  3638 2e31 2e31 3935 0d0a 4163 6365 7074  68.1.195..Accept
        0x00a0:  3a20 2a2f 2a0d 0a55 7365 722d 4167 656e  :.*/*..User-Agen
        0x00b0:  743a 204d 6f7a 696c 6c61 2f32 2e30 2028  t:.Mozilla/2.0.(
        0x00c0:  636f 6d70 6174 6962 6c65 3b20 475a 572d  compatible;.GZW-
        0x00d0:  436c 6965 6e74 2030 2e32 290d 0a0d 0a    Client.0.2)....
17:40:17.812593 IP (tos 0x0, ttl 64, id 58756, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4163: Flags [.], cksum 0x8431 (incorrect -> 0x11b6), seq 1, ack 184, win 30016, length 0
        0x0000:  4500 0028 e584 4000 4006 d134 c0a8 01c3  E..(..@.@..4....
        0x0010:  c0a8 0103 1f93 1043 689d c365 60d1 e81c  .......Ch..e`...
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:23.824313 IP (tos 0x0, ttl 255, id 51970, offset 0, flags [none], proto TCP (6), length 40)
    192.168.1.3.4163 > 192.168.1.195.8083: Flags [F.], cksum 0x75d9 (correct), seq 184, ack 1, win 4380, length 0
        0x0000:  4500 0028 cb02 0000 ff06 6cb6 c0a8 0103  E..(......l.....
        0x0010:  c0a8 01c3 1043 1f93 60d1 e81c 689d c365  .....C..`...h..e
        0x0020:  5011 111c 75d9 0000 01b2 0000 0000       P...u.........
17:40:23.864474 IP (tos 0x0, ttl 64, id 58757, offset 0, flags [DF], proto TCP (6), length 40)
    192.168.1.195.8083 > 192.168.1.3.4163: Flags [.], cksum 0x8431 (incorrect -> 0x11b5), seq 1, ack 185, win 30016, length 0
        0x0000:  4500 0028 e585 4000 4006 d133 c0a8 01c3  E..(..@.@..3....
        0x0010:  c0a8 0103 1f93 1043 689d c365 60d1 e81d  .......Ch..e`...
        0x0020:  5010 7540 8431 0000                      P.u@.1..
17:40:46.538395 ARP, Ethernet (len 6), IPv4 (len 4), Request who-has 192.168.1.3 tell 192.168.1.1, length 46
        0x0000:  0001 0800 0604 0001 0021 91f6 e33f c0a8  .........!...?..
        0x0010:  0101 0000 0000 0000 c0a8 0103 0000 0000  ................
        0x0020:  0000 0000 0000 0000 0000 0000 0000       ..............

Here is the end snippet of the z-way-server log:

Code: Select all

[2020-01-20 17:39:54.936] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 0D 00 00 CF )
[2020-01-20 17:39:54.936] [D] [zway] SENT ACK
[2020-01-20 17:39:54.940] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:39:54.940] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 1.300000
[2020-01-20 17:39:54.940] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-01-20 17:39:54.940] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-01-20 17:39:54.940] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-01-20 17:39:54.945] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 1.3 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=1.3
[2020-01-20 17:39:55.067] [I] [core] Request was successful
[2020-01-20 17:39:55.946] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 60 0D 01 00 32 02 21 34 00 00 00 08 00 00 A6 )
[2020-01-20 17:39:55.946] [D] [zway] SENT ACK
[2020-01-20 17:39:55.947] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:39:55.947] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 0.800000
[2020-01-20 17:39:55.947] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-01-20 17:39:55.947] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-01-20 17:39:55.947] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-01-20 17:39:55.951] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 0.8 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=0.8
[2020-01-20 17:39:56.013] [I] [core] Request was successful
[2020-01-20 17:39:57.637] [I] [core] ---  ZWayVDev_zway_2-0-37 performCommand processing: {"0":"on","1":{}}
[2020-01-20 17:39:57.637] [I] [zway] Adding job: SwitchBinary Set
[2020-01-20 17:39:57.637] [I] [zway] Adding job: SwitchBinary Get
[2020-01-20 17:39:57.645] [D] [zway] SENDING (cb 0xc1): ( 01 0A 00 13 02 03 25 01 FF 25 C1 D8 )
[2020-01-20 17:39:57.646] [D] [zway] RECEIVED ACK
[2020-01-20 17:39:57.650] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-20 17:39:57.651] [D] [zway] SENT ACK
[2020-01-20 17:39:57.651] [D] [zway] Delivered to Z-Wave stack
[2020-01-20 17:39:57.675] [D] [zway] RECEIVED: ( 01 07 00 13 C1 00 00 02 28 )
[2020-01-20 17:39:57.675] [D] [zway] SENT ACK
[2020-01-20 17:39:57.675] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2020-01-20 17:39:57.676] [D] [zway] SendData Response with callback 0xc1 received: received by recipient
[2020-01-20 17:39:57.676] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2020-01-20 17:39:57.676] [D] [zway] SETDATA devices.2.data.lastSend = 14647384 (0x00df8058)
[2020-01-20 17:39:57.676] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2020-01-20 17:39:57.676] [I] [zway] Removing job: SwitchBinary Set
[2020-01-20 17:39:57.716] [D] [zway] SENDING (cb 0xc2): ( 01 09 00 13 02 02 25 02 25 C2 25 )
[2020-01-20 17:39:57.718] [D] [zway] RECEIVED ACK
[2020-01-20 17:39:57.722] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-20 17:39:57.722] [D] [zway] SENT ACK
[2020-01-20 17:39:57.722] [D] [zway] Delivered to Z-Wave stack
[2020-01-20 17:39:57.746] [D] [zway] RECEIVED: ( 01 07 00 13 C2 00 00 02 2B )
[2020-01-20 17:39:57.746] [D] [zway] SENT ACK
[2020-01-20 17:39:57.746] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2020-01-20 17:39:57.747] [D] [zway] SendData Response with callback 0xc2 received: received by recipient
[2020-01-20 17:39:57.747] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2020-01-20 17:39:57.747] [D] [zway] SETDATA devices.2.data.lastSend = 14647391 (0x00df805f)
[2020-01-20 17:39:57.747] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2020-01-20 17:39:57.747] [I] [zway] Waiting for job reply: SwitchBinary Get
[2020-01-20 17:39:57.756] [D] [zway] RECEIVED: ( 01 09 00 04 00 02 03 25 03 FF 2A )
[2020-01-20 17:39:57.756] [D] [zway] SENT ACK
[2020-01-20 17:39:57.757] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:39:57.757] [D] [zway] Received reply on job (SwitchBinary Get)
[2020-01-20 17:39:57.757] [D] [zway] SETDATA devices.2.instances.0.commandClasses.37.data.level = True
[2020-01-20 17:39:57.781] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_2-0-37 : on : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_2-0-37&Lev=on
[2020-01-20 17:39:57.786] [I] [core] Notification: device-info (device-OnOff): {"dev":"Switch (2.0)","l":"on","location":1}
[2020-01-20 17:39:57.949] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 04 00 00 C6 )
[2020-01-20 17:39:57.949] [D] [zway] SENT ACK
[2020-01-20 17:39:57.950] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:39:57.950] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 0.400000
[2020-01-20 17:39:57.950] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-01-20 17:39:57.950] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-01-20 17:39:57.950] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-01-20 17:39:57.959] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 0.4 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=0.4
[2020-01-20 17:39:58.011] [I] [core] Request was successful
[2020-01-20 17:39:58.133] [I] [core] Request was successful
[2020-01-20 17:39:58.965] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 60 0D 01 00 32 02 21 34 00 00 00 06 00 00 A8 )
[2020-01-20 17:39:58.966] [D] [zway] SENT ACK
[2020-01-20 17:39:58.966] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:39:58.966] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 0.600000
[2020-01-20 17:39:58.966] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-01-20 17:39:58.966] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-01-20 17:39:58.966] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-01-20 17:39:58.974] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 0.6 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=0.6
[2020-01-20 17:39:59.248] [I] [core] Request was successful
[2020-01-20 17:39:59.920] [D] [zway] Job 0x13: deleted from queue
[2020-01-20 17:40:00.112] [D] [zway] Job 0x13: deleted from queue
[2020-01-20 17:40:00.319] [I] [core] ---  ZWayVDev_zway_6-0-49-1 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.319] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-01-20 17:40:00.319] [I] [core] ---  ZWayVDev_zway_6-0-50-0 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.320] [I] [zway] Adding job: Meter Get (v2/3)
[2020-01-20 17:40:00.320] [I] [core] ---  ZWayVDev_zway_6-1-50-0 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.320] [I] [zway] Adding job: Meter Get (v2/3)
[2020-01-20 17:40:00.320] [I] [core] ---  ZWayVDev_zway_7-0-49-4 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.320] [D] [zway] Job 0x13 (SensorMultilevel V1-4 Get): removing duplicate
[2020-01-20 17:40:00.320] [I] [core] ---  ZWayVDev_zway_7-0-50-0 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.320] [D] [zway] Job 0x13 (Meter Get (v2/3)): removing duplicate
[2020-01-20 17:40:00.321] [I] [core] ---  ZWayVDev_zway_7-0-50-2 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.321] [D] [zway] Job 0x13 (Meter Get (v2/3)): removing duplicate
[2020-01-20 17:40:00.321] [I] [core] ---  ZWayVDev_zway_10-0-49-1 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.321] [I] [zway] Using security scheme S0
[2020-01-20 17:40:00.321] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-01-20 17:40:00.321] [I] [core] ---  ZWayVDev_zway_11-1-48-1 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.322] [I] [zway] Adding job: SensorBinary Get
[2020-01-20 17:40:00.322] [I] [core] ---  ZWayVDev_zway_11-2-48-1 performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.322] [I] [zway] Adding job: SensorBinary Get
[2020-01-20 17:40:00.323] [I] [core] ---  ZWayVDev_zway_12-0-113-9-7-A performCommand processing: {"0":"update"}
[2020-01-20 17:40:00.323] [I] [zway] Using security scheme S2 Unauthenticated
[2020-01-20 17:40:00.323] [I] [zway] Adding job: Alarm Get (v3)
[2020-01-20 17:40:00.326] [I] [zway] Node 10:0 CC Security: sending Nonce Get
[2020-01-20 17:40:00.326] [D] [zway] SETDATA devices.10.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2020-01-20 17:40:00.326] [I] [zway] Adding job: Nonce Get
[2020-01-20 17:40:00.326] [D] [zway] SETDATA devices.10.data.lastNonceGet = 14647644 (0x00df815c)
[2020-01-20 17:40:00.327] [I] [zway] Adding job: Security S2 Encapsulation (S2 Unauthenticated), Alarm Get (v3)
[2020-01-20 17:40:00.327] [D] [zway] SENDING (cb 0xc3): ( 01 0B 00 13 06 04 31 04 01 00 25 C3 37 )
[2020-01-20 17:40:00.329] [D] [zway] RECEIVED ACK
[2020-01-20 17:40:00.333] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-20 17:40:00.333] [D] [zway] SENT ACK
[2020-01-20 17:40:00.333] [D] [zway] Delivered to Z-Wave stack
[2020-01-20 17:40:00.358] [D] [zway] RECEIVED: ( 01 07 00 13 C3 00 00 02 2A )
[2020-01-20 17:40:00.358] [D] [zway] SENT ACK
[2020-01-20 17:40:00.358] [I] [zway] Job 0x13 (SensorMultilevel V5 Get): Delivered
[2020-01-20 17:40:00.358] [D] [zway] SendData Response with callback 0xc3 received: received by recipient
[2020-01-20 17:40:00.358] [D] [zway] SETDATA devices.6.data.lastSendInternal = **********
[2020-01-20 17:40:00.358] [D] [zway] SETDATA devices.6.data.lastSend = 14647648 (0x00df8160)
[2020-01-20 17:40:00.358] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): success
[2020-01-20 17:40:00.358] [I] [zway] Waiting for job reply: SensorMultilevel V5 Get
[2020-01-20 17:40:00.359] [D] [zway] SENDING (cb 0xc4): ( 01 0D 00 13 0B 06 60 0D 00 01 30 02 25 C4 53 )
[2020-01-20 17:40:00.368] [D] [zway] RECEIVED ACK
[2020-01-20 17:40:00.368] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-20 17:40:00.368] [D] [zway] SENT ACK
[2020-01-20 17:40:00.368] [D] [zway] Delivered to Z-Wave stack
[2020-01-20 17:40:00.389] [D] [zway] RECEIVED: ( 01 07 00 13 C4 00 00 02 2D )
[2020-01-20 17:40:00.390] [D] [zway] SENT ACK
[2020-01-20 17:40:00.390] [I] [zway] Job 0x13 (SensorBinary Get): Delivered
[2020-01-20 17:40:00.390] [D] [zway] SendData Response with callback 0xc4 received: received by recipient
[2020-01-20 17:40:00.390] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2020-01-20 17:40:00.390] [D] [zway] SETDATA devices.11.data.lastSend = 14647651 (0x00df8163)
[2020-01-20 17:40:00.390] [D] [zway] Job 0x13 (SensorBinary Get): success
[2020-01-20 17:40:00.390] [I] [zway] Waiting for job reply: SensorBinary Get
[2020-01-20 17:40:00.390] [D] [zway] SENDING (cb 0xc5): ( 01 09 00 13 0A 02 98 40 25 C5 D5 )
[2020-01-20 17:40:00.399] [W] [zway] Received SOF, while awaiting ACK
[2020-01-20 17:40:00.399] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0B 07 60 0D 01 00 30 03 FF 5A )
[2020-01-20 17:40:00.399] [D] [zway] SENT ACK
[2020-01-20 17:40:00.399] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:40:00.399] [D] [zway] Received reply on job (SensorBinary Get)
[2020-01-20 17:40:00.399] [D] [zway] SETDATA devices.11.instances.1.commandClasses.48.data.1.level = True
[2020-01-20 17:40:00.399] [D] [zway] SETDATA devices.11.instances.1.commandClasses.48.data.1 = Empty
[2020-01-20 17:40:00.409] [D] [zway] RECEIVED CAN
[2020-01-20 17:40:00.409] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_11-1-48-1 : on : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_11-1-48-1&Lev=on
[2020-01-20 17:40:00.419] [D] [zway] SENDING (cb 0xc6): ( 01 09 00 13 0A 02 98 40 25 C6 D6 )
[2020-01-20 17:40:00.422] [W] [zway] Received SOF, while awaiting ACK
[2020-01-20 17:40:00.430] [D] [zway] RECEIVED: ( 01 0C 00 04 00 06 06 31 05 01 22 00 C8 28 )
[2020-01-20 17:40:00.430] [D] [zway] SENT ACK
[2020-01-20 17:40:00.430] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-01-20 17:40:00.430] [D] [zway] Received reply on job (SensorMultilevel V5 Get)
[2020-01-20 17:40:00.430] [D] [zway] SETDATA devices.6.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2020-01-20 17:40:00.432] [D] [zway] SETDATA devices.6.instances.0.commandClasses.49.data.1.scaleString = "°C"
[2020-01-20 17:40:00.432] [D] [zway] SETDATA devices.6.instances.0.commandClasses.49.data.1.val = 20.000000
[2020-01-20 17:40:00.432] [D] [zway] SETDATA devices.6.instances.0.commandClasses.49.data.1 = Empty
[2020-01-20 17:40:00.440] [D] [zway] RECEIVED CAN
[2020-01-20 17:40:00.450] [D] [zway] SENDING (cb 0xc7): ( 01 0A 00 13 06 03 32 01 00 25 C7 32 )
[2020-01-20 17:40:00.452] [D] [zway] RECEIVED ACK
[2020-01-20 17:40:00.456] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-20 17:40:00.456] [D] [zway] SENT ACK
[2020-01-20 17:40:00.456] [D] [zway] Delivered to Z-Wave stack
Hopefully the collected data will be useful clues as to what is happening.

Many thanks in advance,

Regards,

/Joakim
User avatar
PoltoS
Posts: 7565
Joined: 26 Jan 2011 19:36

Re: Z-way server goes down spontaneously

Post by PoltoS »

Even more questions so far... Hopefully one of our employee managed to reproduce it once a week, so we will dig into
Wikibear
Posts: 102
Joined: 01 Jul 2016 14:10

Re: Z-way server goes down spontaneously

Post by Wikibear »

OK i do an upgrade to 3.0.4 and then we must wait and see whats happening at the end.
Greatings from the noob
Wikibear
Posts: 102
Joined: 01 Jul 2016 14:10

Re: Z-way server goes down spontaneously

Post by Wikibear »

Same issue with 3.0.4. today.
Greatings from the noob
User avatar
PoltoS
Posts: 7565
Joined: 26 Jan 2011 19:36

Re: Z-way server goes down spontaneously

Post by PoltoS »

We have one guy from our team who is able to reproduce the issue - digging into it. Unfortunatelly this happens for him only once a week, so we wait ;((

We have made http://storage.z-wave.me/z-way-server/z ... _armhf.deb with a bit of debug output in the function inside V8 where it crashes. Hope thanks to this we can track better where and why it stops. If you can run it, would be nice. Otherwise we wait to reproduce the issue on our side.
User avatar
GokMasE
Posts: 59
Joined: 13 Mar 2016 01:04
Location: Sweden

Re: Z-way server goes down spontaneously

Post by GokMasE »

PoltoS wrote:
03 Feb 2020 23:54
We have one guy from our team who is able to reproduce the issue - digging into it. Unfortunatelly this happens for him only once a week, so we wait ;((

We have made http://storage.z-wave.me/z-way-server/z ... _armhf.deb with a bit of debug output in the function inside V8 where it crashes. Hope thanks to this we can track better where and why it stops. If you can run it, would be nice. Otherwise we wait to reproduce the issue on our side.
Ok, at least it is good news that someone on the dev team is able to see this happening too. I agree it is a problem when trying to debug that it seems hard to trigger frequent crashes.

I will of course try to install and run the new debug build and see if debug output will be of any assistance to you.

Regards,

/Joakim
Wikibear
Posts: 102
Joined: 01 Jul 2016 14:10

Re: Z-way server goes down spontaneously

Post by Wikibear »

That bug is very bad. Great news that one dev get this also. I will install this deb and hope to support you with information. As you told it took most a week. That is so silly...
Greatings from the noob
User avatar
GokMasE
Posts: 59
Joined: 13 Mar 2016 01:04
Location: Sweden

Re: Z-way server goes down spontaneously

Post by GokMasE »

I installed 3.0.4-34 yesterday and it seems to bring a new problem for me. Although the z-wave server starts up OK and seems to run just fine for a while, after a couple of hours it seems one of the HTTP requests I am sending to it regularly (sent to find out changes in device data since given timestamp) is left unanswered. (I am using timeout value of 8s, and I get no reply within that time).

ATM I am at work, so can't provide any further details until I get home. I will try to supply some logs from the situation described above.

EDIT: Here is a snippet of the z-wave server log around the time of the strange timeout. The http request sent to the z-wave server is issued ~05:29:22. Note that there is a "gap" in otherwise quite steady flow of events in the log between 05:29:22.839 and 05:29:38.355.

The request that is timing out is a call for /ZAutomation/api/v1/devices?since=<timestamp>.

Code: Select all

[2020-02-05 05:29:17.848] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-05 05:29:17.848] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 0.900000
[2020-02-05 05:29:17.848] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-05 05:29:17.848] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-05 05:29:17.848] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-02-05 05:29:17.853] [I] [core] Notification: device-info (device-OnOff): {"dev":"Switch (2.0)","l":"on","location":1}
[2020-02-05 05:29:17.899] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 0.9 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=0.9
[2020-02-05 05:29:17.954] [I] [core] Can not make request: Server returned nothing (no headers, no data)
[2020-02-05 05:29:17.975] [I] [core] Request was successful
[2020-02-05 05:29:19.957] [D] [zway] Job 0x13: deleted from queue
[2020-02-05 05:29:21.815] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 10 00 00 D2 )
[2020-02-05 05:29:21.815] [D] [zway] SENT ACK
[2020-02-05 05:29:21.816] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-05 05:29:21.816] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 1.600000
[2020-02-05 05:29:21.816] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-05 05:29:21.816] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-05 05:29:21.816] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-05 05:29:21.822] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 1.6 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=1.6
[2020-02-05 05:29:21.957] [I] [core] Request was successful
[2020-02-05 05:29:22.831] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 60 0D 01 00 32 02 21 34 00 00 00 07 00 00 A9 )
[2020-02-05 05:29:22.831] [D] [zway] SENT ACK
[2020-02-05 05:29:22.831] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-05 05:29:22.832] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 0.700000
[2020-02-05 05:29:22.832] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-05 05:29:22.832] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-05 05:29:22.832] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-02-05 05:29:22.839] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 0.7 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=0.7
[2020-02-05 05:29:38.355] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 12 00 00 D0 )
[2020-02-05 05:29:38.355] [D] [zway] SENT ACK
[2020-02-05 05:29:38.355] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-05 05:29:38.355] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 1.800000
[2020-02-05 05:29:38.356] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-05 05:29:38.356] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-05 05:29:38.356] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-05 05:29:38.365] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 12 00 00 D0 )
[2020-02-05 05:29:38.365] [D] [zway] SENT ACK
EDIT2: I have restarted the test now, and configured my side to ignore timeouts like the one above, and just keep going. (Previously, such timeouts have been a clear indication that the server had already crashed, which was not the case this time)

Regards,

/Joakim
User avatar
GokMasE
Posts: 59
Joined: 13 Mar 2016 01:04
Location: Sweden

Re: Z-way server goes down spontaneously

Post by GokMasE »

Hi again,

Here is a logged crash from 3.0.4-34, hopefully I kept all relevant data when pasting into the forum thread. (Let me know if you need more data from gdb log prior to the time of the crash)


End of gdb log:

Code: Select all

[New Thread 0x6e2fd450 (LWP 8478)]
3 0x7257d4f8
4 0x7257d508
5 0x3cf28
6
3 0x7257d508
4 0x7257d5b8
5 0x3cf28
6
3 0x7257d5b8
4 0x7257d5c8
5 0x3cf28
6
3 0x7257d5c8
4 0x7257d5d8
5 0x3cf28
6
3 0x7257d5d8
4 0x7257d5f8
5 0x3cf28
6
3 0x7257d5f8
4 0x7257d608
5 0x3cf28
6
3 0x7257d608
4 0x7257d618
5 0x3cf28
6
3 0x7257d618
4 0x7257d638
5 0x3cf28
6
3 0x7257d638
4 0x7257d698
5 0x3cf28
6
3 0x7257d698
4 0x7257d808
5 0x3cf28
6
3 0x7257d808
4 0x7257d818
5 0x3cf28
6
3 0x7257d818
4 0x7257d828
5 0x3cf28
6
3 0x7257d828
4 0x7257d838
5 0x3cf28
6
3 0x7257d838
4 0x7257db78
5 0x3cf28
6
3 0x7257db78
4 0x7257db98
5 0x3cf28
6
3 0x7257db98
4 0x7257dba8
5 0x3cf28
6
3 0x7257dba8
4 0x7257dbb8
5 0x3cf28
6
3 0x7257dbb8
4 0x7257dbc8
5 0x3cf28
6
3 0x7257dbc8
4 0x7257dbd8
5 0x3cf28
6
3 0x7257dbd8
4 0x7257dbe8
5 0x3cf28
6
3 0x7257dbe8
4 0x7257dbf8
5 0x3cf28
6
3 0x7257dbf8
4 0x7257dcb8
5 0x3cf28
6
3 0x7257dcb8
4 0x7257ddd8
5 0x3cf28
6
3 0x7257ddd8
4 0x7257dea8
5 0x3cf28
6
3 0x7257dea8
4 0x7257dec8
5 0x3cf28
6
3 0x7257dec8
4 0x7257ded8
5 0x3cf28
6
3 0x7257ded8
4 0x7257dee8
5 0x3cf28
6
3 0x7257dee8
4 0x7257def8
5 0x3cf28
6
3 0x7257def8
4 0x7257df18
5 0x3cf28
6
3 0x7257df18
4 0x7257df28
5 0x3cf28
6
3 0x7257df28
4 0x7257df38
5 0x3cf28
6
3 0x7257df38
4 0x72587de8
5 0x3cf28
6
3 0x72587de8
4 0x7258f850
5 0x3cf28
6
3 0x7258f850
4 0x7258bb50
5 0x3cf28
6
3 0x7258bb50
4 0x725882f8
5 0x3cf28
6
3 0x725882f8
4 0x72588348
5 0x3cf28
6
3 0x72588348
4 0x72588358
5 0x3cf28
6
3 0x72588358
4 0x72588368
5 0x3cf28
6
3 0x72588368
4 0x72588378
5 0x3cf28
6
3 0x72588378
4 0x72588468
5 0x3cf28
6
3 0x72588468
4 0x72588948
5 0x3cf28
6
3 0x72588948
4 0x72588958
5 0x3cf28
6
3 0x72588958
4 0x72588988
5 0x3cf28
6
3 0x72588988
4 0x7258f520
5 0x3cf28
6
3 0x7258f520
4 0x7258f530
5 0x3cf28
6
3 0x7258f530
4 0x7258f540
5 0x3cf28
6
3 0x7258f540
4 0x7258f550
5 0x3cf28
6
3 0x7258f550
4 0x7258f560
5 0x3cf28
6
3 0x7258f560
4 0x7258f570
5 0x3cf28
6
3 0x7257d4d8
4 0x7258f560
5 0x3cf28
6
3 0x7258f560
4 0x7258d820
5 0x3cf28
6
3 0x7258d820
4 0x23b58
[Thread 0x6e2fd450 (LWP 8478) exited]

Thread 7 "zway/core" received signal SIGSEGV, Segmentation fault.
[Switching to Thread 0x73456450 (LWP 2551)]
0x76c03e7c in v8::internal::GlobalHandles::Create(v8::internal::Object*) ()
   from libs/libv8.so
(gdb) i thr
  Id   Target Id         Frame
  1    Thread 0x76ff6140 (LWP 2545) "z-way-server" 0x76350030 in nanosleep ()
    at ../sysdeps/unix/syscall-template.S:84
  2    Thread 0x73c8a450 (LWP 2546) "OptimizingCompi" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5b524)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  3    Thread 0x74062450 (LWP 2547) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5b684)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  4    Thread 0x73489450 (LWP 2548) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5b7a4)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  5    Thread 0x73478450 (LWP 2549) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5b8c4)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
  6    Thread 0x73467450 (LWP 2550) "v8:SweeperThrea" 0x765f6068 in futex_abstimed_wait_cancelable (private=0, abstime=0x0, expected=1, futex_word=0x5b9e4)
    at ../sysdeps/unix/sysv/linux/futex-internal.h:205
* 7    Thread 0x73456450 (LWP 2551) "zway/core" 0x76c03e7c in v8::internal::GlobalHandles::Create(v8::internal::Object*) () from libs/libv8.so
  8    Thread 0x722ff450 (LWP 2554) "zway/webserver" 0x7637e674 in select ()
    at ../sysdeps/unix/syscall-template.S:84
  9    Thread 0x71afe450 (LWP 2555) "zway/timers" 0x76350030 in nanosleep ()
---Type <return> to continue, or q <return> to quit---
    at ../sysdeps/unix/syscall-template.S:84
  10   Thread 0x712fd450 (LWP 2556) "zway/core" 0x765f6f10 in __lll_lock_wait (
    futex=futex@entry=0x72b06f48, private=0) at lowlevellock.c:46
  4865 Thread 0x618f2450 (LWP 8477) "zway/core" 0x7637c120 in poll ()
    at ../sysdeps/unix/syscall-template.S:84
(gdb) bt
#0  0x76c03e7c in v8::internal::GlobalHandles::Create(v8::internal::Object*) ()
   from libs/libv8.so
#1  0x76b50c1c in v8::V8::GlobalizeReference(v8::internal::Isolate*, v8::internal::Object**) () from libs/libv8.so
#2  0x74110cb0 in zwjs::DHWrapper::Setup(v8::Isolate*, v8::Handle<v8::Object>)
    () from ./modules/moddhs.so
#3  0x74105b38 in zwjs::DataHolder::New(zwjs::Environment*, zwjs::DHWrapper*)
    () from ./modules/moddhs.so
#4  0x74105180 in zwjs::DataHolder::New(zwjs::Environment*, _ZDataHolder*) ()
   from ./modules/moddhs.so
#5  0x741097cc in zwjs::DataHolder::NamedPropertyGetter(v8::Local<v8::String>, v8::PropertyCallbackInfo<v8::Value> const&) () from ./modules/moddhs.so
#6  0x76b70e78 in v8::internal::PropertyCallbackArguments::Call(void (*)(v8::Local<v8::String>, v8::PropertyCallbackInfo<v8::Value> const&), v8::Local<v8::String>) () from libs/libv8.so
#7  0x76de0534 in v8::internal::LoadWithInterceptor(v8::internal::Arguments*, PropertyAttributes*) () from libs/libv8.so
#8  0x76de160c in v8::internal::LoadPropertyWithInterceptorForLoad(int, v8::internal::Object**, v8::internal::Isolate*) () from libs/libv8.so
#9  0x2470a3a8 in ?? ()
Backtrace stopped: previous frame identical to this frame (corrupt stack?)
(gdb)
End segment of z-wave server log:

Code: Select all

[2020-02-06 03:59:41.069] [D] [zway] Job 0x13: deleted from queue
[2020-02-06 03:59:41.841] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 04 00 00 C6 )
[2020-02-06 03:59:41.841] [D] [zway] SENT ACK
[2020-02-06 03:59:41.841] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:41.841] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 0.400000
[2020-02-06 03:59:41.842] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:41.842] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:41.842] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:41.853] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 0.4 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=0.4
[2020-02-06 03:59:42.858] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 60 0D 01 00 32 02 21 34 00 00 00 03 00 00 AD )
[2020-02-06 03:59:42.858] [D] [zway] SENT ACK
[2020-02-06 03:59:42.858] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:42.858] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 0.300000
[2020-02-06 03:59:42.858] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:42.859] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:42.859] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:43.038] [I] [core] Request was successful
[2020-02-06 03:59:43.039] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 0.3 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=0.3
[2020-02-06 03:59:43.311] [I] [core] Request was successful
[2020-02-06 03:59:48.291] [D] [zway] RECEIVED: ( 01 12 00 04 00 0B 0C 60 0D 03 03 31 05 01 44 00 00 08 EF 15 )
[2020-02-06 03:59:48.291] [D] [zway] SENT ACK
[2020-02-06 03:59:48.296] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:48.296] [D] [zway] SETDATA devices.11.instances.3.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2020-02-06 03:59:48.298] [D] [zway] SETDATA devices.11.instances.3.commandClasses.49.data.1.scaleString = "°C"
[2020-02-06 03:59:48.298] [D] [zway] SETDATA devices.11.instances.3.commandClasses.49.data.1.val = 22.870001
[2020-02-06 03:59:48.298] [D] [zway] SETDATA devices.11.instances.3.commandClasses.49.data.1 = Empty
[2020-02-06 03:59:48.312] [D] [zway] RECEIVED: ( 01 12 00 04 00 0B 0C 60 0D 04 04 31 05 01 44 00 00 08 34 CE )
[2020-02-06 03:59:48.312] [D] [zway] SENT ACK
[2020-02-06 03:59:48.312] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:48.312] [D] [zway] SETDATA devices.11.instances.4.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2020-02-06 03:59:48.314] [D] [zway] SETDATA devices.11.instances.4.commandClasses.49.data.1.scaleString = "°C"
[2020-02-06 03:59:48.314] [D] [zway] SETDATA devices.11.instances.4.commandClasses.49.data.1.val = 21.000000
[2020-02-06 03:59:48.314] [D] [zway] SETDATA devices.11.instances.4.commandClasses.49.data.1 = Empty
[2020-02-06 03:59:48.332] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_11-3-49-1 : 22.8700016 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_11-3-49-1&Lev=22.8700016
[2020-02-06 03:59:48.365] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_11-4-49-1 : 21 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_11-4-49-1&Lev=21
[2020-02-06 03:59:48.413] [I] [core] Can not make request: Server returned nothing (no headers, no data)
[2020-02-06 03:59:48.434] [I] [core] Request was successful
[2020-02-06 03:59:51.877] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 00 00 00 C2 )
[2020-02-06 03:59:51.877] [D] [zway] SENT ACK
[2020-02-06 03:59:51.878] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:51.878] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 0.000000
[2020-02-06 03:59:51.878] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:51.878] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:51.878] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:51.883] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 0 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=0
[2020-02-06 03:59:52.146] [I] [core] Request was successful
[2020-02-06 03:59:52.883] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 15 00 00 D7 )
[2020-02-06 03:59:52.883] [D] [zway] SENT ACK
[2020-02-06 03:59:52.884] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:52.884] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 2.100000
[2020-02-06 03:59:52.884] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:52.884] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:52.884] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:52.887] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 2.1 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=2.1
[2020-02-06 03:59:53.227] [I] [core] Request was successful
[2020-02-06 03:59:53.891] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 08 00 00 CA )
[2020-02-06 03:59:53.891] [D] [zway] SENT ACK
[2020-02-06 03:59:53.892] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:53.892] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 0.800000
[2020-02-06 03:59:53.892] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:53.892] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:53.892] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:54.146] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 0.8 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=0.8
[2020-02-06 03:59:54.245] [I] [core] Request was successful
[2020-02-06 03:59:54.895] [D] [zway] RECEIVED: ( 01 10 00 04 00 06 0A 32 02 21 34 00 00 00 13 00 00 D1 )
[2020-02-06 03:59:54.895] [D] [zway] SENT ACK
[2020-02-06 03:59:54.895] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:54.895] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.val = 1.900000
[2020-02-06 03:59:54.895] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:54.895] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:54.895] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:54.904] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-2 : 1.9 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-2&Lev=1.9
[2020-02-06 03:59:55.170] [I] [core] Request was successful
[2020-02-06 03:59:55.911] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 60 0D 01 00 32 02 21 34 00 00 00 0C 00 00 A2 )
[2020-02-06 03:59:55.911] [D] [zway] SENT ACK
[2020-02-06 03:59:55.912] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:55.912] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.val = 1.200000
[2020-02-06 03:59:55.912] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.delta = 0 (0x00000000)
[2020-02-06 03:59:55.912] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2020-02-06 03:59:55.912] [D] [zway] SETDATA devices.6.instances.1.commandClasses.50.data.2 = Empty
[2020-02-06 03:59:55.922] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-1-50-2 : 1.2 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-1-50-2&Lev=1.2
[2020-02-06 03:59:55.988] [I] [core] Request was successful
[2020-02-06 03:59:56.534] [I] [core] ---  ZWayVDev_zway_2-0-37 performCommand processing: {"0":"on","1":{}}
[2020-02-06 03:59:56.535] [I] [zway] Adding job: SwitchBinary Set
[2020-02-06 03:59:56.535] [I] [zway] Adding job: SwitchBinary Get
[2020-02-06 03:59:56.535] [D] [zway] SENDING (cb 0x95): ( 01 0A 00 13 02 03 25 01 FF 25 95 8C )
[2020-02-06 03:59:56.537] [D] [zway] RECEIVED ACK
[2020-02-06 03:59:56.541] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 03:59:56.542] [D] [zway] SENT ACK
[2020-02-06 03:59:56.542] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 03:59:56.565] [D] [zway] RECEIVED: ( 01 07 00 13 95 00 00 02 7C )
[2020-02-06 03:59:56.565] [D] [zway] SENT ACK
[2020-02-06 03:59:56.565] [I] [zway] Job 0x13 (SwitchBinary Set): Delivered
[2020-02-06 03:59:56.566] [D] [zway] SendData Response with callback 0x95 received: received by recipient
[2020-02-06 03:59:56.566] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2020-02-06 03:59:56.566] [D] [zway] SETDATA devices.2.data.lastSend = 1017429 (0x000f8655)
[2020-02-06 03:59:56.566] [D] [zway] Job 0x13 (SwitchBinary Set): success
[2020-02-06 03:59:56.566] [I] [zway] Removing job: SwitchBinary Set
[2020-02-06 03:59:56.607] [D] [zway] SENDING (cb 0x96): ( 01 09 00 13 02 02 25 02 25 96 71 )
[2020-02-06 03:59:56.608] [D] [zway] RECEIVED ACK
[2020-02-06 03:59:56.612] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 03:59:56.613] [D] [zway] SENT ACK
[2020-02-06 03:59:56.613] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 03:59:56.637] [D] [zway] RECEIVED: ( 01 07 00 13 96 00 00 02 7F )
[2020-02-06 03:59:56.637] [D] [zway] SENT ACK
[2020-02-06 03:59:56.637] [I] [zway] Job 0x13 (SwitchBinary Get): Delivered
[2020-02-06 03:59:56.638] [D] [zway] SendData Response with callback 0x96 received: received by recipient
[2020-02-06 03:59:56.638] [D] [zway] SETDATA devices.2.data.lastSendInternal = **********
[2020-02-06 03:59:56.638] [D] [zway] SETDATA devices.2.data.lastSend = 1017436 (0x000f865c)
[2020-02-06 03:59:56.638] [D] [zway] Job 0x13 (SwitchBinary Get): success
[2020-02-06 03:59:56.638] [I] [zway] Waiting for job reply: SwitchBinary Get
[2020-02-06 03:59:56.647] [D] [zway] RECEIVED: ( 01 09 00 04 00 02 03 25 03 FF 2A )
[2020-02-06 03:59:56.648] [D] [zway] SENT ACK
[2020-02-06 03:59:56.648] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2020-02-06 03:59:56.648] [D] [zway] Received reply on job (SwitchBinary Get)
[2020-02-06 03:59:56.648] [D] [zway] SETDATA devices.2.instances.0.commandClasses.37.data.level = True
[2020-02-06 03:59:56.686] [I] [core] Notification: device-info (device-OnOff): {"dev":"Switch (2.0)","l":"on","location":1}
[2020-02-06 03:59:56.687] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_2-0-37 : on : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_2-0-37&Lev=on
[2020-02-06 03:59:56.784] [I] [core] Request was successful
[2020-02-06 03:59:57.106] [D] [zway] Job 0x13: deleted from queue
[2020-02-06 03:59:58.783] [D] [zway] Job 0x13: deleted from queue
[2020-02-06 04:00:00.361] [I] [core] ---  ZWayVDev_zway_6-0-50-0 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.362] [I] [zway] Adding job: Meter Get (v2/3)
[2020-02-06 04:00:00.362] [I] [core] ---  ZWayVDev_zway_6-1-50-0 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.362] [I] [zway] Adding job: Meter Get (v2/3)
[2020-02-06 04:00:00.362] [I] [core] ---  ZWayVDev_zway_7-0-49-4 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.363] [D] [zway] Job 0x13 (SensorMultilevel V1-4 Get): removing duplicate
[2020-02-06 04:00:00.363] [I] [core] ---  ZWayVDev_zway_7-0-50-0 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.363] [D] [zway] Job 0x13 (Meter Get (v2/3)): removing duplicate
[2020-02-06 04:00:00.363] [I] [core] ---  ZWayVDev_zway_7-0-50-2 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.363] [D] [zway] Job 0x13 (Meter Get (v2/3)): removing duplicate
[2020-02-06 04:00:00.363] [I] [core] ---  ZWayVDev_zway_10-0-49-1 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.363] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.364] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-02-06 04:00:00.364] [I] [core] ---  ZWayVDev_zway_10-0-113-7-2-A performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.364] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.364] [I] [zway] Adding job: Alarm Get (v3)
[2020-02-06 04:00:00.365] [I] [core] ---  ZWayVDev_zway_10-1-113-7-2-A performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.365] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.365] [I] [zway] Adding job: Alarm Get (v3)
[2020-02-06 04:00:00.365] [I] [core] ---  ZWayVDev_zway_10-2-113-7-2-A performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.365] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.365] [I] [zway] Adding job: Alarm Get (v3)
[2020-02-06 04:00:00.365] [I] [core] ---  ZWayVDev_zway_10-3-49-15 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.366] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.366] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-02-06 04:00:00.366] [I] [core] ---  ZWayVDev_zway_10-4-49-15 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.366] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.366] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-02-06 04:00:00.366] [I] [core] ---  ZWayVDev_zway_10-7-49-1 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.366] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.366] [I] [zway] Adding job: SensorMultilevel V5 Get
[2020-02-06 04:00:00.367] [I] [core] ---  ZWayVDev_zway_10-7-113-9-3-A performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.367] [I] [zway] Using security scheme S0
[2020-02-06 04:00:00.367] [I] [zway] Adding job: Alarm Get (v3)
[2020-02-06 04:00:00.367] [I] [core] ---  ZWayVDev_zway_11-1-48-1 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.367] [I] [zway] Adding job: SensorBinary Get
[2020-02-06 04:00:00.367] [I] [core] ---  ZWayVDev_zway_11-2-48-1 performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.367] [I] [zway] Node 10:0 CC Security: sending Nonce Get
[2020-02-06 04:00:00.368] [D] [zway] SETDATA devices.10.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2020-02-06 04:00:00.368] [I] [zway] Adding job: Nonce Get
[2020-02-06 04:00:00.368] [D] [zway] SETDATA devices.10.data.lastNonceGet = 1017802 (0x000f87ca)
[2020-02-06 04:00:00.368] [D] [zway] SENDING (cb 0x97): ( 01 0A 00 13 06 03 32 01 00 25 97 62 )
[2020-02-06 04:00:00.368] [I] [zway] Adding job: SensorBinary Get
[2020-02-06 04:00:00.368] [I] [core] ---  ZWayVDev_zway_12-0-113-9-7-A performCommand processing: {"0":"update"}
[2020-02-06 04:00:00.368] [I] [zway] Using security scheme S2 Unauthenticated
[2020-02-06 04:00:00.369] [I] [zway] Adding job: Alarm Get (v3)
[2020-02-06 04:00:00.370] [D] [zway] RECEIVED ACK
[2020-02-06 04:00:00.374] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 04:00:00.374] [D] [zway] SENT ACK
[2020-02-06 04:00:00.374] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 04:00:00.533] [D] [zway] RECEIVED: ( 01 07 00 13 97 00 00 0F 73 )
[2020-02-06 04:00:00.533] [D] [zway] SENT ACK
[2020-02-06 04:00:00.533] [I] [zway] Job 0x13 (Meter Get (v2/3)): Delivered
[2020-02-06 04:00:00.534] [D] [zway] SendData Response with callback 0x97 received: received by recipient
[2020-02-06 04:00:00.534] [D] [zway] SETDATA devices.6.data.lastSendInternal = **********
[2020-02-06 04:00:00.534] [D] [zway] SETDATA devices.6.data.lastSend = 1017819 (0x000f87db)
[2020-02-06 04:00:00.534] [D] [zway] Job 0x13 (Meter Get (v2/3)): success
[2020-02-06 04:00:00.534] [I] [zway] Waiting for job reply: Meter Get (v2/3)
[2020-02-06 04:00:00.534] [I] [zway] Adding job: Security S2 Encapsulation (S2 Unauthenticated), Alarm Get (v3)
[2020-02-06 04:00:00.534] [D] [zway] SENDING (cb 0x98): ( 01 0D 00 13 0B 06 60 0D 00 01 30 02 25 98 0F )
[2020-02-06 04:00:00.543] [D] [zway] RECEIVED ACK
[2020-02-06 04:00:00.543] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 04:00:00.543] [D] [zway] SENT ACK
[2020-02-06 04:00:00.544] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 04:00:00.573] [D] [zway] RECEIVED: ( 01 07 00 13 98 00 00 03 70 )
[2020-02-06 04:00:00.573] [D] [zway] SENT ACK
[2020-02-06 04:00:00.573] [I] [zway] Job 0x13 (SensorBinary Get): Delivered
[2020-02-06 04:00:00.573] [D] [zway] SendData Response with callback 0x98 received: received by recipient
[2020-02-06 04:00:00.573] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2020-02-06 04:00:00.574] [D] [zway] SETDATA devices.11.data.lastSend = 1017823 (0x000f87df)
[2020-02-06 04:00:00.574] [D] [zway] Job 0x13 (SensorBinary Get): success
[2020-02-06 04:00:00.574] [I] [zway] Waiting for job reply: SensorBinary Get
[2020-02-06 04:00:00.574] [D] [zway] SENDING (cb 0x99): ( 01 09 00 13 0A 02 98 40 25 99 89 )
[2020-02-06 04:00:00.583] [W] [zway] Received SOF, while awaiting ACK
[2020-02-06 04:00:00.583] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0B 07 60 0D 01 00 30 03 FF 5A )
[2020-02-06 04:00:00.583] [D] [zway] SENT ACK
[2020-02-06 04:00:00.583] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2020-02-06 04:00:00.583] [D] [zway] Received reply on job (SensorBinary Get)
[2020-02-06 04:00:00.583] [D] [zway] SETDATA devices.11.instances.1.commandClasses.48.data.1.level = True
[2020-02-06 04:00:00.584] [D] [zway] SETDATA devices.11.instances.1.commandClasses.48.data.1 = Empty
[2020-02-06 04:00:00.593] [D] [zway] RECEIVED CAN
[2020-02-06 04:00:00.603] [D] [zway] SENDING (cb 0x9a): ( 01 09 00 13 0A 02 98 40 25 9A 8A )
[2020-02-06 04:00:00.605] [D] [zway] RECEIVED ACK
[2020-02-06 04:00:00.607] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_11-1-48-1 : on : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_11-1-48-1&Lev=on
[2020-02-06 04:00:00.609] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 04:00:00.609] [D] [zway] SENT ACK
[2020-02-06 04:00:00.609] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 04:00:00.654] [D] [zway] RECEIVED: ( 01 07 00 13 9A 00 00 05 74 )
[2020-02-06 04:00:00.655] [D] [zway] SENT ACK
[2020-02-06 04:00:00.655] [I] [zway] Job 0x13 (Nonce Get): Delivered
[2020-02-06 04:00:00.655] [D] [zway] SendData Response with callback 0x9a received: received by recipient
[2020-02-06 04:00:00.655] [D] [zway] SETDATA devices.10.data.lastSendInternal = **********
[2020-02-06 04:00:00.655] [D] [zway] SETDATA devices.10.data.lastSend = 1017831 (0x000f87e7)
[2020-02-06 04:00:00.655] [D] [zway] Job 0x13 (Nonce Get): success
[2020-02-06 04:00:00.655] [D] [zway] SETDATA devices.10.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2020-02-06 04:00:00.655] [D] [zway] SETDATA devices.10.data.lastNonceGet = 1017831 (0x000f87e7)
[2020-02-06 04:00:00.655] [I] [zway] Waiting for job reply: Nonce Get
[2020-02-06 04:00:00.655] [D] [zway] SENDING (cb 0x9b): ( 01 0D 00 13 0B 06 60 0D 00 02 30 02 25 9B 0F )
[2020-02-06 04:00:00.664] [D] [zway] RECEIVED ACK
[2020-02-06 04:00:00.664] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 04:00:00.664] [D] [zway] SENT ACK
[2020-02-06 04:00:00.664] [D] [zway] Delivered to Z-Wave stack
[2020-02-06 04:00:00.705] [D] [zway] RECEIVED: ( 01 14 00 04 00 06 0E 32 02 21 24 00 00 00 0E 00 01 00 00 00 0E D3 )
[2020-02-06 04:00:00.705] [D] [zway] SENT ACK
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.data.lastReceived = 0 (0x00000000)
[2020-02-06 04:00:00.705] [D] [zway] Received reply on job (Meter Get (v2/3))
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.0.val = 1.400000
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.0.delta = 1 (0x00000001)
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.0.ratetype = 1 (0x00000001)
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.0.previous = 1.400000
[2020-02-06 04:00:00.705] [D] [zway] SETDATA devices.6.instances.0.commandClasses.50.data.0 = Empty
[2020-02-06 04:00:00.712] [I] [core] http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=%DEVICE%&Lev=%VALUE% : ZWayVDev_zway_6-0-50-0 : 1.4 : http://192.168.1.3:1025/ZWave?Cmd=CBck&Dev=ZWayVDev_zway_6-0-50-0&Lev=1.4
[2020-02-06 04:00:00.715] [D] [zway] RECEIVED: ( 01 07 00 13 9B 00 00 05 75 )
[2020-02-06 04:00:00.715] [D] [zway] SENT ACK
[2020-02-06 04:00:00.715] [I] [zway] Job 0x13 (SensorBinary Get): Delivered
[2020-02-06 04:00:00.715] [D] [zway] SendData Response with callback 0x9b received: received by recipient
[2020-02-06 04:00:00.715] [D] [zway] SETDATA devices.11.data.lastSendInternal = **********
[2020-02-06 04:00:00.715] [D] [zway] SETDATA devices.11.data.lastSend = 1017837 (0x000f87ed)
[2020-02-06 04:00:00.715] [D] [zway] Job 0x13 (SensorBinary Get): success
[2020-02-06 04:00:00.715] [I] [zway] Waiting for job reply: SensorBinary Get
[2020-02-06 04:00:00.715] [D] [zway] SENDING (cb 0x9c): ( 01 0E 00 13 06 07 60 0D 00 01 32 01 00 25 9C 05 )
[2020-02-06 04:00:00.722] [W] [zway] Received SOF, while awaiting ACK
[2020-02-06 04:00:00.725] [D] [zway] RECEIVED: ( 01 0D 00 04 00 0B 07 60 0D 02 00 30 03 FF 59 )
[2020-02-06 04:00:00.726] [D] [zway] SENT ACK
[2020-02-06 04:00:00.729] [D] [zway] SETDATA devices.11.data.lastReceived = 0 (0x00000000)
[2020-02-06 04:00:00.729] [D] [zway] Received reply on job (SensorBinary Get)
[2020-02-06 04:00:00.729] [D] [zway] SETDATA devices.11.instances.2.commandClasses.48.data.1.level = True
[2020-02-06 04:00:00.730] [D] [zway] SETDATA devices.11.instances.2.commandClasses.48.data.1 = Empty
[2020-02-06 04:00:00.735] [D] [zway] RECEIVED CAN
[2020-02-06 04:00:00.745] [D] [zway] SENDING (cb 0x9d): ( 01 0E 00 13 06 07 60 0D 00 01 32 01 00 25 9D 04 )
[2020-02-06 04:00:00.747] [D] [zway] RECEIVED ACK
[2020-02-06 04:00:00.751] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-02-06 04:00:00.751] [D] [zway] SENT ACK
[2020-02-06 04:00:00.751] [D] [zway] Delivered to Z-Wave stack
Hope this log data may be of help in the search of the problem area.

Thanks in advance,

Joakim
Post Reply