Announcement

Collapse
No announcement yet.

HSPI_Insteon.exe takes >30 secs to teminate

Collapse
X
Collapse
 
  • Page of 1
  • Filter
  • Time
  • Show
Clear All
new posts
Previous template Next
    #1

    HSPI_Insteon.exe takes >30 secs to teminate

    Hi Mark,

    I am wondering why HSPI_Insteon.exe takes almost 40 seconds to terminate after HomeSeer has shut down.
    I've noticed this for a while now. Just updated to 3.0.7.26 and still the same...

    Click image for larger version Name: Insteon 3.0.7.26.PNG Views: 1293 Size: 5.0 KB ID: 1395223

    Date/time=2020-06-19 5:19:27 PM PDT
    Version=HS4 Pro Edition 4.0.9.0 (Linux)
    License=Registered
    Uptime=0 Days 0 Hours 4 Minutes 29 Seconds
    Lan IP=192.168.1.103 (Debian10)
    Device Count=150
    Event Count=53
    Plugins Enabled=Pushover 3P:,SDJ-Health:,Insteon:,drhsEventEnabler:,Z-Wave:,mcsMQTT:
    Modules/Threads=77 Modules, 64 Threads
    Available Threads=399
    HomeSeer Memory Used=154 Mbytes
    Plugins Installed=Current Cost 3P 0.0.0.42,drhsEventEnabler 1.0.0.0,Harmony Hub 4.0.11.0,Insteon 3.0.7.26,JowiHue 4.0.1.0,mcsMQTT 5.2.4.9,NetCAM 3.0.0.14,Philips Hue 4.0.16.0,Pushover 3P 0.0.0.48,SDJ-Health 3.0.8.6,Zigbee 4.0.6.0,Z-Wave 3.0.2.0

    Code:
    HomeSeer Linux starting...
