Invalid security token

mp12 · Sep 22, 2016, 10:22 AM

Server

FOG Version: 1.3.0-RC11 Version 54
OS: Ubuntu 14.04

Client

Service Version: 0.11.5
OS: Windows 7

Description

Getting “invalid security token” message. Here the fog.log from the client.

 22.09.2016 10:07 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 22.09.2016 10:07 Middleware::Response Success
 22.09.2016 10:07 Service Sleeping for 146 seconds
 22.09.2016 10:09 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:95:D9:D4|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 22.09.2016 10:09 Middleware::Authentication Waiting for authentication timeout to pass
 22.09.2016 10:09 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 22.09.2016 10:09 Data::RSA FOG Server CA cert found
 22.09.2016 10:09 Middleware::Authentication Cert OK
 22.09.2016 10:09 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 22.09.2016 10:09 Middleware::Response Invalid security token
 22.09.2016 10:09 Middleware::Response Success
 22.09.2016 10:09 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 22.09.2016 10:09 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 22.09.2016 10:09 Service Creating user agent cache
 22.09.2016 10:09 Middleware::Response ERROR: Unable to get subsection
 22.09.2016 10:09 Middleware::Response ERROR: Object reference not set to an instance of an object.
 22.09.2016 10:09 Middleware::Response ERROR: Unable to get subsection
 22.09.2016 10:09 Middleware::Response ERROR: Object reference not set to an instance of an object.
 22.09.2016 10:09 Middleware::Response ERROR: Unable to get subsection
 22.09.2016 10:09 Middleware::Response ERROR: Object reference not set to an instance of an object.

fog.log from the same client 4 minutes before invalid security token.

  22.09.2016 10:03 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 22.09.2016 10:03 Middleware::Response Success
 22.09.2016 10:03 Service Sleeping for 104 seconds
 22.09.2016 10:04 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:95:D9:D4|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 22.09.2016 10:04 Middleware::Response Success
 22.09.2016 10:04 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 22.09.2016 10:04 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 22.09.2016 10:04 Service Creating user agent cache
 22.09.2016 10:04 Middleware::Response Module is disabled globally on the FOG server
 22.09.2016 10:04 Middleware::Response No Printers
 22.09.2016 10:04 Middleware::Response Success

I get this error on each client in our computer pools.
Resetting the encryption data is a temporary solution. Token will be invalid again after a period of time.

Matthieu Jacquart · Sep 22, 2016, 10:48 AM

Agreed, yesterday with version 54 same problem, reset encryption data but for some hosts I had to reset several time (once for hostname changer, once for AD integration…)

mp12 · Sep 28, 2016, 1:54 PM

FOG Version 1.3.0-RC11
OS: Ubuntu 14.04

Client: 0.11.5
OS: Win 7

Still getting the same errors.

Here a snippet from the fog.log

 
 28.09.2016 15:08 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 28.09.2016 15:08 Middleware::Response Success
 28.09.2016 15:08 Service Sleeping for 136 seconds
 28.09.2016 15:10 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:10 Middleware::Response Success
 28.09.2016 15:10 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 28.09.2016 15:10 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 28.09.2016 15:10 Service Creating user agent cache
 28.09.2016 15:10 Middleware::Response Module is disabled globally on the FOG server
 28.09.2016 15:10 Middleware::Response Module is disabled on the host
 28.09.2016 15:10 Middleware::Response Success

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Success
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------TaskReboot----------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Module is disabled on the host
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------HostnameChanger-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Success
 28.09.2016 15:10 HostnameChanger Checking Hostname
 28.09.2016 15:10 HostnameChanger Hostname is correct
 28.09.2016 15:10 HostnameChanger Attempting to join domain
 28.09.2016 15:10 HostnameChanger Host is already joined to target domain
------------------------------------------------------------------------------


------------------------------------------------------------------------------
---------------------------------SnapinClient---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response No snapins
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------PrinterManager--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Module is disabled on the host
 28.09.2016 15:10 PrinterManager Getting installed printers
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------PowerManagement-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Success
 28.09.2016 15:10 PowerManagement Calculating tasks to unschedule
 28.09.2016 15:10 PowerManagement Calculating tasks to schedule
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:10 Client-Info Client Version: 0.11.5
 28.09.2016 15:10 Client-Info Client OS:      Windows
 28.09.2016 15:10 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:10 Middleware::Response Success
