Zidestore problems after upgrading OGo

Fabian Tiefert <[email protected]> Fri, 29 Aug 2008 11:52:04 +0200
Newsgroups	gmane.comp.cms.opengroupware.user
Message-ID	<[email protected]>
Hi all,

we are running an OGo server with the following specs:

OS: Debian Sarge
OGo: Opengroupware 1.0
OGo-Repository: opengroupware-1.0beta.2-rock
Database: Postgresql 7.4.7
Webserver: Apache2 2.0.54
and LDAP for the authentification.

Now we want to upgrade the Debian System and the OGo Version. The specs of the 
new server are:

OS: Debian Etch
OGo: Opengroupware1.1 (svn2106)
OGo-Repository: http://download.opengroupware.og/nightly/packages/debian
Database: Postgresql 7.5.22
Webserver: Apache2 2.2.3

First I backuped the old OGo server:

1. copy the folders .libFoundation, news_images, documents, skyfs    
    from /var/lib/opengroupware.org

2. Backup the old database
    # pg_dump -Ft -f /tmp/OGoBackup.tar -U postgres ogo

Then I restored the backup on the new server:

1. copy the 4 folders to /var/lib/opengroupware.org (removed the existing ones 
first)

2. drop the existing ogo database

3. created a new ogo database

4. restored the backup of the old database:
    # pg_restore --verbose --dbname=ogo OGoBackup.tar

5. set the correct password for the postgresql user ogo

After these steps I can connect to the OGo Server via the Web-Interface and 
everything seems to be fine. But Zidestore doesn't work right. Normally I can 
connect with a webDAV client (e.g. konqueror) via 
webdav://<OGOSERVER>/zidestore/so/<USERNAME> and browse the folders. This 
doesn't work anylonger after the upgrade. In the zidestore.log the following 
line seems to be relevant:

Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: CmdCtx[tiefert]: couldn't rollback 
database transaction !

Something is wrong with the database. Any ideas how to solve this problem?
I attached the zidestore.log at the end of this mail.

Regards,
Fabian



I activated these debug options in NSGlobalDomain.plist:
    PGDebugEnabled = Yes;
    WOHttpAdaptor_LogStream = Yes;

This is what shows in the zidestore.log file:

Aug 29 09:35:47 ogo-zidestore-1.5 [2499]: request is

PROPFIND /zidestore/ HTTP/1.1
x-webobjects-server-protocol: HTTP/1.1
x-webobjects-remote-addr: 192.168.100.177
x-webobjects-remote-host: 192.168.100.177
x-webobjects-server-name: 127.0.0.1
x-webobjects-server-url: http://127.0.0.1
User-Agent: Mozilla/5.0 (compatible; Konqueror/3.5; Linux) KHTML/3.5.5 (like 
Gecko) (Debian)
Pragma: no-cache
Cache-control: no-cache
Accept: text/html, image/jpeg, image/png, text/*, image/*, */*
Accept-Encoding: x-gzip, x-deflate, gzip, deflate
Accept-Charset: iso-8859-15, utf-8;q=0.5, *;q=0.5
Accept-Language: de, en
Host: ts-nl-test
Connection: Keep-Alive
Depth: 1
Content-Type: text/xml; charset=utf-8
Content-Length: 303

<?xml version="1.0" encoding="utf-8" ?><D:propfind 
xmlns:D="DAV:"><D:prop><D:creationdate/><D:getcontentlength/><D:displayname/>
<D:source/><D:getcontentlanguage/><D:getcontenttype/><D:executable/><D:getlastmodified/>
<D:getetag/><D:supportedlock/><D:lockdiscovery/><D:resourcetype/></D:prop></D:propfind>
Aug 29 09:35:47 ogo-zidestore-1.5 [2499]: Note: storing cached vCards files 
in: '/var/lib/opengroupware.org/documents'
Aug 29 09:35:47 ogo-zidestore-1.5 [2499]: account::change-password: using 
password field: 'userPassword'
Aug 29 09:35:47 ogo-zidestore-1.5 [2499]: Note: members of role team 'team 
creators' are allowed to create teams.
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: Note(SoObject): SoDebugKeyLookup is 
enabled!
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: Note(SoObject): SoDebugBaseURL is 
enabled!
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: Note(SoObject): relative base URLs 
are enabled.
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: [WARN] <0x0x8354cfc[WOContext]> Got 
no user from authenticator: <SxAuthenticator 0x8359704>
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: response is