05:06:36:2764:[Database]->Opening (Mode=Read Only) up HomeSeer LOG database /usr/local/HomeSeer/Logs/HomeSeerLog.hsd
05:06:36:4439:[Startup]->Loading Settings
05:06:36:5028:[Startup]->Settings Loaded.
05:06:37:1913:[Startup]->
05:06:37:1916:[Startup]->*********************************************************** *********************
05:06:37:1921:[Startup]-> HomeSeer version 4.0.9.0 Edition: HS4 Pro Starting Now
05:06:37:1923:[Startup]->*********************************************************** *********************
05:06:37:1925:[Startup]->
05:06:37:1993:[Startup]->Creating Class Objects...
05:06:37:2003:[Startup]->COM port classes started.
05:06:37:2018:[Startup]->Application interface class started.
05:06:37:2037:[Startup]->Web server class started.
05:06:37:2042:[Startup]->E-Mail SEND class started.
05:06:37:2044:[Startup]->E-Mail RECEIVE class started.
05:06:37:2058:[Startup]->Plug-In Interface class started.
05:06:37:2066:[Startup]->Local voice recognition class started.
05:06:37:2078:[Startup]->Loading configuration file ...
05:06:37:4420:[Database]->Loaded 150 Devices.
05:06:37:5324:[Database]->Loaded 53 Events.
05:06:37:5328:[3]->Unable to load counters config file, trying backup
05:06:37:5330:[4]->Unable to load counters from normal or backup file!
05:06:37:5334:[3]->Unable to load timers config file, trying backup
05:06:37:5336:[4]->Unable to load timers from normal or backup file!
05:06:37:5376:[Startup]->HomeSeer version is: 4.0.9.0
05:06:37:5379:[Startup]->Creating Speaker Client Interface Object...
05:06:37:5394:[Startup]->This version of HomeSeer is registered as a HS4PRO version.
05:06:37:5455:[Web Server]->Local IP address is: 192.168.1.103
05:06:37:5475:[Web Server]->Web Server started on port 8080
05:06:37:5647:[Startup]->Start automation engine...
05:06:37:5661:[Startup]->Initializing Speaker Client Interface
05:06:37:5794:[Speech]->Listening for remote speaker connections on port 10401
05:06:37:5833:[Database]->Opening (Mode=Read/Write) up HomeSeer Energy database /usr/local/HomeSeer/Data/Energy/Energy.hsd
05:06:37:6111:[Web Server]->Web Server authorized local login successful from: 192.168.1.100 User: homeseer
05:06:37:6111:[Web Server]->Web Server authorized local login successful from: 192.168.1.100 User: homeseer
05:06:37:6111:[Web Server]->Web Server authorized local login successful from: 192.168.1.100 User: homeseer
05:06:37:6257:[Warning]->File does not exist: /usr/local/HomeSeer/html/MQTT
05:06:37:6257:[Warning]->File does not exist: /usr/local/HomeSeer/html/MQTT
05:06:37:6257:[Warning]->File does not exist: /usr/local/HomeSeer/html/MQTT
05:06:37:7985:[Startup]->Updater services starting...
05:06:37:7992:[Startup]->Starting Find service...
05:06:37:8103:[Info]->Remote plug-in API interface started on port 10400
05:06:37:8105:[Startup]->Checking for available plug-ins
05:06:38:0882:[Plug-In]->Found plug-in: NetCAM, version: 3.0.0.14
05:06:38:3081:[Plug-In]->Found plug-in: mcsMQTT, version: 5.2.4.9
05:06:38:5271:[Plug-In]->Found plug-in: Harmony Hub, version: 4.0.11.0
05:06:38:7917:[Plug-In]->Found plug-in: Current Cost 3P, version: 0.0.0.42
05:06:39:1332:[Plug-In]->Found plug-in: SDJ-Health, version: 3.0.8.6
05:06:39:3556:[Plug-In]->Found plug-in: JowiHue, version: 4.0.1.0
05:06:39:5800:[Plug-In]->Found plug-in: Philips Hue, version: 4.0.16.0
05:06:39:8005:[Plug-In]->Found plug-in: Zigbee, version: 4.0.6.0
05:06:40:0227:[Plug-In]->Found plug-in: Pushover 3P, version: 0.0.0.48
05:06:40:2526:[Plug-In]->Found plug-in: Insteon, version: 3.0.7.26
05:06:40:4810:[Plug-In]->Found plug-in: Z-Wave, version: 3.0.2.0
05:06:40:6981:[Plug-In]->Found plug-in: drhsEventEnabler, version: 1.0.0.0
05:06:40:6988:[Startup]->Initializing Plug-Ins
Sub Main() Called
Connecting to server at 127.0.0.1...
05:06:40:9144:[Info]->Plugin Pushover 3P has connected. IP:127.0.0.1:47516
Connected, waiting to be initialized...
05:06:41:1032:[Starting Plug-In]->Initializing plugin Pushover 3P ...
[6/19/2020 5:06:41 PM] Plugin Starting - Checking if HS4/HS3...
05:06:41:1157:[Pushover 3P]->Plugin Starting - Checking if HS4/HS3...
[6/19/2020 5:06:41 PM] HS4 Detected ***** PLEASE NOTE HS4 IS NOT SUPPORTED *****
05:06:41:1175:[Pushover 3P]->HS4 Detected ***** PLEASE NOTE HS4 IS NOT SUPPORTED *****
[6/19/2020 5:06:41 PM] ****** InitIO Ended ******
05:06:41:1212:[Starting Plug-In]->Plugin Pushover 3P started successfully in 18 milliseconds
[6/19/2020 5:06:41 PM] BackGround Thread Started
05:06:41:1250:[Pushover 3P]->CreateTable Entered
[6/19/2020 5:06:41 PM] AddToTable : Plugin Started Row Count: 0
[6/19/2020 5:06:41 PM] RegisterWebPage Called: PushConfig - Configuration - Pushover 3P Configuration
05:06:41:1708:[Pushover 3P]->RegisterWebPage Called: PushConfig - Configuration - Pushover 3P Configuration
[6/19/2020 5:06:41 PM] User Key: uop8rrp4ygu4xw3gmq8ggfqte81u7a
[6/19/2020 5:06:41 PM] Emergency Expiry: 0
[6/19/2020 5:06:41 PM] Emergency Interval: 0
[6/19/2020 5:06:41 PM] Application Names/Tokens: HomeSeer=akny9bgwhvuinj6mu5m551noztfpjf
[6/19/2020 5:06:41 PM] Current Line 0 HomeSeer Current Line 1 akny9bgwhvuinj6mu5m551noztfpjf
[6/19/2020 5:06:41 PM] First Application Token (needed to download the sound files) akny9bgwhvuinj6mu5m551noztfpjf
[6/19/2020 5:06:41 PM] Attempting Sound Download....
05:06:41:1838:[Pushover 3P]->Attempting Sound Download....
[6/19/2020 5:06:41 PM] Downloading sounds from Pushover
05:06:41:1854:[Pushover 3P]->Downloading sounds from Pushover
05:06:41:2096:[Starting Plug-In]->Pushover 3P loaded in 500 milliseconds
05:06:41:2099:[Plug-In]->Finished initializing plug-in Pushover 3P
Connecting to server at 127.0.0.1...
05:06:41:4521:[Info]->Plugin SDJ-Health has connected. IP:127.0.0.1:47522
Connected, waiting to be initialized...
05:06:41:5243:[Starting Plug-In]->Initializing plugin SDJ-Health ...
Starting initializiation.
[6/19/2020 5:06:41 PM] rSound Download <?xml version="1.0" encoding="UTF-8"?>
<response>
<sounds>
<pushover>Pushover (default)</pushover>
<bike>Bike</bike>
<bugle>Bugle</bugle>
<cashregister>Cash Register</cashregister>
<classical>Classical</classical>
<cosmic>Cosmic</cosmic>
<falling>Falling</falling>
<gamelan>Gamelan</gamelan>
<incoming>Incoming</incoming>
<intermission>Intermission</intermission>
<magic>Magic</magic>
<mechanical>Mechanical</mechanical>
<pianobar>Piano Bar</pianobar>
<siren>Siren</siren>
<spacealarm>Space Alarm</spacealarm>
<tugboat>Tug Boat</tugboat>
<alien>Alien Alarm (long)</alien>
<climb>Climb (long)</climb>
<persistent>Persistent (long)</persistent>
<echo>Pushover Echo (long)</echo>
<updown>Up Down (long)</updown>
<vibrate>Vibrate Only</vibrate>
<none>None (silent)</none>
</sounds>
<status>1</status>
<request>4ebc5609-4ea7-4da0-9c6f-c610ca824e0f</request>
</response>

