job queue runs full after upgrade to v4.1.0

Official announcements from Z-Wave.Me team. Check this forum to get information about new releases and bug fixes.
Post Reply
RolfKunkel
Posts: 58
Joined: 03 Jan 2017 18:35

job queue runs full after upgrade to v4.1.0

Post by RolfKunkel »

Hi Folks,

Two days ago I upgraded Z-Way SW from v4.0.3 to v4.1.0 and since that time my smart home is no longer working stable. Two times per day I have to restart Z-Way because there are jobs hanging in the job queue blocking the processing of commands. Switching a light on or off is no longer working then.

It seems some jobs are blocking the job queue and new actions let the queue growing and growing without being processed. A Z-Way restart clears the queue and at least for a few hours the system works stable.

Am I the only one having this problem with v4.1.0? Any idea what could trigger the issue?

Rolf
User avatar
PoltoS
Posts: 7601
Joined: 26 Jan 2011 19:36

Re: job queue runs full after upgrade to v4.1.0

Post by PoltoS »

Please provide us more information: log, queue screenshot, description of the error
RolfKunkel
Posts: 58
Joined: 03 Jan 2017 18:35

Re: job queue runs full after upgrade to v4.1.0

Post by RolfKunkel »

Can you instruct me please how to find the log file
J.nissen
Posts: 111
Joined: 02 Sep 2016 22:12

Re: job queue runs full after upgrade to v4.1.0

Post by J.nissen »

Hello,
same issue.

--- Log ---

