Bug 78101 - The up2date log has a bug in reporting the times of the events that it records.
Summary: The up2date log has a bug in reporting the times of the events that it records.
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Red Hat Linux
Classification: Retired
Component: up2date
Version: 7.2
Hardware: All
OS: Linux
medium
medium
Target Milestone: ---
Assignee: Adrian Likins
QA Contact: Red Hat Satellite QA List
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2002-11-19 02:19 UTC by Need Real Name
Modified: 2007-04-18 16:48 UTC (History)
2 users (show)

Fixed In Version:
Clone Of:
Environment:
Last Closed: 2003-01-15 05:59:55 UTC
Embargoed:


Attachments (Terms of Use)

Description Need Real Name 2002-11-19 02:19:16 UTC
From Bugzilla Helper:
User-Agent: Mozilla/4.78 [en] (X11; U; Linux 2.4.7-10 i686)

Description of problem:
The file /var/log/up2date  records  up2date actions. with time stamps. 
Some sample entries:
[Sun Nov 10 14:00:23 2002] up2date updating login info
[Sun Nov 10 14:00:23 2002] up2date opening rpmdb in /var/lib/rpm/ with option 0
[Sun Nov 10 14:00:23 2002] up2date logging into up2date server
[Sun Nov 10 14:00:23 2002] up2date successfully retrieved authentication token
from up2date server
[Sun Nov 10 14:00:23 2002] up2date Error communicating with server.  The message
was
There was a network error.  It appears the server has abruptly closed the
connection
[Sun Nov 10 13:33:54 2002] up2date Opening rpmdb in /var/lib/rpm/ with option 0

The problem is that the entry telling about the error was entered long after the
time that it says the event occurred.   (I looked at the log several times after
the preceding entry was recorded - over a span of two hours).   
It is clear that the date times, which log the time down to the second, are
reading the clock at the beginning of a session, recording it somewhere, and
using that time for an entire session.  This makes in impossible to pin down
where the difficulties are occurring when there are repeated transmission
problems on the RHN network.

If you need extensive examples of the  log I can send them, but you should be
able to get them easily by looking at a Linux log from any network up2date
transmission.  (assuming my up2date is no different from others).  I have
observed this behavior as a result of my efforts to understand what is happening
in up2date to determine  whether a more serious bug is causing repeated
transmissions of the same informaton.  Specifically whether   " up2date Opening
rpmdb in /var/lib/rpm/ with option 1"  should always be followed with a clean up
operation getting rid of the files which have been replaced and updating the rpm
list with the newly installed programs.  my current updates seem to be in a
loop.


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


How reproducible:
Always

Steps to Reproduce:
1.  I have scheduled Rhn network updates.   I just need to dial up my ISP and
within a couple of hours a download will have occurred which is logged in
/var/log/up2date.  All the entries exhibit this behavior.   Only one time stamp
(to the second) for a long series of events which may take several hours.
2.
3.
	

Actual Results:  Already described.

Expected Results:  I would expect a time delay between the statement "logging
into up2date server" and "successfully retrieved authentication token from
up2date server" .   I would CERTAINLY expect some elapsed time  before  "Error
communicating with server.  The message was:  There was a network error.  It
appears the server has abruplty (sic) closed the connection."   This elapsed
time varies from a few minutes to a couple of hours.

Additional info:

I included a few lines of output to illustrate the problem

Comment 1 Adrian Likins 2002-12-02 22:05:28 UTC
which version is this?

This bug should be fixed in the latest errata versions


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