[6/19/2020 5:06:41 PM] Adding 1 - pushover - Pushover (default)
[6/19/2020 5:06:41 PM] Adding 2 - bike - Bike
[6/19/2020 5:06:41 PM] Adding 3 - bugle - Bugle
[6/19/2020 5:06:41 PM] Adding 4 - cashregister - Cash Register
[6/19/2020 5:06:41 PM] Adding 5 - classical - Classical
[6/19/2020 5:06:41 PM] Adding 6 - cosmic - Cosmic
[6/19/2020 5:06:41 PM] Adding 7 - falling - Falling
[6/19/2020 5:06:41 PM] Adding 8 - gamelan - Gamelan
[6/19/2020 5:06:41 PM] Adding 9 - incoming - Incoming
[6/19/2020 5:06:41 PM] Adding 10 - intermission - Intermission
[6/19/2020 5:06:41 PM] Adding 11 - magic - Magic
[6/19/2020 5:06:41 PM] Adding 12 - mechanical - Mechanical
[6/19/2020 5:06:41 PM] Adding 13 - pianobar - Piano Bar
[6/19/2020 5:06:41 PM] Adding 14 - siren - Siren
[6/19/2020 5:06:41 PM] Adding 15 - spacealarm - Space Alarm
[6/19/2020 5:06:41 PM] Adding 16 - tugboat - Tug Boat
[6/19/2020 5:06:41 PM] Adding 17 - alien - Alien Alarm (long)
[6/19/2020 5:06:41 PM] Adding 18 - climb - Climb (long)
[6/19/2020 5:06:41 PM] Adding 19 - persistent - Persistent (long)
[6/19/2020 5:06:41 PM] Adding 20 - echo - Pushover Echo (long)
[6/19/2020 5:06:41 PM] Adding 21 - updown - Up Down (long)
[6/19/2020 5:06:41 PM] Adding 22 - vibrate - Vibrate Only
[6/19/2020 5:06:41 PM] Adding 23 - none - None (silent)
[6/19/2020 5:06:41 PM] AddToTable : Downloaded Pushover Sounds Row Count: 1
[6/19/2020 5:06:41 PM] Downloaded 23 sounds from Pushover - Adding Device Default
05:06:41:6232:[Pushover 3P]->Downloaded 23 sounds from Pushover - Adding Device Default
[6/19/2020 5:06:41 PM] Attempting To Produce a Client/Group List
05:06:41:6262:[Pushover 3P]->Attempting To Produce a Client/Group List
[6/19/2020 5:06:41 PM] Clients p20pro=removedp30pro=uop8rrp4ygu4xw3gmq8ggfqte81u7a
[6/19/2020 5:06:41 PM] Device Previously Removed - Don't Use
05:06:41:6276:[Pushover 3P]->Device Previously Removed - Don't Use
05:06:41:6282:[Pushover 3P]->Clients p30pro : uop8rrp4ygu4xw3gmq8ggfqte81u7a
[6/19/2020 5:06:41 PM] Current Line 0 p30pro Current Line 1: uop8rrp4ygu4xw3gmq8ggfqte81u7a
05:06:41:6329:[SDJ-Health]->HS Major Version = 4
05:06:41:6353:[SDJ-Health]->Version 3.0.8.6 Initialized.
Initializing done! Ready...
05:06:41:6370:[Starting Plug-In]->Plugin SDJ-Health started successfully in 112 milliseconds
05:06:41:7814:[Starting Plug-In]->SDJ-Health loaded in 567 milliseconds
05:06:41:7823:[Plug-In]->Finished initializing plug-in SDJ-Health
[6/19/2020 5:06:41 PM] Found Plugin Devices, Exit
[6/19/2020 5:06:41 PM] End Of Enumerator - Create Devices False
Plugin: Insteon Instance: starting...
Connecting to server at 127.0.0.1...
[6/19/2020 5:06:42 PM] Devices Owned By Plugin 59 Name: Pushover 3P Root Device
[6/19/2020 5:06:42 PM] Devices Owned By Plugin 60 Name: Pushover 3P Enabled/Disabled
[6/19/2020 5:06:42 PM] Devices Owned By Plugin 61 Name: Pushover 3P Last Message Sent
[6/19/2020 5:06:42 PM] Devices Owned By Plugin 62 Name: Pushover 3P Disable Non-Emergency Messages
[6/19/2020 5:06:42 PM] Devices Owned By Plugin 63 Name: Pushover 3P Application Messages Remaining
05:06:42:1372:[Info]->Plugin Insteon has connected. IP:127.0.0.1:47526
Connected, waiting to be initialized...
[6/19/2020 5:06:42 PM] Array bCode(0) - 59
[6/19/2020 5:06:42 PM] Array bCode(1) - 60
[6/19/2020 5:06:42 PM] Array bCode(2) - 61
[6/19/2020 5:06:42 PM] Array bCode(3) - 62
[6/19/2020 5:06:42 PM] Array bCode(4) - 63
[6/19/2020 5:06:42 PM] Sending Status True Device Value: 100
05:06:42:1409:[Pushover 3P]->Sending Status True Device Value: 100
[6/19/2020 5:06:42 PM] Starting Flood Control Timer
05:06:42:1541:[Pushover 3P]->Starting Flood Control Timer
[6/19/2020 5:06:42 PM] Glance Message Delay Value 20
05:06:42:1569:[Pushover 3P]->Glance Message Delay Value 20
[6/19/2020 5:06:42 PM] Worker Thread Completed
05:06:42:2397:[Starting Plug-In]->Initializing plugin Insteon ...
05:06:42:3338:[Insteon]->Insteon Support library (Insteon.dll) version: 1.1.7.26
05:06:43:5587:[Insteon]->RF PLM contacted on /dev/ttyUSB0 with an Insteon address of 19.84.A0 and firmware version 99
05:06:44:6278:[Starting Plug-In]->Plugin Insteon started successfully in 2388 milliseconds
05:06:44:6280:[Insteon]->Waiting to initialize External Devices...
05:06:44:6378:[Starting Plug-In]->Insteon loaded in 2702 milliseconds
05:06:44:6380:[Plug-In]->Finished initializing plug-in Insteon
Connecting to server at 127.0.0.1...
05:06:44:8275:[Info]->Plugin drhsEventEnabler has connected. IP:127.0.0.1:47530
Connected, waiting to be initialized...
05:06:44:8316:[Starting Plug-In]->Initializing plugin drhsEventEnabler ...
05:06:44:8352:[WARNING]->drhs_EventEnabler plugin may not function correctly on this version of HomeSeer (4.0.9.0) - Please update to at least version 3.0.0.143
05:06:44:8705:[Starting Plug-In]->Plugin drhsEventEnabler started successfully in 38 milliseconds
05:06:44:9454:[Starting Plug-In]->drhsEventEnabler loaded in 300 milliseconds
05:06:44:9458:[Plug-In]->Finished initializing plug-in drhsEventEnabler
Connecting to server at 127.0.0.1...
05:06:45:1524:[Info]->Plugin Z-Wave has connected. IP:127.0.0.1:47534
Connected, waiting to be initialized...
05:06:45:2725:[Starting Plug-In]->Initializing plugin Z-Wave ...
05:06:45:2829:[Z-Wave]->InitIO called, plug-in version 3.0.2.0 is being initialized...
Z-Wave Plugin: OS Type is Linux
05:06:45:3840:[Z-Wave]->Database: Opening (Mode=Read Only) up HomeSeer database /usr/local/HomeSeer/Data/Z-Wave/Z-Wave2.db
05:06:45:4746:[Z-Wave]->Loading Z-Wave Network Objects...
05:06:45:4800:[Z-Wave]->1 total Z-Wave Network Objects loaded.
05:06:45:5639:[Z-Wave]->Loading Z-Wave Data Objects...
05:06:45:5658:[Z-Wave]->1 Networks were restored from the data in the database... Building data connections.
05:06:45:5665:[Z-Wave]->Network ECECBAE4 has 101 device data elements.
05:06:45:5725:[Z-Wave]->101 total Z-Wave Data Objects loaded.
05:06:45:9323:[Starting Plug-In]->Plugin Z-Wave started successfully in 659 milliseconds
05:06:45:9408:[Z-Wave]->0 event triggers were loaded from HomeSeer.
05:06:45:9441:[Z-Wave]->14 event actions were loaded from HomeSeer.
05:06:45:9561:[Starting Plug-In]->Z-Wave loaded in 1000 milliseconds
05:06:45:9563:[Plug-In]->Finished initializing plug-in Z-Wave
05:06:45:9653:[Z-Wave]->465 Simple AV Control Command Class Control Commands have been loaded successfully.
05:06:45:9718:[Z-Wave]->RaZberry: ============================================================ ==========
05:06:45:9729:[Z-Wave]->RaZberry: ----------------------------------------------------------------------
05:06:45:9745:[Z-Wave]->Initializing Z-Wave interface RaZberry (Ethernet Interface) at 192.168.1.101, port 4000
05:06:45:9754:[Z-Wave]->RaZberry: ----------------------------------------------------------------------
05:06:45:9784:[Z-Wave]->RaZberry: The Z-Wave Command Execution Thread was Started or Restarted.
05:06:45:9877:[Z-Wave]->RaZberry: Z-Wave Ethernet at IP 192.168.1.101, connected to interface ok.
Plugin: mcsMQTT Instance: starting...
Connecting to server at 127.0.0.1...
05:06:46:1815:[Info]->Plugin mcsMQTT has connected. IP:127.0.0.1:47540
Connected, waiting to be initialized...
05:06:46:2398:[Z-Wave]->RaZberry: Processing data buffered by the interface while it was disconnected.
05:06:46:2424:[Z-Wave]->RaZberry: The Z-Wave API Execution Thread was Started or Restarted.
05:06:46:2499:[Z-Wave]->RaZberry: Getting node information from controller...
05:06:46:3347:[Starting Plug-In]->Initializing plugin mcsMQTT ...
05:06:46:3530:[mcsMQTT]->Version 5.2.4.9 Registered with Homeseer
05:06:46:6181:[Starting Plug-In]->Plugin mcsMQTT started successfully in 283 milliseconds
05:06:46:6643:[Starting Plug-In]->mcsMQTT loaded in 700 milliseconds
05:06:46:6646:[Plug-In]->Finished initializing plug-in mcsMQTT
05:06:46:6703:[HSTouch Server]->Server started on port 10200
05:06:46:8590:[Startup]->HStouch server started, waiting for clients to connect.
05:06:46:8611:[Startup]->Running the startup script Startup.vb
05:06:47:0663:[Insteon]->External Device Registration complete.
05:06:47:7283:[Startup]->Starting Event Scheduler...
05:06:47:7296:[Startup]->HSSentry is disabled in Setup, sentry will not be started.
05:06:47:7309:[Info]->Checking device parent child relationships...
05:06:47:7355:[Startup]->Scripting is OK and is now running Startup.vb
05:06:47:7373:[Info]->No device issues found
05:06:47:7374:[Startup]->Start up complete.
Type 'shutdown' to shutdown HomeSeer

