NX-8E with more than 48 zones

First off much thanks to futzle for writing the plugin.

I was having issues with an alarm system with more than 48 zones. I found in the /etc/cmh-ludl/L_CaddxNX584Security.lua file on line 283 the code is ‘for zone = 1,48 do’, this needs to be changed to ‘for zone = 1,128 do’.

I changed this on my unit and it now allows me to create zones higher than 48. The ZoneScan function still doesn’t work. It will scan a few zones correctly and then start failing. Manually adding zones seems to be working fine. I’m not much of a programmer but I’m going to go through it a bit more and see if i can’t fix some of the issues I’m having. If I find anything useful I’ll post it to the community for others having the same issues.

Anyone else out there using this plug in with multiple partitions or more than 48 zones?

Keith

Agreed, the hardcoded 48 is wrong and should be increased. It’s 128 in the JavaScript; I just seem to have missed changing it in the Lua.

The Zones tab does at least two Ajax requests for each zone. Probably it is overloading the serial connection. Line 287 of the JavaScript has a number (500) which marks how many milliseconds to offset each query. Try increasing it to (say) 2000 and seeing if it gets further. There are other instances of 500 in that same file which you could try to tune.

I made the suggested timeout longer and after a reload tried scanning 20 zones. It discovered the first four and then started reporting “Scanning zone x failed” for all the zones after that. I have “Debug to Luup log” enabled and included a portion of the log below if you’re interested in taking a look. I noticed after making this change when going to the Zones tab it noticeably takes more time to fill in the partition number in the Info column. I have about 30 zones manually configured and it doesn’t make through all of them even with the extra time delay before it errors out.

Just for background this alarm system has 47 zones in use and the hardware in place to support up to hardwired 72 zones. Zones are divided into partitions 1 and 2. Due to the length of cable involved a NX-320E repeater/remote power supply is in use. There are two keyswitch zones. The Vera is currently used only for the Caddx plugin and HomeWave iOS app.

