Registration of file: repo in Subversion ALM fails with "$error"

[kawk]

Hi,

I'm trying to register a new local repository in Subversion ALM. The registration fails with "$error", no other information is given and even with increased logging I wasn't able to get any more information about the cause.

Two similar repositories have been registered years ago with an older app version (8.6.6) and still "work". Now Subversion ALM is at 9.1.3, JIRA Server at 8.9, newer versions are not available to us..

Is there anything I can do to add the new repo? Any way to go back to an older ALM version? Tried installing for example 9.0.11.obr, but still 9.1.3 is active

Kolja

[Marc - Devoteam]

Hi @kawk 

I would see your log files on the specific error, if any is there. I see you have searched and no more information is available.

You could create a HAR log file and contact the vendor of Subversion ALM and/or Atlassian in this case.

[kawk]

I observed that if I put an empty repository at the very same location, it can be registered. Only after a few "commits" have been made, any further attempt to update or re-register the repo in ALM results in "$error" as before.

The commits are in fact made by a svnsync process and the first ones do not contain any actual changes (they just bump the revision number to stay in sync with the source repository).

May that be a problem for the plugin? I'll try to put at least one meaningful commit into the repository as first revision.

Kolja

[kawk]

An excerpt from my atlassian-jira.log with everything around the time of a failed attempt doesn't show anything suspicious, except a WARN at the end:

2023-08-02 08:05:00,759+0200 ajp-nio-8009-exec-5 url: /pf/jira/secure/ALMAddSubversionRepository.jspa; user: kwaschk DEBUG kwaschk 485x262x1 1u3lt72 90.187.132.69 /secure/ALMAddSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] The input properties (with the format expected by the SVN Web Client) are going to be validated: {CachePrefetchMessagesCount=-1, Password=, ProxySupported=false, TextMimeTypes= text/plain, application/xslt+xml, application/xml, application/xhtml+xml, text/javascript, text/html, text/css,application/x-javascript, application/bat , Username=, BranchesName=branches, PathAutodetect=true, ShowStackTrace=false, BasicAuth=false, ProtocolKeyFile=, SvnConnectionsCount=20, TagsName=tags, DefaultEncoding=UTF-8, VersionsCount=20, TrunkName=trunk, CharacterEncodings= windows-1250,windows-1251,windows-1252,windows-1253,windows-1254,windows-1255,windows-1256,windows-1257,windows-1258,windows-31j,UTF-8,UTF-16LE,UTF-16BE,UTF-16,ISO-8859-1,ISO-8859-13,ISO-8859-15,ISO-8859-2,ISO-8859-3,ISO-8859-4,ISO-8859-5,ISO-8859-6,ISO-8859-7,ISO-8859-8,ISO-8859-9,US-ASCII,KOI8-R , RootUrl=file:///var/www/pf/repos/mls, BasicRealm=Subversion Web Client, ProtocolPassPhrase=, BinaryMimeTypes= application/octet-stream, application/zip, application/x-visio, application/vnd.ms-excel, application/x-tar,application/x-shockwave-flash, application/rtf, application/postscript, application/vnd.ms-powerpoint,application/pdf, application/java-archive, application/x-gzip, application/msword, application/postscript,image/tiff, image/svg+xml, image/x-photoshop, image/png, image/jpeg, image/gif, image/bmp,video/mpeg, audio/x-mpeg, video/x-msvideo , ProxyUserName=, CachePageSize=100, ProtocolPortNumber=22, ProxyPortNumber=8080, DisplayName=mls, ProxyPassword=, ForcedHttpAuth=false, ProxyHost=} ...
2023-08-02 08:05:00,760+0200 ajp-nio-8009-exec-5 url: /pf/jira/secure/ALMAddSubversionRepository.jspa; user: kwaschk DEBUG kwaschk 485x262x1 1u3lt72 90.187.132.69 /secure/ALMAddSubversionRepository.jspa

[o.p.svnwebclient.configuration.ConfigurationProvider] Checking DisplayName...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking RootUrl...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking SvnConnectionsCount...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking VersionsCount...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking PathAutodetect...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking TrunkName...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking BranchesName...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking TagsName...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking DefaultEncoding...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking CachePageSize...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking CachePrefetchMessagesCount...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking ShowStackTrace...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking BasicAuth...
[o.p.svnwebclient.configuration.ConfigurationProvider] The protocol type is HTTP, SVN
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking Username...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking Password...
[o.p.svnwebclient.configuration.ConfigurationProvider] Checking ProxySupported...
[c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] ...the input properties have been validated
[c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] Getting the root from the input url...
[o.p.s.data.javasvn.DataProvider] Creating the Subversion repository for the input url...
[o.p.s.data.javasvn.DataProvider] ... the repository has been created
[o.p.s.data.javasvn.DataProvider] username:
[o.p.s.data.javasvn.DataProvider] password: [for security reasons is not displayed but is it set?false]
[o.p.s.data.javasvn.DataProvider] protocol key file:
[o.p.s.data.javasvn.DataProvider] protocol pass phrase:
[o.p.s.data.javasvn.DataProvider] protocol port number: 22
[o.p.s.data.javasvn.DataProvider] SVNKit authentication manager created
[o.p.s.data.javasvn.DataProvider] SVNKit authentication manager set to the repository
[o.p.s.data.javasvn.DataProvider] Retrieving the root url from the Subversion server...


2023-08-02 08:05:00,807+0200 ajp-nio-8009-exec-5 url: /pf/jira/secure/ALMAddSubversionRepository.jspa; user: kwaschk WARN kwaschk 485x262x1 1u3lt72 90.187.132.69 /secure/ALMAddSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction]