------------------------------------------------------------------------------

 28.09.2016 15:10 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 28.09.2016 15:10 Middleware::Response Success
 28.09.2016 15:10 Service Sleeping for 131 seconds
 28.09.2016 15:12 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:12 Middleware::Authentication Waiting for authentication timeout to pass
 28.09.2016 15:12 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 28.09.2016 15:12 Data::RSA FOG Server CA cert found
 28.09.2016 15:12 Middleware::Authentication Cert OK
 28.09.2016 15:12 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 28.09.2016 15:12 Middleware::Response Success
 28.09.2016 15:12 Middleware::Authentication Authenticated
 28.09.2016 15:12 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:12 Middleware::Response Success
 28.09.2016 15:12 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 28.09.2016 15:12 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 28.09.2016 15:12 Service Creating user agent cache
 28.09.2016 15:12 Middleware::Response Module is disabled globally on the FOG server
 28.09.2016 15:12 Middleware::Response Module is disabled on the host
 28.09.2016 15:12 Middleware::Response Success

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Success
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------TaskReboot----------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Module is disabled on the host
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------HostnameChanger-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Success
 28.09.2016 15:12 HostnameChanger Checking Hostname
 28.09.2016 15:12 HostnameChanger Hostname is correct
 28.09.2016 15:12 HostnameChanger Attempting to join domain
 28.09.2016 15:12 HostnameChanger Host is already joined to target domain
------------------------------------------------------------------------------


------------------------------------------------------------------------------
---------------------------------SnapinClient---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response No snapins
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------PrinterManager--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Module is disabled on the host
 28.09.2016 15:12 PrinterManager Getting installed printers
------------------------------------------------------------------------------


------------------------------------------------------------------------------
--------------------------------PowerManagement-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Success
 28.09.2016 15:12 PowerManagement Calculating tasks to unschedule
 28.09.2016 15:12 PowerManagement Calculating tasks to schedule
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:12 Client-Info Client Version: 0.11.5
 28.09.2016 15:12 Client-Info Client OS:      Windows
 28.09.2016 15:12 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:12 Middleware::Response Success
------------------------------------------------------------------------------

 28.09.2016 15:12 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 28.09.2016 15:12 Middleware::Response Success
 28.09.2016 15:12 Service Sleeping for 136 seconds
 28.09.2016 15:15 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:15 Middleware::Authentication Waiting for authentication timeout to pass
 28.09.2016 15:15 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 28.09.2016 15:15 Data::RSA FOG Server CA cert found
 28.09.2016 15:15 Middleware::Authentication Cert OK
 28.09.2016 15:15 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 28.09.2016 15:15 Middleware::Response Invalid security token
 28.09.2016 15:15 Middleware::Response Success
 28.09.2016 15:15 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 28.09.2016 15:15 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 28.09.2016 15:15 Service Creating user agent cache
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response Success
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------TaskReboot----------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------HostnameChanger-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------SnapinClient---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PrinterManager--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PowerManagement-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:15 Client-Info Client Version: 0.11.5
 28.09.2016 15:15 Client-Info Client OS:      Windows
 28.09.2016 15:15 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:15 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:15 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:15 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 28.09.2016 15:15 Middleware::Response Success
 28.09.2016 15:15 Service Sleeping for 107 seconds
 28.09.2016 15:16 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:16 Middleware::Authentication Waiting for authentication timeout to pass
 28.09.2016 15:17 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 28.09.2016 15:17 Data::RSA FOG Server CA cert found
 28.09.2016 15:17 Middleware::Authentication Cert OK
 28.09.2016 15:17 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 28.09.2016 15:17 Middleware::Response Invalid security token
 28.09.2016 15:17 Middleware::Response Success
 28.09.2016 15:17 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 28.09.2016 15:17 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 28.09.2016 15:17 Service Creating user agent cache
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response Success
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------TaskReboot----------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------HostnameChanger-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------SnapinClient---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PrinterManager--------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PowerManagement-------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 28.09.2016 15:17 Client-Info Client Version: 0.11.5
 28.09.2016 15:17 Client-Info Client OS:      Windows
 28.09.2016 15:17 Client-Info Server Version: 1.3.0-RC-11
 28.09.2016 15:17 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:17 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:17 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 28.09.2016 15:17 Middleware::Response Success
 28.09.2016 15:17 Service Sleeping for 127 seconds
 28.09.2016 15:19 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:03:D2|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 28.09.2016 15:19 Middleware::Authentication Waiting for authentication timeout to pass
 28.09.2016 15:19 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 28.09.2016 15:19 Data::RSA FOG Server CA cert found
 28.09.2016 15:19 Middleware::Authentication Cert OK
 28.09.2016 15:19 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 28.09.2016 15:19 Middleware::Response Invalid security token
 28.09.2016 15:19 Middleware::Response Success
 28.09.2016 15:19 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 28.09.2016 15:19 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 28.09.2016 15:19 Service Creating user agent cache
 28.09.2016 15:19 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:19 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:19 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:19 Middleware::Response ERROR: Object reference not set to an instance of an object.
 28.09.2016 15:19 Middleware::Response ERROR: Unable to get subsection
 28.09.2016 15:19 Middleware::Response ERROR: Object reference not set to an instance of an object.