> 05:06:47:7442:[TTS]->Speak ():Welcome to Home-Seer
ALSA lib pcm_dmix.c:1108:(snd_pcm_dmix_open) unable to open slave
audio_open_alsa: failed to open audio device default. No such file or directory
05:06:47:7836:[TTS]->Speak ():Web server port number is 8080
ALSA lib pcm_dmix.c:1108:(snd_pcm_dmix_open) unable to open slave
audio_open_alsa: failed to open audio device default. No such file or directory
05:06:47:8342:[Info]->System connected to MyHS Service, waiting for acknowledge...
File upload thread started
05:06:47:8978:[Info]->System connected to MyHS Service successfully with license ID 663928.
05:06:48:1576:[Event]->Event Trigger "Scripts Solar Position - Daytime"
05:06:48:1598:[Event]->Running script in background: SolarPosition.vb
05:06:48:8738:[mcsMQTT]->MQTTClient is Connected to broker at 192.168.1.101
Error: RaZberry: The RaZberry interface did not respond to the request for the controller capabilities - it may be disconnected or malfunctioning (3).
05:06:51:2981:[Z-Wave Error]->RaZberry: The RaZberry interface did not respond to the request for the controller capabilities - it may be disconnected or malfunctioning (3).
05:06:51:3173:[Z-Wave Warning]->Trying to re-connect to Ethernet interface at IP 192.168.1.101...
05:06:51:9602:[SDJ-Health]->PEData read successfully from child device #246
05:06:51:9741:[SDJ-Health]->PEData read successfully from child device #247
05:06:51:9817:[SDJ-Health]->PEData read successfully from child device #248
05:06:51:9938:[SDJ-Health]->PEData read successfully from child device #249
05:06:52:0026:[SDJ-Health]->PEData read successfully from child device #250
05:06:52:0100:[SDJ-Health]->PEData read successfully from child device #415
05:06:52:0192:[SDJ-Health]->PEData read successfully from child device #416
05:06:52:0294:[SDJ-Health]->PEData read successfully from child device #417
05:06:52:0377:[SDJ-Health]->PEData read successfully from child device #418
05:06:52:0451:[SDJ-Health]->PEData read successfully from child device #419
05:06:52:0554:[SDJ-Health]->PEData read successfully from child device #420
05:06:52:0653:[SDJ-Health]->PEData read successfully from child device #421
05:06:52:0727:[SDJ-Health]->PEData read successfully from child device #422
05:06:52:2551:[SDJ-Health]->Found existing battery database.
Can't find custom attr constructor image: /usr/local/HomeSeer/Scheduler.dll mtoken: 0x0a0000c0 due to: Could not load file or assembly 'Newtonsoft.Json, Version=12.0.0.0, Culture=neutral, PublicKeyToken=30ad4fe6b2a6aeed' or one of its dependencies.
05:06:52:3013:[SDJ-Health]->Polling devices started.
05:06:52:3043:[SDJ-Health]->Activity Checking stopped.
05:06:52:5717:[SDJ-Health]->Read Exclusion List from INI adding 0 devices.
05:06:52:5783:[SDJ-Health]->GeneralHealthTimer turned off as no interfaces set to be checked.
05:06:53:0922:[Z-Wave]->*********************************************************** ********************
05:06:53:0929:[Z-Wave]-> STARTUP COMPLETE: All configured interfaces were successfully initialized.
05:06:53:0935:[Z-Wave]->*********************************************************** ********************
05:06:56:3212:[Z-Wave]->RaZberry: Z-Wave Ethernet at IP 192.168.1.101, connected to interface ok.
05:06:56:5741:[Z-Wave]->RaZberry: Getting node information from controller...
05:06:58:7196:[Z-Wave]->RaZberry: Controller Manufacturer: Telsey, ID=0x2, Type=0x4
05:06:58:7205:[Z-Wave]->RaZberry: Controller firmware version: 5.4
05:06:59:1572:[Z-Wave]->RaZberry: Z-Wave services for the SECURITY COMMAND CLASS were successfully started.
05:06:59:1580:[Z-Wave]->RaZberry: Z-Wave Serial API version: 5
05:06:59:1587:[Z-Wave]->RaZberry: Z-Wave interface node ID: 1, Home ID: ECECBAE4
05:06:59:1597:[Z-Wave]->RaZberry: Found 21 Z-Wave nodes in interface node ID 1 (RaZberry)
05:06:59:1962:[Z-Wave]->The interface's security support has been set.
05:06:59:2230:[Z-Wave]->RaZberry Controller chip type is ZW050x.
05:06:59:2238:[Z-Wave]->RaZberry Controller is static lib.
05:06:59:2246:[Z-Wave]->RaZberry There is a SUC/SIS in the network.
05:06:59:2253:[Z-Wave]->RaZberry is the SIS for the network.
05:06:59:2279:[Z-Wave]->RaZberry: ............................................................ ..........
05:06:59:2286:[Z-Wave]->RaZberry: .............................. FINISHED ..............................
05:06:59:2293:[Z-Wave]->RaZberry: ............................................................ ..........
05:07:02:3468:[SDJ-Health]->Z-Wave Device #175 polled succesfully.
05:07:02:3478:[SDJ-Health]->Polled Device #174 added to message queue for processing.
05:07:16:0692:[Z-Wave]->Starting Z-Wave Polling for Network ECECBAE4...
shutdown
05:07:26:2129:[Shutdown]->Application shutdown at 6/19/2020 5:07:26 PM
05:07:26:2406:[Shutdown]->Running shutdown script ...
05:07:26:2408:[Shutdown]->Shutdown Script Completed
05:07:26:2409:[Shutdown]->Shutting down plug-ins ...
05:07:26:2414:[Shutdown]->Shutting down all plug-ins.
05:07:26:2415:[Shutdown]->Shutting down plug-in: drhsEventEnabler
05:07:26:2434:[Shutdown]->Shutting down plug-in: Insteon
05:07:26:2630:[1]->Error while shutting down Plugin : The type initializer for 'System.Windows.Forms.XplatUI' threw an exception.
05:07:26:2633:[Shutdown]->Shutting down plug-in: mcsMQTT
ShutdownIO
Shutting down plugin
Disconnected from server - client
05:07:26:2726:[Info]->Plugin drhsEventEnabler with ID: and Instance: has disconnected
Writing events, serializing start...
Writing events, done serializing, elapsed MS: 18
05:07:27:1186:[Shutdown]->Shutting down plug-in: Pushover 3P
[6/19/2020 5:07:27 PM] ShutdownIO Called
05:07:27:1220:[Shutdown]->Shutting down plug-in: SDJ-Health
ShutdownIO complete.
05:07:27:1259:[Shutdown]->Shutting down plug-in: Z-Wave
05:07:27:1280:[Error]->Posting back to plugin web page MQTT: Object reference not set to an instance of an object->

