Good day, Well, I'm back on this after a reinstall to see how things are looking.
Here's the nice thing so far.... SOGo installed. I've got it working with PGSQL I believe I have it working with OpenLDAP with exceptions. Versions that we are playing with here: FreeBSD 9.1 RELEASE apache22-2.2.24 memcached-1.4.15 nss_ldap-1.265_7 openldap-client-2.4.35 pam_ldap-1.8.6_2 postgresql-client-9.2.4 sogo-2.0.5 sope-2.0.5 Sogo starts and runs Apache starts and runs Memcached starts and runs Anonymous ldap binding seems to run fine and I can grab a uid in the subtree. My LDAP server has a configuration like: dc=company,dc=com ou=people,dc=company,dc=com When I start up sogo, I get the following in the /var/log/sogo/sogo.log: Jun 26 16:06:03 sogod [67881]: version 2.0.5 (build [email protected] 201306251909) -- starting Jun 26 16:06:03 sogod [67881]: vmem size check enabled: shutting down app when vmem > 384 MB Jun 26 16:06:03 sogod [67881]: <0x0x8087b5988[SOGoProductLoader]> SOGo products loaded from '/usr/local/GNUstep/Local/Library/SOGo': Jun 26 16:06:03 sogod [67881]: <0x0x8087b5988[SOGoProductLoader]> MailPartViewers.SOGo, ContactsUI.SOGo, Mailer.SOGo, Appointments.SOGo, MainUI.SOGo, CommonUI.SOGo, SchedulerUI.SOGo, AdministrationUI.SOGo, PreferencesUI.SOGo, Contacts.SOGo, MailerUI.SOGo Jun 26 16:06:03 sogod [67881]: <0x0x808470438[WOWatchDog]> listening on *:20000 Jun 26 16:06:03 sogod [67881]: <0x0x808470438[WOWatchDog]> watchdog process pid: 67881 Jun 26 16:06:03 sogod [67881]: <0x0x801c3bb60[WOWatchDogChild]> watchdog request timeout set to 10 minutes Jun 26 16:06:03 sogod [67881]: <0x0x808470438[WOWatchDog]> preparing 1 children Jun 26 16:06:03 sogod [67881]: <0x0x808470438[WOWatchDog]> child spawned with pid 67882 2013-06-26 16:06:03.695 sogod[67882] PostgreSQL72 connection established: <0x0x809cf20a8[PGConnection]: connection=0x0x809d7c000> 2013-06-26 16:06:03.695 sogod[67882] PostgreSQL72 channel 0x0x809cba048 opened (connection=<0x0x809cf20a8[PGConnection]: connection=0x0x809d7c000>) 2013-06-26 16:06:03.695 sogod[67882] PG0x0x809cba048 SQL: SELECT count(*) FROM sogo_user_profile 2013-06-26 16:06:03.696 sogod[67882] PG0x0x809cba048 SQL: SELECT count(*) FROM sogo_folder_info 2013-06-26 16:06:03.698 sogod[67882] PG0x0x809cba048 SQL: SELECT count(*) FROM sogo_sessions_folder Hmmmm... looks fine in that we're connecting to PostGreSQL and everything checks out on the PGSQL side. A simple web server test on the machine, retries the "It Works!" on the home page. Attempting to go to the http://www.company.com/SOGo page.... I get the following in the sogo log: 2013-06-26 16:30:54.616 sogod[68616] Note: Using UTF-8 as URL encoding in NGExtensions. Jun 26 16:30:54 sogod [68616]: |SOGo| starting method 'GET' on uri '/SOGo/' Jun 26 16:30:54 sogod [68616]: <0x0x809df6d38[SOGoCache]> Cache cleanup interval set every 300.000000 seconds Jun 26 16:30:54 sogod [68616]: <0x0x809df6d38[SOGoCache]> Using host(s) 'localhost' as server(s) 2013-06-26 16:30:54.618 sogod[68616] PG0x0x809cba048 SQL: BEGIN TRANSACTION 2013-06-26 16:30:54.619 sogod[68616] PG0x0x809cba048 SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM sogo_sessions_folder t1 WHERE t1.c_id='OtUy2L39/oh7u6ije6m1xA==' 2013-06-26 16:30:54.620 sogod[68616] PG0x0x809cba048 SQL: ROLLBACK TRANSACTION 2013-06-26 16:30:54.623 sogod[68616] PostgreSQL72 connection established: <0x0x809d6fc88[PGConnection]: connection=0x0x809d80c00> 2013-06-26 16:30:54.623 sogod[68616] PostgreSQL72 channel 0x0x809d04848 opened (connection=<0x0x809d6fc88[PGConnection]: connection=0x0x809d80c00>) 2013-06-26 16:30:54.623 sogod[68616] PG0x0x809d04848 SQL: BEGIN TRANSACTION 2013-06-26 16:30:54.623 sogod[68616] PG0x0x809d04848 SQL: SELECT t1.c_creationdate, t1.c_id, t1.c_lastseen, t1.c_value FROM sogo_sessions_folder t1 WHERE t1.c_id='OtUy2L39/oh7u6ije6m1xA==' 2013-06-26 16:30:54.624 sogod[68616] PG0x0x809d04848 SQL: ROLLBACK TRANSACTION 2013-06-26 16:30:54.624 sogod[68616] PG0x0x809cba048 SQL: BEGIN TRANSACTION 2013-06-26 16:30:54.624 sogod[68616] PG0x0x809cba048 SQL: UPDATE sogo_sessions_folder SET c_value = 'RHtwGoSxIq0lcvmfWdP6FXwvWMu1wV/IEqoBSkoK13hTtXPg8+COyTrMIY5xgcvIitCGUCmfDQ5KK+IXySFijtt/7j9EvJMYEm1oapBHU79u/IsrdAu5F8hHGEAl54uZkqCAKHmymjQIXaMo3UZYjkoonmaIcIXRpo07RmUhz4+UOf+SV9Pm8GSnaiekMoPsW7muqzEfJiplmsge+VGZEg==', c_lastseen = 1372278654, c_creationdate = 1372274587, c_id = 'OtUy2L39/oh7u6ije6m1xA==' WHERE c_id='OtUy2L39/oh7u6ije6m1xA==' 2013-06-26 16:30:54.625 sogod[68616] PG0x0x809cba048 SQL: COMMIT TRANSACTION Jun 26 16:30:54 sogod [68616]: <0x0x809e303d8[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://<LDAP IP>:389 2013-06-26 16:30:54.651 sogod[68616] Note(SoObject): SoDebugKeyLookup is enabled! 2013-06-26 16:30:54.651 sogod[68616] Note(SoObject): SoDebugBaseURL is enabled! 2013-06-26 16:30:54.651 sogod[68616] Note(SoObject): relative base URLs are enabled. Jun 26 16:30:54 sogod [68616]: <0x0x809e9f2e8[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://<LDAP IP>:389 2013-06-26 16:30:54.652 sogod[68616] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search with at base ��N filter ��N for attrs �OM 2013-06-26 16:30:54.653 sogod[68616] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search with at base filter ��N for attrs �OM 2013-06-26 16:30:54.656 sogod[68616] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search with at base ��N filter ��N for attrs �OM 2013-06-26 16:30:54.658 sogod[68616] PG0x0x809d04848 SQL: SELECT c_defaults FROM sogo_user_profile WHERE c_uid = 'sogo' 2013-06-26 16:30:54.661 sogod[68616] ERROR(-[NGBundleManager bundleWithPath:]): could not create bundle for path: '/usr/local/GNUstep/System/Library/Libraries/gnustep-base/Versions/1.24/Resources/SSL.bundle' 2013-06-26 16:30:54.668 sogod[68616] WOCompoundElement: pool embedding is on. 2013-06-26 16:30:54.668 sogod[68616] WOCompoundElement: id logging is on. Jun 26 16:30:54 sogod [68616]: |SOGo| constructed root-url: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| setting root-url in context: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| request took 0.053222 seconds to execute 172.24.0.4 - - [26/Jun/2013:16:30:54 GMT] "GET /SOGo/ HTTP/1.1" 302 0/0 0.055 - - - Jun 26 16:30:54 sogod [68616]: |SOGo| starting method 'GET' on uri '/SOGo/sogo' Jun 26 16:30:54 sogod [68616]: |SOGo| request took 0.001013 seconds to execute 172.24.0.4 - - [26/Jun/2013:16:30:54 GMT] "GET /SOGo/sogo HTTP/1.1" 302 0/0 0.001 - - - Jun 26 16:30:54 sogod [68616]: |SOGo| starting method 'GET' on uri '/SOGo/sogo/view' Jun 26 16:30:54 sogod [68616]: |SOGo| constructed root-url: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| setting root-url in context: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/ Jun 26 16:30:54 sogod [68616]: <0x09DF6EC8[SOGoUserFolder]:sogo> baseURL: name=sogo (container=SOGo) container: /SOGo -- http://team.atlantisservices.net/SOGo/sogo/view own: /SOGo/sogo Jun 26 16:30:54 sogod [68616]: |SOGo| request took 0.001716 seconds to execute 172.24.0.4 - - [26/Jun/2013:16:30:54 GMT] "GET /SOGo/sogo/view HTTP/1.1" 302 0/0 0.002 - - - Jun 26 16:30:54 sogod [68616]: |SOGo| starting method 'GET' on uri '/SOGo/so/sogo/Mail' Jun 26 16:30:54 sogod [68616]: |SOGo| request took 0.000898 seconds to execute 172.24.0.4 - - [26/Jun/2013:16:30:54 GMT] "GET /SOGo/so/sogo/Mail HTTP/1.1" 302 0/0 0.001 - - - Jun 26 16:30:54 sogod [68616]: |SOGo| starting method 'GET' on uri '/SOGo/so/sogo/Mail/view' 2013-06-26 16:30:54.715 sogod[68616] PG0x0x809cba048 SQL: SELECT c_settings FROM sogo_user_profile WHERE c_uid = 'sogo' Jun 26 16:30:54 sogod [68616]: |SOGo| constructed root-url: /SOGo/ Jun 26 16:30:54 sogod [68616]: |SOGo| setting root-url in context: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: <0x09DF6EC8[SOGoUserFolder]:sogo> baseURL: name=sogo (container=SOGo) container: /SOGo -- http://team.atlantisservices.net/SOGo/so/sogo/Mail/view own: /SOGo/so/sogo Jun 26 16:30:54 sogod [68616]: |SOGo| using root-url from context: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: <0x09DF6EC8[SOGoUserFolder]:sogo> baseURL: name=sogo (container=SOGo) container: /SOGo -- http://team.atlantisservices.net/SOGo/so/sogo/Mail/view own: /SOGo/so/sogo Jun 26 16:30:54 sogod [68616]: |SOGo| using root-url from context: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: <0x09DF6EC8[SOGoUserFolder]:sogo> baseURL: name=sogo (container=SOGo) container: /SOGo -- http://team.atlantisservices.net/SOGo/so/sogo/Mail/view own: /SOGo/so/sogo Jun 26 16:30:54 sogod [68616]: |SOGo| using root-url from context: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: |SOGo| ROOT baseURL(no container, name=(null)): own: /SOGo/so/ Jun 26 16:30:54 sogod [68616]: <0x09DF6EC8[SOGoUserFolder]:sogo> baseURL: name=sogo (container=SOGo) container: /SOGo -- http://team.atlantisservices.net/SOGo/so/sogo/Mail/view own: /SOGo/so/sogo Jun 26 16:30:54 sogod [68616]: <0x0x80c171568[NGLdapConnection]> Using ldap_initialize for LDAP URL: ldap://<LDAP IP>:389 2013-06-26 16:30:54.727 sogod[68616] -[NGLdapConnection _searchAtBaseDN:qualifier:attributes:scope:]: search with at base ��N filter ��N for attrs �OM 2013-06-26 16:30:54.729 sogod[68616] WARNING: IMAP4 connection pooling is disabled! Jun 26 16:31:54 sogod [68615]: [WARN] <0x0x809cc3568[WOWatchDogChild]> pid 68616 has been hanging in the same request for 1 minutes Jun 26 16:32:54 sogod [68615]: [WARN] <0x0x809cc3568[WOWatchDogChild]> pid 68616 has been hanging in the same request for 2 minutes I check LDAP on the server when I perform: ldapsearch -x -H ldap://ldap.company.com -b "ou=people,dc=company,dc=com" -D "cn=sogo,ou=people,dc=company,dc=com" -w qwerty uid=sogo uid It responds with the sogo uid, so binding and searching seem to be working fine. (I tried other people as sogo bind and found found them to work) I get no web page......It just hangs..... The page, finally, comes back with: Proxy Error The proxy server received an invalid response from an upstream server. The proxy server could not handle the request GET /SOGo/so/sogo/Mail/view. Reason: Error reading from remote server It looks like, from the http-access log that it's trying to get sogo's information: From the http error log: [Wed Jun 26 20:27:50 2013] [notice] Apache/2.2.24 (FreeBSD) DAV/2 PHP/5.4.14 mod_ssl/2.2.24 OpenSSL/0.9.8x configured -- resuming normal operations [Wed Jun 26 20:28:36 2013] [error] [client ] (54)Connection reset by peer: proxy: error reading status line from remote server 127.0.0.1:20000 [Wed Jun 26 20:28:36 2013] [error] [client ] proxy: Error reading from remote server returned by /SOGo/ [Wed Jun 26 20:29:09 2013] [error] (61)Connection refused: proxy: HTTP: attempt to connect to 127.0.0.1:20000 (127.0.0.1) failed [Wed Jun 26 20:29:09 2013] [error] ap_proxy_connect_backend disabling worker for (127.0.0.1) [Wed Jun 26 20:35:54 2013] [error] [client ] (70007)The timeout specified has expired: proxy: error reading status line from remote server 127.0.0.1:20000 [Wed Jun 26 20:35:54 2013] [error] [client ] proxy: Error reading from remote server returned by /SOGo/so/sogo/Mail/view Anyone got some insight as to what I check/trace/debug on this? Thank you! P. ________________________________-- [email protected] https://inverse.ca/sogo/lists