50 07/14/14 8:40:22.298 luup_log:5: Sending message: 0x1D Positive Acknowledge <0x2d8d5680>
50 07/14/14 8:40:22.299 luup_log:5: Message: Outgoing: 0x7e 0x01 0x1d 0x1e 0x1f <0x2d8d5680>
50 07/14/14 8:40:22.349 luup_log:5: Received good message 0x07, acknowledge requested <0x2d8d5680>
50 07/14/14 8:40:22.349 luup_log:5: Message: Incoming message body: 0x01 0x01 0x02 0x02 0x02 0x02 0x02 0x02 <0x2d8d5680>
50 07/14/14 8:40:22.350 luup_log:5: Handling message: 0x07 Partitions Snapshot <0x2d8d5680>
50 07/14/14 8:40:22.350 luup_log:5: Sending message: 0x1D Positive Acknowledge <0x2d8d5680>
50 07/14/14 8:40:22.351 luup_log:5: Message: Outgoing: 0x7e 0x01 0x1d 0x1e 0x1f <0x2d8d5680>
50 07/14/14 8:40:22.393 luup_log:5: Received good message 0x04, acknowledge requested <0x2d8d5680>
50 07/14/14 8:40:22.394 luup_log:5: Message: Incoming message body: 0x20 0x02 0x00 0x1b 0xf0 0x01 0x00 <0x2d8d5680>
50 07/14/14 8:40:22.394 luup_log:5: Handling message: 0x04 Zone Status <0x2d8d5680>
50 07/14/14 8:40:22.395 luup_log:5: Valid zone 33 <0x2d8d5680>
50 07/14/14 8:40:22.395 luup_log:5: Setting state for zone 33 <0x2d8d5680>
50 07/14/14 8:40:22.396 luup_log:5: Tripped: 1 <0x2d8d5680>
06 07/14/14 8:40:22.396 Device_Variable::m_szValue_set device: 33 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 0 now: 1 #hooks: 0 upnp: 0 v:0x85c090/NONE duplicate:0 <0x2d8d5680>
06 07/14/14 8:40:22.397 Device_Variable::m_szValue_set device: 33 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1405345156 now: 1405345222 #hooks: 0 upnp: 0 v:0x85baf0/NONE duplicate:0 <0x2d8d5680>
50 07/14/14 8:40:22.397 luup_log:5: Armed: 1 <0x2d8d5680>
06 07/14/14 8:40:22.398 Device_Variable::m_szValue_set device: 33 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Armed was: 1 now: 1 #hooks: 0 upnp: 0 v:0x85b678/NONE duplicate:1 <0x2d8d5680>
50 07/14/14 8:40:22.398 luup_log:5: Sending message: 0x1D Positive Acknowledge <0x2d8d5680>
50 07/14/14 8:40:22.399 luup_log:5: Message: Outgoing: 0x7e 0x01 0x1d 0x1e 0x1f <0x2d8d5680>
01 07/14/14 8:40:22.424 Lua_Job::ReturnMessageNotReceived job#10 : dev:5 (0x93e480) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:22.424 <0x2afa2000>
02 07/14/14 8:40:22.425 JobHandler::PurgeCompletedJobs purge job#10 : dev:5 (0x93e480) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:22.426 Lua_Job::ReturnMessageNotReceived job#11 : dev:5 (0x93ebe0) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:22.426 <0x2afa2000>
02 07/14/14 8:40:22.427 JobHandler::PurgeCompletedJobs purge job#11 : dev:5 (0x93ebe0) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:22.428 Lua_Job::ReturnMessageNotReceived job#12 : dev:5 (0x93f608) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:22.428 <0x2afa2000>
02 07/14/14 8:40:22.429 JobHandler::PurgeCompletedJobs purge job#12 : dev:5 (0x93f608) P:50 S:2 status 2 <0x2afa2000>
50 07/14/14 8:40:22.444 luup_log:5: Received good message 0x04, acknowledge requested <0x2d8d5680>
50 07/14/14 8:40:22.445 luup_log:5: Message: Incoming message body: 0x2e 0x01 0x00 0x1b 0xf0 0x01 0x00 <0x2d8d5680>
50 07/14/14 8:40:22.445 luup_log:5: Handling message: 0x04 Zone Status <0x2d8d5680>
50 07/14/14 8:40:22.446 luup_log:5: Valid zone 47 <0x2d8d5680>
50 07/14/14 8:40:22.446 luup_log:5: Setting state for zone 47 <0x2d8d5680>
50 07/14/14 8:40:22.447 luup_log:5: Tripped: 1 <0x2d8d5680>
06 07/14/14 8:40:22.447 Device_Variable::m_szValue_set device: 29 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Tripped was: 0 now: 1 #hooks: 0 upnp: 0 v:0x85c090/NONE duplicate:0 <0x2d8d5680>
06 07/14/14 8:40:22.448 Device_Variable::m_szValue_set device: 29 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: LastTrip was: 1405345210 now: 1405345222 #hooks: 0 upnp: 0 v:0x85baf0/NONE duplicate:0 <0x2d8d5680>
50 07/14/14 8:40:22.448 luup_log:5: Armed: 1 <0x2d8d5680>
06 07/14/14 8:40:22.448 Device_Variable::m_szValue_set device: 29 service: urn:micasaverde-com:serviceId:SecuritySensor1 variable: Armed was: 1 now: 1 #hooks: 0 upnp: 0 v:0x85b678/NONE duplicate:1 <0x2d8d5680>
50 07/14/14 8:40:22.449 luup_log:5: Sending message: 0x1D Positive Acknowledge <0x2d8d5680>
50 07/14/14 8:40:22.449 luup_log:5: Message: Outgoing: 0x7e 0x01 0x1d 0x1e 0x1f <0x2d8d5680>
08 07/14/14 8:40:22.484 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2d6d5680>
08 07/14/14 8:40:22.484 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d6d5680>
08 07/14/14 8:40:22.484 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2d6d5680>
08 07/14/14 8:40:22.485 JobHandler_LuaUPnP::HandleActionRequest argument Zone=15 <0x2d6d5680>
08 07/14/14 8:40:22.485 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d6d5680>
50 07/14/14 8:40:22.488 luup_log:5: Job: Alarm: ZoneScan: 5 15 job userdata: 0x967508 <0x2afa2000>
50 07/14/14 8:40:22.488 luup_log:5: Job: Processing send queue <0x2afa2000>
50 07/14/14 8:40:22.489 luup_log:5: Job: Started <0x2afa2000>
50 07/14/14 8:40:22.498 luup_log:5: Received good message 0x05, acknowledge requested <0x2d8d5680>
50 07/14/14 8:40:22.499 luup_log:5: Message: Incoming message body: 0x02 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x81 <0x2d8d5680>
50 07/14/14 8:40:22.499 luup_log:5: Handling message: 0x05 Zones Snapshot <0x2d8d5680>
50 07/14/14 8:40:22.500 luup_log:5: Sending message: 0x1D Positive Acknowledge <0x2d8d5680>
50 07/14/14 8:40:22.501 luup_log:5: Message: Outgoing: 0x7e 0x01 0x1d 0x1e 0x1f <0x2d8d5680>
01 07/14/14 8:40:23.490 Lua_Job::ReturnMessageNotReceived job#13 : dev:5 (0x93f050) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:23.491 <0x2afa2000>
02 07/14/14 8:40:23.491 JobHandler::PurgeCompletedJobs purge job#13 : dev:5 (0x93f050) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:23.492 Lua_Job::ReturnMessageNotReceived job#14 : dev:5 (0x93ff40) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:23.493 <0x2afa2000>
02 07/14/14 8:40:23.493 JobHandler::PurgeCompletedJobs purge job#14 : dev:5 (0x93ff40) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:23.494 Lua_Job::ReturnMessageNotReceived job#15 : dev:5 (0x9402b8) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:23.495 <0x2afa2000>
02 07/14/14 8:40:23.495 JobHandler::PurgeCompletedJobs purge job#15 : dev:5 (0x9402b8) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:23.497 Lua_Job::ReturnMessageNotReceived job#16 : dev:5 (0x961190) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:23.497 <0x2afa2000>
02 07/14/14 8:40:23.498 JobHandler::PurgeCompletedJobs purge job#16 : dev:5 (0x961190) P:50 S:2 status 2 <0x2afa2000>
08 07/14/14 8:40:24.407 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2ced5680>
08 07/14/14 8:40:24.407 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2ced5680>
08 07/14/14 8:40:24.407 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2ced5680>
08 07/14/14 8:40:24.408 JobHandler_LuaUPnP::HandleActionRequest argument Zone=27 <0x2ced5680>
08 07/14/14 8:40:24.408 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2ced5680>
01 07/14/14 8:40:24.409 Lua_Job::ReturnMessageNotReceived job#17 : dev:5 (0x9614a0) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:24.410 <0x2afa2000>
02 07/14/14 8:40:24.410 JobHandler::PurgeCompletedJobs purge job#17 : dev:5 (0x9614a0) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:24.413 Lua_Job::ReturnMessageNotReceived job#18 : dev:5 (0x963298) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:24.414 <0x2afa2000>
02 07/14/14 8:40:24.415 JobHandler::PurgeCompletedJobs purge job#18 : dev:5 (0x963298) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:24.416 Lua_Job::ReturnMessageNotReceived job#19 : dev:5 (0x963a28) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:24.417 <0x2afa2000>
02 07/14/14 8:40:24.417 JobHandler::PurgeCompletedJobs purge job#19 : dev:5 (0x963a28) P:50 S:2 status 2 <0x2afa2000>
50 07/14/14 8:40:24.418 luup_log:5: Job: Alarm: ZoneScan: 5 27 job userdata: 0x9686e8 <0x2afa2000>
50 07/14/14 8:40:24.419 luup_log:5: Job: Processing send queue <0x2afa2000>
50 07/14/14 8:40:24.419 luup_log:5: Job: Started <0x2afa2000>
01 07/14/14 8:40:25.420 Lua_Job::ReturnMessageNotReceived job#20 : dev:5 (0x9640c8) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:25.421 <0x2afa2000>
02 07/14/14 8:40:25.422 JobHandler::PurgeCompletedJobs purge job#20 : dev:5 (0x9640c8) P:50 S:2 status 2 <0x2afa2000>
01 07/14/14 8:40:25.422 Lua_Job::ReturnMessageNotReceived job#21 : dev:5 (0x940aa8) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:25.423 <0x2afa2000>
02 07/14/14 8:40:25.424 JobHandler::PurgeCompletedJobs purge job#21 : dev:5 (0x940aa8) P:50 S:2 status 2 <0x2afa2000>
08 07/14/14 8:40:26.408 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2d6d5680>
08 07/14/14 8:40:26.408 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d6d5680>
08 07/14/14 8:40:26.409 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2d6d5680>
08 07/14/14 8:40:26.409 JobHandler_LuaUPnP::HandleActionRequest argument Zone=28 <0x2d6d5680>
08 07/14/14 8:40:26.409 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d6d5680>
01 07/14/14 8:40:26.411 Lua_Job::ReturnMessageNotReceived job#22 : dev:5 (0x935808) P:50 S:5 failed <0x2afa2000>
04 07/14/14 8:40:26.412 <0x2afa2000>
02 07/14/14 8:40:26.413 JobHandler::PurgeCompletedJobs purge job#22 : dev:5 (0x935808) P:50 S:2 status 2 <0x2afa2000>

