3d does not allow login

[m_r_]

Given the last 2 times I've tried to get help in this forum I've been unsuccessful - I'll try again because I'm just that way.

Versions in play:
apache-tomcat-6.0.18
postgresql-server-8.2.9
http://dev.alfresco.com/downloads/nightly/dist/alfresco-labs-war-3d-dev.zip
Tomcat says:
Apache Tomcat/6.0.18     1.6.0_07-b02     The FreeBSD Foundation     FreeBSD     6.3-STABLE     i386


Tomcat log looks like this (BTW the best its looked in this effort) :
INFO: HTMLManager: list: Listing contexts for virtual host 'localhost'
Jan 16, 2009 9:24:40 PM org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: start: Starting web application at '/alfresco'
Jan 16, 2009 9:24:44 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring root WebApplicationContext
21:24:53,246  INFO  [web.site.FrameworkHelper] Successfully Initialized Web Framework

Modified WEB-INF/classes/log4j.properties per others comments to a fixed path so this is what I get when the login attempt happens:

21:24:53,246 INFO  [org.alfresco.web.site.FrameworkHelper] Successfully Initialized Web Framework



to admin:admin  and alfresco:alfresco   (the logins that are supposed to work)

Failed to Login
The remote server may be unavailable or your authentication details have not been recognized.
OK


So - what error logging do I need to turn on to figure out why the login is not allowed?


(Oh and have any of you run the install on OpenCMS?   The feedback it gives was nice.   The community might wanna look at what they are doing and ape it.)

[mikeh]

Hi

The login "alfresco:alfresco" has never been configured as far as I know - where did you get that from out of interest?

From the error, you're presumably trying to login to /share, but there's been an error deploying the /alfresco webapp (alfresco.war). Could you post the error(s) in the log file please?

Actually, looking at what you've posted again I don't believe PostgreSQL is supported in Labs V3 releases - only Derby (not recommended for anything other than simple POC) and MySQL (which is what we use day-to-day for development).

Mike

[m_r_]

The login "alfresco:alfresco" has never been configured as far as I know - where did you get that from out of interest?

Some website talking about their Alfresco config/demo thing.   Might have been 'here is our VM - and here are the passwords.   Alas, I can not find it again.

From the error, you're presumably trying to login to /share, but there's been an error deploying the /alfresco webapp (alfresco.war). Could you post the error(s) in the log file please?

Already did - there is only the start up messages - no errors.   Hence my asking if there was some knob I could turn to increase log verbosity.
So - where is this knob?

Actually, looking at what you've posted again I don't believe PostgreSQL is supported in Labs V3 releases - only Derby (not recommended for anything other than simple POC) and MySQL (which is what we use day-to-day for development).

Lets say your belief is correct.  What is the last know to work with PostgreSQL version so that I can use that version in the Advanced Linux course I teach?

[mikeh]

Lets say your belief is correct.  What is the last know to work with PostgreSQL version so that I can use that version in the Advanced Linux course I teach?

Any of the 2.x releases will support PostgreSQL.

Mike

[m_r_]

Any of the 2.x releases will support PostgreSQL.

2.9?

[mikeh]

Should do, yes. We're planning to release patches to the PostgreSQL scripts for v3.0 - I don't have a time for that though. http://wiki.alfresco.com/wiki/Alfresco_Labs_3_Stable_Release_Notes

Thanks,
Mike

[m_r_]

Should do, yes.

Ok - I'll try 2.9 again.   My memory is I had an issue - but I don't remember what it was.

We're planning to release patches to the PostgreSQL scripts for v3.0 -

Ok.   Just reply to topic/send email.    For my class Nuxeo looks like it'll do what I wanted - so I'm not SOL on having some technology that'll work.

[m_r_]

2B issues: now that I'm trying it again:

So I've changed the file so it uses PostgreSQL
grep -v ^# custom-repository.properties
db.username=alfresco
db.password=redacted
dir.root=/data/alfresco/alf_data
db.driver=org.postgresql.Driver
db.url=jdbcostgresql://localhost:5432/alfresco

And I'm seeing errors that its trying MySQL
Caused by: java.sql.SQLException: Access denied for user 'alfresco'@'localhost' (using password: YES)
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1055)
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3515)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3447)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:911)
        at com.mysql.jdbc.MysqlIO.secureAuth411(MysqlIO.java:3953)
        at com.mysql.jdbc.MysqlIO.doHandshake(MysqlIO.java:1276)
        at com.mysql.jdbc.ConnectionImpl.createNewIO(ConnectionImpl.java:2048)
        at com.mysql.jdbc.ConnectionImpl.<init>(ConnectionImpl.java:723)
        at com.mysql.jdbc.JDBC4Connection.<init>(JDBC4Connection.java:46)

