We have moved at community.getvera.com

Author Topic: NX-8E with more than 48 zones  (Read 2516 times)

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
NX-8E with more than 48 zones
« on: July 13, 2014, 03:53:35 pm »
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

Offline futzle

  • Moderator
  • Master Member
  • *****
  • Posts: 3260
  • Karma: +192/-9
Re: NX-8E with more than 48 zones
« Reply #1 on: July 14, 2014, 01:28:01 am »
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.

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
Re: NX-8E with more than 48 zones
« Reply #2 on: July 14, 2014, 10:12:23 am »
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    <Job ID="10" Name="" Device="5" Created="2014-07-14 8:40:12" Started="2014-07-14 8:40:12" Completed="2014-07-14 8:40:22" Duration="10.17869000" Runtime="10.17155000" Status="Failed" LastNote=""/> <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    <Job ID="11" Name="" Device="5" Created="2014-07-14 8:40:12" Started="2014-07-14 8:40:12" Completed="2014-07-14 8:40:22" Duration="9.770876000" Runtime="9.769153000" Status="Failed" LastNote=""/> <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    <Job ID="12" Name="" Device="5" Created="2014-07-14 8:40:12" Started="2014-07-14 8:40:12" Completed="2014-07-14 8:40:22" Duration="9.615526000" Runtime="9.614917000" Status="Failed" LastNote=""/> <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    <Job ID="13" Name="" Device="5" Created="2014-07-14 8:40:13" Started="2014-07-14 8:40:13" Completed="2014-07-14 8:40:23" Duration="10.334735000" Runtime="10.332946000" Status="Failed" LastNote=""/> <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    <Job ID="14" Name="" Device="5" Created="2014-07-14 8:40:13" Started="2014-07-14 8:40:13" Completed="2014-07-14 8:40:23" Duration="10.205500000" Runtime="10.203743000" Status="Failed" LastNote=""/> <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    <Job ID="15" Name="" Device="5" Created="2014-07-14 8:40:13" Started="2014-07-14 8:40:13" Completed="2014-07-14 8:40:23" Duration="9.833996000" Runtime="9.833261000" Status="Failed" LastNote=""/> <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    <Job ID="16" Name="" Device="5" Created="2014-07-14 8:40:13" Started="2014-07-14 8:40:13" Completed="2014-07-14 8:40:23" Duration="9.701499000" Runtime="9.699732000" Status="Failed" LastNote=""/> <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    <Job ID="17" Name="" Device="5" Created="2014-07-14 8:40:14" Started="2014-07-14 8:40:14" Completed="2014-07-14 8:40:24" Duration="10.253687000" Runtime="10.251797000" Status="Failed" LastNote=""/> <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    <Job ID="18" Name="" Device="5" Created="2014-07-14 8:40:14" Started="2014-07-14 8:40:14" Completed="2014-07-14 8:40:24" Duration="10.6383000" Runtime="10.4526000" Status="Failed" LastNote=""/> <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    <Job ID="19" Name="" Device="5" Created="2014-07-14 8:40:14" Started="2014-07-14 8:40:14" Completed="2014-07-14 8:40:24" Duration="9.760017000" Runtime="9.758208000" Status="Failed" LastNote=""/> <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    <Job ID="20" Name="" Device="5" Created="2014-07-14 8:40:15" Started="2014-07-14 8:40:15" Completed="2014-07-14 8:40:25" Duration="10.264293000" Runtime="10.263682000" Status="Failed" LastNote=""/> <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    <Job ID="21" Name="" Device="5" Created="2014-07-14 8:40:15" Started="2014-07-14 8:40:15" Completed="2014-07-14 8:40:25" Duration="9.763299000" Runtime="9.762053000" Status="Failed" LastNote=""/> <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    <Job ID="22" Name="" Device="5" Created="2014-07-14 8:40:16" Started="2014-07-14 8:40:16" Completed="2014-07-14 8:40:26" Duration="10.5469000" Runtime="10.3567000" Status="Failed" LastNote=""/> <0x2afa2000>
02      07/14/14 8:40:26.413    JobHandler::PurgeCompletedJobs purge job#22 : dev:5 (0x935808) P:50 S:2  status 2 <0x2afa2000>

Offline futzle

  • Moderator
  • Master Member
  • *****
  • Posts: 3260
  • Karma: +192/-9