[2023-05-23 19:17:39.284] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:17:39.285] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:17:39.295] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:17:39.493] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:17:51.471] [D] [zway] RECEIVED: ( 01 24 00 04 00 33 1A 9F 03 F3 00 86 52 5B 2C DA FD AB DE E3 17 2C 2A 84 34 7D C0 B3 EB 95 65 00 87 AD 00 01 05 11 )
[2023-05-23 19:17:51.472] [D] [zway] SENT ACK
[2023-05-23 19:17:51.472] [D] [zway] SETDATA devices.51.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:17:51.472] [zway] Node 51:0 CC SecurityS2: passing S2 Unauthenticated decrypted packet to application level: [ 32 02 21 44 80 00 1f ee 01 2d 80 00 1f ee ]
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.precision = 2 (0x00000002)
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.intVal = -2147475474 (0x80001fee)
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.val = -21474754.000000
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.delta = 301 (0x0000012d)
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.ratetype = 1 (0x00000001)
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0.previous = -21474754.000000
[2023-05-23 19:17:51.473] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.0 = Empty
[2023-05-23 19:17:51.484] [core] HK: updated ZWayVDev_zway_51-0-50-0
[2023-05-23 19:17:52.056] [D] [zway] RECEIVED: ( 01 24 00 04 00 33 1A 9F 03 F4 00 C0 9D FB 34 43 EC 3B 5E 2A 5B 76 07 44 7A 68 FE 4F 87 BE 1F 66 4C AD 00 01 05 84 )
[2023-05-23 19:17:52.056] [D] [zway] SENT ACK
[2023-05-23 19:17:52.056] [D] [zway] SETDATA devices.51.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:17:52.057] [zway] Node 51:0 CC SecurityS2: passing S2 Unauthenticated decrypted packet to application level: [ 32 02 21 54 00 00 00 00 01 2d 00 00 00 00 ]
[2023-05-23 19:17:52.057] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.precision = 2 (0x00000002)
[2023-05-23 19:17:52.057] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:17:52.057] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:17:52.058] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.delta = 301 (0x0000012d)
[2023-05-23 19:17:52.058] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:17:52.058] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2.previous = 0.000000
[2023-05-23 19:17:52.058] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:17:52.061] [core] HK: updated ZWayVDev_zway_51-0-50-2
[2023-05-23 19:17:52.626] [D] [zway] RECEIVED: ( 01 20 00 04 00 33 16 9F 03 F5 00 C5 D7 61 B5 F0 B9 F2 66 71 6D F0 67 A2 E9 8B B3 CE 8E AA 00 01 05 9A )
[2023-05-23 19:17:52.627] [D] [zway] SENT ACK
[2023-05-23 19:17:52.627] [D] [zway] SETDATA devices.51.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:17:52.627] [zway] Node 51:0 CC SecurityS2: passing S2 Unauthenticated decrypted packet to application level: [ 32 02 a1 42 5d 7d 01 2d 5d 69 ]
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.precision = 2 (0x00000002)
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.intVal = 23933 (0x00005d7d)
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.val = 239.330002
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.delta = 301 (0x0000012d)
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.ratetype = 1 (0x00000001)
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4.previous = 239.130005
[2023-05-23 19:17:52.628] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.4 = Empty
[2023-05-23 19:17:52.640] [core] HK: updated ZWayVDev_zway_51-0-50-4
[2023-05-23 19:17:52.666] [core] [BaseModule-19] Set lastLevel to 239.3299968 for ZWayVDev_zway_51-0-50-4 (was 239.1300096)
[2023-05-23 19:17:53.194] [D] [zway] RECEIVED: ( 01 20 00 04 00 33 16 9F 03 F6 00 8A 47 A4 05 74 FC 0A 8B CD CE 79 13 B1 34 EB 55 11 CA AC 00 01 05 D0 )
[2023-05-23 19:17:53.195] [D] [zway] SENT ACK
[2023-05-23 19:17:53.195] [D] [zway] SETDATA devices.51.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:17:53.195] [zway] Node 51:0 CC SecurityS2: passing S2 Unauthenticated decrypted packet to application level: [ 32 02 a1 4a 00 00 01 2d 00 00 ]
[2023-05-23 19:17:53.195] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.precision = 2 (0x00000002)
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.intVal = 0 (0x00000000)
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.val = 0.000000
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.delta = 301 (0x0000012d)
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.ratetype = 1 (0x00000001)
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5.previous = 0.000000
[2023-05-23 19:17:53.196] [D] [zway] SETDATA devices.51.instances.0.commandClasses.50.data.5 = Empty
[2023-05-23 19:17:53.208] [core] HK: updated ZWayVDev_zway_51-0-50-5
[2023-05-23 19:18:09.287] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:18:09.287] [D] [zway] SENT ACK
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:18:09.287] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:18:09.288] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:18:09.290] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:18:09.504] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:18:38.406] [I] [core] [Astronomy] Calculate
[2023-05-23 19:18:38.411] [I] [core] Notification: device-info (device-status): {"dev":"Solar altitude","l":"16.72 °","location":0}
[2023-05-23 19:18:38.412] [I] [core] HK: updated Astronomy_15_altitude
[2023-05-23 19:18:38.423] [I] [core] Notification: device-info (device-status): {"dev":"Solar azimuth","l":"282.12 °","location":0}
[2023-05-23 19:18:38.424] [I] [core] HK: updated Astronomy_15_azimuth
[2023-05-23 19:18:38.472] [I] [core] [BaseModule-19] Set lastLevel to 16.72 for Astronomy_15_altitude (was 16.87)
[2023-05-23 19:18:38.475] [I] [core] [BaseModule-19] Set lastLevel to 282.12 for Astronomy_15_azimuth (was 281.92)
[2023-05-23 19:18:39.290] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:18:39.290] [D] [zway] SENT ACK
[2023-05-23 19:18:39.290] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:18:39.290] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:18:39.291] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:18:39.291] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:18:39.291] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:18:39.291] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:18:39.291] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:18:39.299] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:18:39.507] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:18:54.652] [D] [zway] RECEIVED: ( 01 0C 00 04 00 09 02 84 07 B6 00 01 24 EC )
[2023-05-23 19:18:54.652] [D] [zway] SENT ACK
[2023-05-23 19:18:54.652] [D] [zway] SETDATA devices.9.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:18:54.652] [I] [zway] Node 9:0 CC Wakeup: Wakeup notification
[2023-05-23 19:18:54.653] [D] [zway] SETDATA devices.9.instances.0.commandClasses.132.data.lastWakeup = 1684862334 (0x646cf57e)
[2023-05-23 19:18:54.653] [D] [zway] SETDATA devices.9.data.lastSendInternal = **********
[2023-05-23 19:18:54.653] [D] [zway] SETDATA devices.9.data.lastNonceGet = 4719486 (0x0048037e)
[2023-05-23 19:18:54.653] [D] [zway] Running wakeup handler for node 9
[2023-05-23 19:18:54.653] [D] [zway] SETDATA devices.9.instances.0.commandClasses.132.data.lastSleep = 1684862334 (0x646cf57e)
[2023-05-23 19:18:54.653] [I] [zway] Node 9:0 CC Wakeup: Send node to sleep
[2023-05-23 19:18:54.653] [D] [zway] Job 0x13 (Wakeup Sleep): removing duplicate
[2023-05-23 19:19:09.293] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:19:09.293] [D] [zway] SENT ACK
[2023-05-23 19:19:09.293] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:19:09.293] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:19:09.293] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:19:09.294] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:19:09.294] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:19:09.294] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:19:09.294] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:19:09.329] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:19:09.525] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:19:23.703] [D] [zway] Job 0x3a (Get statistics gathered by the Z-Wave protocol): removing duplicate
[2023-05-23 19:19:38.409] [I] [core] [Astronomy] Calculate
[2023-05-23 19:19:38.416] [I] [core] Notification: device-info (device-status): {"dev":"Solar altitude","l":"16.58 °","location":0}
[2023-05-23 19:19:38.419] [I] [core] HK: updated Astronomy_15_altitude
[2023-05-23 19:19:38.429] [I] [core] Notification: device-info (device-status): {"dev":"Solar azimuth","l":"282.31 °","location":0}
[2023-05-23 19:19:38.429] [I] [core] HK: updated Astronomy_15_azimuth
[2023-05-23 19:19:38.472] [I] [core] [BaseModule-19] Set lastLevel to 16.58 for Astronomy_15_altitude (was 16.72)
[2023-05-23 19:19:38.474] [I] [core] [BaseModule-19] Set lastLevel to 282.31 for Astronomy_15_azimuth (was 282.12)
[2023-05-23 19:19:39.296] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:19:39.296] [D] [zway] SENT ACK
[2023-05-23 19:19:39.296] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:19:39.296] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:19:39.296] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:19:39.297] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:19:39.297] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:19:39.297] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:19:39.297] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:19:39.309] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:19:39.535] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:20:09.299] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:20:09.299] [D] [zway] SENT ACK
[2023-05-23 19:20:09.299] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:20:09.299] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:20:09.299] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:20:09.300] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:20:09.300] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:20:09.300] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:20:09.300] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:20:09.304] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:20:09.554] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:20:38.410] [I] [core] [Astronomy] Calculate
[2023-05-23 19:20:38.416] [I] [core] Notification: device-info (device-status): {"dev":"Solar altitude","l":"16.44 °","location":0}
[2023-05-23 19:20:38.417] [I] [core] HK: updated Astronomy_15_altitude
[2023-05-23 19:20:38.427] [I] [core] Notification: device-info (device-status): {"dev":"Solar azimuth","l":"282.51 °","location":0}
[2023-05-23 19:20:38.427] [I] [core] HK: updated Astronomy_15_azimuth
[2023-05-23 19:20:38.475] [I] [core] [BaseModule-19] Set lastLevel to 16.44 for Astronomy_15_altitude (was 16.58)
[2023-05-23 19:20:38.478] [I] [core] [BaseModule-19] Set lastLevel to 282.51 for Astronomy_15_azimuth (was 282.31)
[2023-05-23 19:20:39.302] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:20:39.302] [D] [zway] SENT ACK
[2023-05-23 19:20:39.302] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:20:39.302] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:20:39.302] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:20:39.302] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:20:39.303] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:20:39.303] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:20:39.303] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:20:39.312] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:20:39.571] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:21:09.305] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:21:09.305] [D] [zway] SENT ACK
[2023-05-23 19:21:09.305] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:21:09.305] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:21:09.305] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:21:09.306] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:21:09.306] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:21:09.306] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:21:09.306] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:21:09.340] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:21:09.575] [D] [zway] Job 0x3b (Get background noise level): removing duplicate
[2023-05-23 19:21:38.438] [I] [core] [Astronomy] Calculate
[2023-05-23 19:21:38.447] [I] [core] Notification: device-info (device-status): {"dev":"Solar altitude","l":"16.3 °","location":0}
[2023-05-23 19:21:38.450] [I] [core] HK: updated Astronomy_15_altitude
[2023-05-23 19:21:38.464] [I] [core] Notification: device-info (device-status): {"dev":"Solar azimuth","l":"282.7 °","location":0}
[2023-05-23 19:21:38.465] [I] [core] HK: updated Astronomy_15_azimuth
[2023-05-23 19:21:38.510] [I] [core] [BaseModule-19] Set lastLevel to 16.3 for Astronomy_15_altitude (was 16.44)
[2023-05-23 19:21:38.512] [I] [core] [BaseModule-19] Set lastLevel to 282.7 for Astronomy_15_azimuth (was 282.51)
[2023-05-23 19:21:39.308] [D] [zway] RECEIVED: ( 01 13 00 04 00 38 0A 32 02 21 12 00 00 00 00 00 00 C8 00 00 11 )
[2023-05-23 19:21:39.308] [D] [zway] SENT ACK
[2023-05-23 19:21:39.312] [D] [zway] SETDATA devices.56.data.lastReceived = 0 (0x00000000)
[2023-05-23 19:21:39.312] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.precision = 0 (0x00000000)
[2023-05-23 19:21:39.312] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.intVal = 0 (0x00000000)
[2023-05-23 19:21:39.312] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.val = 0.000000
[2023-05-23 19:21:39.312] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.delta = 0 (0x00000000)
[2023-05-23 19:21:39.313] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2.ratetype = 1 (0x00000001)
[2023-05-23 19:21:39.313] [D] [zway] SETDATA devices.56.instances.0.commandClasses.50.data.2 = Empty
[2023-05-23 19:21:39.317] [I] [core] HK: updated ZWayVDev_zway_56-0-50-2
[2023-05-23 19:21:39.589] [D] [zway] Job 0x3b (Get background noise level): removing duplicate


