Alfresco 3.2 Community, openSUSE 11.1 und LDAP Auth - ERROR

[ibg-monforts]

Folgender Fehler taucht beim Starten von Alfresco auf:
10:11:25,901 ERROR [org.alfresco.web.scripts.AbstractRuntime] Exception from executeScript - redirecting to status template error: 09160003 Login failed
org.alfresco.web.scripts.WebScriptException: 09160003 Login failed
        at org.alfresco.repo.web.scripts.bean.AbstractLoginBean.login(AbstractLoginBean.java:81)
        at org.alfresco.repo.web.scripts.bean.LoginPost.executeImpl(LoginPost.java:79)
        at org.alfresco.web.scripts.DeclarativeWebScript.executeImpl(DeclarativeWebScript.java:223)
        at org.alfresco.web.scripts.DeclarativeWebScript.execute(DeclarativeWebScript.java:70)
        at org.alfresco.repo.web.scripts.RepositoryContainer$2.execute(RepositoryContainer.java:357)
        at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:326)
        at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecute(RepositoryContainer.java:407)
        at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecuteAs(RepositoryContainer.java:424)
        at org.alfresco.repo.web.scripts.RepositoryContainer.executeScript(RepositoryContainer.java:253)
        at org.alfresco.web.scripts.AbstractRuntime.executeScript(AbstractRuntime.java:262)
        at org.alfresco.web.scripts.AbstractRuntime.executeScript(AbstractRuntime.java:139)
        at org.alfresco.web.scripts.servlet.WebScriptServlet.service(WebScriptServlet.java:122)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845)
        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
        at java.lang.Thread.run(Thread.java:619)

Folgende Konfiguration ist in alfresco-global.properties unter <alfresco-root>/tomcat/shared/classes/ vorgenommen worden:
authentication.chain=ldap1:ldap
ldap.authentication.active=true
ldap.authentication.userNameFormat=uid\=%s,ou\=People,dc\=ibg-monforts,dc\=de
ldap.authentication.java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory
ldap.authentication.java.naming.provider.url=ldap://<ldap.unsereDomain>:389
ldap.authentication.java.naming.security.authentication=simple

Seit Version 3 soll die Authentifizierungsmethode doch in dieser Datei konfiguriert werden, oder?

[bwerner]

Hallo,

Hast Du das WebStudio mit im Einsatz?
Dort scheint der Fehler aufzutreten, wenn das admin Passwort geändert wurde oder eine externe Authentifizierung eingerichtet wurde.
Hier ein entsprechender Eintrag in Jira:
https://issues.alfresco.com/jira/browse/ALFCOM-3344

VG,
Bernhard

[ibg-monforts]

Wir haben lediglich die Community-Version von der folgenden Seite installiert:
http://wiki.alfresco.com/wiki/Download_Alfresco_Community_Network

und die oben genannten Änderungen in der Konfigurationsdatei vorgenommen.
Das openSUSE System ist in der Standard Paketauswahl und es wird der MySQL
Server aus den Repositories benutzt.

[bwerner]

Hallo,

Dann wirst Du WebStudio noch im Stack haben.
Lösche doch ein mal das Verzeichnis ../tomcat/webapps/studio und verschiebe die Datei ../tomcat/webapps/studio.war in ein Verzeichnis ausserhalb des Tomcat Verzeichnisses.
Vor einem Neustart solltest Du das alfresco.log leeren und dann das neu geschriebene hier posten.

Viele Grüße,
Bernhard

[ibg-monforts]

Haben das studio-Verzeichnis unterhalb von /tomcat/webapps gelöscht und die studio.war-Datei
außerhalb des tomcat-Ordners verschoben. Allerdings scheint das nicht zu funktionieren.
Die Fehlermeldung taucht zwar nicht mehr auf, jedoch startet Alfresco nicht mehr vollstandig.
ps aux sagt folgendes:

