[RC21] ERROR: Unable to get subsection

mp12 · Nov 9, 2016, 2:33 PM

Server

FOG Version: FOG 1.3.0 RC-21 (6014)
OS: Ubuntu 14.04

Client

Service Version: 0.11.5
OS: Win7

Description

I reset the encryption data for the client and the whole group twice. Error reappears after 30 min.

------------------------------------------------------------------------------

 09.11.2016 14:35 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 09.11.2016 14:35 Middleware::Response Success
 09.11.2016 14:35 Service Sleeping for 132 seconds
 09.11.2016 14:37 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=02:00:4C:4F:4F:50|00:0C:29:87:7E:B0|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
 09.11.2016 14:37 Middleware::Communication ERROR: No response recieved
 09.11.2016 14:37 Middleware::Response Success
 09.11.2016 14:37 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 09.11.2016 14:37 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

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

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 09.11.2016 14:37 Client-Info Client Version: 0.11.5
 09.11.2016 14:37 Client-Info Client OS:      Windows
 09.11.2016 14:37 Client-Info Server Version: 1.3.0-RC-21
 09.11.2016 14:37 Middleware::Response Success
------------------------------------------------------------------------------


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

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

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

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

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

mp12 · Nov 9, 2016, 3:07 PM

I reset the encryption data at 15:29 and now it is 16:00.

In the hosts column hostSecTime the entry shows 2016-11-09 15:59:31
No idea if this has anything to do with it.

------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 09.11.2016 15:58 Client-Info Client Version: 0.11.5
 09.11.2016 15:58 Client-Info Client OS:      Windows
 09.11.2016 15:58 Client-Info Server Version: 1.3.0-RC-21
 09.11.2016 15:58 Middleware::Response Module is disabled on the host
------------------------------------------------------------------------------

 09.11.2016 15:58 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 09.11.2016 15:58 Middleware::Response Success
 09.11.2016 15:58 Service Sleeping for 85 seconds
 09.11.2016 16:00 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=02:00:4C:4F:4F:50|00:0C:29:87:7E:B0|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
 09.11.2016 16:00 Middleware::Communication ERROR: No response recieved
 09.11.2016 16:00 Middleware::Response Success
 09.11.2016 16:00 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 09.11.2016 16:00 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

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

Tom Elliott · Nov 9, 2016, 3:56 PM

I believe I found the issue. I fixed it and realized the “fix” was not proper, but was more appropriately handled a bit different (missing one component). I’m only going to guess here, but this host is not already registered?

I’ve tried in the working-RC-22 to make sure the requestClientInfo call returns the data as appropriate now.

Essentially what was happening (I think) is the host is not valid/registered/found in the system. There should’ve been a return of the data immediately in json form, which you can see the change was doing, but the return goes to a function that is more or less specific to the direct service calls.

However, the return needs to be echoed if another element is throwing an immediate error.
You can see this here:

https://github.com/FOGProject/fogproject/commit/385f6e1f087cab360933115e99f5694e62eecc60

Tom Elliott · Nov 9, 2016, 6:49 PM

RC-22 officially release.

Please update to validate/verify as needed.

Thank you.

mp12 · Nov 10, 2016, 7:19 AM

@Tom-Elliott

The host(s) were registered before. Will check if RC-22 fixes this error.

mp12 · Nov 10, 2016, 9:47 AM

@Tom-Elliott

The error still shows up.

I reset the encryption on three hosts for testing and rebooted them. After 30 minutes the connection fails on all three hosts with the error seen above.

Tom Elliott · Nov 10, 2016, 3:55 AM

@mp12 can you run a browser call to http://fog-server/fog/management/index,php?sub=requestClientInfo&mac=macofsubsectionhost&newService&json

mp12 · Nov 10, 2016, 10:07 AM

@Tom-Elliott

I rechecked the error and it was a little bit different then before. Sorry!
I now get Invalid host certificate

Full error:

 10.11.2016 10:46 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 10.11.2016 10:46 Middleware::Response Success
 10.11.2016 10:46 Service Sleeping for 138 seconds
 10.11.2016 10:48 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:95:DF:7B|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
 10.11.2016 10:48 Middleware::Response Invalid host certificate
 10.11.2016 10:48 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 10.11.2016 10:48 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

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

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 10.11.2016 10:48 Client-Info Client Version: 0.11.5
 10.11.2016 10:48 Client-Info Client OS:      Windows
 10.11.2016 10:48 Client-Info Server Version: 1.3.0-RC-22
 10.11.2016 10:48 Middleware::Response Success