HTTP/1.0 401 Authorization Required
www-authenticate: basic realm="OpenGroupware.org"
content-length: 0


Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: request is

PROPFIND /zidestore/so/ HTTP/1.1
x-webobjects-server-protocol: HTTP/1.1
x-webobjects-remote-addr: 192.168.100.177
x-webobjects-remote-host: 192.168.100.177
x-webobjects-server-name: 127.0.0.1
x-webobjects-server-url: http://127.0.0.1
User-Agent: Mozilla/5.0 (compatible; Konqueror/3.5; Linux) KHTML/3.5.5 (like 
Gecko) (Debian)
Pragma: no-cache
Cache-control: no-cache
Accept: text/html, image/jpeg, image/png, text/*, image/*, */*
Accept-Encoding: x-gzip, x-deflate, gzip, deflate
Accept-Charset: iso-8859-15, utf-8;q=0.5, *;q=0.5
Accept-Language: de, en
Host: ts-nl-test
Connection: Keep-Alive
Depth: 1
Content-Type: text/xml; charset=utf-8
Content-Length: 303

<?xml version="1.0" encoding="utf-8" ?><D:propfind 
xmlns:D="DAV:"><D:prop><D:creationdate/><D:getcontentlength/><D:displayname/>
<D:source/><D:getcontentlanguage/><D:getcontenttype/><D:executable/><D:getlastmodified/>
<D:getetag/><D:supportedlock/><D:lockdiscovery/><D:resourcetype/></D:prop></D:propfind>
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: [WARN] <0x0x8108b1c[WOContext]> Got 
no user from authenticator: <SxAuthenticator 0x8359704>
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: response is

HTTP/1.0 401 Authorization Required
www-authenticate: basic realm="OpenGroupware.org"
content-length: 0


Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: request is

PROPFIND /zidestore/so/tiefert HTTP/1.1
x-webobjects-server-protocol: HTTP/1.1
x-webobjects-remote-addr: 192.168.100.177
x-webobjects-remote-host: 192.168.100.177
x-webobjects-server-name: 127.0.0.1
x-webobjects-server-url: http://127.0.0.1
User-Agent: Mozilla/5.0 (compatible; Konqueror/3.5; Linux) KHTML/3.5.5 (like 
Gecko) (Debian)
Pragma: no-cache
Cache-control: no-cache
Accept: text/html, image/jpeg, image/png, text/*, image/*, */*
Accept-Encoding: x-gzip, x-deflate, gzip, deflate
Accept-Charset: iso-8859-15, utf-8;q=0.5, *;q=0.5
Accept-Language: de, en
Host: ts-nl-test
Connection: Keep-Alive
Depth: 0
Content-Type: text/xml; charset=utf-8
Content-Length: 303

<?xml version="1.0" encoding="utf-8" ?><D:propfind 
xmlns:D="DAV:"><D:prop><D:creationdate/><D:getcontentlength/><D:displayname/>
<D:source/><D:getcontentlanguage/><D:getcontenttype/><D:executable/><D:getlastmodified/>
<D:getetag/><D:supportedlock/><D:lockdiscovery/><D:resourcetype/></D:prop></D:propfind>
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: |ogo-zidestore-1| ERROR: cannot 
fetch logins, no cmdctx!
Aug 29 09:35:48 ogo-zidestore-1.5 [2499]: response is

HTTP/1.0 401 Authorization Required
www-authenticate: basic realm="OpenGroupware.org"
content-length: 0


Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: request is