I have Downgrade per Bash. GUI doesn´t work.

export BUILD=4.0.3-lws16; cd /tmp/ && wget https://storage.z-wave.me/z-way-server/ ... _armhf.deb && sudo dpkg -i z-way-${BUILD}_armhf.deb
Last edited by J.nissen on 23 May 2023 20:43, edited 1 time in total.
Greetings from germany
Jörg

Raspi 4(Buster), Zwave Version 4.1.1 Raz 7 Pro, Serial API Version:07.38(SDK 7.20)
J.nissen
Posts: 111
Joined: 02 Sep 2016 22:12

Re: job queue runs full after upgrade to v4.1.0

Post by J.nissen »

RolfKunkel wrote:
23 May 2023 15:05
Can you instruct me please how to find the log file
/var/log/z-way-server.log
Greetings from germany
Jörg

Raspi 4(Buster), Zwave Version 4.1.1 Raz 7 Pro, Serial API Version:07.38(SDK 7.20)
RolfKunkel
Posts: 58
Joined: 03 Jan 2017 18:35

Re: job queue runs full after upgrade to v4.1.0

Post by RolfKunkel »

My system worked fine the last 6 days but today the issue is back. The job queue is growing and growing and for example switching lamps on / off via the GUI is not working.With each new command given via the GUI the job queue is growing and even without giving commands via GUI it is growing. Only a restart helps. Screenshot attached