root      2542  0.0  0.1  11228  1532 pts/0    S    08:29   0:00 /bin/sh /usr/bin/mysqld_safe –mysqld=mysqld –user=mysql –pid-file=/var/lib/mysql/mysqld.pid –socket=/var/lib/mysql/mysql.sock –datadir=/var/lib/mysql
mysql     2582  0.1  3.0 207624 31816 pts/0    Sl   08:29   0:02 /usr/sbin/mysqld –basedir=/usr –datadir=/var/lib/mysql –user=mysql –pid-file=/var/lib/mysql/mysqld.pid –skip-external-locking –port=3306 –socket=/var/lib/mysql/mysql.sock
root      2609  3.5 23.0 1345004 244092 pts/0  Sl   08:29   1:16 /usr/lib/jvm/java-1.6.0-sun-1.6.0/jre/bin/java -Xms128m -Xmx1024m -XX:MaxPermSize=128m -server -Dalfresco.home=/opt/Alfresco -Dcom.sun.management.jmxremote -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.util.logging.config.file=/opt/Alfresco/tomcat/conf/logging.properties -Djava.endorsed.dirs=/opt/Alfresco/tomcat/endorsed -classpath :/opt/Alfresco/tomcat/bin/bootstrap.jar -Dcatalina.base=/opt/Alfresco/tomcat -Dcatalina.home=/opt/Alfresco/tomcat -Djava.io.tmpdir=/opt/Alfresco/tomcat/temp org.apache.catalina.startup.Bootstrap start
root      2769  0.0  2.8 403864 30068 pts/0    Sl   08:30   0:00 /usr/lib64/ooo3/program/soffice.bin -accept=socket,host=localhost,port=8100;urp;StarOffice.ServiceManager -env:UserInstallation=file:///opt/Alfresco/alf_data/oouser -nologo -headless -nofirststartwizard -nocrashrep -norestore -splash-pipe=5‍‍‍‍

