Bug 1371885 - pulp django.request:WARNING: Not Found: /some/path/treeinfo shall be on DEBUG verbosity
Summary: pulp django.request:WARNING: Not Found: /some/path/treeinfo shall be on DEBUG...
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Red Hat Satellite
Classification: Red Hat
Component: Pulp
Version: 6.2.0
Hardware: All
OS: Linux
low
low vote
Target Milestone: Unspecified
Assignee: satellite6-bugs
QA Contact: Ivan Necas
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2016-08-31 11:08 UTC by Pavel Moravec
Modified: 2021-08-30 10:40 UTC (History)
23 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2018-02-21 16:33:23 UTC
Target Upstream Version:


Attachments (Terms of Use)


Links
System ID Private Priority Status Summary Last Updated
Pulp Redmine 2510 0 Normal CLOSED - CURRENTRELEASE django logs warning when responding with 404 2017-01-16 21:01:38 UTC
Red Hat Knowledge Base (Solution) 2184801 0 None None None 2016-08-31 11:08:42 UTC

Description Pavel Moravec 2016-08-31 11:08:43 UTC
Description of problem:
When syncyng some repos, below red herring warning appears in /var/log/messages:

Aug 30 09:39:31 satelliteserver pulp: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/MyOrg/Library/come-content-view/content/dist/rhel/server/7/7Server/x86_64/sat-tools/6.2/os/treeinfo

Quoting mmccune: there is code that checks to see if a repo you are syncing has a kickstart tree. That is it just stating it didnt find one, which is in WARNING level.

Since it is normal a repo does (not) have kickstart tree, that log shall be of DEBUG level rather.


Version-Release number of selected component (if applicable):
Sat 6.2


How reproducible:
100%


Steps to Reproduce:
1. synchronize (some specific?) repo
2. check /var/log/messages


Actual results:
messages having above WARNING


Expected results:
messages not having that warning (just debug log when pulp debugs are enabled)