Correction, the offset number for scanning zones is at line 398, in the function scanAllZones().

The log shows that a request (made before the log started) has failed to respond (“Lua_Job::ReturnMessageNotReceived”). The actual problem probably happened about five seconds before that. Catch a longer log, starting from moments before you press the “Scan” button, until you get at least one failure, and you’ll have the window I need.

It’s still my belief that the scanning is flooding the serial link, and because there’s no retry-logic, a spurious message during the scan is messing with the results.

I updated the delay in the new area. Ran into a problem last time with the site not allowing me to post that many characters. I ran this scan when no one would be tripping sensors to help keep it clean. I let it complete scanning the zones I had already manually entered. Then I asked it to scan zones through 10. Zones 1, 6, 8, 9 and 10 were not already manually added. Web interface returned properly for zones 1, 6 and 8. Zones 9 and 10 report failed.

I’m curious how we could be flooding the serial link. Wouldn’t CTS/RTS handle this at the hardware level and the software on top of that have at least some sort of buffer?

Thanks,
Keith

08 07/18/14 18:06:23.525 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2dd8d680>
08 07/18/14 18:06:23.525 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2dd8d680>
08 07/18/14 18:06:23.526 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2dd8d680>
08 07/18/14 18:06:23.526 JobHandler_LuaUPnP::HandleActionRequest argument Zone=64 <0x2dd8d680>
08 07/18/14 18:06:23.526 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2dd8d680>
50 07/18/14 18:06:23.528 luup_log:5: Job: Alarm: ZoneScan: 5 64 job userdata: 0x9c4db8 <0x2b65a000>
50 07/18/14 18:06:23.528 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:06:23.529 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x3f 0x65 0x8d <0x2b65a000>
50 07/18/14 18:06:23.530 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:06:23.587 luup_log:5: Received good message 0x04 <0x2df8d680>
50 07/18/14 18:06:23.588 luup_log:5: Message: Incoming message body: 0x3f 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2df8d680>
50 07/18/14 18:06:23.588 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2df8d680>
50 07/18/14 18:06:23.589 luup_log:5: ZoneScan Zone 64 <0x2df8d680>
50 07/18/14 18:06:23.589 luup_log:5: Valid zone 64 <0x2df8d680>
50 07/18/14 18:06:23.590 luup_log:5: Finishing pending job userdata: 0x9c4db8 <0x2df8d680>
04 07/18/14 18:06:23.591 <0x2df8d680>
50 07/18/14 18:06:24.159 luup_log:5: callbackHandler: request ZoneScan <0x2dd8d680>
50 07/18/14 18:06:24.159 luup_log:5: callbackHandler: format json <0x2dd8d680>
08 07/18/14 18:06:25.519 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:06:25.520 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:06:25.520 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:06:25.520 JobHandler_LuaUPnP::HandleActionRequest argument Zone=65 <0x2e18d680>
08 07/18/14 18:06:25.520 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:06:25.523 luup_log:5: Job: Alarm: ZoneScan: 5 65 job userdata: 0x9c5f88 <0x2b65a000>
50 07/18/14 18:06:25.523 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:06:25.524 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x40 0x66 0x8e <0x2b65a000>
50 07/18/14 18:06:25.525 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:06:25.583 luup_log:5: Received good message 0x04 <0x2df8d680>
50 07/18/14 18:06:25.584 luup_log:5: Message: Incoming message body: 0x40 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2df8d680>
50 07/18/14 18:06:25.584 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2df8d680>
50 07/18/14 18:06:25.584 luup_log:5: ZoneScan Zone 65 <0x2df8d680>
50 07/18/14 18:06:25.585 luup_log:5: Valid zone 65 <0x2df8d680>
50 07/18/14 18:06:25.585 luup_log:5: Finishing pending job userdata: 0x9c5f88 <0x2df8d680>
04 07/18/14 18:06:25.586 <0x2df8d680>
50 07/18/14 18:06:26.154 luup_log:5: callbackHandler: request ZoneScan <0x2dd8d680>
50 07/18/14 18:06:26.154 luup_log:5: callbackHandler: format json <0x2dd8d680>
08 07/18/14 18:06:58.514 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:06:58.514 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:06:58.514 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:06:58.515 JobHandler_LuaUPnP::HandleActionRequest argument Zone=1 <0x2e18d680>
08 07/18/14 18:06:58.515 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:06:58.519 luup_log:5: Job: Alarm: ZoneScan: 5 1 job userdata: 0x9c6ad0 <0x2b65a000>
50 07/18/14 18:06:58.520 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:06:58.520 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x00 0x26 0x4e <0x2b65a000>
50 07/18/14 18:06:58.521 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:06:58.543 luup_log:5: Received good message 0x04 <0x2df8d680>
50 07/18/14 18:06:58.544 luup_log:5: Message: Incoming message body: 0x00 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2df8d680>
50 07/18/14 18:06:58.544 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2df8d680>
50 07/18/14 18:06:58.544 luup_log:5: ZoneScan Zone 1 <0x2df8d680>
50 07/18/14 18:06:58.545 luup_log:5: Finishing pending job userdata: 0x9c6ad0 <0x2df8d680>
04 07/18/14 18:06:58.546 <0x2df8d680>
50 07/18/14 18:06:59.150 luup_log:5: callbackHandler: request ZoneScan <0x2dd8d680>
50 07/18/14 18:06:59.150 luup_log:5: callbackHandler: format json <0x2dd8d680>
08 07/18/14 18:06:59.717 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2e18d680>
08 07/18/14 18:06:59.718 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:06:59.718 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2e18d680>
08 07/18/14 18:06:59.718 JobHandler_LuaUPnP::HandleActionRequest argument Zone=1 <0x2e18d680>
08 07/18/14 18:06:59.718 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:06:59.720 luup_log:5: Job: Alarm: ZoneNameScan: 5 1 job userdata: 0x9c8618 <0x2b65a000>
50 07/18/14 18:06:59.720 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:06:59.721 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x00 0x25 0x4c <0x2b65a000>
50 07/18/14 18:06:59.722 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:07:00.019 luup_log:5: Received good message 0x1f <0x2df8d680>
50 07/18/14 18:07:00.020 luup_log:5: Message: Incoming message body: <0x2df8d680>
50 07/18/14 18:07:00.020 luup_log:5: Handling message: 0x1F Message Reject <0x2df8d680>
50 07/18/14 18:07:00.020 luup_log:5: Finishing pending job userdata: 0x9c8618 <0x2df8d680>
04 07/18/14 18:07:00.021 <0x2df8d680>
02 07/18/14 18:07:00.022 JobHandler::PurgeCompletedJobs purge job#39 : dev:5 (0x9c8618) P:50 S:2 status 2 <0x2b65a000>
08 07/18/14 18:07:00.492 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:07:00.493 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:07:00.493 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:07:00.493 JobHandler_LuaUPnP::HandleActionRequest argument Zone=6 <0x2e18d680>
08 07/18/14 18:07:00.493 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:07:00.496 luup_log:5: Job: Alarm: ZoneScan: 5 6 job userdata: 0x9c93a0 <0x2b65a000>
50 07/18/14 18:07:00.498 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:00.498 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x05 0x2b 0x53 <0x2b65a000>
50 07/18/14 18:07:00.500 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:07:00.543 luup_log:5: Received good message 0x04 <0x2df8d680>
50 07/18/14 18:07:00.544 luup_log:5: Message: Incoming message body: 0x05 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2df8d680>
50 07/18/14 18:07:00.544 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2df8d680>
50 07/18/14 18:07:00.544 luup_log:5: ZoneScan Zone 6 <0x2df8d680>
50 07/18/14 18:07:00.545 luup_log:5: Finishing pending job userdata: 0x9c93a0 <0x2df8d680>
04 07/18/14 18:07:00.546 <0x2df8d680>
50 07/18/14 18:07:01.126 luup_log:5: callbackHandler: request ZoneScan <0x2dd8d680>
50 07/18/14 18:07:01.126 luup_log:5: callbackHandler: format json <0x2dd8d680>
08 07/18/14 18:07:01.694 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2dd8d680>
08 07/18/14 18:07:01.695 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2dd8d680>
08 07/18/14 18:07:01.695 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2dd8d680>
08 07/18/14 18:07:01.695 JobHandler_LuaUPnP::HandleActionRequest argument Zone=6 <0x2dd8d680>
08 07/18/14 18:07:01.695 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2dd8d680>
50 07/18/14 18:07:01.697 luup_log:5: Job: Alarm: ZoneNameScan: 5 6 job userdata: 0x9ca8c8 <0x2b65a000>
50 07/18/14 18:07:01.697 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:01.698 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x05 0x2a 0x51 <0x2b65a000>
50 07/18/14 18:07:01.699 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:07:01.969 luup_log:5: Received good message 0x1f <0x2df8d680>
50 07/18/14 18:07:01.970 luup_log:5: Message: Incoming message body: <0x2df8d680>
50 07/18/14 18:07:01.970 luup_log:5: Handling message: 0x1F Message Reject <0x2df8d680>
50 07/18/14 18:07:01.970 luup_log:5: Finishing pending job userdata: 0x9ca8c8 <0x2df8d680>
04 07/18/14 18:07:01.971 <0x2df8d680>
02 07/18/14 18:07:01.972 JobHandler::PurgeCompletedJobs purge job#41 : dev:5 (0x9ca8c8) P:50 S:2 status 2 <0x2b65a000>
08 07/18/14 18:07:02.483 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:07:02.483 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:07:02.484 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:07:02.484 JobHandler_LuaUPnP::HandleActionRequest argument Zone=8 <0x2e18d680>
08 07/18/14 18:07:02.484 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:07:02.488 luup_log:5: Job: Alarm: ZoneScan: 5 8 job userdata: 0x9cb618 <0x2b65a000>
50 07/18/14 18:07:02.488 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:02.489 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x07 0x2d 0x55 <0x2b65a000>
50 07/18/14 18:07:02.490 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:07:02.543 luup_log:5: Received good message 0x04 <0x2df8d680>
50 07/18/14 18:07:02.544 luup_log:5: Message: Incoming message body: 0x07 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2df8d680>
50 07/18/14 18:07:02.544 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2df8d680>
50 07/18/14 18:07:02.545 luup_log:5: ZoneScan Zone 8 <0x2df8d680>
50 07/18/14 18:07:02.545 luup_log:5: Finishing pending job userdata: 0x9cb618 <0x2df8d680>
04 07/18/14 18:07:02.546 <0x2df8d680>
50 07/18/14 18:07:03.125 luup_log:5: callbackHandler: request ZoneScan <0x2dd8d680>
50 07/18/14 18:07:03.126 luup_log:5: callbackHandler: format json <0x2dd8d680>
08 07/18/14 18:07:03.692 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2dd8d680>
08 07/18/14 18:07:03.692 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2dd8d680>
08 07/18/14 18:07:03.693 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2dd8d680>
08 07/18/14 18:07:03.693 JobHandler_LuaUPnP::HandleActionRequest argument Zone=8 <0x2dd8d680>
08 07/18/14 18:07:03.693 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2dd8d680>
50 07/18/14 18:07:03.695 luup_log:5: Job: Alarm: ZoneNameScan: 5 8 job userdata: 0x9ccc08 <0x2b65a000>
50 07/18/14 18:07:03.696 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:03.698 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x07 0x2c 0x53 <0x2b65a000>
50 07/18/14 18:07:03.699 luup_log:5: Job: Started <0x2b65a000>
08 07/18/14 18:07:04.483 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:07:04.484 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:07:04.484 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:07:04.484 JobHandler_LuaUPnP::HandleActionRequest argument Zone=9 <0x2e18d680>
08 07/18/14 18:07:04.484 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:07:04.488 luup_log:5: Job: Alarm: ZoneScan: 5 9 job userdata: 0x9cd278 <0x2b65a000>
50 07/18/14 18:07:04.488 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:04.489 luup_log:5: Job: Started <0x2b65a000>
50 07/18/14 18:07:04.740 luup_log:5: Received good message 0x1c <0x2df8d680>
50 07/18/14 18:07:04.740 luup_log:5: Message: Incoming message body: <0x2df8d680>
08 07/18/14 18:07:06.484 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2e18d680>
08 07/18/14 18:07:06.484 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2e18d680>
08 07/18/14 18:07:06.484 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2e18d680>
08 07/18/14 18:07:06.485 JobHandler_LuaUPnP::HandleActionRequest argument Zone=10 <0x2e18d680>
08 07/18/14 18:07:06.485 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2e18d680>
50 07/18/14 18:07:06.487 luup_log:5: Job: Alarm: ZoneScan: 5 10 job userdata: 0x9cdd18 <0x2b65a000>
50 07/18/14 18:07:06.488 luup_log:5: Job: Processing send queue <0x2b65a000>
50 07/18/14 18:07:06.489 luup_log:5: Job: Started <0x2b65a000>
01 07/18/14 18:07:14.490 Lua_Job::ReturnMessageNotReceived job#43 : dev:5 (0x9ccc08) P:50 S:5 failed <0x2b65a000>
04 07/18/14 18:07:14.491 <0x2b65a000>
02 07/18/14 18:07:14.491 JobHandler::PurgeCompletedJobs purge job#43 : dev:5 (0x9ccc08) P:50 S:2 status 2 <0x2b65a000>
01 07/18/14 18:07:14.492 Lua_Job::ReturnMessageNotReceived job#44 : dev:5 (0x9cd278) P:50 S:5 failed <0x2b65a000>
04 07/18/14 18:07:14.493 <0x2b65a000>
02 07/18/14 18:07:14.493 JobHandler::PurgeCompletedJobs purge job#44 : dev:5 (0x9cd278) P:50 S:2 status 2 <0x2b65a000>
01 07/18/14 18:07:16.494 Lua_Job::ReturnMessageNotReceived job#45 : dev:5 (0x9cdd18) P:50 S:5 failed <0x2b65a000>
04 07/18/14 18:07:16.495

