Trying to update too many bugs at once causes a spurious TooMuchTimeSkew error with DebBugs watches

Bug #364450 reported by Pietro Battiston
10
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Launchpad itself
Fix Released
Low
Graham Binns

Bug Description

https://bugs.launchpad.net/debian/+source/pyclutter/+bug/267478 is reported as "Fix Released" in Ubuntu but "New" in Debian. However, I went to the Debian page ( http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=494242 ), where it is described as "Fixed in version 0.8.0-1" and archived.

Indeed, by expanding the header of the bug related to Debian, I see that "Remote watch" has the selected option "Debian Bug tracker #494242", but I also see the following error message:

"Launchpad couldn't import bug #494242 from Debian Bug tracker."

The following bugs seem to have the same problem:
https://bugs.launchpad.net/debian/+source/cinepaint/+bug/190791
https://bugs.launchpad.net/ubuntu/+source/dput/+bug/295613
https://bugs.launchpad.net/ubuntu/+source/gdebi/+bug/37649

Tags: lp-bugs
Revision history for this message
Graham Binns (gmb) wrote : Re: [Bug 364450] [NEW] Launchpad can't follow status of a Debian bug

I'm unclear as to why this should happen unless there's bad data in
Launchpad's DebBugs mirror. I'll investigate and report back.

 status incomplete

Changed in malone:
status: New → Incomplete
Revision history for this message
Graham Binns (gmb) wrote : Re: Launchpad can't follow status of a Debian bug

This was apparently caused by OOPS-1206CCW762, which resulted from a TooMuchTimeSkew error.

What's weird is that the DebBugs database that Launchpad imports from is actually a local mirror, so the TooMuchTimeSkew error is essentially the checkwatches process saying that the server its on is too far out of sync with... itself. Which makes no sense.

The watches that failed to be updated should be checked some time in the next 24 hours, though it may be worth doing a full checkwatches run against DebBugs in the meantime to update everything just to be sure.

I'll mark this as low priority since I'm fairly certain it's a spurious failure. If it happens again we can bump it up.

Changed in malone:
importance: Undecided → Low
status: Incomplete → Triaged
summary: - Launchpad can't follow status of a Debian bug
+ Spurious TooMuchTimeSkew error when updating DebBugs watches
Revision history for this message
Graham Binns (gmb) wrote : Re: Spurious TooMuchTimeSkew error when updating DebBugs watches

Okay, so in an example of spurious behaviour suddenly not being quite so spurious, it's happened again: OOPS-1207CCW717.

A couple of questions spring to mind:

 1. What's going on at the same time as this (it failed this time at ~13:00; last time at ~13:51; maybe there's a process on loganberry chewing up CPU at about that time).
 2. How long is it taking to get from the time that the expected server time is declared (i.e. server_time = datetime.now()) to where it's checked against the *actual* server time (also datetime.now())? Are we getting stuck in a stupidly long-running loop at some point?

I'm going to bump this up to high for investigation purposes.

Changed in malone:
importance: Low → High
status: Triaged → Incomplete
Revision history for this message
Graham Binns (gmb) wrote :

It's happened again: OOPS-1208CCW674, this time at 12:11. I'll see if I can investigate today with the help of a LOSA. At the very least we could add some debugging code for subsequent runs.

Revision history for this message
Graham Binns (gmb) wrote :

This is happening with depressing regularity now - once a day at least. This needs fixing sooner rather than later.

Changed in malone:
milestone: none → 2.2.6
status: Incomplete → Triaged
Revision history for this message
Graham Binns (gmb) wrote :

This is happening because there are 9000+ DebBugs watches all being checked at the same time. I'll write a script to fix this. This isn't a bug in checkwatches so much as a failure to deal with an abnormally large volume of data.

Changed in malone:
status: Triaged → Invalid
Revision history for this message
Graham Binns (gmb) wrote :

Er. I didn't mean to invalidate the bug. I meant to bump it to low priority and un-milestone it. Like this.

Changed in malone:
importance: High → Low
milestone: 2.2.6 → none
status: Invalid → Triaged
summary: - Spurious TooMuchTimeSkew error when updating DebBugs watches
+ Trying to update too many bugs at once causes a spurious TooMuchTimeSkew
+ error with DebBugs watches
Revision history for this message
Graham Binns (gmb) wrote :

Fixing this looks to be as simple as moving the check for a time skew so that it isn't likely to be impacted by long-running loops.

Changed in malone:
assignee: nobody → Graham Binns (gmb)
status: Triaged → In Progress
Graham Binns (gmb)
Changed in malone:
milestone: none → 2.2.6
Revision history for this message
Diogo Matsubara (matsubara) wrote : Bug fixed by a commit

Fixed in devel r8623.

Changed in malone:
status: In Progress → Fix Committed
Graham Binns (gmb)
Changed in malone:
status: Fix Committed → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Duplicates of this bug

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.