Project

General

Profile

Actions

Bug #3838

closed

First agent inventory is truncated on server and thus cannot be processed

Added by Olivier Mauras over 10 years ago. Updated about 9 years ago.

Status:
Rejected
Priority:
1
Category:
Web - Nodes & inventories
Target version:
-
Severity:
UX impact:
User visibility:
Effort required:
Priority:
Name check:
Fix check:
Regression:

Description

I installed several agents and noted that the first initialization results in an invalid truncated inventory file on the rudder server.

Here's the rudder log:

[2013-08-14 09:46:15] INFO  com.normation.inventory.provisioning.endpoint.FusionReportEndpoint - New input report: 'oratest-2013-08-14-09-36-45.ocs'
[2013-08-14 09:46:15] ERROR com.normation.inventory.provisioning.endpoint.FusionReportEndpoint - Error when trying to parse report: 
Can't parse the input report, aborting
cause: Cannot parse uploaded file as an XML Fusion Inventory report

[2013-08-14 09:46:15] ERROR com.normation.inventory.provisioning.endpoint.FusionReportEndpoint - Exception was: 
org.xml.sax.SAXParseException: XML document structures must start and end within the same entity.
        at com.sun.org.apache.xerces.internal.util.ErrorHandlerWrapper.createSAXParseException(ErrorHandlerWrapper.java:198) ~[na:1.7.0_19]
[2013-08-14 09:46:29] INFO  com.normation.rudder.services.servers.NodeConfigurationServiceImpl - Configuration of following nodes were updated, their promises are going to be written: ec734cb7-d2d3-45fb-8200-bd527262ccb2, root
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Closing report RuleId(root-DP)
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Closing report RuleId(inventory-all)
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Closing report RuleId(hasPolicyServer-root)
[2013-08-14 09:46:30] WARN  com.normation.rudder.services.reports.ReportingServiceImpl - Somewhere in the expansion of variables, the bounded variable TRACKINGKEY for TRACKINGKEY in DirectiveVal inventory-all was lost
[2013-08-14 09:46:30] WARN  com.normation.rudder.services.reports.ReportingServiceImpl - Somewhere in the expansion of variables, the bounded variable TRACKINGKEY for TRACKINGKEY in DirectiveVal inventory-all was lost
[2013-08-14 09:46:30] WARN  com.normation.rudder.services.reports.ReportingServiceImpl - Somewhere in the expansion of variables, the bounded variable TRACKINGKEY for TRACKINGKEY in DirectiveVal common-root was lost
[2013-08-14 09:46:30] WARN  com.normation.rudder.services.reports.ReportingServiceImpl - Somewhere in the expansion of variables, the bounded variable TRACKINGKEY for TRACKINGKEY in DirectiveVal common-root was lost
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Saving expected report for rule inventory-all
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Saving expected report for rule root-DP
[2013-08-14 09:46:30] INFO  com.normation.rudder.repository.jdbc.RuleExpectedReportsJdbcRepository - Saving expected report for rule hasPolicyServer-root

/var/log/rudder/httpd/error.log around the same timestamp gives me this:

[Wed Aug 14 09:45:12 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/nodes
[Wed Aug 14 09:45:12 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/32362328020/1fojs0nqlmxqbar3k52gtlo8e/F9555048506034KSZCH, referer: http://rudder/rudder/secure/nodeManager/nodes
[Wed Aug 14 09:45:23 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:45:23 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/33876255456/1fojs0nqlmxqbar3k52gtlo8e/F9555048507235QFQLV, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:45:49 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:45:49 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/31393631362/1fojs0nqlmxqbar3k52gtlo8e/F955504850770PVLQJR, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:46:24 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:46:24 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/65418000988/1fojs0nqlmxqbar3k52gtlo8e/F955504850817HPZNGN, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:47:44 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/index.html
[Wed Aug 14 09:47:44 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/96460750717/40d49lt7pi5p1coqveik013m5/F955504850872DNUAVF, referer: http://rudder/rudder/secure/index.html
[Wed Aug 14 09:47:46 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/nodes
[Wed Aug 14 09:47:46 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/94608047803/40d49lt7pi5p1coqveik013m5/F955504850895IMJBKQ, referer: http://rudder/rudder/secure/nodeManager/nodes
[Wed Aug 14 09:47:48 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:47:48 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/76358653381/40d49lt7pi5p1coqveik013m5/F9555048509023HEDLQ, referer: http://rudder/rudder/secure/nodeManager/manageNewNode
[Wed Aug 14 09:47:54 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/administration/apiManagement
[Wed Aug 14 09:47:54 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/31327420549/40d49lt7pi5p1coqveik013m5/F9555048509354WAIVA, referer: http://rudder/rudder/secure/administration/apiManagement
[Wed Aug 14 09:49:09 2013] [error] [client 10.0.199.52] (70007)The timeout specified has expired: proxy: error reading status line from remote server localhost, referer: http://rudder/rudder/secure/administration/apiManagement
[Wed Aug 14 09:49:09 2013] [error] [client 10.0.199.52] proxy: Error reading from remote server returned by /rudder/comet_request/20226886345/40d49lt7pi5p1coqveik013m5/F9555048509354WAIVA, referer: http://rudder/rudder/secure/administration/apiManagement  

Running the agent a second time with "-KI -D force_inventory" seems to make it work.

Actions #1

Updated by Nicolas PERRON over 10 years ago

  • Description updated (diff)
  • Status changed from New to Discussion
  • Assignee set to Olivier Mauras

Thanks for the report.

If I understand well, every inventory made for the first time is wrongly formed but not the other times ?

Could you provide us the truncated file ? or a part of it ?

Actions #2

Updated by Olivier Mauras over 10 years ago

I'll try deploying a new agent and see if i can reproduce it to send you the truncated file.

Actions #3

Updated by Nicolas PERRON over 10 years ago

Olivier Mauras wrote:

I'll try deploying a new agent and see if i can reproduce it to send you the truncated file.

Did you succeed to reproduce it ?

Actions #4

Updated by Olivier Mauras over 10 years ago

Haven't been able to reproduce it. Installed new agent this morning, run it with force_inventory, it went flawless.
Think we can close this bug, i'll reopen it if i see it happening again.

Actions #5

Updated by Nicolas PERRON over 10 years ago

  • Status changed from Discussion to Rejected

Olivier Mauras wrote:

Haven't been able to reproduce it. Installed new agent this morning, run it with force_inventory, it went flawless.
Think we can close this bug, i'll reopen it if i see it happening again.

Ok, thank you for the news about it. Then I reject thie issue.

Actions #6

Updated by Benoît PECCATTE about 9 years ago

  • Category changed from 26 to Web - Nodes & inventories
Actions

Also available in: Atom PDF