And even though I set:
dir.root=/data/alfresco/alf_data
It appears to be being created in webapps/alfresco/extensions/extension
Now its not complaining about not being able to write to alfresco.log - yet the log file was not appended to in the last go-round on attempting to start it.  02:26 is not 02:49

# ls -lt | head
total 216
-rwxrwxrwx  1 root  wheel   2087 Jan 20 02:42 custom-repository.properties
-rwxrwxrwx  1 www   wheel  16850 Jan 20 02:26 alfresco.log
drwxrwxrwx  6 www   wheel    512 Jan 20 02:25 alf_data
-rwxrwxrwx  1 root  wheel   2238 Jan 20 02:14 file-servers-custom.xml
-rwxrwxrwx  1 root  wheel    935 Jan 20 02:14 custom-hibernate-dialect.properties

# pwd
(redaction)/webapps/alfresco/extensions/extension
INFO: HTMLManager: start: Starting web application at '/alfresco'
Jan 20, 2009 2:48:24 AM org.apache.catalina.core.StandardContext addApplicationListener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is already configured for this context. The duplicate definition has been ignored.
Jan 20, 2009 2:48:28 AM org.apache.catalina.core.ApplicationContext log
INFO: Loading Spring root WebApplicationContext
Jan 20, 2009 2:48:49 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already.  Could not load org.bouncycastle.jce.provider.JDKMessageDigest$MD4.  The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
java.lang.IllegalStateException
        at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1273)
        at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1233)

(A whole lotta BLAH BLAH BLAH now deleted)

02:48:54,503 Userystem WARN  [remoting.rmi.RmiRegistryFactoryBean] Could not detect RMI registry - creating new one
02:49:00,863 Userystem ERROR [web.context.ContextLoader] Context initialization failed
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'usageQuotaProtector' defined in class path resource [alfresco/usage-services-context.xml]: Invocation of init method failed; nested exception is org.springframework.transaction.CannotCreateTransactionException: Could not open Hibernate Session for transaction; nested exception is org.hibernate.exception.GenericJDBCException: Cannot open connection
Caused by: org.springframework.transaction.CannotCreateTransactionException: Could not open Hibernate Session for transaction; nested exception is org.hibernate.exception.GenericJDBCException: Cannot open connection
Caused by: org.hibernate.exception.GenericJDBCException: Cannot open connection
        at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:103)
        at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:91)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:29)

(A whole lotta BLAH BLAH BLAH now deleted)


at org.springframework.orm.hibernate3.LocalDataSourceConnectionProvider.getConnection(LocalDataSourceConnectionProvider.java:81)
        at org.hibernate.jdbc.ConnectionManager.openConnection(ConnectionManager.java:417)
        … 50 more
Caused by: java.sql.SQLException: Access denied for user 'alfresco'@'localhost' (using password: YES)
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1055)
        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3515)

(The rest of the errors deleted as its all about JDBC failures and that is to be expected with the attempt to connect to MySQL failing)

So - why the MySQL 'I can't log in' messages?

[zaizi]

You need to edit custom-hibernate-dialect.properties and change it to use PostgreSQL dialect.

See http://wiki.alfresco.com/wiki/Repository_Configuration#Core_Configuration_Files.

[m_r_]

You need to edit custom-hibernate-dialect.properties and change it to use PostgreSQL dialect.

Although it is not stated above that was done already.   Below is the conformation of that documented change.

# ls -lt | head
total 216
-rwxrwxrwx  1 root  wheel   2087 Jan 20 02:42 custom-repository.properties
-rwxrwxrwx  1 www   wheel  16850 Jan 20 02:26 alfresco.log
drwxrwxrwx  6 www   wheel    512 Jan 20 02:25 alf_data
-rwxrwxrwx  1 root  wheel   2238 Jan 20 02:14 file-servers-custom.xml
-rwxrwxrwx  1 root  wheel    935 Jan 20 02:14 custom-hibernate-dialect.properties
-rwxrwxrwx  1 root  wheel   1092 Nov 16  2007 custom-email-server.sample.zip
# date
Tue Jan 20 16:10:54 UTC 2009

# grep -v ^# custom-hibernate-dialect.properties
hibernate.dialect=org.hibernate.dialect.PostgreSQLDialect
hibernate.query.substitutions=true TRUE, false FALSE

Is there any other changes or have I found another bug?

http://wiki.alfresco.com/wiki/Repository_Configuration#Core_Configuration_Files

Core Configuration Files
The <configRoot> folder, and especially the <configRoot>/alfresco folder contains the core, Alfresco-maintained, configuration files.

Is that still the case today?  It is not custom-repository.properties?  I found repository.properties in
alfresco/WEB-INF/classes/alfresco/repository.properties


Any ideas on stdout.log:INFO: Illegal access: this web application instance has been stopped already.  Could not load org.bouncycastle.jce.provider.JDKMessageDigest$MD4.   The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.