Guest User!

You are not Sophos Staff.

[8.940][ANSWERED] endpoint clients show as offline(Can't talk to Sophos LiveConnect)

Hi All

All endpoint clients show as offline and I am getting the following errors

2012:04:26-17:37:35 **** epsecd[4946]: I Epsec::Utils::Logging::_log:59() => id="4202" severity="info" sys="System" sub="epsecd" name="Run initialization database"

2012:04:26-17:37:35 a**** epsecd[4946]: >=========================================================================
2012:04:26-17:37:35 **** epsecd[4946]: E id="4281" severity="critical" sys="System" sub="epsecd" name="DBIx::Class::ResultSet::first(): DBI Exception: DBD:[[:P]]g::st execute failed: ERROR: column me.reference does not exist
2012:04:26-17:37:35 **** epsecd[4946]: LINE 1: ..., me.creation_time, me.mcs_id, me.last_connected, me.referen...
2012:04:26-17:37:35 **** epsecd[4946]: ^ [for Statement "SELECT me.id, me.device_type, me.name, me.device_id, me.instance_id, me.creation_time, me.mcs_id, me.last_connected, me.reference FROM devices me WHERE ( ( device_id = ? AND instance_id = ? ) )" with ParamValues: 1='USBSTOR\DiskIronKey_Secure_Drive____1.00', 2='USBSTOR\DISK&VEN_IRONKEY&PROD_SECURE_DRIVE&REV_1.00\850C01065A6947020E25&1'] at /Epsec/Helpers/Client.pm line 363" effect="Can't talk to Sophos LiveConnect"
2012:04:26-17:37:35 **** epsecd[4946]:
2012:04:26-17:37:35 **** epsecd[4946]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
2012:04:26-17:37:35 **** epsecd[4946]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
2012:04:26-17:37:35 **** epsecd[4946]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
2012:04:26-17:37:35 **** epsecd[4946]: 4. main::top-level:59() client.pl
2012:04:26-17:37:41 **** epsecd[4946]:  id="4202" severity="info" sys="System" sub="epsecd" name="Run initialization database"
2012:04:26-17:37:41 **** epsecd[4946]: >=========================================================================
2012:04:26-17:37:41 **** epsecd[4946]: E id="4281" severity="critical" sys="System" sub="epsecd" name="DBIx::Class::ResultSet::first(): DBI Exception: DBD:[[:P]]g::st execute failed: ERROR: column me.reference does not exist
2012:04:26-17:37:41 **** epsecd[4946]: LINE 1: ..., me.creation_time, me.mcs_id, me.last_connected, me.referen...
2012:04:26-17:37:41 **** epsecd[4946]: ^ [for Statement "SELECT me.id, me.device_type, me.name, me.device_id, me.instance_id, me.creation_time, me.mcs_id, me.last_connected, me.reference FROM devices me WHERE ( ( device_id = ? AND instance_id = ? ) )" with ParamValues: 1='USBSTOR\DiskIronKey_Secure_Drive____1.00', 2='USBSTOR\DISK&VEN_IRONKEY&PROD_SECURE_DRIVE&REV_1.00\850C01065A6947020E25&1'] at /Epsec/Helpers/Client.pm line 363" effect="Can't talk to Sophos LiveConnect"
2012:04:26-17:37:41 **** epsecd[4946]:
2012:04:26-17:37:41 **** epsecd[4946]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
2012:04:26-17:37:41 **** epsecd[4946]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
2012:04:26-17:37:41 **** epsecd[4946]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
2012:04:26-17:37:41 **** epsecd[4946]: 4. main::top-level:59() client.pl

Thanks
  • restarting the service seems to go into a loop as "column me.reference does not exist"

    2012:04:26-17:40:40 **** epsecd[7303]:  id="4202" severity="info" sys="System" sub="epsecd" name="Run initialization database"
    2012:04:26-17:40:40 **** epsecd[7303]: >=========================================================================
    2012:04:26-17:40:40 **** epsecd[7303]: E id="4281" severity="critical" sys="System" sub="epsecd" name="DBIx::Class::ResultSet::first(): DBI Exception: DBD:[[:P]]g::st execute failed: ERROR: column me.reference does not exist
    2012:04:26-17:40:40 **** epsecd[7303]: LINE 1: ..., me.creation_time, me.mcs_id, me.last_connected, me.referen...
    2012:04:26-17:40:40 **** epsecd[7303]: ^ [for Statement "SELECT me.id, me.device_type, me.name, me.device_id, me.instance_id, me.creation_time, me.mcs_id, me.last_connected, me.reference FROM devices me WHERE ( ( device_id = ? AND instance_id = ? ) )" with ParamValues: 1='USBSTOR\DiskIronKey_Secure_Drive____1.00', 2='USBSTOR\DISK&VEN_IRONKEY&PROD_SECURE_DRIVE&REV_1.00\850C01065A6947020E25&1'] at /Epsec/Helpers/Client.pm line 363" effect="Can't talk to Sophos LiveConnect"
    2012:04:26-17:40:40 **** epsecd[7303]:
    2012:04:26-17:40:40 **** epsecd[7303]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:26-17:40:40 **** epsecd[7303]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
    2012:04:26-17:40:40 **** epsecd[7303]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
    2012:04:26-17:40:40 **** epsecd[7303]: 4. main::top-level:59() client.pl
    2012:04:26-17:40:46 **** epsecd[7303]:  id="4202" severity="info" sys="System" sub="epsecd" name="Run initialization database"
    2012:04:26-17:40:46 **** epsecd[7303]: >=========================================================================
    2012:04:26-17:40:46 **** epsecd[7303]: E id="4281" severity="critical" sys="System" sub="epsecd" name="DBIx::Class::ResultSet::first(): DBI Exception: DBD:[[:P]]g::st execute failed: ERROR: column me.reference does not exist
    2012:04:26-17:40:46 **** epsecd[7303]: LINE 1: ..., me.creation_time, me.mcs_id, me.last_connected, me.referen...
    2012:04:26-17:40:46 **** epsecd[7303]: ^ [for Statement "SELECT me.id, me.device_type, me.name, me.device_id, me.instance_id, me.creation_time, me.mcs_id, me.last_connected, me.reference FROM devices me WHERE ( ( device_id = ? AND instance_id = ? ) )" with ParamValues: 1='USBSTOR\DiskIronKey_Secure_Drive____1.00', 2='USBSTOR\DISK&VEN_IRONKEY&PROD_SECURE_DRIVE&REV_1.00\850C01065A6947020E25&1'] at /Epsec/Helpers/Client.pm line 363" effect="Can't talk to Sophos LiveConnect"
    2012:04:26-17:40:46 **** epsecd[7303]:
    2012:04:26-17:40:46 **** epsecd[7303]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:26-17:40:46 **** epsecd[7303]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
    2012:04:26-17:40:46 **** epsecd[7303]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
    2012:04:26-17:40:46 **** epsecd[7303]: 4. main::top-level:59() client.pl
  • I have the same Problem.
    My LOG at the UTM looks the same.
  • A slightly different error with the same effect:


    2012:04:27-09:09:26 fw-1 epsecd[4574]: E id="4281" severity="critical" sys="System" sub="epsecd" name="Unknown error at /Epsec/Logic/Client.pm line 93." effect="Can't talk to Sophos LiveConnect"
  • Hi Everyone,

    These error messages may concern the UMT's local databse. So can you please post the output of: 
    skywalker:/ # rpm -qa | egrep 'postgre|ep-client'


    Cheers,
    Cristof
  • Here it is:

    fw:/var/log # rpm -qa | egrep 'postgre|ep-client'
    ep-client-endpoint-1.0.0-38.g911e752
    ep-postgresql-8.90-2.g958a1f0
    postgresql-8.4.11-0.ga4da70c.rb1
  • richter:/ # rpm -qa | egrep 'postgre|ep-client'

    ep-postgresql-8.90-2.g958a1f0
    postgresql-8.4.11-0.ga4da70c.rb1
    ep-client-endpoint-1.0.0-38.g911e752
  • Hi guys,

    The Problem: UTM can't talk to Sophos Live Connect/Database errors
    When/How this happens: If you did the upgrade to 8.940 and you have devices detected
    Temporary Solution: Run the following command
    psql -t -A -U epp -c "alter table devices add column reference text;" epp


    Like I mentioned before, this is a bug introduced by the 8.940 release. We'll try to have a fix with the 8.950 release. I apologize for the inconvenience created by this.

    Cristof has also opened a thread with this information.

    Thank you for reporting this problem. Please respond to this thread if you still encounter problems after running the command I provided.

    PS: I hope you find the new logging style better then what we had before.
  • is still not working( I';ve restarted the service as well)

    2012:04:27-09:53:54 ***** epsecd[28404]: |=========================================================================
    2012:04:27-09:53:54 ***** epsecd[28404]: E id="4280" severity="critical" sys="System" sub="epsecd" name="Error creating socket" ssl_errstr="IO::Socket::INET configuration failederror:00000000:lib(0):func(0):reason(0)" syscall_error="Connection reset by peer"
    2012:04:27-09:53:54 ***** epsecd[28404]:
    2012:04:27-09:53:54 ***** epsecd[28404]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 2. Epsec::Logic::Client::_start:77() /Epsec/Logic/Client.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 3. Epsec::Logic::Client:[:$]n_load:40() /Epsec/Logic/Client.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 4. (eval):53() /Epsec/Logic/Base.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 5. Epsec::Logic::Base::run:52() /Epsec/Logic/Base.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 6. main::top-level:59() client.pl
    2012:04:27-09:53:54 ***** epsecd[28404]: |=========================================================================
    2012:04:27-09:53:54 ***** epsecd[28404]: E id="4281" severity="critical" sys="System" sub="epsecd" name="Unknown error at /Epsec/Logic/Client.pm line 93." effect="Can't talk to Sophos LiveConnect"
    2012:04:27-09:53:54 ***** epsecd[28404]:
    2012:04:27-09:53:54 ***** epsecd[28404]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
    2012:04:27-09:53:54 ***** epsecd[28404]: 4. main::top-level:59() client.pl
    2012:04:27-09:54:34 ***** epsecd[28404]: |=========================================================================
    2012:04:27-09:54:34 ***** epsecd[28404]: E id="4280" severity="critical" sys="System" sub="epsecd" name="Error creating socket" ssl_errstr="IO::Socket::INET configuration failederror:00000000:lib(0):func(0):reason(0)" syscall_error="Connection reset by peer"
    2012:04:27-09:54:34 ***** epsecd[28404]:
    2012:04:27-09:54:34 ***** epsecd[28404]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 2. Epsec::Logic::Client::_start:77() /Epsec/Logic/Client.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 3. Epsec::Logic::Client:[:$]n_load:40() /Epsec/Logic/Client.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 4. (eval):53() /Epsec/Logic/Base.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 5. Epsec::Logic::Base::run:52() /Epsec/Logic/Base.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 6. main::top-level:59() client.pl
    2012:04:27-09:54:34 ***** epsecd[28404]: |=========================================================================
    2012:04:27-09:54:34 ***** epsecd[28404]: E id="4281" severity="critical" sys="System" sub="epsecd" name="Unknown error at /Epsec/Logic/Client.pm line 93." effect="Can't talk to Sophos LiveConnect"
    2012:04:27-09:54:34 ***** epsecd[28404]:
    2012:04:27-09:54:34 ***** epsecd[28404]: 1. Epsec::Utils::Logging::_log:59() /Epsec/Utils/Logging.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 2. Epsec::Logic::Client:[:$]n_error:1030() /Epsec/Logic/Client.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 3. Epsec::Logic::Base::run:60() /Epsec/Logic/Base.pm
    2012:04:27-09:54:34 ***** epsecd[28404]: 4. main::top-level:59() client.pl
  • No need to restart the service. Please let me know when this picks up. It should start working on it's own. If it doesn't pick up the connection in 20 minutes, please let me know.
  • I can confirm that it works for me now
    Thanks rfarcas