trac-admin $ENV repository sync hangs at certain SVN revision

[Michael Forrester]

Greetings Everyone,

trac-admin is hanging after running a trac-admin repository sync command on a particular SVN revision.   SVN itself works great as my devs are using it and they say the repo is fine, but I ran a sync after the resync hung.  Is there a way to get more information?

ps reveals:

/var/log/trac# ps aux | grep trac-admin

root      2078 99.8  4.0 303524 153772 pts/0   R+   04:36   9:38 /usr/bin/python /usr/local/bin/trac-admin /usr2/trac/worthpoint repository sync worthpoint

root      2091  0.0  0.0   8104   928 pts/1    S+   04:45   0:00 grep --color=auto trac-admin

/var/log/trac/trac.log contains the following:

2012-12-19 04:32:19,060 Trac[svn_fs] DEBUG: Subversion bindings imported

2012-12-19 04:36:15,246 Trac[env] INFO: -------------------------------- environment startup [Trac 0.12.2] ----             ----------------------------

2012-12-19 04:36:15,259 Trac[loader] DEBUG: Loading acct_mgr.admin from /usr/local/lib/python2.7/dist-packages/             TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,269 Trac[loader] DEBUG: Loading acct_mgr.api from /usr/local/lib/python2.7/dist-packages/Tr             acAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,269 Trac[loader] DEBUG: Loading acct_mgr.db from /usr/local/lib/python2.7/dist-packages/Tra             cAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,270 Trac[loader] DEBUG: Loading acct_mgr.htfile from /usr/local/lib/python2.7/dist-packages             /TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,270 Trac[loader] DEBUG: Loading acct_mgr.http from /usr/local/lib/python2.7/dist-packages/T             racAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,271 Trac[loader] DEBUG: Loading acct_mgr.macros from /usr/local/lib/python2.7/dist-packages             /TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,271 Trac[loader] DEBUG: Loading acct_mgr.notification from /usr/local/lib/python2.7/dist-pa             ckages/TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,272 Trac[loader] DEBUG: Loading acct_mgr.pwhash from /usr/local/lib/python2.7/dist-packages             /TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,272 Trac[loader] DEBUG: Loading acct_mgr.svnserve from /usr/local/lib/python2.7/dist-packag             es/TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,273 Trac[loader] DEBUG: Loading acct_mgr.web_ui from /usr/local/lib/python2.7/dist-packages             /TracAccountManager-0.4-py2.7.egg

2012-12-19 04:36:15,273 Trac[loader] DEBUG: Loading privatewiki from /usr/local/lib/python2.7/dist-packages/Pri             vateWikis-1.0.0-py2.7.egg

2012-12-19 04:36:15,274 Trac[loader] DEBUG: Loading trac.about from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,274 Trac[loader] DEBUG: Loading trac.admin.console from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,274 Trac[loader] DEBUG: Loading trac.admin.web_ui from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,276 Trac[loader] DEBUG: Loading trac.attachment from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,276 Trac[loader] DEBUG: Loading trac.db.mysql from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,277 Trac[loader] DEBUG: Loading trac.db.postgres from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,278 Trac[loader] DEBUG: Loading trac.db.sqlite from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,280 Trac[loader] DEBUG: Loading trac.mimeview.patch from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,281 Trac[loader] DEBUG: Loading trac.mimeview.pygments from /usr/lib/python2.7/dist-package             s

2012-12-19 04:36:15,292 Trac[loader] DEBUG: Loading trac.mimeview.rst from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,368 Trac[loader] DEBUG: Loading trac.mimeview.silvercity from /usr/lib/python2.7/dist-packa             ges

2012-12-19 04:36:15,374 Trac[loader] DEBUG: Skipping "trac.mimeview.silvercity = trac.mimeview.silvercity [silv             ercity]": ("DistributionNotFound: SilverCity>=0.9.4" not found)

2012-12-19 04:36:15,374 Trac[loader] DEBUG: Loading trac.mimeview.txtl from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,440 Trac[loader] DEBUG: Skipping "trac.mimeview.txtl = trac.mimeview.txtl [textile]": ("Dis             tributionNotFound: textile>=2.0" not found)