Wayne Workman · Sep 28, 2016, 3:50 PM

It depends on correct time. Is the client’s time being managed by an NTP server? Pay attention to the date and time on the next one that has this problem.

Also, what is rc11 version 54? Are you still on a working branch? rc11 is out, you should switch to it. Message me or @Tom-Elliott for instructions on how to do that.

Joe Schmitt · Sep 28, 2016, 8:15 PM

@Wayne-Workman client time does does not affect this. This is a server issue.

mp12 · Sep 29, 2016, 8:08 AM

I switched to RC11 on monday morning. Date and time is synced correctly.
On tuesday I re-run the installer (FOG Client Auto Updating Hotfix).

I reset the encryption data on 37 computers around 8:30. Some of them get invalid token at around 8:50 others at 9:09 and some of them didn’t even reset the encryption data or are still working fine after reset (didn’t check all of them).

On server side, where should I check for errors?

Tom Elliott · Sep 29, 2016, 1:01 PM

I can only think of one reason the hosts are getting IST when they seem fine initially. I believe this may be due to the PingHosts Service due to how it was originally coded. This would prove quite troublesome particularly on layouts with many hosts in their environment.

Essentially, the way FOGPingHosts works, is it iterates through each host in the server. To do this, it first has to get each host in the server.

The way I handled this was simply by calling the individual objects directly. The problem with this approach is it’s possible to have the Host in one state, but altered after the fact. The host object called would then be in whatever state it was, not whatever state it currently is. (Hopefully that makes sense.)

I’ve refactored how the ping status will be updated. It uses a total of three queries now, instead of a single query, but I am no longer saving the whole of the object. I’m simply going to update the relevant field. This should help in performance back and forth to the DB and Memory as we’re only using data necessary to perform the update. It should also ensure only the relevant item to update is changed, rather than require the whole of the item to be reinserted/changed.

@mp12 if you’d like to test around with the new bit, please changeover to the working-RC-12 branch. I only ask this as I don’t want only one file to be adjusted and due to the “timing” of such events I think it’d be a good idea to start simply at what will be in the code for RC-12.

mp12 · Sep 29, 2016, 1:01 PM

@Tom-Elliott

I switched to RC12 now.

I also recognized that I set PINGHOSTSLEEPTIME to 60. Set it back to 300 seconds.

Will report back asap.

mp12 · Sep 29, 2016, 8:22 AM

For now the IST did not reappear.

But it seems that the FOGPingHosts service isn’t working properly.
The last entry in the pinghost.log is before I upgraded to RC12.

root     10085     1  0 15:50 ?        00:00:00 /usr/bin/php -q /opt/fog/service/FOGPingHosts/FOGPingHosts
root     10088 10085  0 15:50 ?        00:00:00 [FOGPingHosts] <defunct>

Tom Elliott · Sep 29, 2016, 2:47 PM

@mp12 I forget what os you’re running. I suspect there could be an error in code only because I can’t test before I push while I’m at work.

Anything in the apache error logs?

Anything if you try: systemctl status -l FOGPingHosts.service

Tom Elliott · Sep 30, 2016, 8:40 AM

I found the typo, and this typo is now corrected for too.

mp12 · Sep 30, 2016, 2:45 AM

@Tom-Elliott

Running Ubuntu 14.04

Here the apache error.log after upgrading to Version 28 this morning.

[Fri Sep 30 08:31:18.468858 2016] [:error] [pid 29186] [client x.x.x.x:58533] PHP Warning:  PDO::query(): Error reading result set's header in /var/www/fog/lib/db/pdodb.class.php on line 195, referer: http://x.x.x.x/fog/management/index.php?node=home
[Fri Sep 30 08:31:18.478751 2016] [:error] [pid 29212] [client x.x.x.x:58982] PHP Warning:  PDO::query(): MySQL server has gone away in /var/www/fog/lib/db/pdodb.class.php on line 195, referer: http://x.x.x.x/fog/management/index.php?node=home
[Fri Sep 30 08:31:18.478768 2016] [:error] [pid 29212] [client x.x.x.x:58982] PHP Warning:  PDO::query(): Error reading result set's header in /var/www/fog/lib/db/pdodb.class.php on line 195, referer: http://x.x.x.x/fog/management/index.php?node=home
[Fri Sep 30 08:31:24.923284 2016] [:error] [pid 29211] [client x.x.x.x:59015] PHP Warning:  PDO::__construct(): MySQL server has gone away in /var/www/fog/lib/db/pdodb.class.php on line 151, referer: http://x.x.x.x/fog/management/index.php?node=service&sub=edit
[Fri Sep 30 08:31:27.014932 2016] [mpm_prefork:notice] [pid 1401] AH00169: caught SIGTERM, shutting down
[Fri Sep 30 08:31:49.639445 2016] [mpm_prefork:notice] [pid 15677] AH00163: Apache/2.4.18 (Ubuntu) OpenSSL/1.0.2j configured -- resuming normal operations
[Fri Sep 30 08:31:49.639485 2016] [core:notice] [pid 15677] AH00094: Command line: '/usr/sbin/apache2'