Server stack trace:


Exception rethrown at [0]:
at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke (System.Runtime.Remoting.Proxies.RealProxy rp, System.Runtime.Remoting.Messaging.IMessage msg, System.Exception& exc, System.Object[]& out_args) [0x0014d] in <f2b3ab7dfff746f594d2ef5b16ec3c90>:0
at (wrapper managed-to-native) System.Object.__icall_wrapper_mono_remoting_wrapper(intptr,i ntptr)
at (wrapper remoting-invoke) HomeSeerAPI.IPlugInAPI.PostBackProc(string,string,string,int )
at Scheduler.proxyPage.postBackProc (Scheduler.StateObject& state, System.String Data) [0x00040] in <cc78980d80864114adfa9f72caadc35e>:0
Shutting down plugin
Shutting down plugin
05:07:27:1397:[Info]->Plugin SDJ-Health with ID: and Instance: has disconnected
Disconnected from server - client
Disconnected from server - client05:07:27:1445:[Info]->Plugin Pushover 3P with ID: and Instance: has disconnected

05:07:27:5910:[Error]->Posting back to plugin web page MQTT: Object reference not set to an instance of an object->

Server stack trace:


Exception rethrown at [0]:
at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke (System.Runtime.Remoting.Proxies.RealProxy rp, System.Runtime.Remoting.Messaging.IMessage msg, System.Exception& exc, System.Object[]& out_args) [0x0014d] in <f2b3ab7dfff746f594d2ef5b16ec3c90>:0
at (wrapper managed-to-native) System.Object.__icall_wrapper_mono_remoting_wrapper(intptr,i ntptr)
at (wrapper remoting-invoke) HomeSeerAPI.IPlugInAPI.PostBackProc(string,string,string,int )
at Scheduler.proxyPage.postBackProc (Scheduler.StateObject& state, System.String Data) [0x00040] in <cc78980d80864114adfa9f72caadc35e>:0
05:07:28:0720:[Shutdown]->Finished shutting down all plug-ins.
05:07:28:0722:[Shutdown]->Shutting down plug-in Comms ...
Disconnected from server - client
Connection lost, exiting
Disconnected from server - client
05:07:28:0803:[Shutdown]->Saving Data ...Connection lost, exiting

