Bug 715504 - Apache's error_log also generating pulp log messages
Summary: Apache's error_log also generating pulp log messages
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Red Hat Update Infrastructure for Cloud Providers
Classification: Red Hat
Component: RHUA
Version: 2.0
Hardware: x86_64
OS: Linux
unspecified
medium
Target Milestone: ---
: ---
Assignee: John Matthews
QA Contact: wes hayutin
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2011-06-23 06:31 UTC by Sachin Ghai
Modified: 2012-05-31 12:56 UTC (History)
4 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2012-05-31 12:56:16 UTC
Target Upstream Version:


Attachments (Terms of Use)

Description Sachin Ghai 2011-06-23 06:31:32 UTC
Description of problem:
I checked the sync status using rhui-manager and checked the error_log of apache and found following logs:

[root@dhcp201-111 ~]# tail -f /var/log/httpd/error_log 
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,621 pulp.server.async:INFO: Loaded Task from database: Task b53a5b78-9cef-11e0-a639-525400734a2e: _sync(rhel-server-6-optional-releases-6Server-x86_64, synchronizer=<pulp.server.api.synchronizers.YumSynchronizer object at 0x7f77605c83d0>, skip_dict={}, max_speed=None, threads=None)
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,623 pulp.server.async:INFO: Loaded Task from database: Task b55df6f8-9cef-11e0-a722-525400734a2e: _sync(rhel-server-6-updates-6Server-x86_64, synchronizer=<pulp.server.api.synchronizers.YumSynchronizer object at 0x7f77605c8790>, skip_dict={}, max_speed=None, threads=None)
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,642 pulp:WARNING: IntervalScheduler created with start time more than one interval in the past: 2011-06-23 04:18:31+05:30, 6:00:00
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,643 pulp.server.tasking.task:WARNING: Task 1b9518b5-9d60-11e0-85d5-525400734a2e: _sync(rhel-server-6-updates-6Server-x86_64, synchronizer=<pulp.server.api.synchronizers.YumSynchronizer object at 0x7f77605d0b90>, progress_callback=<bound method RepoSyncTask.progress_callback of <pulp.server.api.repo_sync_task.RepoSyncTask object at 0x7f77605d0b10>>) missed 1 scheduled runs
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,643 pulp:INFO: Added scheduled sync for rhel-server-6-updates-6Server-x86_64 to task queue
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,655 pulp:WARNING: IntervalScheduler created with start time more than one interval in the past: 2011-06-23 04:23:34+05:30, 6:00:00
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,656 pulp.server.tasking.task:WARNING: Task 1b990d19-9d60-11e0-85ef-525400734a2e: CdsApi.cds_sync(dhcp201-133.englab.pnq.redhat.com, ) missed 1 scheduled runs
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,656 pulp:INFO: Added sync for dhcp201-133.englab.pnq.redhat.com to task queue
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,658 pulp:WARNING: IntervalScheduler created with start time more than one interval in the past: 2011-06-23 04:24:13+05:30, 6:00:00
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,658 pulp.server.tasking.task:WARNING: Task 1b996ccc-9d60-11e0-85f1-525400734a2e: CdsApi.cds_sync(dhcp201-175.englab.pnq.redhat.com, ) missed 1 scheduled runs
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,659 pulp:INFO: Added sync for dhcp201-175.englab.pnq.redhat.com to task queue
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,660 pulp.server.api.repo_sync:INFO: Sync invoked for repo <rhel-server-6-optional-releases-6Server-x86_64>
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,663 pulp.server.api.repo_sync:INFO: Sync of rhel-server-6-optional-releases-6Server-x86_64 starting, skip_dict = {}
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,664 pulp.server.api.synchronizers:INFO: cacert = </etc/pki/content/rhel-server-6-optional-releases-6Server-x86_64/feed-rhel-server-6-optional-releases-6Server-x86_64.ca>, cert = </etc/pki/content/rhel-server-6-optional-releases-6Server-x86_64/feed-rhel-server-6-optional-releases-6Server-x86_64.cert>
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,669 pulp.server.api.repo_sync:INFO: Sync invoked for repo <rhel-server-6-updates-6Server-x86_64>
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,673 pulp.server.api.repo_sync:INFO: Sync of rhel-server-6-updates-6Server-x86_64 starting, skip_dict = {}
[Thu Jun 23 11:44:52 2011] [error] 2011-06-23 11:44:52,675 pulp.server.api.synchronizers:INFO: cacert = </etc/pki/content/rhel-server-6-updates-6Server-x86_64/feed-rhel-server-6-updates-6Server-x86_64.ca>, cert = </etc/pki/content/rhel-server-6-updates-6Server-x86_64/feed-rhel-server-6-updates-6Server-x86_64.cert>



Version-Release number of selected component (if applicable):
pulp 0.194
rhui-tools 2.0.32

How reproducible:
always

Steps to Reproduce:
1. Login to rhui-manager
2. Check the sync status for existing rhui-managed repos
3. Check logs under /var/log/httpd/error_log
  
Actual results:
Pulp logs in error_log of apche

Expected results:
Since erro_log is apache's log file. It should not contain the pulp logs

Additional info:

I found that in new build 0.194, the logs section in pulp.conf has also been changed. 

[logs]
config: /etc/pulp/logging/basic.cfg

Not sure if it is related.

Comment 1 John Matthews 2011-06-23 11:58:10 UTC
Fixed and pushed to RHUI

http://git.fedorahosted.org/git/?p=pulp.git;a=commitdiff;h=e61fb7b743c8d198d3ef1a32e82d76f1ea222db4

Issue was that 'Console' logging in basic.cfg was set to 'INFO'
I bumped it to ERROR.  Thought it would be helpful to have ERRORs in http log.  We could also remove 'handler_console' to remove all pulp generated messages if that is desired.

--- a/etc/pulp/logging/basic.cfg
+++ b/etc/pulp/logging/basic.cfg
@@ -70,7 +70,7 @@ format: %(asctime)s %(process)d:%(thread)d: %(name)s:%(levelname)s: %(module)s:%
 keys: console,pulp_file,grinder_file
 
 [handler_console]
-level: INFO
+level: ERROR
 class: StreamHandler
 args: []
 formatter: simple

Comment 2 Jay Dobies 2011-06-23 13:21:53 UTC
Fixed in Pulp 0.197

Comment 3 Sachin Ghai 2011-06-24 06:16:00 UTC
Verified with new build (pulp 0.197. rhui-tools 2.0.33)

Apache's error_log file is not containing pulp generated messages. Thanks !

Comment 4 wes hayutin 2011-08-01 21:40:14 UTC
moving to release pending

Comment 5 wes hayutin 2012-05-31 12:56:16 UTC
closing out, product released


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