Forum Post: RE: Unable to connect to Progress On-Premise from the Data Direct Cloud, again

[jbeisch]

Here's the log: ============================================================== Client Build #Unknown Started: 2014-01-15T20-11-34 ============================================================== 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Loading settings from registry key Software\Progress Software Corporation\DDCloudOpAcc 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key UdpServer, type REG_SZ, using value 'notification.datadirectcloud.com' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key UdpPort, type REG_SZ, using value '11235' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ClientID, type REG_SZ, using value 'jbeisch' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ConnectTimeoutSecsUdp, type DWORD, using value '60' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ConnectTimeoutSecsTcp, type DWORD, using value '60' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ConnectMaxErrorRetries, type DWORD, using value '2' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ConnectFailedSleepSecs, type DWORD, using value '60' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key KeepAwakeIntervalUdp, type DWORD, using value '15' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key KeepAwakeIntervalTcp, type DWORD, using value '15' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key DeviceId, type REG_SZ, using value '07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key TcpServer, type REG_SZ, using value 'notification.datadirectcloud.com' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key TcpPort, type REG_SZ, using value '11235' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key RestServer, type REG_SZ, using value 'service.datadirectcloud.com' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key RestPort, type REG_SZ, using value 'https' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key ConnectionType, type DWORD, using value '1' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key OnPremiseDASPort, type REG_SZ, using value '10180' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key OnPremiseDAS, type REG_SZ, using value 'localhost' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key OnPremisePort, type REG_SZ, using value 'https' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key OnPremiseServer, type REG_SZ, using value 'opas.datadirectcloud.com' 2014-01-15T20:11:34.0+00:00 INFO [CRegKeyEx::LoadValues] Result = 0 reading key RestServiceEntry, type REG_SZ, using value 'cloudservice/CloudSql' 2014-01-15T20:11:34.0+00:00 DETAILS [CServerMonitor::startMonitor] Starting notification server monitor 2014-01-15T20:11:34.0+00:00 DETAILS [CServerMonitor::asyncStartup] Setting timer for asynchronous startup in 100ms 2014-01-15T20:11:34.0+00:00 DETAILS [serverMonitorWorkerFunc] Notification server monitor thread started, calling run to allow async operation 2014-01-15T20:11:34.0+00:00 DETAILS [CServerMonitor::beginMonitor] Initializing monitor and calling asyncStartup() 2014-01-15T20:11:34.0+00:00 DETAILS [CServerMonitor::getNotificationKey] Registering with ServiceAPI to get notification key 2014-01-15T20:11:34.0+00:00 DETAILS [CServiceAPI::getDeviceNotificationKey] Device id = 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2, payload = {"name":"DMSIDEVEL11","description":"Windows Server 2008 R2 Enterprise"} 2014-01-15T20:11:34.0+00:00 DETAILS [CServiceAPI::authenticateClient] Authenticating client 2014-01-15T20:11:34.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authenticating user: jbeisch using HTTPS 2014-01-15T20:11:35.0+00:00 DETAILS [CSecureSocket::connect] Calling resolver.resolve for host 'service.datadirectcloud.com', port 'https' 2014-01-15T20:11:35.0+00:00 DETAILS [CSecureSocket::connect] Resolved endpoint = 107.23.123.251 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authenticating userId 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] REST Request Headers: 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] POST /cloudservice/CloudSql/auth HTTP/1.1 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Host: service.datadirectcloud.com 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Connection: close 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readStatusLine] HTTP/1.1 200 OK 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] Processing non-chunked payload, contentLen = 290 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] ContentLen (or current chunk length) = 290 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] Bytes already available in the response object = 290 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] Bytes to read (contentLen - available) = 0 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] BytesToRead is greater than 0 so setting bytesRead = contentLen = 0 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readPayloadString] Appending 0 bytes to payload string starting at offset 290 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authentication result: '{"createdAt":"2014-01-15T20:11:35.451+0000","key":"CuYBLcE+2+fkVfyH4eRJQkBoFEZygYzoWKDpo1YiDv8UfRWOpuqJY61f+vL11JwMhC7i9tvKsv+2rDNsDqZtZ4dnP/7x1nSpDfVNKiIdAf/P14rfpKvEgvnE7dtpJgYRmz+JykvJsvAcTht2cAYFEB5AmS4F7XtAtWPGO7Agov+W9AmvLz9bn4vqc7W/5tg1","expiresAt":"2014-01-15T21:11:35.451+0000"} ' 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::authenticateClient] New authentication good for 3601 seconds 2014-01-15T20:11:35.0+00:00 DETAILS [CSecureSocket::connect] Calling resolver.resolve for host 'service.datadirectcloud.com', port 'https' 2014-01-15T20:11:35.0+00:00 DETAILS [CSecureSocket::connect] Resolved endpoint = 107.21.50.14 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] REST Request Headers: 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] POST /cloudservice/CloudSql/devices/07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 HTTP/1.1 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Host: service.datadirectcloud.com 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Accept: */* 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Content-Type: application/json 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] X-DataDirect-Palette-AuthToken:CuYBLcE+2+fkVfyH4eRJQkBoFEZygYzoWKDpo1YiDv8UfRWOpuqJY61f+vL11JwMhC7i9tvKsv+2rDNsDqZtZ4dnP/7x1nSpDfVNKiIdAf/P14rfpKvEgvnE7dtpJgYRmz+JykvJsvAcTht2cAYFEB5AmS4F7XtAtWPGO7Agov+W9AmvLz9bn4vqc7W/5tg1 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] X-DataDirect-Easyl-AuthToken:CuYBLcE+2+fkVfyH4eRJQkBoFEZygYzoWKDpo1YiDv8UfRWOpuqJY61f+vL11JwMhC7i9tvKsv+2rDNsDqZtZ4dnP/7x1nSpDfVNKiIdAf/P14rfpKvEgvnE7dtpJgYRmz+JykvJsvAcTht2cAYFEB5AmS4F7XtAtWPGO7Agov+W9AmvLz9bn4vqc7W/5tg1 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Content-Length: 72 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] X-Palette-Device: 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] X-Easyl-Device: 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] Connection: close 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::logHeaders] 2014-01-15T20:11:35.0+00:00 ERROR [CServiceAPI::readStatusLine] Response returned with HTTP status code 401 2014-01-15T20:11:35.0+00:00 DETAILS [CServiceAPI::readStatusLine] HTTP/1.1 401 Unauthorized 2014-01-15T20:11:35.0+00:00 ERROR [CServiceAPI::getDeviceNotificationKey] Failed reading response with HTTP error 401, socket error 0, The operation completed successfully 2014-01-15T20:11:36.0+00:00 INFO [CServerMonitor::getNotificationKey] Authentication error when getting Device Notification Key, now doing a forced re-authenticate 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateClient] Authenticating client 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authenticating user: jbeisch using HTTPS 2014-01-15T20:11:36.0+00:00 DETAILS [CSecureSocket::connect] Calling resolver.resolve for host 'service.datadirectcloud.com', port 'https' 2014-01-15T20:11:36.0+00:00 DETAILS [CSecureSocket::connect] Resolved endpoint = 107.23.123.251 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authenticating userId 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] REST Request Headers: 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] POST /cloudservice/CloudSql/auth HTTP/1.1 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Host: service.datadirectcloud.com 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Connection: close 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readStatusLine] HTTP/1.1 200 OK 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] Processing non-chunked payload, contentLen = 290 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] ContentLen (or current chunk length) = 290 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] Bytes already available in the response object = 290 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] Bytes to read (contentLen - available) = 0 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] BytesToRead is greater than 0 so setting bytesRead = contentLen = 0 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readPayloadString] Appending 0 bytes to payload string starting at offset 290 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateUser] Authentication result: '{"createdAt":"2014-01-15T20:11:36.463+0000","key":"CuYBLQC92gQ2AFXdwAumX2s0D/SfwpMV9LlI1jdGjNBeNy4wK2B+9NGfc8jqzDnTZDPLQRSKhniQ2+3kRHxcx6SbxkrjUXo92ZwQeVj8bQH5gNALPwCSgdd2Va0KBXiNbiirmjMgGjc7e5XSqLEjPMJnhyVfTeJBc/mh4wF0fwbC75aodPffaIcjzauNftDa","expiresAt":"2014-01-15T21:11:36.463+0000"} ' 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateClient] New authentication good for 3600 seconds 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::getDeviceNotificationKey] Device id = 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2, payload = {"name":"DMSIDEVEL11","description":"Windows Server 2008 R2 Enterprise"} 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::authenticateClient] Authentication still valid for 3600 seconds 2014-01-15T20:11:36.0+00:00 DETAILS [CSecureSocket::connect] Calling resolver.resolve for host 'service.datadirectcloud.com', port 'https' 2014-01-15T20:11:36.0+00:00 DETAILS [CSecureSocket::connect] Resolved endpoint = 107.21.50.14 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] REST Request Headers: 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] POST /cloudservice/CloudSql/devices/07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 HTTP/1.1 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Host: service.datadirectcloud.com 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Accept: */* 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Content-Type: application/json 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] X-DataDirect-Palette-AuthToken:CuYBLQC92gQ2AFXdwAumX2s0D/SfwpMV9LlI1jdGjNBeNy4wK2B+9NGfc8jqzDnTZDPLQRSKhniQ2+3kRHxcx6SbxkrjUXo92ZwQeVj8bQH5gNALPwCSgdd2Va0KBXiNbiirmjMgGjc7e5XSqLEjPMJnhyVfTeJBc/mh4wF0fwbC75aodPffaIcjzauNftDa 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] X-DataDirect-Easyl-AuthToken:CuYBLQC92gQ2AFXdwAumX2s0D/SfwpMV9LlI1jdGjNBeNy4wK2B+9NGfc8jqzDnTZDPLQRSKhniQ2+3kRHxcx6SbxkrjUXo92ZwQeVj8bQH5gNALPwCSgdd2Va0KBXiNbiirmjMgGjc7e5XSqLEjPMJnhyVfTeJBc/mh4wF0fwbC75aodPffaIcjzauNftDa 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Content-Length: 72 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] X-Palette-Device: 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] X-Easyl-Device: 07ea3e8a-0e81-4bf4-8cfc-de295aa04ba2 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] Connection: close 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::logHeaders] 2014-01-15T20:11:36.0+00:00 ERROR [CServiceAPI::readStatusLine] Response returned with HTTP status code 401 2014-01-15T20:11:36.0+00:00 DETAILS [CServiceAPI::readStatusLine] HTTP/1.1 401 Unauthorized 2014-01-15T20:11:36.0+00:00 ERROR [CServiceAPI::getDeviceNotificationKey] Failed reading response with HTTP error 401, socket error 0, The operation completed successfully 2014-01-15T20:11:36.0+00:00 WARN [CServerMonitor::getNotificationKey] Call to api.getDeviceNotificationKey() failed 2014-01-15T20:11:36.0+00:00 INFO [CServerMonitor:nDisconnect] Sleeping for 60 seconds before next retry It still errors and from the log it looks like something isn't connecting right to the DDC resources. I have the same username and password in the On-Premise configuration as I use to log into the website to do the testing and view my DDC resources.

Continue reading...