Additional info:
(not sure why I can't reproduce it on my Satellite - tried various repos to sync, never hit that WARN)

Comment 3 Michael Hrivnak 2016-09-08 20:05:54 UTC
I am also not able to reproduce. I think this may have been fixed in some of the on-demand-sync work that was done, which changed retry behavior in the download workflow.

Please NEEDSINFO me if you figure out how to reproduce it, but otherwise I suggest closing this.

Comment 5 Pavel Moravec 2016-09-24 10:56:38 UTC
(In reply to Michael Hrivnak from comment #3)
> I am also not able to reproduce. I think this may have been fixed in some of
> the on-demand-sync work that was done, which changed retry behavior in the
> download workflow.
> 
> Please NEEDSINFO me if you figure out how to reproduce it, but otherwise I
> suggest closing this.

OK closing it since dont have a reproducer either. Will reopen if I would found one.

Comment 6 ken.zheng 2016-11-08 17:08:18 UTC
Nov 08 12:07:31 satellite.mgmt.cloud.td.com pulp[25788]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/custom/TD_ODBC_Clients/tdodbc-clients/.treeinfo
Nov 08 12:07:32 satellite.mgmt.cloud.td.com pulp[25790]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/custom/TD_ODBC_Clients/tdodbc-clients/treeinfo
Nov 08 12:07:39 satellite.mgmt.cloud.td.com pulp[25790]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/custom/nexus/nexus/.treeinfo
Nov 08 12:07:40 satellite.mgmt.cloud.td.com pulp[25788]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/custom/nexus/nexus/treeinfo
Nov 08 12:07:44 satellite.mgmt.cloud.td.com pulp[25790]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/content/dist/rhel/server/6/6Server/x86_64/sat-tools/6.2/os/.treeinfo
Nov 08 12:07:45 satellite.mgmt.cloud.td.com pulp[25790]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/content/dist/rhel/server/6/6Server/x86_64/sat-tools/6.2/os/treeinfo
Nov 08 12:07:46 satellite.mgmt.cloud.td.com pulp[25788]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/Compute/Library/content/dist/rhel/server/7/7.2/x86_64/kickstart/.treeinfo
Nov 08 12:07:52 satellite.mgmt.cloud.td.com pulp[25790]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/content/dist/rhel/server/6/6Server/x86_64/loadbalancer/os/.treeinfo
Nov 08 12:07:53 satellite.mgmt.cloud.td.com pulp[25789]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/content/dist/rhel/server/6/6Server/x86_64/loadbalancer/os/treeinfo
Nov 08 12:08:00 satellite.mgmt.cloud.td.com pulp[25789]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/TD_Cloud/Library/content/dist/rhel/server/7/7Server/x86_64/sat-tools/6.2/os/.treeinfo

Comment 7 hprakash 2016-12-26 05:54:06 UTC
Reopening the ticket as it is reported recently(linked with the consumer portal's case number)

Comment 8 Michael Hrivnak 2017-01-03 20:23:37 UTC
On further examination, this particular log message appears to happen when a client requests a file from pulp that does not exist in a published repo, and gets a 404.

I reproduced with the following steps:

- sync and publish the repo found here: https://repos.fedorapeople.org/repos/pulp/pulp/fixtures/rpm/
- curl -k https://localhost/pulp/repos/repos/pulp/pulp/fixtures/rpm/helloworld

This resulted in the following message in the system log:

Jan 03 20:20:31 dev pulp[3152]: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/repos/pulp/pulp/fixtures/rpm/helloworld

So I believe the ".treeinfo" message is seen when a yum client attempts to retrieve that file, which legitimately and rightfully does not exist. We should try to prevent django from logging a warning in this case.

Comment 9 pulp-infra@redhat.com 2017-01-03 22:13:45 UTC
The Pulp upstream bug status is at POST. Updating the external tracker on this bug.

Comment 10 pulp-infra@redhat.com 2017-01-03 22:13:48 UTC
The Pulp upstream bug priority is at Normal. Updating the external tracker on this bug.

Comment 11 pulp-infra@redhat.com 2017-01-04 14:01:53 UTC
The Pulp upstream bug status is at MODIFIED. Updating the external tracker on this bug.

Comment 12 pulp-infra@redhat.com 2017-01-04 14:31:46 UTC
All upstream Pulp bugs are at MODIFIED+. Moving this bug to POST.

Comment 13 pulp-infra@redhat.com 2017-01-10 02:01:23 UTC
The Pulp upstream bug status is at ON_QA. Updating the external tracker on this bug.

Comment 14 pulp-infra@redhat.com 2017-01-16 21:01:39 UTC
The Pulp upstream bug status is at CLOSED - CURRENTRELEASE. Updating the external tracker on this bug.

Comment 15 Pavel Moravec 2017-01-24 14:30:55 UTC
(In reply to Michael Hrivnak from comment #8)
> On further examination, this particular log message appears to happen when a
> client requests a file from pulp that does not exist in a published repo,
> and gets a 404.
> 
> I reproduced with the following steps:
> 
> - sync and publish the repo found here:
> https://repos.fedorapeople.org/repos/pulp/pulp/fixtures/rpm/
> - curl -k
> https://localhost/pulp/repos/repos/pulp/pulp/fixtures/rpm/helloworld
> 
> This resulted in the following message in the system log:
> 
> Jan 03 20:20:31 dev pulp[3152]: django.request:WARNING: Not Found:
> /var/www/pub/yum/https/repos/repos/pulp/pulp/fixtures/rpm/helloworld
> 
> So I believe the ".treeinfo" message is seen when a yum client attempts to
> retrieve that file, which legitimately and rightfully does not exist. We
> should try to prevent django from logging a warning in this case.

Today we noticed similar string there:

Jan 24 15:15:08 satellite pulp: django.request:WARNING: Not Found: /var/www/pub/yum/https/repos/Organization_SI/RHEL5_DMZ_SOMETHING_PROD/RHEL5/content/dist/rhel/server/5/5Server/x86_64/os/repodata/repomd.xml

doe it belong to the fix as well? The repomd.xml in metadata is something new..

(no known reproducer, though)

Comment 16 Michael Hrivnak 2017-01-27 11:14:56 UTC
Pavel, either you don't have the fix, or it isn't working as expected. With the fix, "django.request" should never log anything at WARNING. 

To clarify reproduction: All you need to do is request a file from Pulp that doesn't exist. In this case, someone must have requested:

/pulp/repos/Organization_SI/RHEL5_DMZ_SOMETHING_PROD/RHEL5/content/dist/rhel/server/5/5Server/x86_64/os/repodata/repomd.xml

and apparently that file didn't exist.

Comment 17 Pavel Moravec 2017-01-27 11:36:03 UTC
(In reply to Michael Hrivnak from comment #16)
> Pavel, either you don't have the fix, or it isn't working as expected. With
> the fix, "django.request" should never log anything at WARNING. 
> 
> To clarify reproduction: All you need to do is request a file from Pulp that
> doesn't exist. In this case, someone must have requested:
> 
> /pulp/repos/Organization_SI/RHEL5_DMZ_SOMETHING_PROD/RHEL5/content/dist/rhel/
> server/5/5Server/x86_64/os/repodata/repomd.xml
> 
> and apparently that file didn't exist.

Yes, the fix isnt applied on that Satellite. So I understood that some client requested that URI that does not exist on the pulp server.

It makes sense to raise some warning (it is sort of 404 error), but I expect they will be logged as the 404 errors in httpd access ssl logs either way. So ok if django.request won't log anything under WARNING.

Comment 18 Ivan Necas 2017-08-30 10:57:34 UTC
Verification version: Satellite 6.3 Snap 13

Verification steps:

1. sychrnized repository
2. tail -f /var/log/messages
3. curl -k http://cisco-b200m1-04.rhts.eng.bos.redhat.com/pulp/repos/Default_Organization/Library/content/dist/rhel/server/7/7.4/x86_64/kickstart/missing-file

No error messages produced in /var/log/messages, the curl returns 404, as expected

Comment 19 Bryan Kearney 2018-02-21 16:33:23 UTC
Since the problem described in this bug report should be resolved in a recent advisory, it has been closed with a resolution of ERRATA.

For information on the advisory, and where to find the updated files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHSA-2018:0336


Note You need to log in before you can comment on or make changes to this bug.