Resolved: Keypad and Logs don't update after UPNP enabled

[drewsonian]

I'm having an issue where once I try to turn on UPNP push notifications for my SmartThings integration, the keypad status never loads and the logs don't update. Once I disable the UPNP push and then reboot, the keypad status comes back and logs start back up again.

The diagnostics test when clicking "Test Device" all show as green check marks.

"Test Modules" show the following packages in red: cffi, usb, pycparser, pyftdi <--- I tried installing these from CLI, but they seem to be there from the package manager

System: AD2PI
Code Branch: Origin Master, up to date for both AlarmDecoderWebapp and AlarmDecoderLibrary
Firmware: V2.2a.8.9b-305

Below is the last 100 line logs:

Code: Select all

</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>False</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted /><last_message_received><!-- --><![CDATA[[10000001000000003A--],013,[f70100040013001c08020000000000],"****DISARMED****  READY TO ARM  "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:29:23,452 INFO: AlarmDecoder device was opened. [in /opt/alarmdecoder-webapp/ad2web/decoder.py:399]
2019-08-08 06:30:10,669 INFO: SUBSCRIBE host:10.20.0.202:5000 callback: <http://10.20.3.127:39500/notify> timeout: Second-28800 [in /opt/alarmdecoder-webapp/ad2web/api/views.py:243]
2019-08-08 06:30:10,671 INFO: add_subscriber: f1cf448e-b9df-11e9-b4a6-b827ebd19baf [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:203]
2019-08-08 06:31:37,480 INFO: _notify_subscribers [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:447]
2019-08-08 06:31:37,483 INFO: _notify_subscribers: {'f1cf448e-b9df-11e9-b4a6-b827ebd19baf': {'callback': u'<http://10.20.3.127:39500/notify>', 'host': u'10.20.0.202:5000', 'timeout': 1565299810.671187}}
<e:propertyset xmlns:e="urn:schemas-upnp-org:service:AlarmDecoder:1">
<e:property>
<eventid>17</eventid>
</e:property>
<e:property>
<eventdesc>rfx</eventdesc>
</e:property>
<e:property>
<eventmessage><![CDATA[0875375:0:1:1:0:0:0]]></eventmessage>
</e:property>
<e:property>
<rawmessage><![CDATA[!RFX:0875375,84]]></rawmessage>
</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>True</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted /><last_message_received><!-- --><![CDATA[[10000001000000003A--],022,[f70100040022001c08020000000000],"****DISARMED****  READY TO ARM  "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:31:37,564 INFO: _send_notify_event: status:200 reason:OK headers:{'Content-Length': 1203, 'Content-Type': 'text/xml', 'NTS': 'upnp:propchange', 'HOST': u'10.20.3.127:39500', 'SID': 'uuid:f1cf448e-b9df-11e9-b4a6-b827ebd19baf', 'NT': 'upnp:event'} [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:557]
2019-08-08 06:31:37,574 INFO: Event: 0875375:0:1:1:0:0:0 [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:422]
2019-08-08 06:32:29,934 INFO: _notify_subscribers [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:447]
2019-08-08 06:32:29,943 INFO: _notify_subscribers: {'f1cf448e-b9df-11e9-b4a6-b827ebd19baf': {'callback': u'<http://10.20.3.127:39500/notify>', 'host': u'10.20.0.202:5000', 'timeout': 1565299810.671187}}
<e:propertyset xmlns:e="urn:schemas-upnp-org:service:AlarmDecoder:1">
<e:property>
<eventid>17</eventid>
</e:property>
<e:property>
<eventdesc>rfx</eventdesc>
</e:property>
<e:property>
<eventmessage><![CDATA[0581207:0:0:1:0:0:0]]></eventmessage>
</e:property>
<e:property>
<rawmessage><![CDATA[!RFX:0581207,80]]></rawmessage>
</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>True</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted /><last_message_received><!-- --><![CDATA[[10000001000000003A--],022,[f70100040022001c08020000000000],"****DISARMED****  READY TO ARM  "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:32:30,027 INFO: _send_notify_event: status:200 reason:OK headers:{'Content-Length': 1203, 'Content-Type': 'text/xml', 'NTS': 'upnp:propchange', 'HOST': u'10.20.3.127:39500', 'SID': 'uuid:f1cf448e-b9df-11e9-b4a6-b827ebd19baf', 'NT': 'upnp:event'} [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:557]
2019-08-08 06:32:30,038 INFO: Event: 0581207:0:0:1:0:0:0 [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:422]
2019-08-08 06:32:30,092 INFO: _notify_subscribers [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:447]
2019-08-08 06:32:30,096 INFO: _notify_subscribers: {'f1cf448e-b9df-11e9-b4a6-b827ebd19baf': {'callback': u'<http://10.20.3.127:39500/notify>', 'host': u'10.20.0.202:5000', 'timeout': 1565299810.671187}}
<e:propertyset xmlns:e="urn:schemas-upnp-org:service:AlarmDecoder:1">
<e:property>
<eventid>15</eventid>
</e:property>
<e:property>
<eventdesc>ready changed</eventdesc>
</e:property>
<e:property>
<eventmessage><![CDATA[Ready status has changed to False]]></eventmessage>
</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>False</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted /><last_message_received><!-- --><![CDATA[[10000001000000003A--],022,[f70100040022001c08020000000000],"****DISARMED****  READY TO ARM  "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:32:30,161 INFO: _send_notify_event: status:200 reason:OK headers:{'Content-Length': 1140, 'Content-Type': 'text/xml', 'NTS': 'upnp:propchange', 'HOST': u'10.20.3.127:39500', 'SID': 'uuid:f1cf448e-b9df-11e9-b4a6-b827ebd19baf', 'NT': 'upnp:event'} [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:557]
2019-08-08 06:32:30,171 INFO: Event: Ready status has changed to False [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:422]
2019-08-08 06:32:30,212 INFO: _notify_subscribers [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:447]
2019-08-08 06:32:30,215 INFO: _notify_subscribers: {'f1cf448e-b9df-11e9-b4a6-b827ebd19baf': {'callback': u'<http://10.20.3.127:39500/notify>', 'host': u'10.20.0.202:5000', 'timeout': 1565299810.671187}}
<e:propertyset xmlns:e="urn:schemas-upnp-org:service:AlarmDecoder:1">
<e:property>
<eventid>8</eventid>
</e:property>
<e:property>
<eventdesc>zone fault</eventdesc>
</e:property>
<e:property>
<eventmessage><![CDATA[Zone Main Lobby Courtyard Door (20) has been faulted.]]></eventmessage>
</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>False</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted><z>20</z></panel_zones_faulted><last_message_received><!-- --><![CDATA[[10000001000000003A--],022,[f70100040022001c08020000000000],"****DISARMED****  READY TO ARM  "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:32:30,291 INFO: _send_notify_event: status:200 reason:OK headers:{'Content-Length': 1185, 'Content-Type': 'text/xml', 'NTS': 'upnp:propchange', 'HOST': u'10.20.3.127:39500', 'SID': 'uuid:f1cf448e-b9df-11e9-b4a6-b827ebd19baf', 'NT': 'upnp:event'} [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:557]
2019-08-08 06:32:35,012 INFO: _notify_subscribers [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:447]
2019-08-08 06:32:35,015 INFO: _notify_subscribers: {'f1cf448e-b9df-11e9-b4a6-b827ebd19baf': {'callback': u'<http://10.20.3.127:39500/notify>', 'host': u'10.20.0.202:5000', 'timeout': 1565299810.671187}}
<e:propertyset xmlns:e="urn:schemas-upnp-org:service:AlarmDecoder:1">
<e:property>
<eventid>17</eventid>
</e:property>
<e:property>
<eventdesc>rfx</eventdesc>
</e:property>
<e:property>
<eventmessage><![CDATA[0581207:0:0:0:0:0:0]]></eventmessage>
</e:property>
<e:property>
<rawmessage><![CDATA[!RFX:0581207,00]]></rawmessage>
</e:property>
<e:property><panelstate><panel_perimeter_only>False</panel_perimeter_only><panel_chime>False</panel_chime><panel_alarming>False</panel_alarming><panel_battery_trouble>False</panel_battery_trouble><panel_entry_delay_off>False</panel_entry_delay_off><panel_ready>False</panel_ready><panel_fire_detected>False</panel_fire_detected><panel_powered>True</panel_powered><panel_exit>False</panel_exit><panel_panicked>False</panel_panicked><panel_armed_stay>False</panel_armed_stay><panel_type>ADEMCO</panel_type><panel_bypassed>False</panel_bypassed><panel_armed>False</panel_armed><panel_relay_status /><panel_zones_faulted><z>20</z></panel_zones_faulted><last_message_received><!-- --><![CDATA[[00000001000000000A--],020,[f70100040020000008020000000000],"FAULT 020 ZN 020                "]]><!-- --></last_message_received></panelstate></e:property>
</e:propertyset> [in /opt/alarmdecoder-webapp/ad2web/notifications/types.py:462]
2019-08-08 06:34:08,967 INFO: Checking for version updates - last check at: 08-08-2019 06:24:02 [in /opt/alarmdecoder-webapp/ad2web/decoder.py:616]
2019-08-08 06:34:26,299 ERROR: Exception while handling socketio connection [in /opt/alarmdecoder-webapp/ad2web/decoder.py:1071]
Traceback (most recent call last):
File "/opt/alarmdecoder-webapp/ad2web/decoder.py", line 1068, in handle_socketio
socketio_manage(request.environ, {'/alarmdecoder': DecoderNamespace}, { "alarmdecoder": g.alarmdecoder, "request": request})
File "/usr/local/lib/python2.7/dist-packages/socketio/__init__.py", line 67, in socketio_manage
socket = environ['socketio']
KeyError: 'socketio'


[drewsonian]

Update: I went through and updated all the software I could and pulled in all changes from Github for the ST code and the AD code, but I'm still experiencing the same thing. Once I enable UPNP notifications, my keypad won't load and logs aren't shown in the web portal. Any ideas?

[drewsonian]

Resolution: I rebooted SmartThings and also pulled in the latest webapp from GitHub. Not sure which one did it, but turning on UPNP notifications doesn't make the logs freeze anymore.

[mathewss]

I just released a new image that fixes issues and from the looks of your logs you were running an older image.
https://www.alarmdecoder.com/wiki/index ... spberry_Pi

Backup your settings and then reload this image and do the setup just enough to get it up and running and then restore your settings.

I usually have to force a new subscription from ST if I am impatient and dont want to wait a few minutes for it to automatically re subscribe.

Best
Sean M