That’s a fascinating log, thank you for posting it. What it shows me is that the plugin is getting back an unexpected response from a Zone Name request on Zone 8. Whereas your other zones are returning 0x1F Message Reject on a Zone Name Request, for zone 8 the response timed out and returned 0x1C Message Fail. I didn’t expect that to be something that the interface would return, and the plugin doesn’t handle it.

I will definitely add a case to the Zone Scan code to handle a response of 0x1C. Is there something special about your Zone 8 that would make it act differently when the plugin queries its name? If not, I can treat it the same as 0x1F and call it a failed fetch. (Looks like none of your zones have names anyway: I’m guessing that you’ve got a LED keypad instead of an LCD keypad.)

When I said “flood the serial link” I was being a bit glib. The full version is: the plugin tries to keep track of the requests it’s made over the serial link (the buffer that you mentioned) but it doesn’t retry them if one gets corrupted during transmission (so the alarm panel gets a bad checksum), and it doesn’t like to get too many messages unrelated to its request (which might happen if someone is walking through your house while you do a scan). If the plugin doesn’t get the response it needs in five seconds then it fails the job. It turned out to be hard to track pending responses in the context of a Vera plugin, and I didn’t have a large system to test it on (my alarm has three zones). So I didn’t try too hard to iron out a more resilient process for scanning. (Besides, there was always manual zone configuration so users had a workaround.) This in addition to an underspecified (leaked) serial protocol meant that I couldn’t be certain I’d know how the interface always responds. Hence sometimes users get interesting results like yours. On a good day, that user knows how to report the problem and I can maybe harden up the plugin a bit.

