[2016-07-21 15:35:40,523][3289][140033279760128][DEBUG][privacyidea.api.lib.utils:235] Can not get param: No JSON object could be decoded
[2016-07-21 15:35:40,527][3289][140033279760128][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 0x7f5becb8b490>}, '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:35:40,527][3289][140033279760128][DEBUG][privacyidea.lib.audit:172] Entering getAuditClass with arguments ('privacyidea.lib.auditmodules.sqlaudit', 'Audit') and keywords {}
[2016-07-21 15:35:40,527][3289][140033279760128][DEBUG][privacyidea.lib.audit:73] klass: <class 'privacyidea.lib.auditmodules.sqlaudit.Audit'>
[2016-07-21 15:35:40,528][3289][140033279760128][DEBUG][privacyidea.lib.audit:184] Exiting getAuditClass with result <class 'privacyidea.lib.auditmodules.sqlaudit.Audit'>
[2016-07-21 15:35:40,528][3289][140033279760128][DEBUG][privacyidea.lib.auditmodules.sqlaudit:146] using the connect string mysql://pi:84L8uALgbk0K@localhost/pi
[2016-07-21 15:35:40,529][3289][140033279760128][DEBUG][privacyidea.lib.auditmodules.sqlaudit:153] Using SQL pool_size of 20
[2016-07-21 15:35:40,533][3289][140033279760128][DEBUG][privacyidea.lib.audit:184] Exiting getAudit with result <privacyidea.lib.auditmodules.sqlaudit.Audit object at 0x7f5becb804d0>
[2016-07-21 15:35:40,533][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('OverrideAuthorizationClient',) and keywords {}
[2016-07-21 15:35:40,534][3289][140033279760128][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result None
[2016-07-21 15:35:40,535][3289][140033279760128][WARNING][privacyidea.lib.utils:439] Proxy ::1 not allowed to set IP to 10.10.10.3.
[2016-07-21 15:35:40,535][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:35:40,536][3289][140033279760128][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:35:40,536][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:35:40,536][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:35:40,537][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:35:40,538][3289][140033279760128][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:35:40,538][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:35:40,539][3289][140033279760128][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:35:40,539][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:35:40,543][3289][140033279760128][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:35:40,543][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:35:40,544][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,544][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,548][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,548][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,549][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,549][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:35:40,549][3289][140033279760128][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:35:40,549][3289][140033279760128][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:35:40,550][3289][140033279760128][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:35:40,550][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:35:40,550][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:35:40,550][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:35:40,550][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:35:40,550][3289][140033279760128][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:35:40,551][3289][140033279760128][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:35:40,551][3289][140033279760128][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:35:40,551][3289][140033279760128][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:35:40,551][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,551][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,556][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,556][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,557][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,557][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:35:40,560][3289][140033279760128][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becb47490>
[2016-07-21 15:35:40,562][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:35:40,562][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:35:40,574][3289][140033279760128][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:35:40,574][3289][140033279760128][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:35:40,574][3289][140033279760128][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:35:40,574][3289][140033279760128][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:35:40,575][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:35:40,575][3289][140033279760128][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becc0ae90>, 'setrealm') and keywords {'scope': 'authorization', 'client': '::1', 'realm': u'defrealm'}
[2016-07-21 15:35:40,575][3289][140033279760128][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5becb3a9d0>,) and keywords {'realm': u'defrealm', 'action': 'setrealm', 'client': '::1', 'user': None, 'resolver': None, 'active': True, 'scope': 'authorization'}
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:35:40,576][3289][140033279760128][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:35:40,577][3289][140033279760128][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:35:40,577][3289][140033279760128][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:35:40,577][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:35:40,579][3289][140033279760128][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:35:40,579][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:35:40,579][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:35:40,579][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:35:40,581][3289][140033279760128][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:35:40,581][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:35:40,581][3289][140033279760128][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:35:40,581][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:35:40,585][3289][140033279760128][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:35:40,585][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:35:40,586][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,586][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,592][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,593][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,593][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,593][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:35:40,593][3289][140033279760128][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:35:40,594][3289][140033279760128][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:35:40,594][3289][140033279760128][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:35:40,594][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:35:40,594][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:35:40,594][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:35:40,594][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:35:40,595][3289][140033279760128][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:35:40,595][3289][140033279760128][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:35:40,595][3289][140033279760128][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:35:40,595][3289][140033279760128][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:35:40,595][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,596][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,600][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,600][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,600][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,601][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:35:40,603][3289][140033279760128][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becedcb10>
[2016-07-21 15:35:40,605][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:35:40,606][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:35:40,616][3289][140033279760128][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:35:40,616][3289][140033279760128][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:35:40,617][3289][140033279760128][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:35:40,617][3289][140033279760128][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:35:40,617][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:35:40,617][3289][140033279760128][DEBUG][privacyidea.lib.policy:172] Entering get_action_values with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccbb390>, 'mangle') and keywords {'scope': 'authentication', 'client': '::1', 'realm': u'defrealm', 'user': u'mrogers'}
[2016-07-21 15:35:40,618][3289][140033279760128][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccbb550>,) and keywords {'realm': u'defrealm', 'action': 'mangle', 'client': '::1', 'user': u'mrogers', 'resolver': None, 'active': True, 'scope': 'authentication'}
[2016-07-21 15:35:40,618][3289][140033279760128][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:35:40,618][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:35:40,618][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:35:40,618][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.policy:184] Exiting get_action_values with result []
[2016-07-21 15:35:40,619][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:35:40,621][3289][140033279760128][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:35:40,621][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:35:40,622][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:35:40,622][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:35:40,623][3289][140033279760128][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:35:40,623][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:35:40,624][3289][140033279760128][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:35:40,624][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:35:40,628][3289][140033279760128][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:35:40,628][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:35:40,628][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,629][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,634][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,634][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,635][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,635][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:35:40,635][3289][140033279760128][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:35:40,635][3289][140033279760128][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:35:40,635][3289][140033279760128][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:35:40,636][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:35:40,636][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:35:40,636][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:35:40,636][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:35:40,636][3289][140033279760128][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:35:40,636][3289][140033279760128][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:35:40,637][3289][140033279760128][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:35:40,637][3289][140033279760128][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:35:40,637][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,637][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,642][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,643][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,643][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,643][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:35:40,647][3289][140033279760128][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5becb5c290>
[2016-07-21 15:35:40,649][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:35:40,650][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:35:40,660][3289][140033279760128][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:35:40,660][3289][140033279760128][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:35:40,660][3289][140033279760128][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:35:40,660][3289][140033279760128][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:35:40,660][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:35:40,661][3289][140033279760128][DEBUG][privacyidea.lib.policy:172] Entering get_policies with arguments (<privacyidea.lib.policy.PolicyClass object at 0x7f5beccbb7d0>,) and keywords {'realm': u'defrealm', 'action': 'api_key_required', 'client': '::1', 'user': u'mrogers', 'active': True, 'scope': 'authorization'}
[2016-07-21 15:35:40,661][3289][140033279760128][DEBUG][privacyidea.lib.policy:350] Policies after matching time: []
[2016-07-21 15:35:40,661][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching active: []
[2016-07-21 15:35:40,661][3289][140033279760128][DEBUG][privacyidea.lib.policy:360] Policies after matching scope: []
[2016-07-21 15:35:40,661][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching action: []
[2016-07-21 15:35:40,662][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching user: []
[2016-07-21 15:35:40,662][3289][140033279760128][DEBUG][privacyidea.lib.policy:395] Policies after matching realm: []
[2016-07-21 15:35:40,662][3289][140033279760128][DEBUG][privacyidea.lib.policy:432] Policies after matching client
[2016-07-21 15:35:40,662][3289][140033279760128][DEBUG][privacyidea.lib.policy:184] Exiting get_policies with result []
[2016-07-21 15:35:40,662][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_from_config with arguments ('splitAtSign',) and keywords {'default': False, 'return_bool': True}
[2016-07-21 15:35:40,664][3289][140033279760128][DEBUG][privacyidea.lib.config:184] Exiting get_from_config with result True
[2016-07-21 15:35:40,664][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering split_user with arguments (u'mrogers',) and keywords {}
[2016-07-21 15:35:40,665][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting split_user with result (u'mrogers', '')
[2016-07-21 15:35:40,665][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_default_realm with arguments () and keywords {}
[2016-07-21 15:35:40,666][3289][140033279760128][DEBUG][privacyidea.lib.realm:184] Exiting get_default_realm with result defrealm
[2016-07-21 15:35:40,667][3289][140033279760128][DEBUG][privacyidea.lib.user:172] Entering User with arguments () and keywords {'login': u'mrogers', 'realm': u'defrealm'}
[2016-07-21 15:35:40,667][3289][140033279760128][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:35:40,667][3289][140033279760128][DEBUG][privacyidea.lib.realm:172] Entering get_realms with arguments (u'defrealm',) and keywords {}
[2016-07-21 15:35:40,672][3289][140033279760128][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:35:40,672][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting get_realm_resolvers with result {u'abc': {'priority': None, 'type': u'ldapresolver'}}
[2016-07-21 15:35:40,672][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_object with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,672][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,677][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,677][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,678][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,678][3289][140033279760128][DEBUG][privacyidea.lib.config:172] Entering get_resolver_list with arguments () and keywords {}
[2016-07-21 15:35:40,678][3289][140033279760128][DEBUG][privacyidea.lib.config:443] None
[2016-07-21 15:35:40,678][3289][140033279760128][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:35:40,679][3289][140033279760128][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:35:40,679][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.PasswdIdResolver
[2016-07-21 15:35:40,679][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SCIMIdResolver
[2016-07-21 15:35:40,679][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.SQLIdResolver
[2016-07-21 15:35:40,679][3289][140033279760128][DEBUG][privacyidea.lib.config:582] import module: privacyidea.lib.resolvers.LDAPIdResolver
[2016-07-21 15:35:40,680][3289][140033279760128][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:35:40,680][3289][140033279760128][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:35:40,680][3289][140033279760128][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:35:40,680][3289][140033279760128][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:35:40,681][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_config with arguments (u'abc',) and keywords {}
[2016-07-21 15:35:40,681][3289][140033279760128][DEBUG][privacyidea.lib.resolver:172] Entering get_resolver_list with arguments () and keywords {'filter_resolver_name': u'abc'}
[2016-07-21 15:35:40,686][3289][140033279760128][DEBUG][privacyidea.lib.crypto:172] Entering decryptPassword with arguments (u'2085bbb41fcd0954ce397fd3d84ac99d:7f797e7fd1bfb243187ef79ac9022ef15bb3170b6f8e82bb9bcd9bec368fb63f9e38e7b3688f37a643093d780208acf7',) and keywords {}
[2016-07-21 15:35:40,687][3289][140033279760128][DEBUG][privacyidea.lib.crypto:186] Exiting decryptPassword with result HIDDEN
[2016-07-21 15:35:40,687][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_list with result HIDDEN
[2016-07-21 15:35:40,687][3289][140033279760128][DEBUG][privacyidea.lib.resolver:186] Exiting get_resolver_config with result HIDDEN
[2016-07-21 15:35:40,691][3289][140033279760128][DEBUG][privacyidea.lib.resolver:184] Exiting get_resolver_object with result <privacyidea.lib.resolvers.LDAPIdResolver.IdResolver object at 0x7f5beccf94d0>
[2016-07-21 15:35:40,693][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.86, None, False to server pool.
[2016-07-21 15:35:40,694][3289][140033279760128][DEBUG][privacyidea.lib.resolvers.LDAPIdResolver:563] Added 10.10.10.100, None, False to server pool.
[2016-07-21 15:35:40,700][3289][140033279760128][INFO][privacyidea.lib.user:187] user u'mrogers' found in resolver u'abc'
[2016-07-21 15:35:40,700][3289][140033279760128][INFO][privacyidea.lib.user:188] userid resolved to 'f50a6e69-b756-43ee-b619-5cb51d24426e'
[2016-07-21 15:35:40,701][3289][140033279760128][DEBUG][privacyidea.lib.user:191] priority of the resolver is None
[2016-07-21 15:35:40,701][3289][140033279760128][DEBUG][privacyidea.lib.user:192] The highest priority is 1000
[2016-07-21 15:35:40,701][3289][140033279760128][DEBUG][privacyidea.lib.user:184] Exiting User with result <mrogers.abc@defrealm>
[2016-07-21 15:35:40,727][3289][140033279760128][DEBUG][privacyidea.api.lib.utils:235] Can not get param: No JSON object could be decoded
[2016-07-21 15:35:40,750][3289][140033279760128][DEBUG][privacyidea.api.lib.utils:235] Can not get param: No JSON object could be decoded