So this is the delay from:
2012-04-12 16:36:11,789 - softwarecenter.backend - INFO -
add_repo_add_key_and_install_app()
'private-ppa.launchpad.net/commercial-ppa-uploaders/bobby/ubuntu precise main'
'1024R/75254D99' 'Bobby,bobby'
... (apparently 4 failed attempts before)...
2012-04-12 16:40:07,105 - softwarecenter.backend - INFO -
_on_reload_for_add_repo_and_install_app_finished() <AptTransaction object at
0x587b370 (aptdaemon+client+AptTransaction at 0x66b5c80)> True Bobby,bobby
during which time USC is unable to see /var/lib/apt/lists/private-
ppa.launchpad.net_commercial-ppa-
uploaders_bobby_ubuntu_dists_precise_Release.gpg
This would indeed indicate that USC wasn't able to access the private
PPA until then, but is strange as the only LP script that I can imagine
causing this delay is the one updating the ht-access for the private PPA
- that's currently scheduled to happen every minute (cronscripts
/generate-ppa-htaccess.py has a crontab with * * * * *).
Indeed, checking the logs for generate-ppa-htaccess.py and assuming that
the above logs are all -0300 (Natalia's tz) shows it happily chugging
along nearly once-per minute until
{{{
2012-04-12 19:36:33 INFO Logging INFO and higher messages to
/srv/launchpad.net/production-logs/lp_publish/generate-ppa-htaccess.log
2012-04-12 19:36:33 INFO Creating lockfile:
/var/lock/launchpad-generate-ppa-htaccess.lock
2012-04-12 19:37:14 INFO Logging INFO and higher messages to
/srv/launchpad.net/production-logs/lp_publish/generate-ppa-htaccess.log
2012-04-12 19:37:14 INFO Creating lockfile:
/var/lock/launchpad-generate-ppa-htaccess.lock
2012-04-12 19:37:35 INFO Starting the PPA .htaccess generation
2012-04-12 19:38:10 INFO Logging INFO and higher messages to
/srv/launchpad.net/production-logs/lp_publish/generate-ppa-htaccess.log
2012-04-12 19:38:10 INFO Creating lockfile:
/var/lock/launchpad-generate-ppa-htaccess.lock
2012-04-12 19:39:10 INFO Logging INFO and higher messages to
/srv/launchpad.net/production-logs/lp_publish/generate-ppa-htaccess.log
2012-04-12 19:39:10 INFO Creating lockfile:
/var/lock/launchpad-generate-ppa-htaccess.lock
2012-04-12 19:39:50 INFO Committing transaction...
2012-04-12 19:39:56 INFO Finished PPA .htaccess generation
}}}
(more context for log https://pastebin.canonical.com/64326/)
which seems to indicate that:
1) Started at 19:36:33 but didn't even start PPA.htaccess generation (perhaps
lockfile existed? Need to check the lp code)
2) Restarted at 19:37:14 but didn't commit the transaction or finish the
htaccess generation.
3) Restarted at 19:38:10 but didn't even start PPA .htaccess generation, before
finally
4) Restarted at 19:39:10 and finished 46 seconds later.
I've put that in an LP bug 982938 so we can track it (we may have to
investigate/fix ourselves, just let me know).
--
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
https://bugs.launchpad.net/bugs/980241
Title:
Purchasing software took almost 5 minutes
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/software-center/+bug/980241/+subscriptions
--
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