The job queue is full with "Security S2 Nonce Report" entries.

Taking a log file is a bit difficult on my side. I hope the log file provided by J. is sufficient.
Attachments
job queue end screenshot 2023-05-28 12_42_19-Z-Wave Expert UI – Mozilla Firefox.png
job queue end screenshot 2023-05-28 12_42_19-Z-Wave Expert UI – Mozilla Firefox.png (42.14 KiB) Viewed 34882 times
Job Queue counter 20230528 12-16 Z-Wave Expert UI.png
Job Queue counter 20230528 12-16 Z-Wave Expert UI.png (69.01 KiB) Viewed 34882 times
harre
Posts: 95
Joined: 24 Nov 2020 02:22

Re: job queue runs full after upgrade to v4.1.0

Post by harre »

I have the same problem on and off, i have sent logs in another thread without any attention on how to proceed with further debug instructions

https://forum.z-wave.me/posting.php?mod ... 22&p=98241
otverber
Posts: 68
Joined: 28 Mar 2018 20:17

Re: job queue runs full after upgrade to v4.1.0

Post by otverber »

Similar problem wih loads of S2 items

Vendor: RaZberry by Z-Wave.Me
Vendors Product ID: 1024 / 2
Z-Wave Chip: ZW0500
Firmware
Library Type: Static Controller
SDK Version: 6.82.01
Serial API Version: 05.39

any solutions anybody?
hubert
Posts: 20
Joined: 25 Sep 2021 07:46

Re: job queue runs full after upgrade to v4.1.0

Post by hubert »

Same thing happening here with v4.1.0 - Every few days the queue gets to 90+ jobs and I have to reboot

Have been running this system for years and this never happened before

vendor: RaZberry by Z-Wave.Me (03:14)
manufacturerProductType: 1024 (03:14)
manufacturerProductId: 2 (03:14)
ZWaveChip: ZW0500 (03:14)
seattleneil
Posts: 182
Joined: 02 Mar 2020 22:41

Re: job queue runs full after upgrade to v4.1.0

Post by seattleneil »

Although this response does nothing to solve the job queue build-up problem, you may want to clear the job queue by restarting z-way-server process instead of rebooting linux. Either run "sudo systemctl restart z-way-server" or "sudo /etc/init.d/z-way-server restart".

To see the job queue from the command line, run "curl -s -u admin:[YOUR ADMIN PASSWORD] --globoff '127.0.0.1:8083/ZWaveAPI/InspectQueue'". As a suggestion/reminder, @PoltoS requested the following:
Please provide us more information: log, queue screenshot, description of the error
Users who experience the job queue problem should consider providing additional information so that the Z-Way developers can fix the problem.

In the meantime, a kludge for restarting the z-way-server process automatically is to create a cron job that runs every hour to check the queue size and restart the process if the queue size is over some amount (e.g., 2800 characters). The 2800 character check is approximately 20 queue entries since each queue entry is approximately 140 characters. A cron job as user root like this should work: [ `curl -s -u admin:[YOUR ADMIN PASSWORD] --globoff '127.0.0.1:8083/ZWaveAPI/InspectQueue' | wc -c` -gt 2800 ] && systemctl restart z-way-server
Post Reply