Re: NX-8E with more than 48 zones
« Reply #3 on: July 14, 2014, 06:11:04 pm »
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.

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
Re: NX-8E with more than 48 zones
« Reply #4 on: July 18, 2014, 07:16:38 pm »
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   <Job ID="36" Name="" Device="5" Created="2014-07-18 18:06:23" Started="2014-07-18 18:06:23" Completed="2014-07-18 18:06:23" Duration="0.63364000" Runtime="0.62767000" Status="Successful" LastNote=""/> <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   <Job ID="37" Name="" Device="5" Created="2014-07-18 18:06:25" Started="2014-07-18 18:06:25" Completed="2014-07-18 18:06:25" Duration="0.65226000" Runtime="0.63339000" Status="Successful" LastNote=""/> <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   <Job ID="38" Name="" Device="5" Created="2014-07-18 18:06:58" Started="2014-07-18 18:06:58" Completed="2014-07-18 18:06:58" Duration="0.30153000" Runtime="0.29551000" Status="Successful" LastNote=""/> <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   <Job ID="39" Name="" Device="5" Created="2014-07-18 18:06:59" Started="2014-07-18 18:06:59" Completed="2014-07-18 18:07:00" Duration="0.302361000" Runtime="0.301759000" Status="Failed" LastNote=""/> <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   <Job ID="40" Name="" Device="5" Created="2014-07-18 18:07:00" Started="2014-07-18 18:07:00" Completed="2014-07-18 18:07:00" Duration="0.51537000" Runtime="0.49818000" Status="Successful" LastNote=""/> <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   <Job ID="41" Name="" Device="5" Created="2014-07-18 18:07:01" Started="2014-07-18 18:07:01" Completed="2014-07-18 18:07:01" Duration="0.275011000" Runtime="0.274326000" Status="Failed" LastNote=""/> <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   <Job ID="42" Name="" Device="5" Created="2014-07-18 18:07:02" Started="2014-07-18 18:07:02" Completed="2014-07-18 18:07:02" Duration="0.61140000" Runtime="0.59428000" Status="Successful" LastNote=""/> <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   <Job ID="43" Name="" Device="5" Created="2014-07-18 18:07:03" Started="2014-07-18 18:07:03" Completed="2014-07-18 18:07:14" Duration="10.797052000" Runtime="10.795365000" Status="Failed" LastNote=""/> <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   <Job ID="44" Name="" Device="5" Created="2014-07-18 18:07:04" Started="2014-07-18 18:07:04" Completed="2014-07-18 18:07:14" Duration="10.7741000" Runtime="10.5030000" Status="Failed" LastNote=""/> <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   <Job ID="45" Name="" Device="5" Created="2014-07-18 18:07:06" Started="2014-07-18 18:07:06" Completed="2014-07-18 18:07:16" Duration="10.9564000" Runtime="10.8285000" Status="Failed" LastNote=""/>

Offline futzle

  • Moderator
  • Master Member
  • *****
  • Posts: 3260
  • Karma: +192/-9
Re: NX-8E with more than 48 zones
« Reply #5 on: July 19, 2014, 04:35:21 am »
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 http://code.mios.com/trac/mios_caddxnx584/browser/trunk 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).

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
Re: NX-8E with more than 48 zones
« Reply #6 on: July 19, 2014, 04:02:45 pm »
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   <Job ID="63" Name="" Device="5" Created="2014-07-19 14:44:14" Started="2014-07-19 14:44:14" Completed="2014-07-19 14:44:14" Duration="0.77479000" Runtime="0.76456000" Status="Successful" LastNote=""/> <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   <Job ID="64" Name="" Device="5" Created="2014-07-19 14:44:55" Started="2014-07-19 14:44:55" Completed="2014-07-19 14:44:55" Duration="0.56088000" Runtime="0.52532000" Status="Successful" LastNote=""/> <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   <Job ID="65" Name="" Device="5" Created="2014-07-19 14:44:56" Started="2014-07-19 14:44:56" Completed="2014-07-19 14:44:57" Duration="0.275377000" Runtime="0.274538000" Status="Failed" LastNote=""/> <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   <Job ID="66" Name="" Device="5" Created="2014-07-19 14:44:57" Started="2014-07-19 14:44:57" Completed="2014-07-19 14:44:57" Duration="0.180972000" Runtime="0.180344000" Status="Successful" LastNote=""/> <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   <Job ID="67" Name="" Device="5" Created="2014-07-19 14:44:58" Started="2014-07-19 14:44:58" Completed="2014-07-19 14:44:59" Duration="0.340977000" Runtime="0.339044000" Status="Failed" LastNote=""/> <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   <Job ID="68" Name="" Device="5" Created="2014-07-19 14:44:59" Started="2014-07-19 14:44:59" Completed="2014-07-19 14:44:59" Duration="0.211675000" Runtime="0.209829000" Status="Successful" LastNote=""/> <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   <Job ID="69" Name="" Device="5" Created="2014-07-19 14:45:00" Started="2014-07-19 14:45:00" Completed="2014-07-19 14:45:01" Duration="0.431418000" Runtime="0.430772000" Status="Successful" LastNote=""/>

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
Re: NX-8E with more than 48 zones
« Reply #7 on: July 19, 2014, 04:09:21 pm »
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

Offline futzle

  • Moderator
  • Master Member
  • *****
  • Posts: 3260
  • Karma: +192/-9
Re: NX-8E with more than 48 zones
« Reply #8 on: July 19, 2014, 08:17:31 pm »
I can't get it to puke anymore so your 0x1C coding fixed that problem.

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.)

Offline kwayment

  • Newbie
  • *
  • Posts: 7
  • Karma: +0/-0
Re: NX-8E with more than 48 zones
« Reply #9 on: July 29, 2014, 02:47:44 pm »
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.