Announcement

Collapse
No announcement yet.

Condord Error Messages appearing in log

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

    Condord Error Messages appearing in log

    After several days of running the Concord plug-in with no problems, it appears to crash with these messages in the log:

    Dec-02 7:30:12 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file unable to open database file at System.Data.SQLite.SQLite3.Reset (System.Data.SQLite.SQLiteStatement stmt) [0x00000] in :0 at System.Data.SQLite.SQLite3.Step (System.Data.SQLite.SQLiteStatement stmt) [0x00000] in :0 at System.Data.SQLite.SQLiteDataReader.NextResult () [0x00000] in :0 at System.Data.SQLite.SQLiteDataReader..ctor (System.Data.SQLite.SQLiteCommand cmd, CommandBehavior behave) [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteDataReader:.ctor (System.Data.SQLite.SQLiteCommand,System.Data.CommandBehavio r) at System.Data.SQLite.SQLiteCommand.ExecuteReader (CommandBehavior behavior) [0x00000] in :0 at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteCommand:ExecuteNonQuery () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 ]
    Dec-02 7:30:12 PM CONCORD Error Error with SQL statement = Insert into Tracking (HCDC, TriggerDateTime, TimeSequence, ZoneNumber, ZoneName, Partition, Event) values('CONCORD_8', 1449106206, 380, 8, 'FAMILYROOMMOTION', 1, 'Faulted')
    Dec-02 7:30:12 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
    Dec-02 7:30:18 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:21 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:22 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
    Dec-02 7:30:22 PM CONCORD Error Error with SQL statement =
    Dec-02 7:30:22 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
    Dec-02 7:30:31 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:32 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
    Dec-02 7:30:32 PM CONCORD Error Error with SQL statement =
    Dec-02 7:30:32 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
    Dec-02 7:30:33 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:34 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:37 PM CONCORD WriteLog() - Error Too many open files
    Dec-02 7:30:42 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
    Dec-02 7:30:42 PM CONCORD Error Error with SQL statement =
    Dec-02 7:30:42 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
    Dec-02 7:30:52 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
    Dec-02 7:30:52 PM CONCORD Error Error with SQL statement =
    Dec-02 7:30:52 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
    Dec-02 7:31:02 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
    Dec-02 7:31:02 PM CONCORD Error Error with SQL statement =


    If I shutdown restart it, things seem to work fine for a day or so till the same thing happens

    Current Date/Time: 12/2/2015 8:15:07 PM
    HomeSeer Version: HS3 ZEE S2 Edition 3.0.0.208
    Linux version: Linux HomeTrollerZeeS2 3.18.11-v7+ #781 SMP PREEMPT Tue Apr 21 18:07:59 BST 2015 armv7l GNU/Linux System Uptime: 3 Days 6 Hours 17 Minutes 2 Seconds
    IP Address: 192.168.0.161
    Number of Devices: 103
    Number of Events: 12

    Enabled Plug-Ins
    3.0.0.4: CM15A
    3.1.13.2: CONCORD
    3.0.0.68: HSTouch Server
    3.0.1.25: Z-Wave

    #2
    OK. i'll let Vasrc weigh in here too but to me it looks like there is a "permissions" problem here writing to the log and then too many connections causing it to fail? It doesn't look like the .dll issue but could be wrong.

    Have you just installed the plugin? or has it run and now just started with these errors? What operating system?

    -Matt

    Comment


      #3
      Originally posted by knr View Post
      After several days of running the Concord plug-in with no problems, it appears to crash with these messages in the log:

      Dec-02 7:30:12 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file unable to open database file at System.Data.SQLite.SQLite3.Reset (System.Data.SQLite.SQLiteStatement stmt) [0x00000] in :0 at System.Data.SQLite.SQLite3.Step (System.Data.SQLite.SQLiteStatement stmt) [0x00000] in :0 at System.Data.SQLite.SQLiteDataReader.NextResult () [0x00000] in :0 at System.Data.SQLite.SQLiteDataReader..ctor (System.Data.SQLite.SQLiteCommand cmd, CommandBehavior behave) [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteDataReader:.ctor (System.Data.SQLite.SQLiteCommand,System.Data.CommandBehavio r) at System.Data.SQLite.SQLiteCommand.ExecuteReader (CommandBehavior behavior) [0x00000] in :0 at System.Data.SQLite.SQLiteCommand.ExecuteNonQuery () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteCommand:ExecuteNonQuery () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 ]
      Dec-02 7:30:12 PM CONCORD Error Error with SQL statement = Insert into Tracking (HCDC, TriggerDateTime, TimeSequence, ZoneNumber, ZoneName, Partition, Event) values('CONCORD_8', 1449106206, 380, 8, 'FAMILYROOMMOTION', 1, 'Faulted')
      Dec-02 7:30:12 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
      Dec-02 7:30:18 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:21 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:22 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
      Dec-02 7:30:22 PM CONCORD Error Error with SQL statement =
      Dec-02 7:30:22 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
      Dec-02 7:30:31 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:32 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
      Dec-02 7:30:32 PM CONCORD Error Error with SQL statement =
      Dec-02 7:30:32 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
      Dec-02 7:30:33 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:34 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:37 PM CONCORD WriteLog() - Error Too many open files
      Dec-02 7:30:42 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
      Dec-02 7:30:42 PM CONCORD Error Error with SQL statement =
      Dec-02 7:30:42 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
      Dec-02 7:30:52 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
      Dec-02 7:30:52 PM CONCORD Error Error with SQL statement =
      Dec-02 7:30:52 PM CONCORD Error An unexpected error occurred in the Timer1_Elapsed function/subroutine: [System.ObjectDisposedException: The object was used after being disposed. at System.Data.SQLite.SQLiteConnection.CheckDisposed () [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.get_State () [0x00000] in :0 at (wrapper remoting-invoke-with-check) System.Data.SQLite.SQLiteConnection:get_State () at HSPI_CONCORD.dbModule.updateTrackingTable () [0x00000] in :0 at HSPI_CONCORD.devModule.Timer1_Elapsed (System.Object sender, System.Timers.ElapsedEventArgs e) [0x00000] in :0 ]
      Dec-02 7:31:02 PM CONCORD Error An unexpected error occurred in the UpdateTrackingTable Err=unable to open database file function/subroutine: [System.Data.SQLite.SQLiteException: unable to open database file at System.Data.SQLite.SQLite3.Open (System.String strFilename, SQLiteConnectionFlags connectionFlags, SQLiteOpenFlagsEnum openFlags, Int32 maxPoolSize, Boolean usePool) [0x00000] in :0 at System.Data.SQLite.SQLiteConnection.Open () [0x00000] in :0 ]
      Dec-02 7:31:02 PM CONCORD Error Error with SQL statement =


      If I shutdown restart it, things seem to work fine for a day or so till the same thing happens

      Current Date/Time: 12/2/2015 8:15:07 PM
      HomeSeer Version: HS3 ZEE S2 Edition 3.0.0.208
      Linux version: Linux HomeTrollerZeeS2 3.18.11-v7+ #781 SMP PREEMPT Tue Apr 21 18:07:59 BST 2015 armv7l GNU/Linux System Uptime: 3 Days 6 Hours 17 Minutes 2 Seconds
      IP Address: 192.168.0.161
      Number of Devices: 103
      Number of Events: 12

      Enabled Plug-Ins
      3.0.0.4: CM15A
      3.1.13.2: CONCORD
      3.0.0.68: HSTouch Server
      3.0.1.25: Z-Wave
      Considering it's a motion sensor, I'd say it may have gone berzerk and is using up all of the file handles trying to write all of the changes.
      What are you using for a motion sensor?

      Also, do you have Log to Disk checked in the Debug section? If so, which?
      Do you get a clean startup log when you start the Concord Plugin?

      Thanks,
      Z
      Last edited by vasrc; December 3, 2015, 02:34 PM.

      Comment


        #4
        Luckily you're on Linux, so it's easy to see the open files. Funny this didn't show up earlier, I suspect the ulimit on the PI boxes is much smaller than on MS so it's not occurred there yet.
        Looks like I have a FD I'm not closing, so let me go track that down. PM me your email and I'll send you a new EXE to test with if you don't mind.

        Thanks,
        Z

        Comment

        Working...
        X