und die alfresco.log sieht wie folgt aus:
08:29:52,214 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
08:29:52,216 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
08:29:52,216 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/opt/Alfresco/tomcat/shared/classes/alfresco-global.properties]
08:29:52,281 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:12,227 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
08:30:13,721 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
08:30:17,943 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [default]
08:30:17,975 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:19,555 WARN  [org.alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
08:30:19,555 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [default] complete
08:30:20,185 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/Alfresco/alf_data
08:30:20,226 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Sucht nach anwendbaren Patches…
08:30:20,507 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Es sind keine Patches erforderlich
08:30:20,510 INFO  [org.alfresco.repo.module.ModuleServiceImpl] 0 Modul(e) gefunden.
08:30:20,523 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [default]
08:30:20,646 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:21,829 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [managed, ldap1]
08:30:21,887 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:22,242 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [managed, ldap1] complete
08:30:22,242 WARN  [org.alfresco.fileserver] No enabled CIFS authenticator found in authentication chain. CIFS Server disabled
08:30:22,532 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [default] complete
08:30:22,532 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [default]
08:30:22,567 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:22,607 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [default] complete
08:30:22,607 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [default]
08:30:22,686 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:22,693 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [default] complete
08:30:22,732 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_11-b03; maximum heap size 1012,625MB
08:30:22,733 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (2039) schema 2019 - Installed version 3.2.0 (2039) schema 2019
08:30:35,330 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'wcm_deployment_receiver' subsystem, ID: [default]
08:30:35,371 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
08:30:35,469 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'wcm_deployment_receiver' subsystem, ID: [default] complete
08:30:37,583 INFO  [org.alfresco.config.JBossEnabledWebApplicationContext] Refreshing org.alfresco.config.JBossEnabledWebApplicationContext@1f19c37: display name [Root WebApplicationContext]; startup date [Mon Nov 02 08:30:37 CET 2009]; root of context hierarchy
08:30:37,837 INFO  [org.alfresco.config.JBossEnabledWebApplicationContext] Bean factory for application context [org.alfresco.config.JBossEnabledWebApplicationContext@1f19c37]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1e61638
08:30:39,245 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 24 Web Scripts (+0 failed), 26 URLs
08:30:39,264 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised Presentation Web Script Container (in 185.76167ms)
08:30:39,350 INFO  [org.alfresco.web.scripts.DeclarativeRegistry] Registered 42 Web Scripts (+0 failed), 44 URLs
08:30:39,388 INFO  [org.alfresco.web.scripts.AbstractRuntimeContainer] Initialised WebFramework Web Script Container (in 120.8841ms)
08:30:39,420 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
08:30:42,864 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework
08:31:00,302 INFO  [org.alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.

Ich hoffe das Problem lässt sich beheben, aber vielen Dank bis hierhin schon mal.

[bwerner]

Hallo,

Das log file sieht aber durchaus so aus als ob Dein Alfresco Server läuft.
Einzig CIFS wurde nicht gestartet.
Bekommst Du irgendwo eine Fehlermeldung?

Viele Grüße,
Bernhard

[ibg-monforts]

Nein leider nicht!
Also nach einigen Anläufen scheint das Webinterface jetzt wieder zugänglich zu sein,
aber die LDAP Anmeldung funktioniert immer noch nicht:
Anmelden nicht möglich - Unbekannter Benutzername/Passwort.
Mittlerweile startet das gesamte Programm aber auch schneller.
Zuvor dauerte der Start mehr als 2 Minuten bzw. länger da, ein Stillstand in der Log mit
und ohne LDAP Auth vonetwa 2 Minuten zu bemerken war. Wir sind wohl auf dem richtigen Weg.

[bwerner]

Hallo,

Also laut log werden die benötigten subsysteme gestartet (LDAP & Sync).
Jetzt kann es eigentlich nur noch an der Konfiguration für LDAP selbst liegen.
Kannst Du Deine Konfiguration noch ein mal mit diesen Seiten abstimmen?
http://wiki.alfresco.com/wiki/Alfresco_Authentication_Subsystems#Example_2:_Advanced_LDAP_Chain
http://wiki.alfresco.com/wiki/Alfresco_Authentication_Subsystems#LDAP

Viele Grüße,
Bernhard

[ibg-monforts]

Wie im Alfresco Wiki beschrieben, wurde nun in die alfresco-global.properties lediglich:
authentication.chain=ldap1:ldap
eingetragen und die Datei ldap-authentication.properties unterhalb von
$TOMCAT_HOME/webapps/alfresco/WEB-INF/classes/alfresco/subsystems/Authentication/ldap/
nach $TOMCAT_HOME/shared/classes/alfresco/extension/subsystems/Authentication/ldap/ldap1/
kopiert. Hierbei erhalten wir folgende Fehlermeldung:
12:00:53,072 ERROR [org.alfresco.repo.security.authentication.ldap.LDAPInitialDirContextFactoryImpl] Unable to connect to LDAP Server; check LDAP configuration
javax.naming.CommunicationException: openldap.domain.com:389 [Root exception is java.net.UnknownHostException: openldap.domain.com]
Wie es aussieht versucht er sich hier auf dem falschen LDAP-Server ein zu loggen, obwohl wir doch die Werte in der ldap-authentication.properties geschrieben haben!

Wenn die alfresco-global.properties zusätzlich noch die Angaben enthält die auch in der Datei:
ldap-authentication.properties festgelegt werden (LDAP-Host,searchstring…) taucht der Fehler nicht auf,
Anmeldung ist weiterhin nicht erfolgreich:
Anmelden nicht möglich - Unbekannter Benutzername/Passwort.
Jedoch fällt beim Anmeldeversuch in der Log nun folgendes auf:
12:14:10,507 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] Synchronizing users and groups with user registry 'ldap1'
12:14:10,508 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] Retrieving all users from user registry 'AUTH.EXT.ldap1'