Announcement

Collapse
No announcement yet.

Null object reference error in Startup Log

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

    Null object reference error in Startup Log

    I occasionally receive errors in the Startup Log. Sometimes the Restart Plugin does not successfully start. Any idea what is going on? Copied my system profile at the end.

    I am a software developer of business applications so I am familiar with developing and debugging but not Homeseer. This error occurs with both the Restart Plugin and the Insteon plugin. Either there is something those two have in common, or the bug is in the Startup routine of Homeseer.

    When I asked the Homeseer folks about the error, they said it was a plugin problem.

    I use the Restart Plugin to restart the Homeseer software once a day. I was plagued with unreliability issues and decided to use the Restart Plugin to get a fresh start each day.


    11/30/2017 3:03:57 PM ~!~Info~!~Plugin Restart has connected. IP:127.0.0.1:1227
    11/30/2017 3:03:57 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.
    11/30/2017 3:03:58 PM ~!~Z-Wave~!~Z-Net Z-Wave: Getting node information from controller...
    11/30/2017 3:03:59 PM ~!~Error~!~Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
    11/30/2017 3:03:59 PM ~!~Plug-In~!~Finished initializing plug-in Restart
    11/30/2017 3:04:00 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller Manufacturer: Express Controls, ID=0x2, Type=0x5

    11/30/2017 3:03:57 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave Command Execution Thread was Started or Restarted.
    11/30/2017 3:03:57 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Ethernet at IP 10.0.0.129, connected to interface ok.
    11/30/2017 3:03:57 PM ~!~Info~!~Plugin Restart has connected. IP:127.0.0.1:1227
    11/30/2017 3:03:57 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.
    11/30/2017 3:03:58 PM ~!~Z-Wave~!~Z-Net Z-Wave: Getting node information from controller...
    11/30/2017 3:03:59 PM ~!~Error~!~Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
    11/30/2017 3:03:59 PM ~!~Plug-In~!~Finished initializing plug-in Restart
    11/30/2017 3:04:00 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller Manufacturer: Express Controls, ID=0x2, Type=0x5
    11/30/2017 3:04:00 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller firmware version: 4.32
    11/30/2017 3:04:00 PM ~!~Info~!~Plugin Insteon has connected. IP:127.0.0.1:1231
    11/30/2017 3:04:00 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave services for the SECURITY COMMAND CLASS were successfully started.


    This time the Insteon Plugin received the error:
    11/26/2017 3:03:41 AM ~!~Z-Wave~!~Z-Net Z-Wave: .............................. FINISHED ..............................
    11/26/2017 3:03:41 AM ~!~Z-Wave~!~Z-Net Z-Wave: ............................................................ ..........
    11/26/2017 3:03:41 AM ~!~Error~!~Initializing plug-in(2): Insteon Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
    11/26/2017 3:03:42 AM ~!~Plug-In~!~Finished initializing plug-in Insteon
    11/26/2017 3:03:42 AM ~!~HSTouch Server~!~Server started on port 10200
    1

    This time both plugins received the error



    11/16/2017 3:04:53 AM ~!~Z-Wave~!~Z-Net Z-Wave: ----------------------------------------------------------------------
    11/16/2017 3:04:53 AM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave Command Execution Thread was Started or Restarted.
    11/16/2017 3:04:53 AM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Ethernet at IP 10.0.0.129, connected to interface ok.
    11/16/2017 3:04:53 AM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.
    11/16/2017 3:04:53 AM ~!~Z-Wave~!~Z-Net Z-Wave: Getting node information from controller...
    11/16/2017 3:04:55 AM ~!~Error~!~Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
    11/16/2017 3:04:55 AM ~!~Plug-In~!~Finished initializing plug-in Restart
    11/16/2017 3:04:56 AM ~!~Z-Wave~!~Z-Net Z-Wave: Controller Manufacturer: Express Controls, ID=0x2, Type=0x5
    11/16/2017 3:04:56 AM ~!~Info~!~Plugin Insteon has connected. IP:127.0.0.1:1287
    11/16/2017 3:04:56 AM ~!~Z-Wave~!~Z-Net Z-Wave: Controller firmware version: 4.32
    11/16/2017 3:04:56 AM ~!~Error~!~Initializing plug-in(2): Insteon Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
    11/16/2017 3:04:56 AM ~!~Plug-In~!~Finished initializing plug-in Insteon
    11/16/2017 3:04:57 AM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave services for the SECURITY COMMAND CLASS were successfully started.
    11/16/2017 3:04:57 AM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Serial API version: 5
    11/16/2017 3:04:57 AM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave interface node ID: 1, Home ID: DCA7BC56
    1


    Current Date/Time: 12/7/2017 10:49:17 PM
    HomeSeer Version: HS3 Standard Edition 3.0.0.357
    Operating System: Microsoft Windows 7 Home Premium - Work Station
    System Uptime: 7 Days 7 Hours 46 Minutes 1 Second
    IP Address: 10.0.0.32
    Number of Devices: 122
    Number of Events: 72
    Available Threads: 200

    Enabled Plug-Ins
    3.0.0.8: CM15A
    3.0.6.0: Insteon
    1.0.0.5: Restart
    3.0.1.87: Z-Wave

    #2
    I have 2 installs of HS and have never seen anything like this.
    One of my installs is HS3 3.0.0.318
    The other is HS3 3.0.0.379.

    To me it sounds like there is a bigger issue. You should not have to restrart HS every day. The only time I have to restart my system is when I need to install Windows updates.
    --
    Jeff Farmer
    HS 3, HSPhone
    My HS3 Plugins: CFHSExtras, Random, Restart, Tracker, WeatherXML, PanaBluRay
    Other Plugins In Use: APCUPSD, BLOnkyo, Device History, EasyTrigger, HSTouch Server, PHLocation2, Pushover, RFXCom, UltraGCIR3, UltraMon3, UltraPioneerAVR3, X10, Z-Wave

    Hardware: GoControl Irrigation Controler, Schlage Lever Lock, Schlage Deadbolt, Way2Call Hi-Phone, RFXCom RFXrec433 Receiver, WGL 800, TI-103, Z-Net, Pioneer 1120, Pioneer 1021, Pioneer LX302, Panasonic BDT-110, Panasonic BDT-210 x2

    Comment


      #3
      Null Reference

      I do not, necessarily, need to restart my Homeseer software everyday. It is just a precaution after the problems I had. I suppose I could reduce the frequency. However, the problem I was asking about were the NULL REFERENCE errors. Homeseer says they are a problem with the Restart Plugin. Are they?

      What can I do to gather information for you about the Restart Plugin. Is there a debug or detail logging mode?

      Comment


        #4
        Another Null Reference error

        The error occurred again today. Is it caused by HS3 or the Restart Plugin?

        12/9/2017 3:04:24 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Ethernet at IP 10.0.0.129, connected to interface ok.
        12/9/2017 3:04:24 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.
        12/9/2017 3:04:24 PM ~!~Z-Wave~!~Z-Net Z-Wave: Getting node information from controller...
        12/9/2017 3:04:25 PM ~!~Error~!~Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
        12/9/2017 3:04:25 PM ~!~Plug-In~!~Finished initializing plug-in Restart
        12/9/2017 3:04:26 PM ~!~Info~!~Plugin Insteon has connected. IP:127.0.0.1:1263
        12/9/2017 3:04:26 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller Manufacturer: Express Controls, ID=0x2, Type=0x5
        12/9/2017 3:04:26 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller firmware version: 4.32
        12/9/2017 3:04:26 PM ~!~Insteon~!~Insteon Support library (Insteon.dll) version: 1.0.6.0
        12/9/20

        Comment


          #5
          Do you have a HS device named Restart Version? If you can get the plugin to startup go to the device and click on the name to edit it.

          You should have a tab labeled Restart. Go to the tab and make set the Logging Level to high. That should give you more info when the plugin starts up.
          --
          Jeff Farmer
          HS 3, HSPhone
          My HS3 Plugins: CFHSExtras, Random, Restart, Tracker, WeatherXML, PanaBluRay
          Other Plugins In Use: APCUPSD, BLOnkyo, Device History, EasyTrigger, HSTouch Server, PHLocation2, Pushover, RFXCom, UltraGCIR3, UltraMon3, UltraPioneerAVR3, X10, Z-Wave

          Hardware: GoControl Irrigation Controler, Schlage Lever Lock, Schlage Deadbolt, Way2Call Hi-Phone, RFXCom RFXrec433 Receiver, WGL 800, TI-103, Z-Net, Pioneer 1120, Pioneer 1021, Pioneer LX302, Panasonic BDT-110, Panasonic BDT-210 x2

          Comment


            #6
            Logging Debug Level Already on HIGH

            Found the Restart tab and the Logging Debug Level is already on HIGH LEVEL. Where does the Restart Plugin write its log?

            Comment


              #7
              It writes it to the HS log.

              Check in the HomeSeer HS3/config directory. Find hspi_restart.ini.

              What value is in the file for the key debugMode?
              --
              Jeff Farmer
              HS 3, HSPhone
              My HS3 Plugins: CFHSExtras, Random, Restart, Tracker, WeatherXML, PanaBluRay
              Other Plugins In Use: APCUPSD, BLOnkyo, Device History, EasyTrigger, HSTouch Server, PHLocation2, Pushover, RFXCom, UltraGCIR3, UltraMon3, UltraPioneerAVR3, X10, Z-Wave

              Hardware: GoControl Irrigation Controler, Schlage Lever Lock, Schlage Deadbolt, Way2Call Hi-Phone, RFXCom RFXrec433 Receiver, WGL 800, TI-103, Z-Net, Pioneer 1120, Pioneer 1021, Pioneer LX302, Panasonic BDT-110, Panasonic BDT-210 x2

              Comment


                #8
                [DeviceRef]
                PluginVersion=3
                [Settings]
                debugMode=2
                [Restart]
                waitSecs=60
                userLevel=2
                showHSRestart=True
                showHSOnlyShutdown=True
                showHSReboot=True
                showHSShutdown=True
                delaySecs=30
                startHS_Error=
                startHS_StartTime=Dec/11/2017 03:00 PM
                debugstart=HomeSeer is already running.
                RestartTime=11/26/2017 9:16:37 AM
                restartHS_Error=
                debugrestart=Waiting 30 seconds to restart HomeSeer.
                debugrestarttime=11/26/2017 9:17:24 AM
                debugpath=C:\Program Files\HomeSeer HS3\HS3.exe

                Comment


                  #9
                  Dec-09 3:04:26 PM Info Plugin Insteon has connected. IP:127.0.0.1:1263
                  Dec-09 3:04:25 PM Plug-In Finished initializing plug-in Restart
                  Dec-09 3:04:25 PM Error Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK: Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
                  Dec-09 3:04:24 PM Z-Wave Z-Net Z-Wave: Getting node information from controller...
                  Dec-09 3:04:24 PM Z-Wave Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.

                  Comment


                    #10
                    I don't know what is causing this as I have never seen an error with the startup of the restart plugin.

                    I did add some more debugging to the startup function and placed it in the Updater as .6. You can install the new version and watch the HS log for any error messages to help narrow this down.
                    --
                    Jeff Farmer
                    HS 3, HSPhone
                    My HS3 Plugins: CFHSExtras, Random, Restart, Tracker, WeatherXML, PanaBluRay
                    Other Plugins In Use: APCUPSD, BLOnkyo, Device History, EasyTrigger, HSTouch Server, PHLocation2, Pushover, RFXCom, UltraGCIR3, UltraMon3, UltraPioneerAVR3, X10, Z-Wave

                    Hardware: GoControl Irrigation Controler, Schlage Lever Lock, Schlage Deadbolt, Way2Call Hi-Phone, RFXCom RFXrec433 Receiver, WGL 800, TI-103, Z-Net, Pioneer 1120, Pioneer 1021, Pioneer LX302, Panasonic BDT-110, Panasonic BDT-210 x2

                    Comment


                      #11
                      Since the same error occurred with two different plugins, I suspect it has something to do with the HS3 software but until I have something to show them, they will still insist that the problem is in the plugin.

                      Comment


                        #12
                        Installed version .6 and I see a few more lines in the Startup Log. Now to wait and see if the error occurs again. Thank you for your timely response.

                        Comment


                          #13
                          It happened again!

                          The error happened again. The new version logged a little more information.

                          2/13/2017 3:03:43 PM ~!~Z-Wave~!~Z-Net Z-Wave: ----------------------------------------------------------------------
                          12/13/2017 3:03:43 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave Command Execution Thread was Started or Restarted.
                          12/13/2017 3:03:43 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Ethernet at IP 10.0.0.129, connected to interface ok.
                          12/13/2017 3:03:43 PM ~!~Info~!~Plugin Restart has connected. IP:127.0.0.1:1214
                          12/13/2017 3:03:43 PM ~!~Z-Wave~!~Z-Net Z-Wave: The Z-Wave API Execution Thread was Started or Restarted.
                          12/13/2017 3:03:44 PM ~!~Z-Wave~!~Z-Net Z-Wave: Getting node information from controller...
                          12/13/2017 3:03:45 PM ~!~Error~!~Initializing plug-in(2): Restart Instance::Object reference not set to an instance of an object.STACK:Server stack trace: Exception rethrown at [0]: at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessag e(IMessage reqMsg, IMessage retMsg) at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(Mess ageData& msgData, Int32 type) at HomeSeerAPI.IPlugInAPI.InitIO(String port) at Scheduler.clsHSPI.CheckInterfaces()
                          12/13/2017 3:03:45 PM ~!~Plug-In~!~Finished initializing plug-in Restart
                          12/13/2017 3:03:46 PM ~!~Info~!~Plugin Insteon has connected. IP:127.0.0.1:1220
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller Manufacturer: Express Controls, ID=0x2, Type=0x5
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Controller firmware version: 4.32
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave services for the SECURITY COMMAND CLASS were successfully started.
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave Serial API version: 5
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Z-Wave interface node ID: 1, Home ID: DCA7BC56
                          12/13/2017 3:03:46 PM ~!~Z-Wave~!~Z-Net Z-Wave: Found 18 Z-Wave nodes in interface node ID 1 (Z-Ne

                          Comment


                            #14
                            I don't see how this can be something in the plugin. I have several Try Catch in place that writes a message to the HS log if part of the code errors.
                            The very first thing in Init IO is a try catch for one line of code.

                            And there are several places where I am writing messages to the HS log if it runs through the code correctly.
                            --
                            Jeff Farmer
                            HS 3, HSPhone
                            My HS3 Plugins: CFHSExtras, Random, Restart, Tracker, WeatherXML, PanaBluRay
                            Other Plugins In Use: APCUPSD, BLOnkyo, Device History, EasyTrigger, HSTouch Server, PHLocation2, Pushover, RFXCom, UltraGCIR3, UltraMon3, UltraPioneerAVR3, X10, Z-Wave

                            Hardware: GoControl Irrigation Controler, Schlage Lever Lock, Schlage Deadbolt, Way2Call Hi-Phone, RFXCom RFXrec433 Receiver, WGL 800, TI-103, Z-Net, Pioneer 1120, Pioneer 1021, Pioneer LX302, Panasonic BDT-110, Panasonic BDT-210 x2

                            Comment


                              #15
                              From the original error messages, it did not look to me like a problem with the plugin. In addition to that, the identical error occurred with the Insteon plugin, but not as often.

                              What are the next steps? What is the procedure for reporting this to HomeSeer with the supporting info from you?

                              Comment

                              Working...
                              X