There’s a comment on the serial protocol document that says the interface ignores RTS/CTS, so flow control would seem to be the responsibility of the plugin.

Go grab version 78 of L_CaddxNX584Security.lua from trunk – GE Caddx Networx NX-584 NX-8E Alarm Plugin and install it on your Vera; It’s got the 0x1C case covered and should prevent the exact failure that you posted in the last log. I’d be interested to see if scanning gets any further (and what the log looks like if and when it fails).

Actually I do have LCD keypads (3 in partition 1 and 2 in partition 2) and the zones I’m using are labeled. The keypad I used to label all the zones is in partition 2. Perhaps that’s helps explain it? Zone 8 is configured as zone type 6 and is connected to a magnetic door sensor labeled ‘Ship OHD15’. I didn’t mess with the custom programing of the zone types so it should be pretty Plain Jane. I uploaded your new code and did some testing. My first test log results are below. To keep the log manageable I removed the first 10 zones that I had manually entered. Went to the Zones tab and waited for things to quite down from the zones already configured. Then I ran the Scan Zones option with 3 as the maximum zone. Interestingly it didn’t pick up the zone names for zone 1 and 2, but did for zone 3 (Ship OHD10). I ran a larger test with 20 as the maximum zone. Zone names did appear for zones 2 and 9. I ran another test with 60 as the maximum zone. Zone names appeared for zones 45 and 58 only.