05:07:28:0981:[Info]->Plugin Insteon with ID: and Instance: has disconnected
05:07:28:0984:[Info]->Plugin mcsMQTT with ID: and Instance: has disconnected
05:07:28:0984:[Info]->Plugin Z-Wave with ID: and Instance: has disconnected
Connection lost, exiting
05:07:29:9027:[Shutdown]->Shutting down email ...
05:07:29:9030:[Shutdown]->-------------------------------------------------------------------
05:07:29:9033:[Shutdown]-> HomeSeer Successfully Shut Down Now
05:07:29:9036:[Shutdown]->-------------------------------------------------------------------
HomeSeer Linux has shut down.
root@Debian10:/usr/local/HomeSeer# while (ps -ef | grep HSPI_Insteon.exe | grep -v grep) do sleep 5; done
root 10414 1 4 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 4 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 4 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 3 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 3 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 3 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 3 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root 10414 1 2 17:06 pts/0 00:00:02 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
root@Debian10:/usr/local/HomeSeer#
    HS4 Pro Edition 4.1.10.0 (Linux - Debian 10) running on Lenovo ThinkCenter
    Plugins: Z-Wave
    HA 2020.12.1 running on HP ProDesk
    Add-ons: AccuWeather, Adaptive Lighting, Alexa Media Player, COVID-19, Geofency, Google Cast, HACS, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Mobile App, MQTT, Nest, OpenWeather, OpenZWave, SpeedTest, Ubiquiti UniFi, Zigbee
    Integrations: AppDaemon, Duck DNS, Entiry Controller, File Editor, Google Drive Backup, Home Panel, Mosquitto, NGINX SSL Proxy, Samba, TasmoAdmin, Terminal SSH, motionEye
    OpenZWave 1.6 running on RPi3B+ with RaZberry controller
    Tags: None
    #2
    Must be an incompatibility between the hs3 plugin and HS4
    Mark

    HS3 Pro 3.0.0.534
    Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
    Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
    Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

    Comment

      #3
      Originally posted by mnsandler View Post
      Must be an incompatibility between the hs3 plugin and HS4
      Are you working on an HS4 flavor?
      HS4 Pro Edition 4.1.10.0 (Linux - Debian 10) running on Lenovo ThinkCenter
      Plugins: Z-Wave
      HA 2020.12.1 running on HP ProDesk
      Add-ons: AccuWeather, Adaptive Lighting, Alexa Media Player, COVID-19, Geofency, Google Cast, HACS, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Mobile App, MQTT, Nest, OpenWeather, OpenZWave, SpeedTest, Ubiquiti UniFi, Zigbee
      Integrations: AppDaemon, Duck DNS, Entiry Controller, File Editor, Google Drive Backup, Home Panel, Mosquitto, NGINX SSL Proxy, Samba, TasmoAdmin, Terminal SSH, motionEye
      OpenZWave 1.6 running on RPi3B+ with RaZberry controller

      Comment

        #4
        Originally posted by dbrunt View Post

        Are you working on an HS4 flavor?
        is this still an issue with the latest HS4 build?

        working on an hs4 plugin slowly
        Mark

        HS3 Pro 3.0.0.534
        Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
        Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
        Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

        Comment

          #5
          It's actually seems worse now; ver 3.0.7.36 does not terminate at all. I have to manually kill /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe whenever I stop HS4. Disabling the pi does not terminate the process either.
          HS4 Pro Edition 4.1.10.0 (Linux - Debian 10) running on Lenovo ThinkCenter
          Plugins: Z-Wave
          HA 2020.12.1 running on HP ProDesk
          Add-ons: AccuWeather, Adaptive Lighting, Alexa Media Player, COVID-19, Geofency, Google Cast, HACS, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Mobile App, MQTT, Nest, OpenWeather, OpenZWave, SpeedTest, Ubiquiti UniFi, Zigbee
          Integrations: AppDaemon, Duck DNS, Entiry Controller, File Editor, Google Drive Backup, Home Panel, Mosquitto, NGINX SSL Proxy, Samba, TasmoAdmin, Terminal SSH, motionEye
          OpenZWave 1.6 running on RPi3B+ with RaZberry controller

          Comment

            #6
            Originally posted by dbrunt View Post
            It's actually seems worse now; ver 3.0.7.36 does not terminate at all. I have to manually kill /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe whenever I stop HS4. Disabling the pi does not terminate the process either.
            i didn't change anything in my plugin regarding shutdown

            what version of the HS4 are you running?

            i will check with HST. they must have changed something that i was monitoring to determine when to shutdown the plugin

            Mark

            HS3 Pro 3.0.0.534
            Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
            Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
            Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

            Comment

              #7
              does the exe terminate if you manually shutdown the plugin from the Manage Plugin page?
              Mark

              HS3 Pro 3.0.0.534
              Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
              Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
              Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

              Comment

                #8
                i didn't change anything in my plugin regarding shutdown - Famous last words!

                what version of the HS4 are you running? - 4.1.6.0

                does the exe terminate if you manually disable the plugin from the Manage Plugin page? - Yes.
                HS4 Pro Edition 4.1.10.0 (Linux - Debian 10) running on Lenovo ThinkCenter
                Plugins: Z-Wave
                HA 2020.12.1 running on HP ProDesk
                Add-ons: AccuWeather, Adaptive Lighting, Alexa Media Player, COVID-19, Geofency, Google Cast, HACS, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Mobile App, MQTT, Nest, OpenWeather, OpenZWave, SpeedTest, Ubiquiti UniFi, Zigbee
                Integrations: AppDaemon, Duck DNS, Entiry Controller, File Editor, Google Drive Backup, Home Panel, Mosquitto, NGINX SSL Proxy, Samba, TasmoAdmin, Terminal SSH, motionEye
                OpenZWave 1.6 running on RPi3B+ with RaZberry controller

                Comment

                  #9
                  please update to hs4 4.1.7.0 and retest
                  Mark

                  HS3 Pro 3.0.0.534
                  Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
                  Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
                  Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

                  Comment

                    #10
                    Updated to 4.1.7.0 but no change...

                    [CODEroot@homeseer:/usr/local# systemctl start HS4
                    root@homeseer:/usr/local# while true
                    > do
                    > ps -ef|grep mono
                    > sleep 10
                    > done
                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22300 1 7 21:11 ? 00:00:04 /usr/bin/mono HSConsole.exe --log
                    root 22340 22300 1 21:11 ? 00:00:00 /usr/bin/mono /usr/local/HomeSeer/HSPI_PUSHOVER.exe
                    root 22355 22300 3 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_ZWave.exe
                    root 22382 22300 2 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_MCSMQTT.exe
                    root 22395 22300 1 21:11 ? 00:00:00 /usr/bin/mono /usr/local/HomeSeer/HSPI_SDJHealth.exe
                    root 22410 22300 3 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
                    root 22500 23137 0 21:12 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22410 1 2 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
                    root 22536 23137 0 21:12 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22410 1 2 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
                    root 22568 23137 0 21:12 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22410 1 2 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
                    root 22585 23137 0 21:12 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22410 1 1 21:11 ? 00:00:01 /usr/bin/mono /usr/local/HomeSeer/HSPI_Insteon.exe
                    root 22609 23137 0 21:12 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22626 23137 0 21:12 pts/0 00:00:00 grep mono
                    (finally stopped)

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22647 23137 0 21:13 pts/0 00:00:00 grep mono

                    www-data 633 1 0 Sep16 ? 00:00:00 /usr/bin/mono /usr/lib/mono/4.5/xsp4.exe --port 8084 --address 0.0.0.0 --appconfigdir /etc/xsp4 --nonstop
                    root 22660 23137 0 21:13 pts/0 00:00:00 grep mono
                    [/CODE]
                    HS4 Pro Edition 4.1.10.0 (Linux - Debian 10) running on Lenovo ThinkCenter
                    Plugins: Z-Wave
                    HA 2020.12.1 running on HP ProDesk
                    Add-ons: AccuWeather, Adaptive Lighting, Alexa Media Player, COVID-19, Geofency, Google Cast, HACS, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Mobile App, MQTT, Nest, OpenWeather, OpenZWave, SpeedTest, Ubiquiti UniFi, Zigbee
                    Integrations: AppDaemon, Duck DNS, Entiry Controller, File Editor, Google Drive Backup, Home Panel, Mosquitto, NGINX SSL Proxy, Samba, TasmoAdmin, Terminal SSH, motionEye
                    OpenZWave 1.6 running on RPi3B+ with RaZberry controller

                    Comment

                      #11
                      I tested the HS4 shutdown on Windows and everything shuts down quickly as expected.

                      I'll have to load up HS4 on linux

                      Mark

                      HS3 Pro 3.0.0.534
                      Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
                      Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
                      Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

                      Comment

                        #12
                        On my debian linux VM, i do see HS4 calling the plugin for shutdown, and then the plugin shutting down. Everything looks normal, but It does seem to take 20-30 secs for the actual exe to be removed from memory.

                        If the plugin is manually shutdown from the Manage plugin page, HS4 apparently terminates the EXE forcefully (you can see a log entry for this), but this doesn't happen during a complete HS4 shutdown.
                        Mark

                        HS3 Pro 3.0.0.534
                        Hardware: Insteon Serial PLM | AD2USB for Vista Alarm | HAI Omnistat2 | 1-Wire HA7E | RFXrec433 | Dahua Cameras | LiftMaster Internet Gateway
                        Plugins: Insteon (mine) | Vista Alarm (mine) | Omnistat 3 (by Kirby) | Ultra1Wire3 | RFXCOM | NetCAM | MyQ | BLRadar | BLDenon | Jon00 Charting
                        Platform: HP h8-1360t, Windows Server 2012 R2, i7-3.4GHz, 16GB memory

                        Comment

                        Previous template Next
                        Working...
                        X