search

MutonUfoAI / pgina

pGina fork: Open Source Windows Authentication
http://mutonufoai.github.io/pgina
BSD 3-Clause "New" or "Revised" License
155 stars 38 forks source link

MySQL logger doesn't work #95

Closed manoliko2004 closed 6 years ago

manoliko2004 commented 6 years ago

Hello! I have problems with the "MySQL logger" plugin in Session Log mode on Windows 7 x64. I have configured it as in pGina 3.1.8 and I have performed the test with satisfactory results, but when I start and close session, nothing is registered in the database. I have read the pGina log and it informs that the plugin has been loaded correctly and of the correct access with LDAP but it does not inform of the log of the MySQL logger. In the pGina log, no error of this plugin is reported. Any ideas?

manoliko2004 commented 6 years ago

I just read incident # 66 but I enter the PC with an LDAP user and password, not with a local account.

MutonUfoAI commented 6 years ago

pGina config + pGina log = help

manoliko2004 commented 6 years ago

I have the following plugins and in this order: Authentication:

  1. Local Machine
  2. LDAP
  3. Email authentication
  4. MySQL

Gateway:

  1. Single User Login

Event notification

  1. MySQL Logger

The pGina Service log is

2018-01-18 09:39:50,846 [1988|6|INFO ] Startup: Starting up, log4net configured from: C:\Program Files\pGina.fork\log4net.config 2018-01-18 09:39:52,718 [1988|6|DEBUG] PluginLoader: Initializing 2018-01-18 09:39:52,905 [1988|6|DEBUG] PluginLoader: Loading plugins from C:\Program Files\pGina.fork\Plugins 2018-01-18 09:39:53,186 [1988|6|DEBUG] EmailAuthPlugin: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,186 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.Email.EmailAuthPlugin from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.Email.dll uuid: ec3221a6-621f-44ce-b77b-e074298d6b4e 2018-01-18 09:39:53,279 [1988|6|DEBUG] LdapPlugin: LDAP Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,279 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.Ldap.LdapPlugin from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.Ldap.dll uuid: 0f52390b-c781-43ae-bd62-553c77fa4cf7 2018-01-18 09:39:53,310 [1988|6|DEBUG] LocalMachine: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,326 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.LocalMachine.PluginImpl from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.LocalMachine.dll uuid: 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d 2018-01-18 09:39:53,326 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.MySQLAuth.PluginImpl from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.MySQLAuth.dll uuid: a89df410-53ca-4fe1-a6ca-4479b841ca19 2018-01-18 09:39:53,342 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.MySqlLogger.PluginImpl from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.MySqlLogger.dll uuid: b68cf064-9299-4765-ac08-acb49f93f892 2018-01-18 09:39:53,357 [1988|6|DEBUG] SimplePlugin: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,357 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.Sample.SimplePlugin from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.Sample.dll uuid: 16fc47c0-f17b-4d99-a820-edbf0b0c764a 2018-01-18 09:39:53,388 [1988|6|DEBUG] SingleUserPlugin: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,388 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.SingleUser.PluginImpl from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.SingleUser.dll uuid: 81f8034e-e278-4754-b10c-7066656de5b7 2018-01-18 09:39:53,404 [1988|6|DEBUG] SSHAuth: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,404 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.SSHAuth.PluginImpl from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.SSHAuth.dll uuid: cc35057c-aca8-499c-b127-ae4cf978f238 2018-01-18 09:39:53,404 [1988|6|DEBUG] UsernameModPlugin: Plugin initialized on CDE-PC3 in PID: 1988 Session: 0 2018-01-18 09:39:53,404 [1988|6|DEBUG] PluginLoader: Created plugin object type: pGina.Plugin.UsernameMod.UsernameModPlugin from plugin: C:\Program Files\pGina.fork\Plugins\pGina.Plugin.UsernameMod.dll uuid: 98477b3a-830d-4bee-b270-2d7435275f9c 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Plugins loaded, list follows: 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Email Authentication -> ec3221a6-621f-44ce-b77b-e074298d6b4e 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: LDAP -> 0f52390b-c781-43ae-bd62-553c77fa4cf7 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Local Machine -> 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: MySQL -> a89df410-53ca-4fe1-a6ca-4479b841ca19 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: MySQL Logger -> b68cf064-9299-4765-ac08-acb49f93f892 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Simple Demonstration -> 16fc47c0-f17b-4d99-a820-edbf0b0c764a 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Single User Login -> 81f8034e-e278-4754-b10c-7066656de5b7 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: SSHAuth -> cc35057c-aca8-499c-b127-ae4cf978f238 2018-01-18 09:39:53,420 [1988|6|DEBUG] PluginLoader: Modify Username Plugin -> 98477b3a-830d-4bee-b270-2d7435275f9c 2018-01-18 09:39:53,451 [1988|6|INFO ] pGina.Service.Impl: pGina version: 3.9.9.7 2018-01-18 09:39:53,451 [1988|6|DEBUG] pGina.Service.Impl: Service created - PipeName: pGinaPipe MaxClients: 25 2018-01-18 09:39:53,466 [1988|6|DEBUG] pGina.Service.Impl: System Info: OS 6.1.7601 Runtime: 4.0.30319.42000 Culture: Spanish (Spain, International Sort) 2018-01-18 09:39:53,466 [1988|6|DEBUG] pGina.Service.Impl: Using plugin directories: 2018-01-18 09:39:53,466 [1988|6|DEBUG] pGina.Service.Impl: C:\Program Files\pGina.fork\Plugins 2018-01-18 09:39:53,466 [1988|6|INFO ] pGina.Service.Impl: Starting service 2018-01-18 09:39:53,560 [1988|16|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|12|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|3|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|23|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|9|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|24|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|20|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|31|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|18|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|19|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|22|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|11|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|28|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|15|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|10|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,607 [1988|9|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,607 [1988|31|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|15|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|19|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|23|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,607 [1988|24|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|11|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|3|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|10|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|12|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,576 [1988|26|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|25|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,638 [1988|26|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,576 [1988|17|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|21|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,622 [1988|18|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,638 [1988|21|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|16|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,576 [1988|13|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,560 [1988|29|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,747 [1988|29|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,622 [1988|20|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,560 [1988|27|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,576 [1988|14|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,747 [1988|27|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,747 [1988|14|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,732 [1988|13|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,560 [1988|32|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,638 [1988|28|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,560 [1988|30|DEBUG] Abstractions: Setting PipeAccess R/W for world: S-1-1-0 2018-01-18 09:39:53,622 [1988|22|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,638 [1988|17|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,747 [1988|32|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,638 [1988|25|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:53,763 [1988|30|DEBUG] Abstractions: Setting PipeAccess FullControl for myself: NT AUTHORITY\SYSTEM 2018-01-18 09:39:54,870 [1988|31|DEBUG] RemoteLog[NativeLib]: [Credential.cpp:177] Credential::GetBitmapValue: Loading image from: C:\Users\Public\Pictures\logo_cuenta_usuario.bmp 2018-01-18 09:39:59,020 [1988|19|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:431] SerializedUserNameAvailable: No serialized creds set 2018-01-18 09:39:59,020 [1988|12|DEBUG] RemoteLog[NativeLib]: [Credential.cpp:177] Credential::GetBitmapValue: Loading image from: C:\Users\Public\Pictures\logo_cuenta_usuario.bmp 2018-01-18 09:40:05,931 [1988|23|DEBUG] RemoteLog[NativeLib]: [Credential.cpp:492] Credential::Connect: Processing login for Pruebas 2018-01-18 09:40:06,071 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: New PluginDriver created 2018-01-18 09:40:06,087 [1988|15|ERROR] Abstractions: GetDomainInfo() Error:1355 El dominio especificado no existe o no se pudo poner en contacto con él 2018-01-18 09:40:06,087 [1988|15|INFO ] pGina.Service.Impl: domain:CDE-PC3 2018-01-18 09:40:06,087 [1988|15|INFO ] pGina.Service.Impl: domain:CDE-PC3 2018-01-18 09:40:06,102 [1988|15|ERROR] Abstractions: GetDomainInfo() Error:1355 El dominio especificado no existe o no se pudo poner en contacto con él 2018-01-18 09:40:06,102 [1988|15|INFO ] pGina.Service.Impl: domain check:[False] [False] [False] 2018-01-18 09:40:06,165 [1988|15|DEBUG] pGina.Service.Impl: Processing LoginRequest for: Pruebas in session: 1 reason: Login 2018-01-18 09:40:06,212 [1988|15|DEBUG] pGina.Service.Impl: Program running as user:SYSTEM 2018-01-18 09:40:06,212 [1988|15|DEBUG] pGina.Service.Impl: Program running as user:Servicio de red 2018-01-18 09:40:06,212 [1988|15|DEBUG] pGina.Service.Impl: Program running as user:SERVICIO LOCAL 2018-01-18 09:40:06,212 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Begin login chain, 1 stateful plugin(s). 2018-01-18 09:40:06,212 [1988|15|DEBUG] LdapPlugin: BeginChain 2018-01-18 09:40:06,274 [1988|15|DEBUG] LdapServer: Initializing LdapServer host(s): [vipdirectorio.si.pruebas.es], port: 636, useSSL = True, useTLS = False, verifyCert = False 2018-01-18 09:40:06,383 [1988|15|DEBUG] LdapServer: Timeout set to 10 seconds. 2018-01-18 09:40:06,399 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Performing login process 2018-01-18 09:40:06,414 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Authenticating user Pruebas, 4 plugins available 2018-01-18 09:40:06,414 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d 2018-01-18 09:40:06,461 [1988|15|DEBUG] LocalMachine: AuthenticateUser(b6c426e0-881f-4ca7-869c-bb09e562bb7c) 2018-01-18 09:40:06,461 [1988|15|DEBUG] LocalMachine: Found username: Pruebas 2018-01-18 09:40:06,664 [1988|15|INFO ] LocalMachine: User Pruebas does not exist on this machine. 2018-01-18 09:40:06,664 [1988|15|ERROR] LocalMachine: Failed to authenticate user: Pruebas 2018-01-18 09:40:06,680 [1988|15|WARN ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d Failed without a message 2018-01-18 09:40:06,680 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling 0f52390b-c781-43ae-bd62-553c77fa4cf7 2018-01-18 09:40:06,680 [1988|15|DEBUG] LdapPlugin: AuthenticateUser(b6c426e0-881f-4ca7-869c-bb09e562bb7c) 2018-01-18 09:40:06,680 [1988|15|DEBUG] LdapPlugin: Received username: Pruebas 2018-01-18 09:40:06,680 [1988|15|DEBUG] LdapPlugin: Attempting authentication for Pruebas 2018-01-18 09:40:06,789 [1988|15|DEBUG] LdapServer: Attempting bind as cn=cestauth,dc=aplicaciones,dc=pruebas,dc=es 2018-01-18 09:40:06,820 [1988|15|DEBUG] LdapServer: Successful bind to vipdirectorio.si.pruebas.es as cn=cestauth,dc=aplicaciones,dc=pruebas,dc=es 2018-01-18 09:40:06,851 [1988|15|DEBUG] LdapServer: Searching for DN using filter (&(cn=Pruebas)(|(eduPersonAffiliation=staff)(edupersonaffiliation=student)(edupersonaffiliation=faculty))(schacuserstatus=active)) 2018-01-18 09:40:06,851 [1988|15|DEBUG] LdapServer: Searching context dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,867 [1988|15|DEBUG] LdapServer: Found DN: cn=PruebasN,dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,867 [1988|15|DEBUG] LdapServer: Attempting to bind with DN cn=PruebasN,dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,867 [1988|15|DEBUG] LdapServer: Attempting bind as cn=PruebasN,dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,882 [1988|15|DEBUG] LdapServer: Successful bind to vipdirectorio.si.pruebas.es as cn=PruebasN,dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,882 [1988|15|DEBUG] LdapServer: LDAP DN cn=PruebasN,dc=personas,dc=pruebas,dc=es successfully bound to server, return success 2018-01-18 09:40:06,882 [1988|15|DEBUG] LdapServer: Attempting bind as cn=cestauth,dc=aplicaciones,dc=pruebas,dc=es 2018-01-18 09:40:06,882 [1988|15|DEBUG] LdapServer: Successful bind to vipdirectorio.si.pruebas.es as cn=cestauth,dc=aplicaciones,dc=pruebas,dc=es 2018-01-18 09:40:06,898 [1988|15|DEBUG] LdapServer: Searching for DN using filter (&(cn=Pruebas)(|(eduPersonAffiliation=staff)(edupersonaffiliation=student)(edupersonaffiliation=faculty))(schacuserstatus=active)) 2018-01-18 09:40:06,898 [1988|15|DEBUG] LdapServer: Searching context dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,898 [1988|15|DEBUG] LdapServer: Found DN: cn=PruebasN,dc=personas,dc=pruebas,dc=es 2018-01-18 09:40:06,898 [1988|15|INFO ] LdapServer: ntplist:vipdirectorio.si.pruebas.es
2018-01-18 09:40:06,898 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: 0f52390b-c781-43ae-bd62-553c77fa4cf7 Succeeded 2018-01-18 09:40:06,898 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling ec3221a6-621f-44ce-b77b-e074298d6b4e 2018-01-18 09:40:06,898 [1988|15|DEBUG] EmailAuthPlugin: AuthenticateUser(b6c426e0-881f-4ca7-869c-bb09e562bb7c) 2018-01-18 09:40:06,929 [1988|15|DEBUG] EmailAuthPlugin: Connecting to imap.pruebas.es:993, using SSL 2018-01-18 09:40:07,054 [1988|15|DEBUG] EmailAuthPlugin: Have network stream... 2018-01-18 09:40:07,070 [1988|15|DEBUG] EmailAuthPlugin: IMAP server: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN] Dovecot ready. 2018-01-18 09:40:07,085 [1988|15|DEBUG] EmailAuthPlugin: IMAP Server: + OK 2018-01-18 09:40:07,085 [1988|15|DEBUG] EmailAuthPlugin: IMAP Server: + OK 2018-01-18 09:40:09,363 [1988|15|DEBUG] EmailAuthPlugin: IMAP Server: li01 NO [AUTHENTICATIONFAILED] Authentication failed. 2018-01-18 09:40:09,363 [1988|15|WARN ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: ec3221a6-621f-44ce-b77b-e074298d6b4e Failed with Message: Invalid username/password. 2018-01-18 09:40:09,363 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling a89df410-53ca-4fe1-a6ca-4479b841ca19 2018-01-18 09:40:09,456 [1988|15|DEBUG] PluginLoader: Resolving dependency MySql.Data.dll 2018-01-18 09:40:09,456 [1988|15|DEBUG] PluginLoader: Looking for: C:\Program Files\pGina.fork\Plugins\MySql.Data.dll 2018-01-18 09:40:09,488 [1988|15|INFO ] PluginLoader: Successfully loaded assembly: MySql.Data, Version=6.4.3.0, Culture=neutral, PublicKeyToken=c5687fc88969c44d 2018-01-18 09:40:09,659 [1988|15|DEBUG] MySQLAuth: Authenticate: Pruebas 2018-01-18 09:40:10,548 [1988|15|DEBUG] MySqlUserDataSource: Closing connection. 2018-01-18 09:40:10,548 [1988|15|DEBUG] MySQLAuth: Authentication failed for Pruebas 2018-01-18 09:40:10,548 [1988|15|WARN ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: a89df410-53ca-4fe1-a6ca-4479b841ca19 Failed with Message: Invalid username or password. 2018-01-18 09:40:10,548 [1988|15|INFO ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Successfully authenticated Pruebas 2018-01-18 09:40:10,548 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Authorizing user Pruebas, 1 plugins available 2018-01-18 09:40:10,548 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d 2018-01-18 09:40:10,564 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: 12fa152d-a2e3-4c8d-9535-5dcd49dfcb6d Succeeded 2018-01-18 09:40:10,564 [1988|15|INFO ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Successfully authorized Pruebas 2018-01-18 09:40:10,564 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Processing gateways for user Pruebas, 1 plugins available 2018-01-18 09:40:10,564 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Calling 81f8034e-e278-4754-b10c-7066656de5b7 2018-01-18 09:40:10,564 [1988|15|DEBUG] SingleUserPlugin: AuthenticatedUserGateway[Pruebas] 2018-01-18 09:40:10,580 [1988|15|DEBUG] SingleUserPlugin: Re-writing user information to login with \Multipuesto 2018-01-18 09:40:10,580 [1988|15|INFO ] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: Successfully processed gateways for Multipuesto 2018-01-18 09:40:10,580 [1988|15|DEBUG] PluginDriver:b6c426e0-881f-4ca7-869c-bb09e562bb7c: End login chain, 1 stateful plugin(s). 2018-01-18 09:40:10,580 [1988|15|DEBUG] LdapPlugin: EndChain 2018-01-18 09:40:10,580 [1988|15|DEBUG] LdapServer: Closing LDAP connection to vipdirectorio.si.pruebas.es. 2018-01-18 09:40:10,595 [1988|15|INFO ] pGina.Service.Impl: ses add LocalProfilePath:[C:\Users\Multipuesto] 2018-01-18 09:40:10,595 [1988|15|INFO ] pGina.Service.Impl: add user Multipuesto to sessioninfo:1 GUID:b6c426e0-881f-4ca7-869c-bb09e562bb7c CREDUI:false 2018-01-18 09:40:10,595 [1988|15|INFO ] pGina.Service.Impl: ses username:Multipuesto description: credui:False isLoggedIN:False 2018-01-18 09:40:10,595 [1988|27|WARN ] RemoteLog[NativeLib]: Plugins did not set a domain name, asspruebasing local machine! 2018-01-18 09:40:10,595 [1988|20|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:273] Provider::UnAdvise() - provider events callback reference released 2018-01-18 09:40:10,611 [1988|28|DEBUG] RemoteLog[NativeLib]: [Credential.cpp:765] Credential::ReportResult(0x00000000, 0x00000000) 2018-01-18 09:40:11,063 [1988|7|INFO ] pGina.Service.Impl: SessionChange:1 5 2018-01-18 09:40:11,172 [1988|36|INFO ] pGina.Service.Impl: SessionChange: 1 -> SessionLogon 2018-01-18 09:40:11,219 [1988|36|INFO ] pGina.Service.Impl: SessionLogon missmatch Multipuesto@CDE-PC3 != Multipuesto@ removing SessionProperties 2018-01-18 09:40:20,402 [1988|22|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:94] Stopping service state helper thread (if necessary) 2018-01-18 09:40:37,991 [1988|30|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:87] Starting service state helper thread 2018-01-18 09:40:37,991 [1988|29|DEBUG] RemoteLog[NativeLib]: [CredentialProviderFilter.cpp:74] CredentialProviderFilter::Filter 2018-01-18 09:40:37,991 [1988|17|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:121] Provider::SetUsageScenario(4, 0x00000100) 2018-01-18 09:40:37,991 [1988|25|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:262] Provider::Advise(00000000035951F0, 0000000003595240) - provider events callback reference added 2018-01-18 09:40:38,051 [1988|21|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:431] SerializedUserNameAvailable: No serialized creds set 2018-01-18 09:40:38,051 [1988|3|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:431] SerializedUserNameAvailable: No serialized creds set 2018-01-18 09:40:38,051 [1988|16|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:448] SerializedPasswordAvailable: No serialized creds set 2018-01-18 09:40:38,051 [1988|24|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:465] SerializedDomainNameAvailable: No serialized creds set 2018-01-18 09:40:38,061 [1988|10|DEBUG] RemoteLog[NativeLib]: [Credential.cpp:177] Credential::GetBitmapValue: Loading image from: C:\Users\Public\Pictures\logo_cuenta_usuario.bmp 2018-01-18 09:40:44,662 [1988|26|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:273] Provider::UnAdvise() - provider events callback reference released 2018-01-18 09:40:44,693 [1988|18|DEBUG] RemoteLog[NativeLib]: [Provider.cpp:94] Stopping service state helper thread (if necessary)

The sensitive data in the LDAP directory has been omitted. I can send you screenshots of the settings, if necessary. This is the same configuration that was working on pGina 3.1.8. Please, if you need to change the configuration of any plugin (the LocalMachine is the most difficult for me), please indicate it to me. Thank you very much for your help.

MutonUfoAI commented 6 years ago

You are using the Single User Plugin which result into this SessionLogon missmatch Multipuesto@CDE-PC3 != Multipuesto@ removing SessionProperties This plugin mess around with username, pwd and domain which is as deadly as the Username Modification Plugin.

Pgina 3.1.8 ... lot of changes have been added since that version

Note to me: I need to add the same warning into the documentation of Single User Plugin as in the Username Modification Plugin.

manoliko2004 commented 6 years ago

@MutonUfoAI, Hello again!. I have managed to resolve the issue :) In the configuration of the plugin "Single User Plugin" I have completed the field "Domain" (I had it blank) with the hostname of the machine: CDE-PC3. Now the "MySQL Logger" plugin records the sessions and log in to the Windows account specified in the "Single User" plugin. Thank you very much for your help. A suggestion for future versions: The "MySQL Logger" plugin could have secure SSL access to the database so that the password does not travel in clear, as does the "MySQL" plugin. Thanks again.