Like I mentioned in my earlier post, the only real difference in this system compared to most is the size with two partitions, lots of zones, two keyswitch zones and the additional power/repeater unit.

08 07/19/14 14:44:14.658 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2d2cd680>
08 07/19/14 14:44:14.659 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d2cd680>
08 07/19/14 14:44:14.659 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2d2cd680>
08 07/19/14 14:44:14.659 JobHandler_LuaUPnP::HandleActionRequest argument Zone=65 <0x2d2cd680>
08 07/19/14 14:44:14.659 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d2cd680>
50 07/19/14 14:44:14.661 luup_log:5: Job: Alarm: ZoneScan: 5 65 job userdata: 0x94b470 <0x2ab9a000>
50 07/19/14 14:44:14.662 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:14.663 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x40 0x66 0x8e <0x2ab9a000>
50 07/19/14 14:44:14.663 luup_log:5: Job: Started <0x2ab9a000>
50 07/19/14 14:44:14.735 luup_log:5: Received good message 0x04 <0x2d4cd680>
50 07/19/14 14:44:14.735 luup_log:5: Message: Incoming message body: 0x40 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2d4cd680>
50 07/19/14 14:44:14.736 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2d4cd680>
50 07/19/14 14:44:14.736 luup_log:5: ZoneScan Zone 65 <0x2d4cd680>
50 07/19/14 14:44:14.736 luup_log:5: Valid zone 65 <0x2d4cd680>
50 07/19/14 14:44:14.737 luup_log:5: Finishing pending job userdata: 0x94b470 <0x2d4cd680>
04 07/19/14 14:44:14.748 <0x2d4cd680>
50 07/19/14 14:44:15.717 luup_log:5: callbackHandler: request ZoneScan <0x2cecd680>
50 07/19/14 14:44:15.718 luup_log:5: callbackHandler: format json <0x2cecd680>
08 07/19/14 14:44:55.079 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2d2cd680>
08 07/19/14 14:44:55.080 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d2cd680>
08 07/19/14 14:44:55.080 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2d2cd680>
08 07/19/14 14:44:55.080 JobHandler_LuaUPnP::HandleActionRequest argument Zone=1 <0x2d2cd680>
08 07/19/14 14:44:55.080 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d2cd680>
50 07/19/14 14:44:55.085 luup_log:5: Job: Alarm: ZoneScan: 5 1 job userdata: 0x94c1e0 <0x2ab9a000>
50 07/19/14 14:44:55.085 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:55.086 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x00 0x26 0x4e <0x2ab9a000>
50 07/19/14 14:44:55.087 luup_log:5: Job: Started <0x2ab9a000>
50 07/19/14 14:44:55.135 luup_log:5: Received good message 0x04 <0x2d4cd680>
50 07/19/14 14:44:55.135 luup_log:5: Message: Incoming message body: 0x00 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2d4cd680>
50 07/19/14 14:44:55.136 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2d4cd680>
50 07/19/14 14:44:55.136 luup_log:5: ZoneScan Zone 1 <0x2d4cd680>
50 07/19/14 14:44:55.136 luup_log:5: Finishing pending job userdata: 0x94c1e0 <0x2d4cd680>
04 07/19/14 14:44:55.137 <0x2d4cd680>
50 07/19/14 14:44:56.134 luup_log:5: callbackHandler: request ZoneScan <0x2cecd680>
50 07/19/14 14:44:56.134 luup_log:5: callbackHandler: format json <0x2cecd680>
08 07/19/14 14:44:56.937 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2d2cd680>
08 07/19/14 14:44:56.937 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d2cd680>
08 07/19/14 14:44:56.938 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2d2cd680>
08 07/19/14 14:44:56.938 JobHandler_LuaUPnP::HandleActionRequest argument Zone=1 <0x2d2cd680>
08 07/19/14 14:44:56.938 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d2cd680>
50 07/19/14 14:44:56.940 luup_log:5: Job: Alarm: ZoneNameScan: 5 1 job userdata: 0x82d400 <0x2ab9a000>
50 07/19/14 14:44:56.940 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:56.941 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x00 0x25 0x4c <0x2ab9a000>
50 07/19/14 14:44:56.942 luup_log:5: Job: Started <0x2ab9a000>
08 07/19/14 14:44:57.054 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2d2cd680>
08 07/19/14 14:44:57.055 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2d2cd680>
08 07/19/14 14:44:57.055 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2d2cd680>
08 07/19/14 14:44:57.055 JobHandler_LuaUPnP::HandleActionRequest argument Zone=2 <0x2d2cd680>
08 07/19/14 14:44:57.055 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2d2cd680>
50 07/19/14 14:44:57.057 luup_log:5: Job: Alarm: ZoneScan: 5 2 job userdata: 0x97fe58 <0x2ab9a000>
50 07/19/14 14:44:57.057 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:57.058 luup_log:5: Job: Started <0x2ab9a000>
50 07/19/14 14:44:57.211 luup_log:5: Received good message 0x1f <0x2d4cd680>
50 07/19/14 14:44:57.211 luup_log:5: Message: Incoming message body: <0x2d4cd680>
50 07/19/14 14:44:57.212 luup_log:5: Handling message: 0x1F Message Reject <0x2d4cd680>
50 07/19/14 14:44:57.212 luup_log:5: Finishing pending job userdata: 0x82d400 <0x2d4cd680>
50 07/19/14 14:44:57.213 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x01 0x27 0x4f <0x2d4cd680>
04 07/19/14 14:44:57.215 <0x2d4cd680>
02 07/19/14 14:44:57.217 JobHandler::PurgeCompletedJobs purge job#65 : dev:5 (0x82d400) P:50 S:2 status 2 <0x2ab9a000>
50 07/19/14 14:44:57.234 luup_log:5: Received good message 0x04 <0x2d4cd680>
50 07/19/14 14:44:57.235 luup_log:5: Message: Incoming message body: 0x01 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2d4cd680>
50 07/19/14 14:44:57.235 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2d4cd680>
50 07/19/14 14:44:57.236 luup_log:5: ZoneScan Zone 2 <0x2d4cd680>
50 07/19/14 14:44:57.236 luup_log:5: Finishing pending job userdata: 0x97fe58 <0x2d4cd680>
04 07/19/14 14:44:57.237 <0x2d4cd680>
50 07/19/14 14:44:58.099 luup_log:5: callbackHandler: request ZoneScan <0x2cecd680>
50 07/19/14 14:44:58.100 luup_log:5: callbackHandler: format json <0x2cecd680>
08 07/19/14 14:44:58.872 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2cecd680>
08 07/19/14 14:44:58.872 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2cecd680>
08 07/19/14 14:44:58.872 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2cecd680>
08 07/19/14 14:44:58.872 JobHandler_LuaUPnP::HandleActionRequest argument Zone=2 <0x2cecd680>
08 07/19/14 14:44:58.872 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2cecd680>
50 07/19/14 14:44:58.875 luup_log:5: Job: Alarm: ZoneNameScan: 5 2 job userdata: 0x9534a0 <0x2ab9a000>
50 07/19/14 14:44:58.876 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:58.877 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x01 0x26 0x4d <0x2ab9a000>
50 07/19/14 14:44:58.877 luup_log:5: Job: Started <0x2ab9a000>
08 07/19/14 14:44:59.024 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneScan <0x2cecd680>
08 07/19/14 14:44:59.024 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2cecd680>
08 07/19/14 14:44:59.025 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneScan <0x2cecd680>
08 07/19/14 14:44:59.025 JobHandler_LuaUPnP::HandleActionRequest argument Zone=3 <0x2cecd680>
08 07/19/14 14:44:59.025 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2cecd680>
50 07/19/14 14:44:59.029 luup_log:5: Job: Alarm: ZoneScan: 5 3 job userdata: 0x953ac0 <0x2ab9a000>
50 07/19/14 14:44:59.029 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:44:59.030 luup_log:5: Job: Started <0x2ab9a000>
50 07/19/14 14:44:59.211 luup_log:5: Received good message 0x1f <0x2d4cd680>
50 07/19/14 14:44:59.211 luup_log:5: Message: Incoming message body: <0x2d4cd680>
50 07/19/14 14:44:59.212 luup_log:5: Handling message: 0x1F Message Reject <0x2d4cd680>
50 07/19/14 14:44:59.212 luup_log:5: Finishing pending job userdata: 0x9534a0 <0x2d4cd680>
50 07/19/14 14:44:59.213 luup_log:5: Message: Outgoing: 0x7e 0x02 0x24 0x02 0x28 0x50 <0x2d4cd680>
04 07/19/14 14:44:59.214 <0x2d4cd680>
02 07/19/14 14:44:59.216 JobHandler::PurgeCompletedJobs purge job#67 : dev:5 (0x9534a0) P:50 S:2 status 2 <0x2ab9a000>
50 07/19/14 14:44:59.234 luup_log:5: Received good message 0x04 <0x2d4cd680>
50 07/19/14 14:44:59.235 luup_log:5: Message: Incoming message body: 0x02 0x01 0x00 0x1b 0xf0 0x00 0x00 <0x2d4cd680>
50 07/19/14 14:44:59.236 luup_log:5: ZoneScan job handling message: 0x04 Zone Status <0x2d4cd680>
50 07/19/14 14:44:59.236 luup_log:5: ZoneScan Zone 3 <0x2d4cd680>
50 07/19/14 14:44:59.236 luup_log:5: Finishing pending job userdata: 0x953ac0 <0x2d4cd680>
04 07/19/14 14:44:59.238 <0x2d4cd680>
50 07/19/14 14:45:00.067 luup_log:5: callbackHandler: request ZoneScan <0x2cecd680>
50 07/19/14 14:45:00.067 luup_log:5: callbackHandler: format json <0x2cecd680>
08 07/19/14 14:45:00.840 JobHandler_LuaUPnP::HandleActionRequest device: 5 service: urn:futzle-com:serviceId:CaddxNX584Security1 action: ZoneNameScan <0x2cecd680>
08 07/19/14 14:45:00.840 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:futzle-com:serviceId:CaddxNX584Security1 <0x2cecd680>
08 07/19/14 14:45:00.841 JobHandler_LuaUPnP::HandleActionRequest argument action=ZoneNameScan <0x2cecd680>
08 07/19/14 14:45:00.841 JobHandler_LuaUPnP::HandleActionRequest argument Zone=3 <0x2cecd680>
08 07/19/14 14:45:00.841 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=5 <0x2cecd680>
50 07/19/14 14:45:00.843 luup_log:5: Job: Alarm: ZoneNameScan: 5 3 job userdata: 0x8245f0 <0x2ab9a000>
50 07/19/14 14:45:00.843 luup_log:5: Job: Processing send queue <0x2ab9a000>
50 07/19/14 14:45:00.844 luup_log:5: Message: Outgoing: 0x7e 0x02 0x23 0x02 0x27 0x4e <0x2ab9a000>
50 07/19/14 14:45:00.845 luup_log:5: Job: Started <0x2ab9a000>
50 07/19/14 14:45:01.271 luup_log:5: Received good message 0x03 <0x2d4cd680>
50 07/19/14 14:45:01.272 luup_log:5: Message: Incoming message body: 0x02 0x53 0x68 0x69 0x70 0x20 0x4f 0x48 0x44 0x31 0x30 0x20 0x20 0x20 0x20 0x20 0x20 <0x2d4cd680>
50 07/19/14 14:45:01.272 luup_log:5: Handling message: 0x03 Zone Name <0x2d4cd680>
50 07/19/14 14:45:01.272 luup_log:5: Finishing pending job userdata: 0x8245f0 <0x2d4cd680>
04 07/19/14 14:45:01.273

I forgot to add in all the tests I ran I can’t get it to puke anymore so your 0x1C coding fixed that problem. Your comments on the serial link make sense.

Thanks again for looking into this further.

Keith

That’s good news. Wish I could figure out what is causing the response to vary. It doesn’t look like there’s any pattern to it.

If your LCD keypad is in Partition 2 then you might try messing with the programming location that controls which keypad the interface queries for zone names. On the NX-584 it’s Location 4, default value 192 (Partition 1 Keypad 1). Setting it to 193 corresponds to Partition 2 Keypad 1.

(Zone names is something that I have to debug by proxy through helpful users like you. I’ve only got a lowly LED keypad, so names are a luxury I can only dream of.)

I see what you?re referring too. On the NX-8E, location 212 is the keypad address. I played with this a little but wasn?t having much success. For better or worse this system is in production mode and difficult to test with now. I get failures in the zones tab when I get all the zones loaded up and they?re constantly getting tripped. The good news is that after manual entry since you fixed that one problem it?s very stable. Perhaps one day we?ll have to chip in and get you a development NX-8E with LCD keypad.