------------------------------------------------------------------------------

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

.....

Here the output running http://fogserver/fog/management/index.php?sub=requestClientInfo&mac=B8:CA:3A:95:DF:7B&newService&json

{"error":"ihc"}

Tom Elliott · Nov 10, 2016, 10:09 AM

@mp12 how long will you be available

mp12 · Nov 10, 2016, 10:55 AM

@Tom-Elliott

Sporadically for the next 4 hours.

I checked the DB table hosts and noticed that the column hostPubKey emptys every 30 minutes after the host checked in.
So for 30 minutes the hosts are fine and then the connection breaks.

Tom Elliott · Nov 10, 2016, 10:56 AM

@mp12 that’s kind of intended. The idea to to reset the key so as to prevent hijacking. I can fix fairly quickly, kind of. That’s why ask ask for availability

Tom Elliott · Nov 10, 2016, 11:34 AM

I’ve pushed up to the working 23 branch what I hope will fix this.

invalid host or not, the client wants to see the rest of the services (which none were sent).

IHC (Invalid Host Certificate) wasn’t coded to be json received, so the trick is trying to figure out when to send all the data vs. just the error. In our case here (the {"error":"ihc"} you saw) the client (as far as I can tell) is actually requiring it to be seen as #!ihc rather than a json string. I’ve pushed code to ensure that ihc will be thrown properly, as well as passed the data for the client in the other case (invalid host) – though I know this isn’t your issue.

Tom Elliott · Nov 15, 2016, 1:17 PM

Confirmed changes in RC-23 branch are working as intended now.

Duncan · Nov 15, 2016, 1:17 PM

@Tom-Elliott

I seem to have this issue with RC22. Just to confirm upgrading to RC23 will resolve the issue.

------------------------------------------------------------------------------
----------------------------------UserTracker---------------------------------
------------------------------------------------------------------------------
 15/11/2016 12:53 Client-Info Client Version: 0.11.5
 15/11/2016 12:53 Client-Info Client OS:      Windows
 15/11/2016 12:53 Client-Info Server Version: 1.3.0-RC-22
 15/11/2016 12:53 Middleware::Response ERROR: Unable to get subsection
 15/11/2016 12:53 Middleware::Response ERROR: Object reference not set to an instance of an object.
 15/11/2016 12:53 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&configure&newService&json
 15/11/2016 12:53 Middleware::Response Success
 15/11/2016 12:53 Service Sleeping for 85 seconds
 15/11/2016 12:55 Middleware::Communication URL: http://x.x.x.x/fog/management/index.php?sub=requestClientInfo&mac=88:51:FB:53:AE:37&newService&json
 15/11/2016 12:55 Middleware::Response Invalid host certificate
 15/11/2016 12:55 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?clientver&newService&json
 15/11/2016 12:55 Middleware::Communication URL: http://x.x.x.x/fog/service/getversion.php?newService&json

 15/11/2016 12:55 Service Creating user agent cache
 15/11/2016 12:55 Middleware::Response ERROR: Unable to get subsection
 15/11/2016 12:55 Middleware::Response ERROR: Object reference not set to an instance of an object.
 15/11/2016 12:55 Middleware::Response ERROR: Unable to get subsection
 15/11/2016 12:55 Middleware::Response ERROR: Object reference not set to an instance of an object.
 15/11/2016 12:55 Middleware::Response ERROR: Unable to get subsection
 15/11/2016 12:55 Middleware::Response ERROR: Object reference not set to an instance of an object.

------------------------------------------------------------------------------
---------------------------------ClientUpdater--------------------------------
------------------------------------------------------------------------------
 15/11/2016 12:55 Client-Info Client Version: 0.11.5
 15/11/2016 12:55 Client-Info Client OS:      Windows
 15/11/2016 12:55 Client-Info Server Version: 1.3.0-RC-22
 15/11/2016 12:55 Middleware::Response Success
------------------------------------------------------------------------------


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

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

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

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

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

Tom Elliott · Nov 15, 2016, 1:19 PM

@Duncan yes.

[RC21] ERROR: Unable to get subsection

Server

Client

Description

212

12.0k

17.3k

155.2k