Error after activating userLogger (Apache)
Concerned version
Version: 2.0.7+ds-2 Debian/Bullseye
Platform: (Apache)
Summary
I am currently going from Lemonldap 1.9 to 2.0.7, in the configuration file "lemonldap-ng.ini" I have for the logs:
logger = Lemonldap::NG::Common::Logger::Syslog
syslogFacility = daemon
userSyslogFacility = auth
What works.
If I add a line for "userLogger":
logger = Lemonldap::NG::Common::Logger::Syslog
userLogger = Lemonldap::NG::Common::Logger::Syslog
syslogFacility = daemon
userSyslogFacility = auth
After connecting to the portal, I have an error and a blank page with the phrase "Internal Server Error".
Logs
In the apache logs there is:
[Thu Feb 20 10:14:30.206044 2020] [fcgid:warn] [pid 9856] [client x.x.x.x:56078] mod_fcgid: stderr: Can't use string ("Lemonldap::NG::Common::Logger::S"...) as a HASH ref while "strict refs" in use at (eval 56) line 1., referer: https://auth.toto.com/
in the syslog there is:
févr. 20 10:14:29 LLNG[12846]: [debug] Check configuration for Lemonldap::NG::Handler::PSGI::Main
févr. 20 10:14:29 LLNG[12846]: [debug] Get configuration from cache without verification.
févr. 20 10:14:29 LLNG[12846]: [debug] Lemonldap::NG::Handler::PSGI::Main: configuration is up to date
févr. 20 10:14:29 LLNG[12846]: [info] No cookie found
févr. 20 10:14:29 LLNG[12846]: [debug] Build URL https://auth.toto.com/
févr. 20 10:14:29 LLNG[12846]: [debug] Redirect x.x.x.x to portal (url was /)
févr. 20 10:14:29 LLNG[12846]: [debug] User not authenticated, Try in use, cancel redirection
févr. 20 10:14:29 LLNG[12846]: [debug] Start routing default route
févr. 20 10:14:29 LLNG[12846]: [debug] Processing restoreArgs
févr. 20 10:14:29 LLNG[12846]: [debug] Processing controlUrl
févr. 20 10:14:29 LLNG[12846]: [debug] Processing code ref
févr. 20 10:14:29 LLNG[12846]: [debug] Processing code ref
févr. 20 10:14:29 LLNG[12846]: [debug] Launching ::Issuer::CAS::storeEnvAndCheckGateway
févr. 20 10:14:29 LLNG[12846]: [debug] Processing extractFormInfo
févr. 20 10:14:29 LLNG[12846]: [debug] Trying to load token 1582118157_44762
févr. 20 10:14:29 LLNG[12846]: [debug] Processing getUser
févr. 20 10:14:29 LLNG[12846]: [debug] Processing authenticate
févr. 20 10:14:29 LLNG[12846]: [debug] Call bind for uid=fredo,ou=People,dc=toto,dc=com
févr. 20 10:14:29 LLNG[12846]: [debug] -> authResult = 0
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setAuthSessionInfo
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setSessionInfo
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setMacros
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setGroups
févr. 20 10:14:29 LLNG[12846]: [debug] Searching LDAP groups in ou=Groups,dc=toto,dc=com for fredo
févr. 20 10:14:29 LLNG[12846]: [debug] Group search filter: (&(objectClass=posixGroup)(|(memberUid=fredo)))
févr. 20 10:14:29 LLNG[12846]: [debug] Matching group cn=tata,ou=Groups,dc=toto,dc=com found
févr. 20 10:14:29 LLNG[12846]: [debug] Store values of cn in group tata
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setPersistentSessionInfo
févr. 20 10:14:29 LLNG[12846]: [debug] Persistent session found for fredo
févr. 20 10:14:29 LLNG[12846]: [debug] Restore persistent parameter _updateTime
févr. 20 10:14:29 LLNG[12846]: [debug] Restore persistent parameter _loginHistory
févr. 20 10:14:29 LLNG[12846]: [debug] Processing setLocalGroups
févr. 20 10:14:29 LLNG[12846]: [debug] Processing store
févr. 20 10:14:29 LLNG[12846]: [debug] Set _updateTime with 20200220101429
févr. 20 10:14:29 LLNG[12846]: [debug] Try to get a new SSO session
févr. 20 10:14:30 LLNG[12846]: [debug] Return SSO session 4316427717ebd17ddf0b3629cb4e62f3af52f152143d2262180ab088cd20fad3
févr. 20 10:14:30 LLNG[12846]: [debug] Create second session for unsecured cookie...
févr. 20 10:14:30 LLNG[12846]: [debug] -> Cookie value : 4316427717ebd17ddf0b3629cb4e62f3af52f152143d2262180ab088cd20fad3
févr. 20 10:14:30 LLNG[12846]: [debug] Store fr in session key _language
févr. 20 10:14:30 LLNG[12846]: [debug] Store 1582190069 in session key _utime
févr. 20 10:14:30 LLNG[12846]: [debug] Store 3 in session key authenticationLevel
févr. 20 10:14:30 LLNG[12846]: [debug] Store Frederic MASSOT in session key cn
févr. 20 10:14:30 LLNG[12846]: [debug] Store fredo in session key _whatToTrace
févr. 20 10:14:30 LLNG[12846]: [debug] Store uid=fredo,ou=People,dc=toto,dc=com in session key _dn
févr. 20 10:14:30 LLNG[12846]: [debug] Store LDAP in session key _userDB
févr. 20 10:14:30 LLNG[12846]: [debug] Store fredo in session key uid
févr. 20 10:14:30 LLNG[12846]: [debug] Store 1582190069 in session key _lastAuthnUTime
févr. 20 10:14:30 LLNG[12846]: [debug] Store HASH(0x5647daafbb10) in session key _loginHistory
févr. 20 10:14:30 LLNG[12846]: [debug] Dump: $VAR1 = {'failedLogin' => [],'successLogin' => [{'_utime' => '1582132374','ipAddr' => 'x.x.x.x'},{'_utime' => '1582132253','ipAddr' => 'x.x.x.x'},{'_utime' => '1582129470','ipAddr' => 'x.x.x.x'},{'_utime' => '1582020454','ipAddr' => 'x.x.x.x'},{'ipAddr' => 'x.x.x.x','_utime' => '1561895707'}]};
févr. 20 10:14:30 LLNG[12846]: [debug] Store tata in session key groups
févr. 20 10:14:30 LLNG[12846]: [debug] Store 20200220101429 in session key _startTime
févr. 20 10:14:30 LLNG[12846]: [debug] Store 4316427717ebd17ddf0b3629cb4e62f3af52f152143d2262180ab088cd20fad3 in session key _httpSession
févr. 20 10:14:30 LLNG[12846]: [debug] Store fredo in session key _user
févr. 20 10:14:30 LLNG[12846]: [debug] Store LDAP in session key _auth
févr. 20 10:14:30 LLNG[12846]: [debug] Store HASH(0x5647daaf6b50) in session key hGroups
févr. 20 10:14:30 LLNG[12846]: [debug] Dump: $VAR1 = {'tata' => {'name' => 'tata','cn' => ['tata']}};
févr. 20 10:14:30 LLNG[12846]: [debug] Store 20200219181254 in session key _updateTime
févr. 20 10:14:30 LLNG[12846]: [debug] Store x.x.x.x in session key ipAddr
févr. 20 10:14:30 LLNG[12846]: [debug] Try to get a new SSO session
févr. 20 10:14:30 LLNG[12846]: [debug] Return SSO session 5359ad0ca03e90510e4c4382f6d26bb4a642fb65214f724fceb50cb8d9007dad
févr. 20 10:14:30 LLNG[12846]: [debug] Processing secondFactor
févr. 20 10:14:30 LLNG[12846]: [debug] Processing code ref
févr. 20 10:14:30 LLNG[12846]: [debug] Launching ::Plugins::GrantSession::run
févr. 20 10:14:30 LLNG[12846]: [info] No cookie found
févr. 20 10:14:30 LLNG[12846]: [debug] Build URL https://auth.toto.com/index.fcgi/lmerror/404
févr. 20 10:14:30 LLNG[12846]: [debug] Redirect x.x.x.x to portal (url was /index.fcgi/lmerror/404)
févr. 20 10:14:30 LLNG[12846]: [debug] User not authenticated, Try in use, cancel redirection
févr. 20 10:14:30 LLNG[12846]: [debug] Start routing lmerror
févr. 20 10:14:30 LLNG[12846]: [debug] Starting HTML generation using /usr/share/lemonldap-ng/portal/templates/bootstrap/error.tpl
févr. 20 10:14:30 LLNG[12846]: [debug] Sending /usr/share/lemonldap-ng/portal/templates/bootstrap/error.tpl
févr. 20 10:14:30 LLNG[12846]: [debug] Apply following CORS policy :
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Allow-Origin
févr. 20 10:14:30 LLNG[12846]: [debug] *
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Allow-Credentials
févr. 20 10:14:30 LLNG[12846]: [debug] true
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Allow-Headers
févr. 20 10:14:30 LLNG[12846]: [debug] *
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Allow-Methods
févr. 20 10:14:30 LLNG[12846]: [debug] POST,GET
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Expose-Headers
févr. 20 10:14:30 LLNG[12846]: [debug] *
févr. 20 10:14:30 LLNG[12846]: [debug] Access-Control-Max-Age
févr. 20 10:14:30 LLNG[12846]: [debug] 86400
févr. 20 10:14:30 LLNG[12846]: [debug] Apply following CSP : default-src 'self';img-src 'self' data:;style-src 'self';font-src 'self';connect-src 'self';script-src 'self';form-action *;frame-ancestors 'none';
Backends used
For any bug on configuration/sessions storage, give us details on backends