locked
Data cache could not be sent to Publishing Server URL RRS feed

  • Question

  • Wondering if someone can provide some suggestions as to what to try.

    Working with App-V Mgmt Server 4.5 RC1 v. 4.5.0.1308
    Have two App-V Mgmt servers that are NLB that resolve to an internal DNS alias.
    Using FileShare for content directory and is specified as File HREF in the OSD Files. The client is communicating to both servers (success in the logs/event viewer) and is successfully streaming/updating applications.

    Issue: Provider Policies and Reporting is not being sent down/sent up.

    This error is appearing in the event log.

    The Application Virtualization Client report data cache could not be sent to Publishing Server URL 'rtsp://appv.domain.net:554/' (rc = 1690630A-200001F4).

    Event ID: 3144

    App-V Service is running / telnet port 554 is success / UDL Files provide success.

    Any ideas what I haven't set yet? Sure its something trivial...

    Cheers...


    Wednesday, July 16, 2008 6:53 PM

Answers

All replies

  • One thing to check is that you have write permissions to your AppV user data which is %appdata% by default.

    Thursday, July 17, 2008 12:22 AM
  • Thanks. No luck on that but appreciate the suggestion. I turned on verbose logging on the server and got copied in the following snippet (if anyone has anything to try)...

    [2008-07-17 10:20:49.272] SoftgridMgmtServer 3280 5656 SW_ServerAuthenticationTask::Authorize - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.272] SoftgridMgmtServer 3280 5656 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 5 65535 "Request has unsupported method."
    [2008-07-17 10:20:49.272] SoftgridMgmtServer 3280 5656 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.272] SoftgridMgmtServer 3280 5656 CXmlReader::LoadXmlDocument - "Default Provider" SoftgridTestUser - 5 65535 "Successfully initialized COM on thread 21297632"
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 SW_SQLDataConnection::BindParameters - "Default Provider" SoftgridTestUser - 5 65535 "Got NULL value for non-nullable parameter."
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 SW_SQLDataConnection:Tongue TiedearchInternal - "Default Provider" SoftgridTestUser - 5 65535 "Failed to bind values to statement. [SELECT * FROM APPLICATION_USAGE WHERE server_name = ? AND app_version = ? AND start_time = ? AND username = ? AND app_name = ? AND host_id = ?]"
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 SW_SQLDataConnection::GetRecord - "Default Provider" SoftgridTestUser - 5 65535 "No records found."
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 ProcessReportingData - "Default Provider" SoftgridTestUser - 5 65535 "Failed to get application usage information record from the database"
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 CXmlReader::~CXmlReader - "Default Provider" SoftgridTestUser - 5 65535 "Uninitializing COM on thread 21297688"
    [2008-07-17 10:20:49.366] SoftgridMgmtServer 3280 5656 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" SoftgridTestUser - 5 65535 "Response: [RTSP/1.0 500 Internal Server Error]"
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5496 5540 SW_LoadBalancer::ForwardRequest - - - - 5 65535 "Sending request 3 to core 5556"
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 3280 5660 SW_RTSPHandler::ReadHeaders - - - - 5 65535 "Socket read error handle [21559884], error code (21560020)."
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 3280 5660 SW_ConnectionManager::CloseConnection - - - - 5 65535 "Delete Connection (1096)"
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 3656 SW_CoreEventHandler::handle_input - - - - 5 65535 "Received command 0x6fca8 from dispatcher."
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 3656 SW_ConnectionManager::CreateConnection - - - - 5 65535 "Create Connection (1092)"
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 3656 SW_ConnectionManager::CreateConnection - - - - 4 42263 "RTSP connection established. Client IP: 114.19.10.154:1168
    "
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 6128 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [筈ÔՍﶤ佼Ā筈ÔÛ]"
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 6128 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" UNAUTHENTICATED - 4 44879 "No authentication header for address: [114.19.10.154:1168].
    "
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 6128 SW_AuthenticateRequestTask::Run - "Default Provider" UNAUTHENTICATED - 5 65535 "All authentication handlers failed."
    [2008-07-17 10:20:49.382] SoftgridMgmtServer 5556 6128 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" UNAUTHENTICATED - 5 65535 "Response: [RTSP/1.0 401 Unauthorized]"
    [2008-07-17 10:20:49.397] SoftgridMgmtServer 5556 6140 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [hĜՍ佼ĀhĜÛ]"
    [2008-07-17 10:20:49.397] SoftgridMgmtServer 5556 6140 SW_SSPIAuthenticationHandler::AcquireCredentials - "Default Provider" UNAUTHENTICATED - 5 65535 "Got credentials for name: SoftgridMgmtServer$@TestDomain.NET"
    [2008-07-17 10:20:49.397] SoftgridMgmtServer 5556 6140 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" UNAUTHENTICATED - 5 65535 -
    [2008-07-17 10:20:49.397] SoftgridMgmtServer 5556 6140 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" UNAUTHENTICATED - 5 65535 "Response: [RTSP/1.0 401 Unauthorized]"
    [2008-07-17 10:20:49.413] SoftgridMgmtServer 5556 6124 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [挨ÐՍ佼Ā挨ÐÛ]"
    [2008-07-17 10:20:49.413] SoftgridMgmtServer 5556 6124 SW_SSPIAuthenticationHandler::AcquireCredentials - "Default Provider" UNAUTHENTICATED - 5 65535 "Got credentials for name: SoftgridMgmtServer$@TestDomain.NET"
    [2008-07-17 10:20:49.413] SoftgridMgmtServer 5556 6124 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 5 65535 "Failed to get context expiry time."
    [2008-07-17 10:20:49.413] SoftgridMgmtServer 5556 6124 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 5 65535 "Function not supported by package."
    [2008-07-17 10:20:49.413] SoftgridMgmtServer 5556 6124 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 4 42258 "User authenticated from address 114.19.10.154:1168.
    "
    [2008-07-17 10:20:49.428] SoftgridMgmtServer 5556 6124 SW_ServerAuthenticationTask::Authorize - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.428] SoftgridMgmtServer 5556 6124 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 5 65535 "Request has unsupported method."
    [2008-07-17 10:20:49.428] SoftgridMgmtServer 5556 6124 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.428] SoftgridMgmtServer 5556 6124 CXmlReader::LoadXmlDocument - "Default Provider" SoftgridTestUser - 5 65535 "Successfully initialized COM on thread 21297632"
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 SW_SQLDataConnection::BindParameters - "Default Provider" SoftgridTestUser - 5 65535 "Got NULL value for non-nullable parameter."
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 SW_SQLDataConnection:Tongue TiedearchInternal - "Default Provider" SoftgridTestUser - 5 65535 "Failed to bind values to statement. [SELECT * FROM APPLICATION_USAGE WHERE server_name = ? AND app_version = ? AND start_time = ? AND username = ? AND app_name = ? AND host_id = ?]"
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 SW_SQLDataConnection::GetRecord - "Default Provider" SoftgridTestUser - 5 65535 "No records found."
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 ProcessReportingData - "Default Provider" SoftgridTestUser - 5 65535 "Failed to get application usage information record from the database"
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 CXmlReader::~CXmlReader - "Default Provider" SoftgridTestUser - 5 65535 "Uninitializing COM on thread 21297688"
    [2008-07-17 10:20:49.475] SoftgridMgmtServer 5556 6124 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" SoftgridTestUser - 5 65535 "Response: [RTSP/1.0 500 Internal Server Error]"
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 5496 5540 SW_LoadBalancer::ForwardRequest - - - - 5 65535 "Sending request 3 to core 3348"
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3644 SW_CoreEventHandler::handle_input - - - - 5 65535 "Received command 0x6fca8 from dispatcher."
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 5556 3368 SW_RTSPHandler::ReadHeaders - - - - 5 65535 "Socket read error handle [22084172], error code (22084308)."
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 5556 3368 SW_ConnectionManager::CloseConnection - - - - 5 65535 "Delete Connection (1092)"
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3644 SW_ConnectionManager::CreateConnection - - - - 5 65535 "Create Connection (1088)"
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3644 SW_ConnectionManager::CreateConnection - - - - 4 42263 "RTSP connection established. Client IP: 114.19.10.154:1169
    "
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3664 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [愈Č懻佼Ā愈Čመċ]"
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3664 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" UNAUTHENTICATED - 4 44879 "No authentication header for address: [114.19.10.154:1169].
    "
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3664 SW_AuthenticateRequestTask::Run - "Default Provider" UNAUTHENTICATED - 5 65535 "All authentication handlers failed."
    [2008-07-17 10:20:49.491] SoftgridMgmtServer 3348 3664 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" UNAUTHENTICATED - 5 65535 "Response: [RTSP/1.0 401 Unauthorized]"
    [2008-07-17 10:20:49.507] SoftgridMgmtServer 3348 3240 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [윐ě懻佼Ā윐ěመċ]"
    [2008-07-17 10:20:49.507] SoftgridMgmtServer 3348 3240 SW_SSPIAuthenticationHandler::AcquireCredentials - "Default Provider" UNAUTHENTICATED - 5 65535 "Got credentials for name: SoftgridMgmtServer$@TestDomain.NET"
    [2008-07-17 10:20:49.507] SoftgridMgmtServer 3348 3240 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" UNAUTHENTICATED - 5 65535 -
    [2008-07-17 10:20:49.507] SoftgridMgmtServer 3348 3240 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" UNAUTHENTICATED - 5 65535 "Response: [RTSP/1.0 401 Unauthorized]"
    [2008-07-17 10:20:49.522] SoftgridMgmtServer 3348 3668 SW_RTSPHandler:Stick out tonguearseHeaders - - - - 5 65535 "Request: [⋰Ð懻佼Ā⋰Ðመċ]"
    [2008-07-17 10:20:49.522] SoftgridMgmtServer 3348 3668 SW_SSPIAuthenticationHandler::AcquireCredentials - "Default Provider" UNAUTHENTICATED - 5 65535 "Got credentials for name: SoftgridMgmtServer$@TestDomain.NET"
    [2008-07-17 10:20:49.522] SoftgridMgmtServer 3348 3668 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 5 65535 "Failed to get context expiry time."
    [2008-07-17 10:20:49.522] SoftgridMgmtServer 3348 3668 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 5 65535 "Function not supported by package."
    [2008-07-17 10:20:49.522] SoftgridMgmtServer 3348 3668 SW_SSPIAuthenticationHandler::Authenticate - "Default Provider" SoftgridTestUser - 4 42258 "User authenticated from address 114.19.10.154:1169.
    "
    [2008-07-17 10:20:49.538] SoftgridMgmtServer 3348 3668 SW_ServerAuthenticationTask::Authorize - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.538] SoftgridMgmtServer 3348 3668 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 5 65535 "Request has unsupported method."
    [2008-07-17 10:20:49.538] SoftgridMgmtServer 3348 3668 SW_AuthorizeRequestTask::Run - "Default Provider" SoftgridTestUser - 4 42259 "User authorized.
    "
    [2008-07-17 10:20:49.538] SoftgridMgmtServer 3348 3668 CXmlReader::LoadXmlDocument - "Default Provider" SoftgridTestUser - 5 65535 "Successfully initialized COM on thread 22084064"
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 SW_SQLDataConnection::BindParameters - "Default Provider" SoftgridTestUser - 5 65535 "Got NULL value for non-nullable parameter."
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 SW_SQLDataConnection:Tongue TiedearchInternal - "Default Provider" SoftgridTestUser - 5 65535 "Failed to bind values to statement. [SELECT * FROM APPLICATION_USAGE WHERE server_name = ? AND app_version = ? AND start_time = ? AND username = ? AND app_name = ? AND host_id = ?]"
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 SW_SQLDataConnection::GetRecord - "Default Provider" SoftgridTestUser - 5 65535 "No records found."
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 ProcessReportingData - "Default Provider" SoftgridTestUser - 5 65535 "Failed to get application usage information record from the database"
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 CXmlReader::~CXmlReader - "Default Provider" SoftgridTestUser - 5 65535 "Uninitializing COM on thread 22084120"
    [2008-07-17 10:20:49.585] SoftgridMgmtServer 3348 3668 SW_RTSPHandler:Tongue TiedendResponse - "Default Provider" SoftgridTestUser - 5 65535 "Response: [RTSP/1.0 500 Internal Server Error]"
    [2008-07-17 10:20:49.600] SoftgridMgmtServer 3348 6120 SW_RTSPHandler::ReadHeaders - - - - 5 65535 "Socket read error handle [21559884], error code (21560020)."
    [2008-07-17 10:20:49.600] SoftgridMgmtServer 3348 6120 SW_ConnectionManager::CloseConnection - - - - 5 65535 "Delete Connection (1088)"

    Thursday, July 17, 2008 2:52 PM
  •  NCScouser wrote:
    Using FileShare for content directory and is specified as File HREF in the OSD Files. The client is communicating to both servers (success in the logs/event viewer) and is successfully streaming/updating applications.

    Issue: Provider Policies and Reporting is not being sent down/sent up.

     

    Let's see if I understood correctly, you are using new file -based "streaming" in the OSD (like, HREF="file://...") in your packages, right?

     

    If so, I'm not sure that provider policy settings (like licensing etc.) and/or reporting are actually supported since client is not in that case communicating through rtsp(s) channel with the server, something that is needed for these features?

     

    br,

    Kalle

    Tuesday, August 5, 2008 6:27 PM
    Moderator
  • Licensing would not be supported over FILE but "Log Usage Information"  is as long as client revfreshes to a HWS

     

    • Proposed as answer by znack Tuesday, August 31, 2010 8:43 PM
    • Marked as answer by Aaron.ParkerModerator Friday, December 2, 2011 12:02 PM
    Thursday, August 7, 2008 3:17 AM
  • Oh yeah, that's right. I was reading OP carelessly..

     

    /Kalle

    Thursday, August 28, 2008 10:00 AM
    Moderator
  • Friday, December 2, 2011 7:48 AM