Okay FOGPingHosts seems to work fine.

The IST error is gone.

Here is what I get from the logs now (host with a fresh “reset encryption data”)

 30.09.2016 10:23 Main Overriding exception handling
 30.09.2016 10:23 Main Bootstrapping Zazzles
 30.09.2016 10:23 Controller Initialize
 30.09.2016 10:23 Zazzles Creating main thread
 30.09.2016 10:23 Zazzles Service construction complete
 30.09.2016 10:23 Controller Start

 30.09.2016 10:23 Service Starting service
 30.09.2016 10:23 Bus Became bus server
 30.09.2016 10:23 Bus {
  "self": true,
  "channel": "Status",
  "data": "{\r\n  \"action\": \"load\"\r\n}"
}
 30.09.2016 10:23 Bus Emmiting message on channel: Status
 30.09.2016 10:23 Service Invoking early JIT compilation on needed binaries

------------------------------------------------------------------------------
--------------------------------Authentication--------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Version: 0.11.5
 30.09.2016 10:23 Client-Info OS:      Windows
 30.09.2016 10:23 Middleware::Authentication Waiting for authentication timeout to pass
 30.09.2016 10:23 Middleware::Communication Download: http://x.x.x.x/fog/management/other/ssl/srvpublic.crt
 30.09.2016 10:23 Data::RSA FOG Server CA cert found
 30.09.2016 10:23 Middleware::Authentication Cert OK
 30.09.2016 10:23 Middleware::Communication POST URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&authorize&newService
 30.09.2016 10:23 Middleware::Response Success
 30.09.2016 10:23 Middleware::Authentication Authenticated


 30.09.2016 10:23 Bus Registering ParseBus in channel Power
 30.09.2016 10:23 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:96:59:0C|02:00:4C:4F:4F:50|00:50:56:C0:00:01|00:50:56:C0:00:08||00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0|00:00:00:00:00:00:00:E0&newService&json
 30.09.2016 10:23 Middleware::Communication ERROR: No response recieved
 30.09.2016 10:23 Middleware::Response Success
 30.09.2016 10:23 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 30.09.2016 10:23 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 30.09.2016 10:23 Service Creating user agent cache
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.
 30.09.2016 10:23 Service Initializing modules

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response Success
------------------------------------------------------------------------------


------------------------------------------------------------------------------
----------------------------------TaskReboot----------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------HostnameChanger-------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------SnapinClient---------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PrinterManager--------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
--------------------------------PowerManagement-------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 30.09.2016 10:23 Client-Info Client Version: 0.11.5
 30.09.2016 10:23 Client-Info Client OS:      Windows
 30.09.2016 10:23 Client-Info Server Version: 28
 30.09.2016 10:23 Middleware::Response ERROR: Unable to get subsection
 30.09.2016 10:23 Middleware::Response ERROR: Object reference not set to an instance of an object.
 30.09.2016 10:23 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 30.09.2016 10:23 Middleware::Response Success
 30.09.2016 10:23 Service Sleeping for 87 seconds

Thanks for the great support!

Tom Elliott · Oct 4, 2016, 8:59 AM

@mp12 Found the issues with it not returning the data. I switched back to foreach looping over array_walk/array_map, but forgot to switch the skip portions to their relevant areas.

array_walk($someArrayHere, function(&$arrayItemValue, &$arrayItemKey) {
    if (!$arrayItemValue) {
        return;
    }
});

Needs to be, in foreach loop:

foreach ($someArrayHere as $arrayItemKey => &$arrayItemValue) {
    if (!$arrayItemValue) {
        continue;
    }
}

I forgot to switch the “return” to “continue” originally, so it was returning the function to what called it, not simply skipping to the start of the loop again.

mp12 · Oct 4, 2016, 8:59 AM

@Tom-Elliott

I update to version RC-12 (42) this morning. Problem solved!
Thanks a lot!

Invalid security token

Server

Client

Description

213

12.0k

17.3k

155.2k