PROPFIND /zidestore/so/tiefert HTTP/1.1
x-webobjects-server-protocol: HTTP/1.1
x-webobjects-remote-addr: 192.168.100.177
x-webobjects-remote-host: 192.168.100.177
x-webobjects-server-name: 127.0.0.1
x-webobjects-server-url: http://127.0.0.1
User-Agent: Mozilla/5.0 (compatible; Konqueror/3.5; Linux) KHTML/3.5.5 (like 
Gecko) (Debian)
Pragma: no-cache
Cache-control: no-cache
Accept: text/html, image/jpeg, image/png, text/*, image/*, */*
Accept-Encoding: x-gzip, x-deflate, gzip, deflate
Accept-Charset: iso-8859-15, utf-8;q=0.5, *;q=0.5
Accept-Language: de, en
Host: ts-nl-test
Authorization: Basic dGllZmVydDpzaHlIaWdoMw==
Connection: Keep-Alive
Depth: 0
Content-Type: text/xml; charset=utf-8
Content-Length: 303

<?xml version="1.0" encoding="utf-8" ?><D:propfind 
xmlns:D="DAV:"><D:prop><D:creationdate/><D:getcontentlength/><D:displayname/>
<D:source/><D:getcontentlanguage/><D:getcontenttype/><D:executable/><D:getlastmodified/>
<D:getetag/><D:supportedlock/><D:lockdiscovery/><D:resourcetype/></D:prop></D:propfind>
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PostgreSQL72 connection established: 
<0x0x8395ebc[PGConnection]:  connection=0x0x849e7a8>
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PostgreSQL72 channel 0x0x83e39b4 
opened (connection=<0x0x8395ebc[PGConnection]:  connection=0x0x849e7a8>)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: BEGIN TRANSACTION
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: SELECT t1.login, 
t1.is_locked, t1.password FROM  person t1 WHERE (t1.login = 'tiefert') AND 
(t1.is_account=1)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: COMMIT 
TRANSACTION
PostgreSQL72 connection dropped 0x0x8395ebc (channel=0x0x83e39b4)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: <0x0xb7e51060[LSCommandContext]> 
+[LSCommandContext(LDAPSupport) isLDAPLoginAuthorized:password:]: LDAP 
server 'ldapserver.hagos.de:389' did authenticate user 'tiefert'
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PostgreSQL72 connection established: 
<0x0x84c1814[PGConnection]:  connection=0x0x8452cd8>
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PostgreSQL72 channel 0x0x83e39b4 
opened (connection=<0x0x84c1814[PGConnection]:  connection=0x0x8452cd8>)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: BEGIN TRANSACTION
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: SELECT t1.login, 
t1.is_locked, t1.password FROM  person t1 WHERE (t1.login = 'tiefert') AND 
(t1.is_account=1)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: PG0x0x83e39b4 SQL: COMMIT 
TRANSACTION
PostgreSQL72 connection dropped 0x0x84c1814 (channel=0x0x83e39b4)
Aug 29 09:35:54 ogo-zidestore-1.5 [2499]: <0x0xb7e51060[LSCommandContext]> 
+[LSCommandContext(LDAPSupport) isLDAPLoginAuthorized:password:]: LDAP 
server 'ldapserver.hagos.de:389' did authenticate user 'tiefert'
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PostgreSQL72 connection established: 
<0x0x848098c[PGConnection]:  connection=0x0x86618a0>
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PostgreSQL72 channel 0x0x866101c 
opened (connection=<0x0x848098c[PGConnection]:  connection=0x0x86618a0>)
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: BEGIN TRANSACTION
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: SELECT 
t1.anniversary, t1.assistant_name, t1.associated_categories, 
t1.associated_company, t1.associated_contacts, t1.birthday, t1.boss_name, 
t1.company_id, t1.contact_id, t1.db_status, t1.degree, t1.department, 
t1.description, t1.dir_server, t1.email_alias, t1.fileas, t1.firstname, 
t1.freebusy_url, t1.im_address, t1.pop3_account, t1.is_account, 
t1.is_customer, t1.is_extra_account, t1.is_intra_account, t1.is_locked, 
t1.is_person, t1.is_private, t1.is_readonly, t1.is_template_user, 
t1.keywords, t1.login, t1.middlename, t1.name, t1.name_affix, t1.name_title, 
t1.number, t1.object_version, t1.occupation, t1.office, t1.owner_id, 
t1.partner_name, t1.password, t1.priority, t1.salutation, t1.sensitivity, 
t1.sex, t1.show_email2_as, t1.show_email3_as, t1.show_email_as, 
t1.source_url, t1.template_user_id, t1.url FROM  person t1 WHERE 
(t1.login='tiefert' AND t1.is_account=1 AND (NOT t1.login='template') AND 
(t1.is_locked=0 OR t1.is_locked is null)) AND (t1.db_status <> 'archived')
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: <0x0xb7e51060[LSCommandContext]> 
+[LSCommandContext(LDAPSupport) isLDAPLoginAuthorized:password:]: LDAP 
server 'ldapserver.hagos.de:389' did authenticate user 'tiefert'
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: SELECT DISTINCT 
t1.attribute, t1.company_id, t1.company_value_id, t1.db_status, t1.is_enum, 
t1.is_label_localized, t1.label, t1.type, t1.uid, t1.value_string FROM  
company_value t1 WHERE t1.company_id IN (1366750)
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: COMMIT 
TRANSACTION
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: BEGIN TRANSACTION
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: PG0x0x866101c SQL: SELECT 
t1.anniversary, t1.assistant_name, t1.associated_categories, 
t1.associated_company, t1.associated_contacts, t1.birthday, t1.boss_name, 
t1.company_id, t1.contact_id, t1.db_status, t1.degree, t1.department, 
t1.description, t1.dir_server, t1.email_alias, t1.fileas, t1.firstname, 
t1.freebusy_url, t1.im_address, t1.pop3_account, t1.is_account, 
t1.is_customer, t1.is_extra_account, t1.is_intra_account, t1.is_locked, 
t1.is_person, t1.is_private, t1.is_readonly, t1.is_template_user, 
t1.keywords, t1.login, t1.middlename, t1.name, t1.name_affix, t1.name_title, 
t1.number, t1.object_version, t1.occupation, t1.office, t1.owner_id, 
t1.partner_name, t1.password, t1.priority, t1.salutation, t1.sensitivity, 
t1.sex, t1.show_email2_as, t1.show_email3_as, t1.show_email_as, 
t1.source_url, t1.template_user_id, t1.url FROM  person t1 WHERE ((1=1) AND 
(t1.is_account=1)) AND (t1.db_status <> 'archived')
Aug 29 09:35:55 ogo-zidestore-1.5 [2499]: CmdCtx[tiefert]: couldn't rollback 
database transaction !
### child 2499 (#2) was terminated by signal 6 (uptime=7998s).
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: SNS support disabled.
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| PATHES: (
    "/usr/local/lib/zidestore-1.5/",
    "/usr/local/lib/zidestore-1.5/",
    "/usr/lib/zidestore-1.5/"
)
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: Tasks.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: PrefsUI.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: zOGI.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: EvoConnect.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: Appointments.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: WCAP.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: RSS.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: Contacts.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: Projects.zsp
Aug 29 09:35:55 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| register ZideStore 
product: ZSCommon.zsp
Aug 29 09:35:56 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| vMem Size check 
enabled: shutting down app when vMem > 200 MB
Aug 29 09:35:56 ogo-zidestore-1.5 [3126]: |ogo-zidestore-1| WOHttpAdaptor 
listening on address *:21000
-- 
OpenGroupware.org Users
[email protected]
http://mail.opengroupware.org/mailman/listinfo/users