Announcement

Collapse
No announcement yet.

HSPI_Insteon.exe takes >30 secs to teminate

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

    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:	1729
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.16.0 running on Lenovo ThinkCenter & Debian Linux
    Plugins: Z-Wave, SDJHealth
    Z-Wave via Nortek USB stick

    Home Assistant 2021.5.5 running on HA "Blue" ODROID-N2
    Add-ons: Android Debug Bridge, AdGuard Home, Duck DNS, ESPHome, File Editor, Glances, HA Google Drive Backup, InfluxDB, Log Viewer, MariaDB, Mosquitto broker, NGINX SSL Proxy, Node-RED, Portainer, SSH & Web Terminal, Samba, TasmoAdmin, UniFi Controller, Visual Studio Code, WireGuard, Z-Wave JS to MQTT
    Integrations: AccuWeather, Adaptive Lighting, Alexa Media Player, Entity Controller, Glances, HACS, HomeSeer, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Magic Areas, Mobile App, MQTT, Nest, OpenWeather, Ubiquiti UniFi, Zigbee, Z-Wave JS
    Insteon: 2413S Dual Band PLM
    Zigbee: zzh! CC2652R Rev A
    Z-Wave: RaZberry daughtercard on RPi 1B via ser2net

    #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.16.0 running on Lenovo ThinkCenter & Debian Linux
      Plugins: Z-Wave, SDJHealth
      Z-Wave via Nortek USB stick

      Home Assistant 2021.5.5 running on HA "Blue" ODROID-N2
      Add-ons: Android Debug Bridge, AdGuard Home, Duck DNS, ESPHome, File Editor, Glances, HA Google Drive Backup, InfluxDB, Log Viewer, MariaDB, Mosquitto broker, NGINX SSL Proxy, Node-RED, Portainer, SSH & Web Terminal, Samba, TasmoAdmin, UniFi Controller, Visual Studio Code, WireGuard, Z-Wave JS to MQTT
      Integrations: AccuWeather, Adaptive Lighting, Alexa Media Player, Entity Controller, Glances, HACS, HomeSeer, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Magic Areas, Mobile App, MQTT, Nest, OpenWeather, Ubiquiti UniFi, Zigbee, Z-Wave JS
      Insteon: 2413S Dual Band PLM
      Zigbee: zzh! CC2652R Rev A
      Z-Wave: RaZberry daughtercard on RPi 1B via ser2net

      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.16.0 running on Lenovo ThinkCenter & Debian Linux
          Plugins: Z-Wave, SDJHealth
          Z-Wave via Nortek USB stick

          Home Assistant 2021.5.5 running on HA "Blue" ODROID-N2
          Add-ons: Android Debug Bridge, AdGuard Home, Duck DNS, ESPHome, File Editor, Glances, HA Google Drive Backup, InfluxDB, Log Viewer, MariaDB, Mosquitto broker, NGINX SSL Proxy, Node-RED, Portainer, SSH & Web Terminal, Samba, TasmoAdmin, UniFi Controller, Visual Studio Code, WireGuard, Z-Wave JS to MQTT
          Integrations: AccuWeather, Adaptive Lighting, Alexa Media Player, Entity Controller, Glances, HACS, HomeSeer, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Magic Areas, Mobile App, MQTT, Nest, OpenWeather, Ubiquiti UniFi, Zigbee, Z-Wave JS
          Insteon: 2413S Dual Band PLM
          Zigbee: zzh! CC2652R Rev A
          Z-Wave: RaZberry daughtercard on RPi 1B via ser2net

          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.16.0 running on Lenovo ThinkCenter & Debian Linux
                Plugins: Z-Wave, SDJHealth
                Z-Wave via Nortek USB stick

                Home Assistant 2021.5.5 running on HA "Blue" ODROID-N2
                Add-ons: Android Debug Bridge, AdGuard Home, Duck DNS, ESPHome, File Editor, Glances, HA Google Drive Backup, InfluxDB, Log Viewer, MariaDB, Mosquitto broker, NGINX SSL Proxy, Node-RED, Portainer, SSH & Web Terminal, Samba, TasmoAdmin, UniFi Controller, Visual Studio Code, WireGuard, Z-Wave JS to MQTT
                Integrations: AccuWeather, Adaptive Lighting, Alexa Media Player, Entity Controller, Glances, HACS, HomeSeer, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Magic Areas, Mobile App, MQTT, Nest, OpenWeather, Ubiquiti UniFi, Zigbee, Z-Wave JS
                Insteon: 2413S Dual Band PLM
                Zigbee: zzh! CC2652R Rev A
                Z-Wave: RaZberry daughtercard on RPi 1B via ser2net

                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.16.0 running on Lenovo ThinkCenter & Debian Linux
                    Plugins: Z-Wave, SDJHealth
                    Z-Wave via Nortek USB stick

                    Home Assistant 2021.5.5 running on HA "Blue" ODROID-N2
                    Add-ons: Android Debug Bridge, AdGuard Home, Duck DNS, ESPHome, File Editor, Glances, HA Google Drive Backup, InfluxDB, Log Viewer, MariaDB, Mosquitto broker, NGINX SSL Proxy, Node-RED, Portainer, SSH & Web Terminal, Samba, TasmoAdmin, UniFi Controller, Visual Studio Code, WireGuard, Z-Wave JS to MQTT
                    Integrations: AccuWeather, Adaptive Lighting, Alexa Media Player, Entity Controller, Glances, HACS, HomeSeer, Insteon, IPP, Life360, Local IP, Logitech Harmony Hub, Magic Areas, Mobile App, MQTT, Nest, OpenWeather, Ubiquiti UniFi, Zigbee, Z-Wave JS
                    Insteon: 2413S Dual Band PLM
                    Zigbee: zzh! CC2652R Rev A
                    Z-Wave: RaZberry daughtercard on RPi 1B via ser2net

                    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

                        Working...
                        X