[kawk]

With strace I can see the last file accesses before that WARN log entry is written (stat, read and close syscalls omitted for better overview):

[pid 26383] openat(AT_FDCWD, "/etc/subversion/config", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/etc/subversion/config", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/format", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/format", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/fs-type", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/min-unpacked-rev",
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/fsfs.conf", O_RDONLY) = 109
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/rep-cache.db", O_RDONLY) = 109

While accessing the rep-cache.db, there is more action than just reading, and a stat of rep-cache.db-journal is the last thing that happens before writing the WARN:

[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] lseek(109, 0, SEEK_SET) = 0
[pid 26383] read(109, "SQLite format 3\0\20\0\1\1\0@ ...
[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] stat("/var/www/pf/repos/mls/db/rep-cache.db-journal", {st_mode=S_IFREG|0644, st_size=0, ...}) = 0
[pid 26383] futex(0x7fe46c079178, FUTEX_WAKE_PRIVATE, 1) = 1
[pid 26383] write(106, "2023-08-19 18:08:20,692+0200 ajp-nio-8009-exec-11 url: /pf/jira/secure/ALMUpdateSubversionRepository.jspa; user: kwaschk WARN kwaschk 1088x51034x1 6oeef6 77.6.167.196 /secure/ALMUpdateSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] \n", 271) = 271

[kawk]

It seems my replies with log excerpts got lost after editing them to show the excerpts as code block instead of normal text..

[kawk]

strace shows these final syscalls leading to the WARN in jira log:

[pid 26383] stat("/var/www/pf/repos/mls/db/rep-cache.db", {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] access("/var/www/pf/repos/mls/db/rep-cache.db", W_OK) = -1 EACCES (Permission denied)
[pid 26383] access("/var/www/pf/repos/mls/db/rep-cache.db", R_OK) = 0
[pid 26383] stat("/var/www/pf/repos/mls/db/rep-cache.db", {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] access("/var/www/pf/repos/mls/db/rep-cache.db", R_OK) = 0
[pid 26383] stat("/var/www/pf/repos/mls/db", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/rep-cache.db", O_RDONLY) = 109
[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] lseek(109, 0, SEEK_SET) = 0
[pid 26383] read(109, "SQLite format 3\0\20\0\1\1\0@ \0\0\0\2\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\4\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\2\0.0:", 100) = 100
[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] stat("/var/www/pf/repos/mls/db/rep-cache.db-journal", {st_mode=S_IFREG|0644, st_size=0, ...}) = 0
[pid 26383] futex(0x7fe46c079178, FUTEX_WAKE_PRIVATE, 1) = 1
[pid 26383] write(106, "2023-08-19 18:08:20,692+0200 ajp-nio-8009-exec-11 url: /pf/jira/secure/ALMUpdateSubversionRepository.jspa; user: kwaschk WARN kwaschk 1088x51034x1 6oeef6 77.6.167.196 /secure/ALMUpdateSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] \n", 271) = 271

[kawk]

The WARN log entry shows up in atlassian-jira.log as follows. Only a few DEBUG messages appear before, just listing the information I put in the registration form, no details about any problems.

2023-08-19 18:08:20,692+0200 ajp-nio-8009-exec-11 url: /pf/jira/secure/ALMUpdateSubversionRepository.jspa; user: kwaschk WARN kwaschk 1088x51034x1 6oeef6 77.6.167.196 /secure/ALMUpdateSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction]

An strace of last actions leading to the WARN log message:

[pid 26383] stat("/var/www/pf/repos/mls/db", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0
[pid 26383] openat(AT_FDCWD, "/var/www/pf/repos/mls/db/rep-cache.db", O_RDONLY) = 109
[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] lseek(109, 0, SEEK_SET) = 0
[pid 26383] read(109, "SQLite format 3\0\20\0\1\1\0@ \0\0\0\2\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\4\0\0\0\0\0\0\0\0\0\0\0\1\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\2\0.0:", 100) = 100
[pid 26383] fstat(109, {st_mode=S_IFREG|0644, st_size=8192, ...}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_RDLCK, l_whence=SEEK_SET, l_start=1073741826, l_len=510}) = 0
[pid 26383] fcntl(109, F_SETLK, {l_type=F_UNLCK, l_whence=SEEK_SET, l_start=1073741824, l_len=1}) = 0
[pid 26383] stat("/var/www/pf/repos/mls/db/rep-cache.db-journal", {st_mode=S_IFREG|0644, st_size=0, ...}) = 0
[pid 26383] futex(0x7fe46c079178, FUTEX_WAKE_PRIVATE, 1) = 1
[pid 26383] write(106, "2023-08-19 18:08:20,692+0200 ajp-nio-8009-exec-11 url: /pf/jira/secure/ALMUpdateSubversionRepository.jspa; user: kwaschk WARN kwaschk 1088x51034x1 6oeef6 77.6.167.196 /secure/ALMUpdateSubversionRepository.jspa [c.k.j.p.e.subversion.action.AddSubversionRepositoryAction] \n", 271) = 271

[kawk]

sorry for the possibly duplicate log quotes, but while editing it seemed that this or that reply got lost. It only shows up now again