t/41-Captcha-with-LDAP.t fails in an updated Debian testing
Here are the logs in debug mode:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Logger Lemonldap::NG::Common::Logger::Std loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] User logger Lemonldap::NG::Common::Logger::Std loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Check configuration for Lemonldap::NG::Handler::PSGI::Main
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Lemonldap::NG::Common::Conf::Backends::File loaded.
Get remote configuration (localStorage unavailable).
Get configuration 1.
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Get configuration 1 aged 1428138808
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [info] Loading configuration 1 for process 2296308
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls defaultValuesInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls jailInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls portalInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls locationRulesInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls sessionStorageInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls headersInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls postUrlInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls aliasInit
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Process 2296308 calls oauth2Init
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Launching Lemonldap::NG::Portal::Main->reloadConf(conf)
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add POST route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add POST route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route psgi.js added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route psgi.js added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route portal.css added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route portal.css added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route : added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route : added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route ping added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route ping added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route refresh added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add OPTIONS route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add OPTIONS route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route logout added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route logout added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Initialized CSP headers : default-src 'self';img-src 'self' data:;style-src 'self';font-src 'self';connect-src 'self';script-src 'self';
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Initialized CORS headers : Access-Control-Allow-Origin;*;Access-Control-Allow-Credentials;true;Access-Control-Allow-Headers;*;Access-Control-Allow-Methods;POST,GET;Access-Control-Expose-Headers;*;Access-Control-Max-Age;86400;
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Cookies will use SameSite=Lax
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Main::Menu loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Main::Menu initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Auth::LDAP loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] LDAP Search base: dc=example,dc=com
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] LDAP transformed filter: (&(uid=".$req->{user}.")(objectClass=inetOrgPerson))
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Auth::LDAP initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::UserDB::LDAP loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] LDAP Search base: dc=example,dc=com
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] LDAP transformed filter: (&(uid=".$req->{user}.")(objectClass=inetOrgPerson))
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::UserDB::LDAP initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::2F::Engines::Default loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking utotp2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking totp2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking u2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking rest2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking mail2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking ext2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking webauthn2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking yubikey2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking radius2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking password2fActivation
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking password2fSelfRegistration
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking totp2fSelfRegistration
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking u2fSelfRegistration
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking webauthn2fSelfRegistration
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Checking yubikey2fSelfRegistration
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> not enabled
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing Extra 2F modules
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::2F::Engines::Default initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Captcha::SecurityImage loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route renewcaptcha added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Captcha::SecurityImage initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Plugins::History loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Found endAuth entry point:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> run
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Plugins::History initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Plugins::Upgrade loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route upgradesession added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add POST route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route upgradesession added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route renewsession added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add POST route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route renewsession added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Plugins::Upgrade initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Plugins::RESTServer loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add POST route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add DELETE route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route * added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route : added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add PUT route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route : added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route myapplications added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring auth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route languages added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Declaring unauth route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Add GET route:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] route languages added
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Plugins::RESTServer initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Password::Null loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Found forAuthUser entry point:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> _modifyPassword
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Plugin ::Password::Null initialized
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost manager.example.com added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost test1.example.com added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost %.oneonly.llng added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost test.example.org added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost *.example.llng added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost test2.example.com added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Vhost auth.example.com added in trusted domains
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Lemonldap::NG::Handler::PSGI::Main: configuration is up to date
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [info] New request Lemonldap::NG::Portal::Main GET /
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] No cookie found
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Build URL http://auth.example.com/
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Redirect 127.0.0.1 to portal (url was /)
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] User not authenticated, Try in use, cancel redirection
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Start routing default route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing checkUnauthLogout
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing controlUrl
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing code ref
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing extractFormInfo
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Module Lemonldap::NG::Portal::Lib::OneTimeToken loaded
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Token 1694007948_1193 created
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Prepare captcha
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Calling hook sendHtml
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Returned error: 9 (PE_FIRSTACCESS)
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Display type standardform
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Skin returned: login
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Calling sendHtml with template login
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Calling hook sendHtml
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Starting HTML generation using site/templates/bootstrap/login.tpl
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Sending site/templates/bootstrap/login.tpl
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Apply following CORS policy:
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Allow-Origin
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] *
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Allow-Credentials
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] true
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Allow-Headers
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] *
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Allow-Methods
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] POST,GET
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Expose-Headers
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] *
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Access-Control-Max-Age
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] 86400
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [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';
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [info] New request Lemonldap::NG::Portal::Main POST /
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] No cookie found
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Build URL http://auth.example.com/
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Redirect 127.0.0.1 to portal (url was /)
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] User not authenticated, Try in use, cancel redirection
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Start routing default route
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing checkUnauthLogout
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing restoreArgs
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing controlUrl
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing code ref
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing extractFormInfo
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Trying to load token 1694007948_1193
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Good captcha response
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Captcha code verified
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing getUser
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Try to build new LDAP connection with: ldap://localhost
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing authenticate
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Try to build new LDAP connection with: ldap://localhost
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Call bind for uid=dwho,ou=users,dc=example,dc=com
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] -> authResult = 0
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing setAuthSessionInfo
[Thu Sep 7 13:43:48 2023] [LLNG:2296308] [debug] Processing setSessionInfo
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing setMacros
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing setGroups
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing setPersistentSessionInfo
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Persistent session found for dwho
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing setLocalGroups
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing store
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store DEMO_demo in session key zeAUTHMODE_authmode
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store LDAP in session key _auth
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store dwho in session key _whatToTrace
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store DEMO in session key authMode
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store dwho in session key uid
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store 1694079828 in session key _lastAuthnUTime
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store 127.0.0.1 in session key ipAddr
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store uid=dwho,ou=users,dc=example,dc=com in session key _dn
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store LDAP in session key _userDB
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store dwho in session key _user
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store 2 in session key authenticationLevel
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store 1694079828 in session key _utime
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store Dr Who in session key cn
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store in session key array
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store su in session key groups
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store 20230907134348 in session key _startTime
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store en in session key _language
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Store HASH(0x55efea4059d0) in session key hGroups
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Dump: $VAR1 = {'su' => {'name' => 'su'}};
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Try to get a new SSO session
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Return SSO session 9dbf9f8ab008087a28106ddfde3193877c4f10371e2acbf946eed6a2b2d0cbb6
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] No 2F module authorized -> Update current request
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing secondFactor
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing storeHistory
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing buildCookie
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [notice] User dwho successfully authenticated at level 2
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] [notice] User dwho successfully authenticated at level 2
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing code ref
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Launching ::Plugins::History::run
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Processing code ref
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Removing keepPdata from pdata
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [notice] dwho connected
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] [notice] dwho connected
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Calling autoredirect
[Thu Sep 7 13:43:49 2023] [LLNG:2296308] [debug] Building redirection to http://auth.example.com/
# Failed test ' HTTP code is 200'
# at t/41-Captcha-with-LDAP.t line 61.
# Expect 200, get $VAR1 = [302,['Location','http://auth.example.com/','Set-Cookie','lemonldap=9dbf9f8ab008087a28106ddfde3193877c4f10371e2acbf946eed6a2b2d0cbb6; domain=.example.com; path=/; HttpOnly=1; SameSite=Lax'],[]];
# Failed test ' Page contains a form'
# at t/41-Captcha-with-LDAP.t line 61.
Use of uninitialized value in pattern match (m//) at t/41-Captcha-with-LDAP.t line 62.
# Failed test 'Password: Found text input'
# at t/41-Captcha-with-LDAP.t line 62.
Use of uninitialized value $query in substitution (s///) at t/41-Captcha-with-LDAP.t line 65.
# Failed test ' Token value is defined'
# at t/41-Captcha-with-LDAP.t line 66.
Use of uninitialized value in pattern match (m//) at t/41-Captcha-with-LDAP.t line 67.
# Failed test ' Login found'
# at t/41-Captcha-with-LDAP.t line 67.
Use of uninitialized value in pattern match (m//) at t/41-Captcha-with-LDAP.t line 69.
# Failed test ' Error found'
# at t/41-Captcha-with-LDAP.t line 69.
# Expect PE_6 or PE_7 found, get
Use of uninitialized value in pattern match (m//) at t/41-Captcha-with-LDAP.t line 72.
# Failed test ' Captcha image inserted'
# at t/41-Captcha-with-LDAP.t line 72.
# Expect Captcha found, get
Assert_Defined failed: Cache::BaseCache line 194
# Tests were run but no plan was declared and done_testing() was not seen.
# Looks like your test exited with 255 just after 23.
t/41-Captcha-with-LDAP.t ..........................................
Dubious, test returned 255 (wstat 65280, 0xff00)
Failed 7/23 subtests