[2016-07-21 15:49:04,315][3289][140033162262272][DEBUG][privacyidea.api.lib.utils:235] Can not get param: No JSON object could be decoded
[2016-07-21 15:49:04,319][3289][140033162262272][DEBUG][privacyidea.lib.audit:172] Entering getAudit with arguments (<Config {'JSON_AS_ASCII': True, 'PI_HSM': 'default', 'USE_X_SENDFILE': False, 'SESSION_COOKIE_PATH': None, 'PROPAGATE_EXCEPTIONS': None, 'PI_LOGFILE': '/var/log/privacyidea/privacyidea.log', 'PI_AUDIT_MODULE': 'privacyidea.lib.auditmodules.sqlaudit', 'LOGGER_NAME': 'privacyidea.app', 'SECRET_KEY': 'weV2c0YUabR40GgMHWecfxpG', 'TRAP_BAD_REQUEST_ERRORS': False, 'APPLICATION_ROOT': None, 'pi_hsm': {'obj': <privacyidea.lib.security.default.DefaultSecurityModule object at 0x7f5becc0ae90>}, 'PREFERRED_URL_SCHEME': 'http', 'TESTING': False, 'PI_AUDIT_KEY_PUBLIC': '/etc/privacyidea/public.pem', 'SQLALCHEMY_POOL_RECYCLE': None, 'SESSION_COOKIE_HTTPONLY': True, 'SQLALCHEMY_ECHO': False, 'PI_PEPPER': 'qBDuecor1rziLOEMnnYqjXvO', 'SQLALCHEMY_DATABASE_URI': 'mysql://pi:84L8uALgbk0K@localhost/pi', 'SQLALCHEMY_TRACK_MODIFICATIONS': True, 'SQLALCHEMY_POOL_TIMEOUT': None, 'SESSION_COOKIE_SECURE': False, 'SQLALCHEMY_RECORD_QUERIES': None, 'PI_ENCFILE': '/etc/privacyidea/enckey', 'SESSION_COOKIE_DOMAIN': None, 'SESSION_COOKIE_NAME': 'session', 'pi_token_types': ['registration', 'yubikey', 'radius', 'tiqr', 'pw', 'daplug', 'u2f', 'spass', '4eyes', 'paper', 'motp', 'sms', 'email', 'totp', 'remote', 'hotp', 'certificate', 'yubico', 'sshkey', 'question'], 'SQLALCHEMY_BINDS': None, 'DEBUG': False, 'SQLALCHEMY_COMMIT_ON_TEARDOWN': False, 'SQLALCHEMY_NATIVE_UNICODE': None, 'MAX_CONTENT_LENGTH': None, 'SERVER_NAME': None, 'JSONIFY_PRETTYPRINT_REGULAR': True, 'PERMANENT_SESSION_LIFETIME': datetime.timedelta(31), 'SQLALCHEMY_POOL_SIZE': None, 'PI_LOGLEVEL': 10, 'SQLALCHEMY_MAX_OVERFLOW': None, 'JSON_SORT_KEYS': True, 'TRAP_HTTP_EXCEPTIONS': False, 'SEND_FILE_MAX_AGE_DEFAULT': 43200, 'PI_AUDIT_KEY_PRIVATE': '/etc/privacyidea/private.pem', 'PRESERVE_CONTEXT_ON_EXCEPTION': None, 'SUPERUSER_REALM': ['super']}>,) and keywords {}
[2016-07-21 15:49:04,319][3289][140033162262272][DEBUG][privacyidea.lib.audit:172] Entering getAuditClass with arguments ('privacyidea.lib.auditmodules.sqlaudit', 'Audit') and keywords {}
[2016-07-21 15:49:04,319][3289][140033162262272][DEBUG][privacyidea.lib.audit:73] klass: <class 'privacyidea.lib.auditmodules.sqlaudit.Audit'>
[2016-07-21 15:49:04,319][3289][140033162262272][DEBUG][privacyidea.lib.audit:184] Exiting getAuditClass with result <class 'privacyidea.lib.auditmodules.sqlaudit.Audit'>
[2016-07-21 15:49:04,320][3289][140033162262272][DEBUG][privacyidea.lib.auditmodules.sqlaudit:146] using the connect string mysql://pi:84L8uALgbk0K@localhost/pi
[2016-07-21 15:49:04,320][3289][140033162262272][DEBUG][privacyidea.lib.auditmodules.sqlaudit:153] Using SQL pool_size of 20
[2016-07-21 15:49:04,325][3289][140033162262272][DEBUG][privacyidea.lib.audit:184] Exiting getAudit with result <privacyidea.lib.auditmodules.sqlaudit.Audit object at 0x7f5becc0a910>
[2016-07-21 15:49:04,325][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('OverrideAuthorizationClient',) and keywords {}
[2016-07-21 15:49:04,326][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result None
[2016-07-21 15:49:04,327][3289][140033162262272][WARNING][privacyidea.lib.utils:439] Proxy ::1 not allowed to set IP to 127.0.0.1.
[2016-07-21 15:49:04,327][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:49:04,329][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:49:04,329][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:49:04,330][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:49:04,330][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:49:04,331][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:49:04,331][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:49:04,332][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_realm_resolvers with arguments (User(login=u'mrogers', realm=u'defrealm', resolver=''),) and keywords {}
[2016-07-21 15:49:04,332][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:49:04,336][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_realms with result {u'defrealm': {'default': True, 'resolver': [{'priority': None, 'type': u'ldapresolver', 'name': u'abc'}]}}
[2016-07-21 15:49:04,336][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:49:04,337][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,337][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,342][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,343][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,343][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,343][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,344][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,345][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,345][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,345][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,345][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,345][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,346][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,346][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,350][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,350][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,351][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,351][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,354][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5bf0e25590>
[2016-07-21 15:49:04,355][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,356][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,373][3289][140033162262272][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:49:04,374][3289][140033162262272][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:49:04,374][3289][140033162262272][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:49:04,374][3289][140033162262272][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:49:04,374][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,375][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5bece0a5d0>, 'setrealm') and keywords {'scope': 'authorization', 'client': '::1', 'realm': u'defrealm'}
[2016-07-21 15:49:04,375][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becc3e150>,) and keywords {'realm': u'defrealm', 'action': 'setrealm', 'client': '::1', 'user': None, 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,375][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,375][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,376][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,377][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:49:04,378][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:49:04,379][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:49:04,379][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:49:04,379][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:49:04,380][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:49:04,381][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:49:04,381][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_realm_resolvers with arguments (User(login=u'mrogers', realm=u'defrealm', resolver=''),) and keywords {}
[2016-07-21 15:49:04,381][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:49:04,386][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_realms with result {u'defrealm': {'default': True, 'resolver': [{'priority': None, 'type': u'ldapresolver', 'name': u'abc'}]}}
[2016-07-21 15:49:04,386][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:49:04,387][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,387][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,392][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,392][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,392][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,393][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,393][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,393][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,393][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,393][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,394][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,394][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,394][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,394][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,394][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,395][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,395][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,395][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,395][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,400][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,401][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,401][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,401][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,404][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becb5c990>
[2016-07-21 15:49:04,406][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,407][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,415][3289][140033162262272][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:49:04,416][3289][140033162262272][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:49:04,416][3289][140033162262272][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:49:04,416][3289][140033162262272][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:49:04,416][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,416][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccbb6d0>, 'mangle') and keywords {'scope': 'authentication', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:49:04,417][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becb5c990>,) and keywords {'realm': u'defrealm', 'action': 'mangle', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,417][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,417][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,417][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,418][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,419][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:49:04,420][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:49:04,420][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:49:04,421][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:49:04,421][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:49:04,422][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:49:04,422][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:49:04,423][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_realm_resolvers with arguments (User(login=u'mrogers', realm=u'defrealm', resolver=''),) and keywords {}
[2016-07-21 15:49:04,423][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:49:04,426][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_realms with result {u'defrealm': {'default': True, 'resolver': [{'priority': None, 'type': u'ldapresolver', 'name': u'abc'}]}}
[2016-07-21 15:49:04,427][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:49:04,427][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,427][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,431][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,432][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,432][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,432][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,433][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,434][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,434][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,434][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,434][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,435][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,435][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,435][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,440][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,440][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,441][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,441][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,444][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becd39450>
[2016-07-21 15:49:04,446][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,447][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,464][3289][140033162262272][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:49:04,465][3289][140033162262272][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:49:04,465][3289][140033162262272][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:49:04,465][3289][140033162262272][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:49:04,465][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,465][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccf9710>,) and keywords {'realm': u'defrealm', 'action': 'api_key_required', 'client': '::1', 'user': u'mrogers', 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,466][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,467][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,467][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,467][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:49:04,469][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:49:04,469][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:49:04,470][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:49:04,470][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:49:04,472][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:49:04,472][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:49:04,472][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_realm_resolvers with arguments (User(login=u'mrogers', realm=u'defrealm', resolver=''),) and keywords {}
[2016-07-21 15:49:04,473][3289][140033162262272][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:49:04,479][3289][140033162262272][DEBUG][privacyidea.lib.realm:184] Exiting get_realms with result {u'defrealm': {'default': True, 'resolver': [{'priority': None, 'type': u'ldapresolver', 'name': u'abc'}]}}
[2016-07-21 15:49:04,479][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:49:04,479][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,479][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,484][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,485][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,485][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,485][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,486][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,486][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,486][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,486][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,486][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,487][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,487][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,487][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,487][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,488][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,488][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,488][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,488][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,494][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,494][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,494][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,494][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,497][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becc3e450>
[2016-07-21 15:49:04,499][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,500][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,508][3289][140033162262272][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:49:04,509][3289][140033162262272][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:49:04,509][3289][140033162262272][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:49:04,509][3289][140033162262272][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:49:04,510][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,510][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becb5c990>,) and keywords {'realm': u'defrealm', 'action': 'passOnNoUser', 'client': '::1', 'user': u'mrogers', 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,511][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,511][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,511][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,512][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,512][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,512][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,513][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,513][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,513][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccc9210>,) and keywords {'realm': u'defrealm', 'action': 'passOnNoToken', 'client': '::1', 'user': u'mrogers', 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,514][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,514][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,514][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,515][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,515][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,515][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,516][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,516][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,516][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becb5c990>,) and keywords {'realm': u'defrealm', 'action': 'passthru', 'client': '::1', 'user': u'mrogers', 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,516][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,516][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,517][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,521][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering check_user_pass with arguments (User(login=u'mrogers', realm=u'defrealm', resolver=u'abc'), u'pin605825', {'clientip': '::1', 'client': u'127.0.0.1', 'user': u'mrogers', 'g': <flask.g of 'privacyidea.app'>, 'pass': u'pin605825'}) and keywords {}
[2016-07-21 15:49:04,522][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering get_tokens with arguments () and keywords {'user': User(login=u'mrogers', realm=u'defrealm', resolver=u'abc')}
[2016-07-21 15:49:04,523][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,534][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,535][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,535][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,535][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,535][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,540][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,541][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,541][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,541][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,541][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,542][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,543][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,543][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,543][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,543][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,544][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,544][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,548][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,549][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,549][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,549][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,552][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becb27e90>
[2016-07-21 15:49:04,554][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,555][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,579][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering create_tokenclass_object with arguments (<<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48970916L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60950>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>,) and keywords {}
[2016-07-21 15:49:04,579][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_token_module_list with arguments () and keywords {}
[2016-07-21 15:49:04,580][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_token_list with arguments () and keywords {}
[2016-07-21 15:49:04,580][3289][140033162262272][DEBUG][privacyidea.lib.config:514] None
[2016-07-21 15:49:04,580][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_token_list with result set(['privacyidea.lib.tokens.tiqrtoken', 'privacyidea.lib.tokens.smstoken', 'privacyidea.lib.tokens.hotptoken', 'privacyidea.lib.tokens.remotetoken', 'privacyidea.lib.tokens.yubicotoken', 'privacyidea.lib.tokens.registrationtoken', 'privacyidea.lib.tokens.passwordtoken', 'privacyidea.lib.tokens.motptoken', 'privacyidea.lib.tokens.emailtoken', 'privacyidea.lib.tokens.papertoken', 'privacyidea.lib.tokens.daplugtoken', 'privacyidea.lib.tokens.spasstoken', 'privacyidea.lib.tokens.certificatetoken', 'privacyidea.lib.tokens.foureyestoken', 'privacyidea.lib.tokens.questionnairetoken', 'privacyidea.lib.tokens.yubikeytoken', 'privacyidea.lib.tokens.u2ftoken', 'privacyidea.lib.tokens.sshkeytoken', 'privacyidea.lib.tokens.totptoken', 'privacyidea.lib.tokens.radiustoken'])
[2016-07-21 15:49:04,580][3289][140033162262272][DEBUG][privacyidea.lib.config:539] using the module list: set(['privacyidea.lib.tokens.tiqrtoken', 'privacyidea.lib.tokens.smstoken', 'privacyidea.lib.tokens.hotptoken', 'privacyidea.lib.tokens.remotetoken', 'privacyidea.lib.tokens.yubicotoken', 'privacyidea.lib.tokens.registrationtoken', 'privacyidea.lib.tokens.passwordtoken', 'privacyidea.lib.tokens.motptoken', 'privacyidea.lib.tokens.emailtoken', 'privacyidea.lib.tokens.papertoken', 'privacyidea.lib.tokens.daplugtoken', 'privacyidea.lib.tokens.spasstoken', 'privacyidea.lib.tokens.certificatetoken', 'privacyidea.lib.tokens.foureyestoken', 'privacyidea.lib.tokens.questionnairetoken', 'privacyidea.lib.tokens.yubikeytoken', 'privacyidea.lib.tokens.u2ftoken', 'privacyidea.lib.tokens.sshkeytoken', 'privacyidea.lib.tokens.totptoken', 'privacyidea.lib.tokens.radiustoken'])
[2016-07-21 15:49:04,580][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.tiqrtoken
[2016-07-21 15:49:04,581][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.smstoken
[2016-07-21 15:49:04,581][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.hotptoken
[2016-07-21 15:49:04,581][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.remotetoken
[2016-07-21 15:49:04,581][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.yubicotoken
[2016-07-21 15:49:04,582][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.registrationtoken
[2016-07-21 15:49:04,582][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.passwordtoken
[2016-07-21 15:49:04,582][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.motptoken
[2016-07-21 15:49:04,582][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.emailtoken
[2016-07-21 15:49:04,582][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.papertoken
[2016-07-21 15:49:04,583][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.daplugtoken
[2016-07-21 15:49:04,583][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.spasstoken
[2016-07-21 15:49:04,583][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.certificatetoken
[2016-07-21 15:49:04,583][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.foureyestoken
[2016-07-21 15:49:04,583][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.questionnairetoken
[2016-07-21 15:49:04,584][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.yubikeytoken
[2016-07-21 15:49:04,584][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.u2ftoken
[2016-07-21 15:49:04,584][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.sshkeytoken
[2016-07-21 15:49:04,584][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.totptoken
[2016-07-21 15:49:04,584][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.radiustoken
[2016-07-21 15:49:04,585][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_token_module_list with result [<module 'privacyidea.lib.tokens.tiqrtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/tiqrtoken.pyc'>, <module 'privacyidea.lib.tokens.smstoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/smstoken.pyc'>, <module 'privacyidea.lib.tokens.hotptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/hotptoken.pyc'>, <module 'privacyidea.lib.tokens.remotetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/remotetoken.pyc'>, <module 'privacyidea.lib.tokens.yubicotoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/yubicotoken.pyc'>, <module 'privacyidea.lib.tokens.registrationtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/registrationtoken.pyc'>, <module 'privacyidea.lib.tokens.passwordtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/passwordtoken.pyc'>, <module 'privacyidea.lib.tokens.motptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/motptoken.pyc'>, <module 'privacyidea.lib.tokens.emailtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/emailtoken.pyc'>, <module 'privacyidea.lib.tokens.papertoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/papertoken.pyc'>, <module 'privacyidea.lib.tokens.daplugtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/daplugtoken.pyc'>, <module 'privacyidea.lib.tokens.spasstoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/spasstoken.pyc'>, <module 'privacyidea.lib.tokens.certificatetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/certificatetoken.pyc'>, <module 'privacyidea.lib.tokens.foureyestoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/foureyestoken.pyc'>, <module 'privacyidea.lib.tokens.questionnairetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/questionnairetoken.pyc'>, <module 'privacyidea.lib.tokens.yubikeytoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/yubikeytoken.pyc'>, <module 'privacyidea.lib.tokens.u2ftoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/u2ftoken.pyc'>, <module 'privacyidea.lib.tokens.sshkeytoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/sshkeytoken.pyc'>, <module 'privacyidea.lib.tokens.totptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/totptoken.pyc'>, <module 'privacyidea.lib.tokens.radiustoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/radiustoken.pyc'>]
[2016-07-21 15:49:04,586][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:172] Entering __init__ with arguments (<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {}>, <<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48970916L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60b90>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>) and keywords {}
[2016-07-21 15:49:04,587][3289][140033162262272][DEBUG][privacyidea.lib.tokenclass:172] Entering __init__ with arguments (<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {}>, <<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48970916L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60650>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>) and keywords {}
[2016-07-21 15:49:04,587][3289][140033162262272][DEBUG][privacyidea.lib.tokenclass:184] Exiting __init__ with result None
[2016-07-21 15:49:04,588][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:184] Exiting __init__ with result None
[2016-07-21 15:49:04,588][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting create_tokenclass_object with result <<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'count\'": \'48970916L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>
[2016-07-21 15:49:04,588][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting get_tokens with result [<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'count\'": \'48970916L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>]
[2016-07-21 15:49:04,589][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_username with arguments (u'f50a6e69-b756-43ee-b619-5cb51d24426e', u'abc') and keywords {}
[2016-07-21 15:49:04,589][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,589][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,603][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,604][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,604][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,605][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,605][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,605][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,606][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,606][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,606][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,606][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,606][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,607][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,607][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,607][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,607][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,608][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,608][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,613][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,614][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,614][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,614][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,618][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becc47610>
[2016-07-21 15:49:04,620][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,621][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,632][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_username with result mrogers
[2016-07-21 15:49:04,635][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm', 'resolver': u'abc'}
[2016-07-21 15:49:04,635][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,637][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering check_token_list with arguments ([<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'realm_list\'": \'[<privacyidea.models.TokenRealm object at 0x7f5becbf9310>]\', "\'count\'": \'48970916L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>], u'pin605825') and keywords {'user': User(login=u'mrogers', realm=u'defrealm', resolver=u'abc'), 'options': {'clientip': '::1', 'client': u'127.0.0.1', 'user': u'mrogers', 'g': <flask.g of 'privacyidea.app'>, 'pass': u'pin605825'}}
[2016-07-21 15:49:04,637][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering get_username with arguments (u'f50a6e69-b756-43ee-b619-5cb51d24426e', u'abc') and keywords {}
[2016-07-21 15:49:04,637][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,638][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,643][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,643][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,643][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,644][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,644][3289][140033162262272][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:49:04,644][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_resolver_list with result set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,644][3289][140033162262272][DEBUG][privacyidea.lib.config:574] using the module list: set(['privacyidea.lib.resolvers.PasswdIdResolver', 'privacyidea.lib.resolvers.SCIMIdResolver', 'privacyidea.lib.resolvers.SQLIdResolver', 'privacyidea.lib.resolvers.LDAPIdResolver'])
[2016-07-21 15:49:04,644][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:49:04,645][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:49:04,645][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:49:04,645][3289][140033162262272][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:49:04,645][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.PasswdIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/PasswdIdResolver.pyc'>
[2016-07-21 15:49:04,645][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SCIMIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SCIMIdResolver.pyc'>
[2016-07-21 15:49:04,646][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.SQLIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/SQLIdResolver.pyc'>
[2016-07-21 15:49:04,646][3289][140033162262272][DEBUG][privacyidea.lib.config:397] module: <module 'privacyidea.lib.resolvers.LDAPIdResolver' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/resolvers/LDAPIdResolver.py'>
[2016-07-21 15:49:04,646][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:49:04,646][3289][140033162262272][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:49:04,655][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:49:04,656][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:49:04,656][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:49:04,656][3289][140033162262272][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:49:04,660][3289][140033162262272][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becc61710>
[2016-07-21 15:49:04,662][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:49:04,663][3289][140033162262272][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:49:04,675][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting get_username with result mrogers
[2016-07-21 15:49:04,676][3289][140033162262272][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm', 'resolver': u'abc'}
[2016-07-21 15:49:04,678][3289][140033162262272][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:49:04,679][3289][140033162262272][DEBUG][privacyidea.lib.token:1880] Found user with loginId User(login=u'mrogers', realm=u'defrealm', resolver=u'abc'): u'TOTP0000602B'
[2016-07-21 15:49:04,680][3289][140033162262272][DEBUG][privacyidea.lib.tokens.hotptoken:172] Entering is_challenge_request with arguments (<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'realm_list\'": \'[<privacyidea.models.TokenRealm object at 0x7f5becbf9310>]\', "\'count\'": \'48970916L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>, u'pin605825') and keywords {'user': User(login=u'mrogers', realm=u'defrealm', resolver=u'abc'), 'options': {'clientip': '::1', 'client': u'127.0.0.1', 'user': User(login=u'mrogers', realm=u'defrealm', resolver=u'abc'), 'g': <flask.g of 'privacyidea.app'>, 'pass': u'pin605825'}}
[2016-07-21 15:49:04,681][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd15d90>,) and keywords {'action': 'challenge_response', 'scope': 'authentication', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:49:04,681][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd15e90>,) and keywords {'realm': u'defrealm', 'action': 'challenge_response', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,681][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,682][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,683][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,683][3289][140033162262272][DEBUG][privacyidea.lib.policydecorators:118] Found these allowed tokentypes: []
[2016-07-21 15:49:04,683][3289][140033162262272][DEBUG][privacyidea.lib.tokens.hotptoken:184] Exiting is_challenge_request with result False
[2016-07-21 15:49:04,683][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments () and keywords {'default': False, 'key': 'PrependPin', 'return_bool': True}
[2016-07-21 15:49:04,685][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:49:04,685][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccdfad0>, 'otppin') and keywords {'scope': 'authentication', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccdf0d0>,) and keywords {'realm': u'defrealm', 'action': 'otppin', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authentication'}
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,686][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,687][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,687][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,687][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,687][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,687][3289][140033162262272][DEBUG][privacyidea.models:335] we got a hashed PIN!
[2016-07-21 15:49:04,688][3289][140033162262272][DEBUG][privacyidea.lib.crypto:175] Entering hash with arguments HIDDEN and keywords HIDDEN
[2016-07-21 15:49:04,688][3289][140033162262272][DEBUG][privacyidea.lib.crypto:251] hash()
[2016-07-21 15:49:04,688][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting hash with result HIDDEN
[2016-07-21 15:49:04,688][3289][140033162262272][DEBUG][privacyidea.models:295] hPin: 26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378, pin: pin, seed: b6076044eb40360cfd2207067d662f9f
[2016-07-21 15:49:04,690][3289][140033162262272][DEBUG][privacyidea.models:172] Entering get_otpkey with arguments (<<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'realm_list'": '[<privacyidea.models.TokenRealm object at 0x7f5becbf9310>]', "'count'": '48970916L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>,) and keywords {}
[2016-07-21 15:49:04,690][3289][140033162262272][DEBUG][privacyidea.models:184] Exiting get_otpkey with result <privacyidea.lib.crypto.SecretObj object at 0x7f5becc61710>
[2016-07-21 15:49:04,751][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:172] Entering checkOtp with arguments (<privacyidea.lib.tokens.HMAC.HmacOtp object at 0x7f5bece79550>, u'605825', 6) and keywords {'symetric': True}
[2016-07-21 15:49:04,751][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:145] OTP range counter: 48971011 - 48971023
[2016-07-21 15:49:04,752][3289][140033162262272][DEBUG][privacyidea.lib.crypto:172] Entering decrypt with arguments ('\xcf]8\x9e+9\x93\xd0\xe9d\x92T8m\xe4\xaa\xad\xca\xc2\xa8\x1ec\x05\x1c/\xd9)\xbar\x05\xbe?\x84\x93YT,\x10H\xaaj\xec\x11\xe8\xaf\xb3\xa5\x7fO$\x0e\xff\xc5\xfej\x05\xcc\xfe\xbbq\xb3,\x97|\xcbo\xbf\xc34\x82$\xe50\x16u\xc9\x91\xb8e;L\xfcNO\xc4\x0bU\xefP_\x08Y\xbe\x98w\xaa', '\x17\xd1?\xf3\x9ab\x99W\xd6\xff\x86\xde\\"IT') and keywords {}
[2016-07-21 15:49:04,752][3289][140033162262272][DEBUG][privacyidea.lib.crypto:186] Exiting decrypt with result HIDDEN
[2016-07-21 15:49:04,752][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971011: u'605825' '270438'
[2016-07-21 15:49:04,752][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971012: u'605825' '646873'
[2016-07-21 15:49:04,753][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971013: u'605825' '753271'
[2016-07-21 15:49:04,753][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971014: u'605825' '118823'
[2016-07-21 15:49:04,753][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971015: u'605825' '986733'
[2016-07-21 15:49:04,753][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971016: u'605825' '129684'
[2016-07-21 15:49:04,753][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971017: u'605825' '819179'
[2016-07-21 15:49:04,754][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:148] calculating counter 48971018: u'605825' '605825'
[2016-07-21 15:49:04,754][3289][140033162262272][DEBUG][privacyidea.lib.tokens.HMAC:184] Exiting checkOtp with result 48971018
[2016-07-21 15:49:04,788][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:352] last auth : datetime.datetime(2016, 7, 21, 14, 57, 45)
[2016-07-21 15:49:04,788][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:353] tokentime : datetime.datetime(2016, 7, 21, 15, 48, 45)
[2016-07-21 15:49:04,788][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:354] now       : datetime.datetime(2016, 7, 21, 15, 49, 4)
[2016-07-21 15:49:04,788][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:355] delta     : -19.0
[2016-07-21 15:49:04,789][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:358] the counter 48971018 matched. New shift: -19.0
[2016-07-21 15:49:04,947][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting check_token_list with result (True, {'serial': u'TOTP0000602B', 'type': u'totp', 'message': 'matching 1 tokens', 'otplen': 6L})
[2016-07-21 15:49:04,948][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting check_user_pass with result (True, {'serial': u'TOTP0000602B', 'type': u'totp', 'message': 'matching 1 tokens', 'otplen': 6L})
[2016-07-21 15:49:04,948][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering get_tokens with arguments () and keywords {'serial': u'TOTP0000602B'}
[2016-07-21 15:49:04,954][3289][140033162262272][DEBUG][privacyidea.lib.token:172] Entering create_tokenclass_object with arguments (<<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48971018L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becd39610>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>,) and keywords {}
[2016-07-21 15:49:04,954][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_token_module_list with arguments () and keywords {}
[2016-07-21 15:49:04,955][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_token_list with arguments () and keywords {}
[2016-07-21 15:49:04,955][3289][140033162262272][DEBUG][privacyidea.lib.config:514] None
[2016-07-21 15:49:04,955][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_token_list with result set(['privacyidea.lib.tokens.tiqrtoken', 'privacyidea.lib.tokens.smstoken', 'privacyidea.lib.tokens.hotptoken', 'privacyidea.lib.tokens.remotetoken', 'privacyidea.lib.tokens.yubicotoken', 'privacyidea.lib.tokens.registrationtoken', 'privacyidea.lib.tokens.passwordtoken', 'privacyidea.lib.tokens.motptoken', 'privacyidea.lib.tokens.emailtoken', 'privacyidea.lib.tokens.papertoken', 'privacyidea.lib.tokens.daplugtoken', 'privacyidea.lib.tokens.spasstoken', 'privacyidea.lib.tokens.certificatetoken', 'privacyidea.lib.tokens.foureyestoken', 'privacyidea.lib.tokens.questionnairetoken', 'privacyidea.lib.tokens.yubikeytoken', 'privacyidea.lib.tokens.u2ftoken', 'privacyidea.lib.tokens.sshkeytoken', 'privacyidea.lib.tokens.totptoken', 'privacyidea.lib.tokens.radiustoken'])
[2016-07-21 15:49:04,955][3289][140033162262272][DEBUG][privacyidea.lib.config:539] using the module list: set(['privacyidea.lib.tokens.tiqrtoken', 'privacyidea.lib.tokens.smstoken', 'privacyidea.lib.tokens.hotptoken', 'privacyidea.lib.tokens.remotetoken', 'privacyidea.lib.tokens.yubicotoken', 'privacyidea.lib.tokens.registrationtoken', 'privacyidea.lib.tokens.passwordtoken', 'privacyidea.lib.tokens.motptoken', 'privacyidea.lib.tokens.emailtoken', 'privacyidea.lib.tokens.papertoken', 'privacyidea.lib.tokens.daplugtoken', 'privacyidea.lib.tokens.spasstoken', 'privacyidea.lib.tokens.certificatetoken', 'privacyidea.lib.tokens.foureyestoken', 'privacyidea.lib.tokens.questionnairetoken', 'privacyidea.lib.tokens.yubikeytoken', 'privacyidea.lib.tokens.u2ftoken', 'privacyidea.lib.tokens.sshkeytoken', 'privacyidea.lib.tokens.totptoken', 'privacyidea.lib.tokens.radiustoken'])
[2016-07-21 15:49:04,955][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.tiqrtoken
[2016-07-21 15:49:04,956][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.smstoken
[2016-07-21 15:49:04,956][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.hotptoken
[2016-07-21 15:49:04,956][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.remotetoken
[2016-07-21 15:49:04,956][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.yubicotoken
[2016-07-21 15:49:04,956][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.registrationtoken
[2016-07-21 15:49:04,957][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.passwordtoken
[2016-07-21 15:49:04,957][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.motptoken
[2016-07-21 15:49:04,957][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.emailtoken
[2016-07-21 15:49:04,957][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.papertoken
[2016-07-21 15:49:04,958][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.daplugtoken
[2016-07-21 15:49:04,958][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.spasstoken
[2016-07-21 15:49:04,959][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.certificatetoken
[2016-07-21 15:49:04,959][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.foureyestoken
[2016-07-21 15:49:04,959][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.questionnairetoken
[2016-07-21 15:49:04,959][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.yubikeytoken
[2016-07-21 15:49:04,960][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.u2ftoken
[2016-07-21 15:49:04,960][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.sshkeytoken
[2016-07-21 15:49:04,960][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.totptoken
[2016-07-21 15:49:04,960][3289][140033162262272][DEBUG][privacyidea.lib.config:553] import module: privacyidea.lib.tokens.radiustoken
[2016-07-21 15:49:04,960][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_token_module_list with result [<module 'privacyidea.lib.tokens.tiqrtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/tiqrtoken.pyc'>, <module 'privacyidea.lib.tokens.smstoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/smstoken.pyc'>, <module 'privacyidea.lib.tokens.hotptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/hotptoken.pyc'>, <module 'privacyidea.lib.tokens.remotetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/remotetoken.pyc'>, <module 'privacyidea.lib.tokens.yubicotoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/yubicotoken.pyc'>, <module 'privacyidea.lib.tokens.registrationtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/registrationtoken.pyc'>, <module 'privacyidea.lib.tokens.passwordtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/passwordtoken.pyc'>, <module 'privacyidea.lib.tokens.motptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/motptoken.pyc'>, <module 'privacyidea.lib.tokens.emailtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/emailtoken.pyc'>, <module 'privacyidea.lib.tokens.papertoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/papertoken.pyc'>, <module 'privacyidea.lib.tokens.daplugtoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/daplugtoken.pyc'>, <module 'privacyidea.lib.tokens.spasstoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/spasstoken.pyc'>, <module 'privacyidea.lib.tokens.certificatetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/certificatetoken.pyc'>, <module 'privacyidea.lib.tokens.foureyestoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/foureyestoken.pyc'>, <module 'privacyidea.lib.tokens.questionnairetoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/questionnairetoken.pyc'>, <module 'privacyidea.lib.tokens.yubikeytoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/yubikeytoken.pyc'>, <module 'privacyidea.lib.tokens.u2ftoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/u2ftoken.pyc'>, <module 'privacyidea.lib.tokens.sshkeytoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/sshkeytoken.pyc'>, <module 'privacyidea.lib.tokens.totptoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/totptoken.pyc'>, <module 'privacyidea.lib.tokens.radiustoken' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/tokens/radiustoken.pyc'>]
[2016-07-21 15:49:04,962][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:172] Entering __init__ with arguments (<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {}>, <<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48971018L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becc93710>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>) and keywords {}
[2016-07-21 15:49:04,963][3289][140033162262272][DEBUG][privacyidea.lib.tokenclass:172] Entering __init__ with arguments (<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {}>, <<class 'privacyidea.models.Token'> {"'active'": 'True', "'count_window'": '10L', "'key_enc'": "u'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa'", "'pin_hash'": "u'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378'", "'so_pin'": "u''", "'user_id'": "u'f50a6e69-b756-43ee-b619-5cb51d24426e'", "'otplen'": '6L', "'so_pin_iv'": "u''", "'serial'": "u'TOTP0000602B'", "'revoked'": 'False', "'locked'": 'False', "'maxfail'": '10L', "'count'": '48971018L', "'pin_seed'": "u'b6076044eb40360cfd2207067d662f9f'", "'sync_window'": '1000L', "'description'": "u'3617d710d7fccb013bd46ef38f1bb2b657f3ef78'", "'resolver_type'": "u'ldapresolver'", "'user_pin_iv'": "u''", "'user_pin'": "u''", "'rollout_state'": "u''", "'failcount'": '0L', "'_sa_instance_state'": '<sqlalchemy.orm.state.InstanceState object at 0x7f5becc93910>', "'id'": '5L', "'resolver'": "u'abc'", "'key_iv'": "u'17d13ff39a629957d6ff86de5c224954'", "'tokentype'": "u'totp'"}>) and keywords {}
[2016-07-21 15:49:04,963][3289][140033162262272][DEBUG][privacyidea.lib.tokenclass:184] Exiting __init__ with result None
[2016-07-21 15:49:04,963][3289][140033162262272][DEBUG][privacyidea.lib.tokens.totptoken:184] Exiting __init__ with result None
[2016-07-21 15:49:04,964][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting create_tokenclass_object with result <<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'count\'": \'48971018L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>
[2016-07-21 15:49:04,964][3289][140033162262272][DEBUG][privacyidea.lib.token:184] Exiting get_tokens with result [<<class 'privacyidea.lib.tokens.totptoken.TotpTokenClass'> {"'token'": '<<class \'privacyidea.models.Token\'> {"\'active\'": \'True\', "\'count_window\'": \'10L\', "\'key_enc\'": "u\'cf5d389e2b3993d0e9649254386de4aaadcac2a81e63051c2fd929ba7205be3f849359542c1048aa6aec11e8afb3a57f4f240effc5fe6a05ccfebb71b32c977ccb6fbfc3348224e5301675c991b8653b4cfc4e4fc40b55ef505f0859be9877aa\'", "\'pin_hash\'": "u\'26d9349998685635348bff965d1aaa6c975080232c03088ed00fd6efa3ed0378\'", "\'so_pin\'": "u\'\'", "\'user_id\'": "u\'f50a6e69-b756-43ee-b619-5cb51d24426e\'", "\'otplen\'": \'6L\', "\'so_pin_iv\'": "u\'\'", "\'serial\'": "u\'TOTP0000602B\'", "\'revoked\'": \'False\', "\'locked\'": \'False\', "\'maxfail\'": \'10L\', "\'count\'": \'48971018L\', "\'pin_seed\'": "u\'b6076044eb40360cfd2207067d662f9f\'", "\'sync_window\'": \'1000L\', "\'description\'": "u\'3617d710d7fccb013bd46ef38f1bb2b657f3ef78\'", "\'resolver_type\'": "u\'ldapresolver\'", "\'user_pin_iv\'": "u\'\'", "\'user_pin\'": "u\'\'", "\'rollout_state\'": "u\'\'", "\'failcount\'": \'0L\', "\'_sa_instance_state\'": \'<sqlalchemy.orm.state.InstanceState object at 0x7f5becd60d10>\', "\'id\'": \'5L\', "\'resolver\'": "u\'abc\'", "\'key_iv\'": "u\'17d13ff39a629957d6ff86de5c224954\'", "\'tokentype\'": "u\'totp\'"}>', "'init_details'": '{}', "'type'": "u'totp'", "'hKeyRequired'": 'True', "'auth_details'": '{}'}>]
[2016-07-21 15:49:04,965][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd39750>,) and keywords {'realm': u'defrealm', 'client': '::1', 'user': u'mrogers', 'action': 'last_auth', 'scope': 'authorization', 'unique': True}
[2016-07-21 15:49:04,965][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becc146d0>,) and keywords {'realm': u'defrealm', 'action': 'last_auth', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,965][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,965][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,966][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,967][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,984][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd39790>,) and keywords {'action': 'auth_max_success', 'scope': 'authorization', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:49:04,984][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becf2b110>,) and keywords {'realm': u'defrealm', 'action': 'auth_max_success', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,985][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,986][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,986][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,986][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,986][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becbf96d0>,) and keywords {'action': 'auth_max_fail', 'scope': 'authorization', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:49:04,987][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccc9210>,) and keywords {'realm': u'defrealm', 'action': 'auth_max_fail', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,987][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,987][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,987][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,987][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,988][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:49:04,988][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:49:04,988][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,988][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,988][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,990][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd39790>, 'serial') and keywords {'scope': 'authorization', 'client': '::1'}
[2016-07-21 15:49:04,990][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becc93090>,) and keywords {'realm': None, 'action': 'serial', 'client': '::1', 'user': None, 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,990][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,990][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,990][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,991][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,991][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,991][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,991][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,992][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becbf9e90>, 'tokentype') and keywords {'scope': 'authorization', 'client': '::1'}
[2016-07-21 15:49:04,992][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd39610>,) and keywords {'realm': None, 'action': 'tokentype', 'client': '::1', 'user': None, 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:49:04,992][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:04,992][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:04,992][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:04,993][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:04,993][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:04,993][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:04,993][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:49:04,994][3289][140033162262272][DEBUG][privacyidea.lib.machine:172] Entering get_machines with arguments () and keywords {'ip': IPAddress('::1')}
[2016-07-21 15:49:04,994][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:04,998][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_list with result {u'local-hosts': {'data': {u'filename': u'/etc/hosts'}, 'type': u'hosts', 'resolvername': u'local-hosts'}}
[2016-07-21 15:49:04,998][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_object with arguments (u'local-hosts',) and keywords {}
[2016-07-21 15:49:04,999][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'local-hosts'}
[2016-07-21 15:49:05,005][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_list with result {u'local-hosts': {'data': {u'filename': u'/etc/hosts'}, 'type': u'hosts', 'resolvername': u'local-hosts'}}
[2016-07-21 15:49:05,006][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_machine_resolver_class_list with arguments () and keywords {}
[2016-07-21 15:49:05,006][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_machine_resolver_class_list with result ['privacyidea.lib.machines.hosts.HostsMachineResolver', 'privacyidea.lib.machines.ldap.LdapMachineResolver']
[2016-07-21 15:49:05,006][3289][140033162262272][DEBUG][privacyidea.lib.config:634] using the class list: ['privacyidea.lib.machines.hosts.HostsMachineResolver', 'privacyidea.lib.machines.ldap.LdapMachineResolver']
[2016-07-21 15:49:05,007][3289][140033162262272][DEBUG][privacyidea.lib.config:640] import module: privacyidea.lib.machines.hosts
[2016-07-21 15:49:05,009][3289][140033162262272][DEBUG][privacyidea.lib.config:640] import module: privacyidea.lib.machines.ldap
[2016-07-21 15:49:05,010][3289][140033162262272][DEBUG][privacyidea.lib.config:320] module: <module 'privacyidea.lib.machines.hosts' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/machines/hosts.pyc'>
[2016-07-21 15:49:05,010][3289][140033162262272][DEBUG][privacyidea.lib.config:320] module: <module 'privacyidea.lib.machines.ldap' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/machines/ldap.pyc'>
[2016-07-21 15:49:05,010][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_object with result <privacyidea.lib.machines.hosts.HostsMachineResolver object at 0x7f5becd39790>
[2016-07-21 15:49:05,011][3289][140033162262272][DEBUG][privacyidea.lib.machine:184] Exiting get_machines with result [<privacyidea.lib.machines.base.Machine object at 0x7f5becd766d0>]
[2016-07-21 15:49:05,011][3289][140033162262272][DEBUG][privacyidea.lib.machine:172] Entering list_machine_tokens with arguments () and keywords {'application': 'offline', 'serial': u'TOTP0000602B', 'hostname': 'localhost'}
[2016-07-21 15:49:05,013][3289][140033162262272][DEBUG][privacyidea.lib.machine:172] Entering get_machines with arguments () and keywords {'ip': None, 'hostname': 'localhost'}
[2016-07-21 15:49:05,013][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:49:05,018][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_list with result {u'local-hosts': {'data': {u'filename': u'/etc/hosts'}, 'type': u'hosts', 'resolvername': u'local-hosts'}}
[2016-07-21 15:49:05,018][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_object with arguments (u'local-hosts',) and keywords {}
[2016-07-21 15:49:05,019][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'local-hosts'}
[2016-07-21 15:49:05,023][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_list with result {u'local-hosts': {'data': {u'filename': u'/etc/hosts'}, 'type': u'hosts', 'resolvername': u'local-hosts'}}
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:172] Entering get_machine_resolver_class_list with arguments () and keywords {}
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:184] Exiting get_machine_resolver_class_list with result ['privacyidea.lib.machines.hosts.HostsMachineResolver', 'privacyidea.lib.machines.ldap.LdapMachineResolver']
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:634] using the class list: ['privacyidea.lib.machines.hosts.HostsMachineResolver', 'privacyidea.lib.machines.ldap.LdapMachineResolver']
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:640] import module: privacyidea.lib.machines.hosts
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:640] import module: privacyidea.lib.machines.ldap
[2016-07-21 15:49:05,024][3289][140033162262272][DEBUG][privacyidea.lib.config:320] module: <module 'privacyidea.lib.machines.hosts' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/machines/hosts.pyc'>
[2016-07-21 15:49:05,025][3289][140033162262272][DEBUG][privacyidea.lib.config:320] module: <module 'privacyidea.lib.machines.ldap' from '/usr/lib/python2.7/dist-packages/privacyidea/lib/machines/ldap.pyc'>
[2016-07-21 15:49:05,025][3289][140033162262272][DEBUG][privacyidea.lib.machineresolver:184] Exiting get_resolver_object with result <privacyidea.lib.machines.hosts.HostsMachineResolver object at 0x7f5becb8b490>
[2016-07-21 15:49:05,025][3289][140033162262272][DEBUG][privacyidea.lib.machine:184] Exiting get_machines with result [<privacyidea.lib.machines.base.Machine object at 0x7f5beccdf690>, <privacyidea.lib.machines.base.Machine object at 0x7f5beccdfad0>]
[2016-07-21 15:49:05,026][3289][140033162262272][INFO][privacyidea.api.lib.postpolicy:389] Can not get unique ID for hostname='localhost' and IP=None. More than one machine found.
[2016-07-21 15:49:05,026][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becb8b490>,) and keywords {'action': 'no_detail_on_success', 'scope': 'authorization', 'client': '::1', 'active': True}
[2016-07-21 15:49:05,026][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:05,027][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:05,027][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:05,027][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:05,027][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:05,027][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:05,028][3289][140033162262272][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becd55bd0>,) and keywords {'action': 'no_detail_on_fail', 'scope': 'authorization', 'client': '::1', 'active': True}
[2016-07-21 15:49:05,028][3289][140033162262272][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:49:05,028][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:49:05,028][3289][140033162262272][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:49:05,029][3289][140033162262272][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:49:05,029][3289][140033162262272][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:49:05,029][3289][140033162262272][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:49:05,052][3289][140033162262272][DEBUG][privacyidea.api.lib.utils:235] Can not get param: No JSON object could be decoded