2012-12-19 04:36:15,440 Trac[loader] DEBUG: Loading trac.prefs from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,441 Trac[loader] DEBUG: Loading trac.search from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,441 Trac[loader] DEBUG: Loading trac.ticket.admin from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,444 Trac[loader] DEBUG: Loading trac.ticket.query from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,446 Trac[loader] DEBUG: Loading trac.ticket.report from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,447 Trac[loader] DEBUG: Loading trac.ticket.roadmap from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,448 Trac[loader] DEBUG: Loading trac.ticket.web_ui from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,451 Trac[loader] DEBUG: Loading trac.timeline from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,452 Trac[loader] DEBUG: Loading trac.versioncontrol.admin from /usr/lib/python2.7/dist-pack             ages

2012-12-19 04:36:15,452 Trac[loader] DEBUG: Loading trac.versioncontrol.svn_authz from /usr/lib/python2.7/dist-             packages

2012-12-19 04:36:15,453 Trac[loader] DEBUG: Loading trac.versioncontrol.svn_fs from /usr/lib/python2.7/dist-pac             kages

2012-12-19 04:36:15,454 Trac[loader] DEBUG: Loading trac.versioncontrol.svn_prop from /usr/lib/python2.7/dist-p             ackages

2012-12-19 04:36:15,459 Trac[loader] DEBUG: Loading trac.versioncontrol.web_ui from /usr/lib/python2.7/dist-pac             kages

2012-12-19 04:36:15,459 Trac[loader] DEBUG: Loading trac.web.auth from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,459 Trac[loader] DEBUG: Loading trac.web.session from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,460 Trac[loader] DEBUG: Loading trac.wiki.admin from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,460 Trac[loader] DEBUG: Loading trac.wiki.interwiki from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,461 Trac[loader] DEBUG: Loading trac.wiki.macros from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,461 Trac[loader] DEBUG: Loading trac.wiki.web_api from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,462 Trac[loader] DEBUG: Loading trac.wiki.web_ui from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,462 Trac[loader] DEBUG: Loading tracopt.mimeview.enscript from /usr/lib/python2.7/dist-pack             ages

2012-12-19 04:36:15,463 Trac[loader] DEBUG: Loading tracopt.mimeview.php from /usr/lib/python2.7/dist-packages

2012-12-19 04:36:15,464 Trac[loader] DEBUG: Loading tracopt.perm.authz_policy from /usr/lib/python2.7/dist-pack             ages

2012-12-19 04:36:15,473 Trac[loader] DEBUG: Loading tracopt.perm.config_perm_provider from /usr/lib/python2.7/d             ist-packages

2012-12-19 04:36:15,474 Trac[loader] DEBUG: Loading tracopt.ticket.commit_updater from /usr/lib/python2.7/dist-             packages

2012-12-19 04:36:15,475 Trac[loader] DEBUG: Loading tracopt.ticket.deleter from /usr/lib/python2.7/dist-package             s

2012-12-19 04:36:15,475 Trac[loader] DEBUG: Loading tracwysiwyg from /usr/local/lib/python2.7/dist-packages/Tra             cWysiwyg-0.12.0.4_r11158-py2.7.egg

2012-12-19 04:36:15,503 Trac[svn_fs] DEBUG: Subversion bindings imported

2012-12-19 04:36:15,506 Trac[cache] INFO: repos rev [9494] != cached rev [4570]

2012-12-19 04:36:15,507 Trac[cache] INFO: Trying to sync revision [4571]

[Michael Forrester]

Forgot to mention that I am running .12.  

[Michael Forrester]

wow it must be early in the AM.  I also didn't mention that the trac.log show a lightning fast resync (when that command is run) until it gets to revision 4571 and then it just stops...

On Wednesday, December 19, 2012 4:47:59 AM UTC-5, Michael Forrester wrote:

[Michael Forrester]

Please ignore my entire post.  This is simply a lesson in patience.  It took 3 hours, but it eventually moved past 4571 and stopped at 9494 which is the latest.

On Wednesday, December 19, 2012 4:47:59 AM UTC-5, Michael Forrester wrote:

[RjOllos]

On Wednesday, December 19, 2012 4:50:50 AM UTC-8, Michael Forrester wrote:

Please ignore my entire post.  This is simply a lesson in patience.  It took 3 hours, but it eventually moved past 4571 and stopped at 9494 which is the latest.

Did you happen to get an idea as to whether it was really stuck on [4571] for a long time, or if perhaps the console just stopped updating?