Age column displaying "48 years" after upgrading to 1.2.2 + Empty Author, Last change & Log Message

[dreamzer@…]

Hello,

We recently changed our development server, and upgraded from 0.12.2 to 1.2.2.

But now, for all our files and directories, the "Age" column shows "48 years" (it thinks that the file/directly was last modified on 1970-01-01), the "Author" and the "Last change" column are empty (see attached screenshot). Same for the "Log Message".

The subversion directory didn't change at all.

[Jun Omae]

Could you please post trac.log in your Trac Environment after enabling TracLogging with DEBUG level and visiting the browser page?

[Jun Omae]

Also, please post current and before-upgrading System Information in About page of your Trac.

[anonymous]

Hello Jun,

Thank you very much for your message and for your help. Please find attached all the things you asked for. Let me know if you need anything else.

Thank you and have a nice day,

[Jun Omae]

Thanks for the feedback.

According to the upload files, Subversion 1.9.5 on Ubuntu zesty is used and the following is logged.

2017-12-01 11:43:35,256 Trac[util] WARNING: Unable to get changeset [39468]

It seems that Trac is unable to read revision properties from the repository.

Could you please verify using svnadmin verify command with the repository?

$ sudo -u www-data svnadmin verify /path/to/repos/dreamzer

[anonymous]

Hi Jun,

I had already made a svnadmin verify and it went all fine. I've just done it again and it was exactly the same.

[Ryan J Ollos]

I would try running resync on the repository (TracAdmin):

$ trac-admin $env repository resync "dreamzer"

where $env is the path to the Trac environment.

$ trac-admin $env help repository resync
repository resync <repos> [rev]

    Re-synchronize trac with repositories

    When [rev] is specified, only that revision is synchronized. Otherwise, the
    complete revision history is synchronized. Note that this operation can
    take a long time to complete. If synchronization gets interrupted, it can
    be resumed later using the `sync` command.

    To synchronize all repositories, specify "*" as the repository.

[Jun Omae]

Thinking another possibilities, metadata in repository table might be broken.

Please post result of the following query in your trac.db:

SELECT * FROM repository ORDER BY id, name;

Currently, nothing about the reason is logged when invalid revision is given to get_changeset(). I think it is good to add logging when NoSuchChangeset is raised to investigate such a situation:

Proposed changes for 1.0-stable:

trac/versioncontrol/web_ui/util.py

@@ -44 +44 @@
             changeset = Changeset(repos, rev, '', '',
                                   datetime(1970, 1, 1, tzinfo=utc))
             if log is not None:
-                log.warning("Unable to get changeset [%s]", rev)
+                log.warning("Unable to get changeset [%s] in %s", rev,
+                            repos.reponame or '(default)')
         changes[rev] = changeset
     return changes
 

tracopt/versioncontrol/svn/svn_fs.py

@@ -431 +431 @@
             return self.youngest_rev
         else:
             try:
-                rev = int(rev)
-                if 0 <= rev <= self.youngest_rev:
-                    return rev
-            except (ValueError, TypeError):
-                pass
+                normrev = int(rev)
+                if 0 <= normrev <= self.youngest_rev:
+                    return normrev
+                else:
+                    self.log.debug("%r cannot be normalized in %s: out of [0, "
+                                   "%r]", rev, self.reponame or '(default)',
+                                   self.youngest_rev)
+            except (ValueError, TypeError), e:
+                self.log.debug("%r cannot be normalized in %s: %s", rev,
+                               self.reponame or '(default)',
+                               exception_to_unicode(e))
             raise NoSuchChangeset(rev)
 
     def close(self):
@@ -967 +973 @@
 class SubversionChangeset(Changeset):
 
     def __init__(self, repos, rev, scope, pool=None):
+        self.log = repos.log
         self.rev = rev
         self.scope = scope
         self.fs_ptr = repos.fs_ptr
@@ -1093 +1100 @@
             yield tuple(change)
 
     def _get_prop(self, name):
-        return fs.revision_prop(self.fs_ptr, self.rev, name, self.pool())
+        try:
+            return fs.revision_prop(self.fs_ptr, self.rev, name, self.pool())
+        except core.SubversionException, e:
+            self.log.debug("%r of the %r cannot be retrieved", name, self.rev)
+            raise
 
 
 #

After the patch:

>>> env = Environment('/var/trac/1.0-sqlite')
>>> repos = rm.get_repository('trac.svn')
>>> repos.get_changeset(-1)
2017-12-05 12:25:00,654 Trac[svn_fs] DEBUG: -1 cannot be normalized in trac.svn: out of [0, 16103L]
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
  File "/src/tracdev/git/trac/versioncontrol/cache.py", line 70, in get_changeset
    return CachedChangeset(self, self.normalize_rev(rev), self.env)
  File "/src/tracdev/git/tracopt/versioncontrol/svn/svn_fs.py", line 262, in normalize_rev
    return self.repos.normalize_rev(rev)
  File "/src/tracdev/git/tracopt/versioncontrol/svn/svn_fs.py", line 445, in normalize_rev
    raise NoSuchChangeset(rev)
trac.versioncontrol.api.NoSuchChangeset: No changeset -1 in the repository
>>> repos.get_changeset('aaa')
2017-12-05 12:25:20,160 Trac[svn_fs] DEBUG: 'aaa' cannot be normalized in trac.svn: ValueError: invalid literal for int() with base 10: 'aaa'
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
  File "/src/tracdev/git/trac/versioncontrol/cache.py", line 70, in get_changeset
    return CachedChangeset(self, self.normalize_rev(rev), self.env)
  File "/src/tracdev/git/tracopt/versioncontrol/svn/svn_fs.py", line 262, in normalize_rev
    return self.repos.normalize_rev(rev)
  File "/src/tracdev/git/tracopt/versioncontrol/svn/svn_fs.py", line 445, in normalize_rev
    raise NoSuchChangeset(rev)
trac.versioncontrol.api.NoSuchChangeset: No changeset aaa in the repository
>>> direct_repos = repos.repos
>>> SubversionChangeset(direct_repos, 999999, direct_repos.pool)
2017-12-05 12:28:10,102 Trac[svn_fs] DEBUG: 'svn:log' of the 999999 cannot be retrieved
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
  File "/src/tracdev/git/tracopt/versioncontrol/svn/svn_fs.py", line 984, in __init__
    raise NoSuchChangeset(rev)
trac.versioncontrol.api.NoSuchChangeset: No changeset 999999 in the repository

[anonymous]

Hi Jun,

The repository resync worked perfectly well. I couldn't be happier, thank you very much for your help!

Maybe this could be added to the Troubleshooting part of the documentation, in case other people ever have the same problem.

Once again, I'm sending you 1,000 thank you. Have a nice day!

[Ryan J Ollos]

Adding the additional logging looks like a good idea.

[Jun Omae]

Thanks. I'm going to commit the additional logging.

[Jun Omae]

Commtted in [16397] and merged in [16398,16399].

Closing this ticket but feel free to reopen it or create new ticket if anyone can reproduce the issue.