Page 1 of 13

job queue runs full after upgrade to v4.1.0

Posted: 21 May 2023 10:59
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

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

Posted: 23 May 2023 04:00
by PoltoS
Please provide us more information: log, queue screenshot, description of the error

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

Posted: 23 May 2023 15:05
by RolfKunkel
Can you instruct me please how to find the log file

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

Posted: 23 May 2023 20:24
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

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

Posted: 23 May 2023 20:25
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

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

Posted: 28 May 2023 13:49
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.

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

Posted: 03 Jun 2023 14:45
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

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

Posted: 11 Jun 2023 00:21
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?

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

Posted: 11 Jun 2023 08:01
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)

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

Posted: 11 Jun 2023 19:42
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