API tools faq
paste
Advertisement
tecoholic

UMA RS Check Access Fails

tecoholic
Jan 12th, 2018
305
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
Apache Log 28.93 KB | None | 0 0
raw download clone embed print report
  1. 2018-01-12 17:27:15,094 INFO  [org.xdi.oxd.server.ServerLauncher] Starting...
  2. 2018-01-12 17:27:15,117 INFO  [org.xdi.oxd.server.ServerLauncher] commit: 3f336eac2c7f91a1c14859f16b0c0e3926860a79, branch: origin/version_3.1.1, build time:27.11.2017 @ 09:52:17 EST
  3. 2018-01-12 17:27:16,088 TRACE [org.xdi.oxd.server.service.ConfigurationService] Try to load configuration from system property: oxd.server.config, value: ./bin/../conf/oxd-conf.json
  4. 2018-01-12 17:27:16,646 TRACE [org.xdi.oxd.server.service.ConfigurationService] Configuration loaded successfully from system property: oxd.server.config.
  5. 2018-01-12 17:27:16,662 TRACE [org.xdi.oxd.server.service.ConfigurationService] Trying to read oxd-default-site-config.json, path: /Users/tecoholic/work/oxd-server-311/./bin/../conf/oxd-default-site-config.json
  6. 2018-01-12 17:27:16,798 INFO  [org.xdi.oxd.server.service.ConfigurationService] Default RP configuration loaded successfully: Rp{oxdId='null', opHost='', opDiscoveryPath='', idToken='null', accessToken='null', authorizationRedirectUri='', postLogoutRedirectUri='null', applicationType='null', redirectUris=[], claimsRedirectUri=null, responseTypes=[code], clientId='null', clientSecret='null', clientRegistrationAccessToken='null', clientRegistrationClientUri='null', clientIdIssuedAt=null, clientSecretExpiresAt=null, clientName='null', sectorIdentifierUri='null', clientJwksUri='', setupOxdId='null', setupClientId='null', scope=[openid, profile, email], uiLocales=[en], claimsLocales=[en], acrValues=[], grantType=null, contacts=[], userId='null', userSecret='null', pat='null', patExpiresIn=0, patCreatedAt=null, patRefreshToken='null', umaProtectedResources=[], rpt='null', rptTokenType='null', rptPct='null', rptExpiresAt=null, rptCreatedAt=null, rptUpgraded=null, oxdRpProgrammingLanguage=null}
  7. 2018-01-12 17:27:16,802 DEBUG [org.xdi.oxd.server.persistence.RedisPersistenceService] Creating RedisPersistenceService ...
  8. 2018-01-12 17:27:16,826 DEBUG [org.xdi.service.cache.RedisProviderFactory] Creating RedisProvider ... configuration:RedisConfiguration{servers='localhost:6379', defaultPutExpiration=60, redisProviderType=STANDALONE}
  9. 2018-01-12 17:27:16,837 DEBUG [org.xdi.service.cache.RedisStandaloneProvider] Starting RedisStandaloneProvider ...
  10. 2018-01-12 17:27:17,661 TRACE [org.xdi.service.cache.RedisStandaloneProvider] put - key: testKey, status: OK
  11. 2018-01-12 17:27:17,677 DEBUG [org.xdi.service.cache.RedisStandaloneProvider] RedisStandaloneProvider started.
  12. 2018-01-12 17:27:17,677 DEBUG [org.xdi.oxd.server.persistence.RedisPersistenceService] RedisPersistenceService started.
  13. 2018-01-12 17:27:17,757 DEBUG [org.xdi.oxd.server.service.MigrationService] Migration source folder is not specified.
  14. 2018-01-12 17:27:17,758 DEBUG [org.xdi.oxd.server.service.MigrationService] Skip migration because migration source folder is not specified or otherwise invalid.
  15. 2018-01-12 17:27:17,882 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: /Users/tecoholic/work/oxd-server-311/.oxd-license
  16. 2018-01-12 17:27:17,883 ERROR [org.xdi.oxd.server.license.LicenseFile] No content to map to Object due to end of input
  17. 2018-01-12 17:27:17,888 TRACE [org.xdi.oxd.server.license.LicenseService] Validating license ...
  18. 2018-01-12 17:27:17,888 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: /Users/tecoholic/work/oxd-server-311/.oxd-license
  19. 2018-01-12 17:27:17,889 ERROR [org.xdi.oxd.server.license.LicenseFile] No content to map to Object due to end of input
  20. 2018-01-12 17:27:17,889 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: /Users/tecoholic/work/oxd-server-311/.oxd-license
  21. 2018-01-12 17:27:17,889 ERROR [org.xdi.oxd.server.license.LicenseService] Failed to load license file : /Users/tecoholic/work/oxd-server-311/.oxd-license
  22. 2018-01-12 17:27:17,916 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  23. 2018-01-12 17:27:18,539 TRACE [org.xdi.oxd.server.license.MacAddressProvider] Generating new mac address ... ip: airy.local/10.0.0.100
  24. 2018-01-12 17:27:18,541 TRACE [org.xdi.oxd.server.license.LicenseFileUpdateService] Updating license, license_id: b9d9140e-0732-4a96-b4be-215597377a10, retry: 0 ... Mac address: E0-AC-CB-70-20-12
  25. 2018-01-12 17:27:21,190 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: /Users/tecoholic/work/oxd-server-311/.oxd-license
  26. 2018-01-12 17:27:21,239 INFO  [org.xdi.oxd.server.license.LicenseFileUpdateService] License file updated successfully. Mac address: E0-AC-CB-70-20-12
  27. 2018-01-12 17:27:21,261 TRACE [org.xdi.oxd.server.license.LicenseService] Validating license ...
  28. 2018-01-12 17:27:21,261 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: /Users/tecoholic/work/oxd-server-311/.oxd-license
  29. 2018-01-12 17:27:21,264 TRACE [org.xdi.oxd.server.license.LicenseFile] MAC ADDRESS set to : E0-AC-CB-70-20-12
  30. 2018-01-12 17:27:21,265 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  31. 2018-01-12 17:27:30,143 TRACE [org.xdi.oxd.server.license.LicenseService] License is validated successfully.
  32. 2018-01-12 17:27:30,157 TRACE [org.xdi.oxd.server.license.LicenseService] License data: LicenseMetadata{creationDate=Fri Dec 01 20:19:12 IST 2017, licenseId=null, autoupdate=true, active=true, product=oxd, licenseName='Gluu, Inc.', expirationDate=Tue Jan 01 03:30:00 IST 2019, licenseCountLimit=9999, customerName=, emails=[]}
  33. 2018-01-12 17:27:30,159 INFO  [org.xdi.oxd.server.service.SocketService] Server socket is bound to port: 8099, with timeout: 0 seconds. Start listening for notifications.
  34. 2018-01-12 17:35:34,636 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor...
  35. 2018-01-12 17:35:34,777 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling...
  36. 2018-01-12 17:35:34,778 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage:
  37. 2018-01-12 17:35:34,794 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0264, commandSize: 264
  38. 2018-01-12 17:35:34,795 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"params": {"authorization_redirect_uri": "https://client.example.com:8085/callback", "op_host": "https://gluu.example.com", "client_name": "oxd-python UMA sample RS", "grant_types": ["client_credentials"], "scope": ["uma_protection"]}, "command": "register_site"}', m_leftString=''}
  39. 2018-01-12 17:35:34,796 TRACE [org.xdi.oxd.server.Processor] Command: {"params": {"authorization_redirect_uri": "https://client.example.com:8085/callback", "op_host": "https://gluu.example.com", "client_name": "oxd-python UMA sample RS", "grant_types": ["client_credentials"], "scope": ["uma_protection"]}, "command": "register_site"}
  40. 2018-01-12 17:35:34,898 INFO  [org.xdi.oxd.server.op.RegisterSiteOperation] Creating RP ...
  41. 2018-01-12 17:35:34,904 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  42. 2018-01-12 17:35:36,613 TRACE [org.xdi.oxd.server.service.DiscoveryService] Discovery response: {
  43.     "issuer": "https://gluu.example.com",
  44.     "authorization_endpoint": "https://gluu.example.com/oxauth/restv1/authorize",
  45.     "token_endpoint": "https://gluu.example.com/oxauth/restv1/token",
  46.     "userinfo_endpoint": "https://gluu.example.com/oxauth/restv1/userinfo",
  47.     "clientinfo_endpoint": "https://gluu.example.com/oxauth/restv1/clientinfo",
  48.     "check_session_iframe": "https://gluu.example.com/oxauth/opiframe",
  49.     "end_session_endpoint": "https://gluu.example.com/oxauth/restv1/end_session",
  50.     "jwks_uri": "https://gluu.example.com/oxauth/restv1/jwks",
  51.     "registration_endpoint": "https://gluu.example.com/oxauth/restv1/register",
  52.     "id_generation_endpoint": "https://gluu.example.com/oxauth/restv1/id",
  53.     "introspection_endpoint": "https://gluu.example.com/oxauth/restv1/introspection",
  54.     "scopes_supported": [
  55.         "mobile_phone",
  56.         "email",
  57.         "phone",
  58.         "profile",
  59.         "openid",
  60.         "permission",
  61.         "address",
  62.         "clientinfo",
  63.         "user_name"
  64.     ],
  65.     "response_types_supported": [
  66.         "token code",
  67.         "id_token token code",
  68.         "id_token",
  69.         "id_token code",
  70.         "token",
  71.         "id_token token",
  72.         "code"
  73.     ],
  74.     "grant_types_supported": [
  75.         "refresh_token",
  76.         "client_credentials",
  77.         "implicit",
  78.         "password",
  79.         "authorization_code",
  80.         "urn:ietf:params:oauth:grant-type:uma-ticket"
  81.     ],
  82.     "acr_values_supported": ["auth_ldap_server"],
  83.     "auth_level_mapping": {"-1": ["auth_ldap_server"]},
  84.     "subject_types_supported": [
  85.         "public",
  86.         "pairwise"
  87.     ],
  88.     "userinfo_signing_alg_values_supported": [
  89.         "HS256",
  90.         "HS384",
  91.         "HS512",
  92.         "RS256",
  93.         "RS384",
  94.         "RS512",
  95.         "ES256",
  96.         "ES384",
  97.         "ES512"
  98.     ],
  99.     "userinfo_encryption_alg_values_supported": [
  100.         "RSA1_5",
  101.         "RSA-OAEP",
  102.         "A128KW",
  103.         "A256KW"
  104.     ],
  105.     "userinfo_encryption_enc_values_supported": [
  106.         "RSA1_5",
  107.         "RSA-OAEP",
  108.         "A128KW",
  109.         "A256KW"
  110.     ],
  111.     "id_token_signing_alg_values_supported": [
  112.         "none",
  113.         "HS256",
  114.         "HS384",
  115.         "HS512",
  116.         "RS256",
  117.         "RS384",
  118.         "RS512",
  119.         "ES256",
  120.         "ES384",
  121.         "ES512"
  122.     ],
  123.     "id_token_encryption_alg_values_supported": [
  124.         "RSA1_5",
  125.         "RSA-OAEP",
  126.         "A128KW",
  127.         "A256KW"
  128.     ],
  129.     "id_token_encryption_enc_values_supported": [
  130.         "A128CBC+HS256",
  131.         "A256CBC+HS512",
  132.         "A128GCM",
  133.         "A256GCM"
  134.     ],
  135.     "request_object_signing_alg_values_supported": [
  136.         "none",
  137.         "HS256",
  138.         "HS384",
  139.         "HS512",
  140.         "RS256",
  141.         "RS384",
  142.         "RS512",
  143.         "ES256",
  144.         "ES384",
  145.         "ES512"
  146.     ],
  147.     "request_object_encryption_alg_values_supported": [
  148.         "RSA1_5",
  149.         "RSA-OAEP",
  150.         "A128KW",
  151.         "A256KW"
  152.     ],
  153.     "request_object_encryption_enc_values_supported": [
  154.         "A128CBC+HS256",
  155.         "A256CBC+HS512",
  156.         "A128GCM",
  157.         "A256GCM"
  158.     ],
  159.     "token_endpoint_auth_methods_supported": [
  160.         "client_secret_basic",
  161.         "client_secret_post",
  162.         "client_secret_jwt",
  163.         "private_key_jwt"
  164.     ],
  165.     "token_endpoint_auth_signing_alg_values_supported": [
  166.         "HS256",
  167.         "HS384",
  168.         "HS512",
  169.         "RS256",
  170.         "RS384",
  171.         "RS512",
  172.         "ES256",
  173.         "ES384",
  174.         "ES512"
  175.     ],
  176.     "display_values_supported": [
  177.         "page",
  178.         "popup"
  179.     ],
  180.     "claim_types_supported": ["normal"],
  181.     "claims_supported": [
  182.         "birthdate",
  183.         "country",
  184.         "name",
  185.         "email",
  186.         "email_verified",
  187.         "given_name",
  188.         "gender",
  189.         "inum",
  190.         "family_name",
  191.         "updated_at",
  192.         "locale",
  193.         "middle_name",
  194.         "nickname",
  195.         "phone_number_verified",
  196.         "picture",
  197.         "preferred_username",
  198.         "profile",
  199.         "zoneinfo",
  200.         "user_name",
  201.         "website"
  202.     ],
  203.     "service_documentation": "http://gluu.org/docs",
  204.     "claims_locales_supported": ["en"],
  205.     "ui_locales_supported": [
  206.         "en",
  207.         "es"
  208.     ],
  209.     "scope_to_claims_mapping": [
  210.         {"mobile_phone": ["phone_mobile_number"]},
  211.         {"email": [
  212.             "email_verified",
  213.             "email"
  214.         ]},
  215.         {"uma_protection": []},
  216.         {"phone": [
  217.             "phone_number_verified",
  218.             "phone_number"
  219.         ]},
  220.         {"profile": [
  221.             "name",
  222.             "family_name",
  223.             "given_name",
  224.             "middle_name",
  225.             "nickname",
  226.             "preferred_username",
  227.             "profile",
  228.             "picture",
  229.             "website",
  230.             "gender",
  231.             "birthdate",
  232.             "zoneinfo",
  233.             "locale",
  234.             "updated_at"
  235.         ]},
  236.         {"openid": []},
  237.         {"permission": []},
  238.         {"address": [
  239.             "formatted",
  240.             "postal_code",
  241.             "street_address",
  242.             "locality",
  243.             "country",
  244.             "region"
  245.         ]},
  246.         {"clientinfo": [
  247.             "name",
  248.             "inum"
  249.         ]},
  250.         {"user_name": ["user_name"]}
  251.     ],
  252.     "claims_parameter_supported": true,
  253.     "request_parameter_supported": true,
  254.     "request_uri_parameter_supported": true,
  255.     "require_request_uri_registration": false,
  256.     "op_policy_uri": "http://ox.gluu.org/doku.php?id=oxauth:policy",
  257.     "op_tos_uri": "http://ox.gluu.org/doku.php?id=oxauth:tos",
  258.     "frontchannel_logout_supported": "true",
  259.     "frontchannel_logout_session_supported": true
  260. }
  261.  
  262. 2018-01-12 17:35:36,652 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  263. 2018-01-12 17:35:36,831 TRACE [org.xdi.oxd.server.op.RegisterSiteOperation] Registered client for site - client_id: @!CCE8.2DCA.4896.A4D1!0001!7102.A1E6!0008!2ABA.C410.F9AC.1B74, claims: {grant_types=["refresh_token","client_credentials","authorization_code"], subject_type=pairwise, application_type=web, default_acr_values=[""], require_auth_time=false, redirect_uris=["https:\/\/client.example.com:8085\/callback"], scopes=["uma_protection"], client_name=oxd-python UMA sample RS, token_endpoint_auth_method=client_secret_basic, response_types=["code"], id_token_signed_response_alg=RS256, frontchannel_logout_session_required=false}
  264. 2018-01-12 17:35:36,886 TRACE [org.xdi.service.cache.RedisStandaloneProvider] put - key: 7707b645-8d40-413b-901a-4205d2b8bdad, status: OK
  265. 2018-01-12 17:35:36,890 INFO  [org.xdi.oxd.server.op.RegisterSiteOperation] RP created: Rp{oxdId='7707b645-8d40-413b-901a-4205d2b8bdad', opHost='https://gluu.example.com', opDiscoveryPath='null', idToken='null', accessToken='null', authorizationRedirectUri='https://client.example.com:8085/callback', postLogoutRedirectUri='null', applicationType='web', redirectUris=[https://client.example.com:8085/callback], claimsRedirectUri=[], responseTypes=[code], clientId='@!CCE8.2DCA.4896.A4D1!0001!7102.A1E6!0008!2ABA.C410.F9AC.1B74', clientSecret='5680e7b1-2a89-4898-ad71-3711fc1a10a6', clientRegistrationAccessToken='47eb2c0b-76dd-4495-9950-94880c422d65', clientRegistrationClientUri='https://gluu.example.com/oxauth/restv1/register?client_id=@!CCE8.2DCA.4896.A4D1!0001!7102.A1E6!0008!2ABA.C410.F9AC.1B74', clientIdIssuedAt=Fri Jan 12 17:35:35 IST 2018, clientSecretExpiresAt=Sat Jan 13 17:35:35 IST 2018, clientName='null', sectorIdentifierUri='null', clientJwksUri='', setupOxdId='null', setupClientId='null', scope=[uma_protection], uiLocales=[en], claimsLocales=[en], acrValues=[], grantType=[client_credentials], contacts=[], userId='null', userSecret='null', pat='null', patExpiresIn=0, patCreatedAt=null, patRefreshToken='null', umaProtectedResources=[], rpt='null', rptTokenType='null', rptPct='null', rptExpiresAt=null, rptCreatedAt=null, rptUpgraded=null, oxdRpProgrammingLanguage=null}
  266. 2018-01-12 17:35:36,908 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"7707b645-8d40-413b-901a-4205d2b8bdad","op_host":"https://gluu.example.com"}}
  267. 2018-01-12 17:35:36,908 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling...
  268. 2018-01-12 17:35:36,909 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage:
  269. 2018-01-12 17:35:36,910 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0410, commandSize: 410
  270. 2018-01-12 17:35:36,910 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"params": {"oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "resources": [{"path": "/api/photos/", "conditions": [{"httpMethods": ["GET"], "scopes": ["https://resource.example.com/uma/scope/view"]}, {"httpMethods": ["POST"], "scopes": ["https://resource.example.com/uma/scope/add"]}, {"httpMethods": ["GET", "POST"], "scopes": ["https://resource.example.com/uma/scope/all"]}]}]}, "command": "uma_rs_protect"}', m_leftString=''}
  271. 2018-01-12 17:35:36,910 TRACE [org.xdi.oxd.server.Processor] Command: {"params": {"oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "resources": [{"path": "/api/photos/", "conditions": [{"httpMethods": ["GET"], "scopes": ["https://resource.example.com/uma/scope/view"]}, {"httpMethods": ["POST"], "scopes": ["https://resource.example.com/uma/scope/add"]}, {"httpMethods": ["GET", "POST"], "scopes": ["https://resource.example.com/uma/scope/all"]}]}]}, "command": "uma_rs_protect"}
  272. 2018-01-12 17:35:36,973 TRACE [org.xdi.oxd.server.license.LicenseService] Updating statistic ... , request: StatisticUpdateRequest{type=CLIENT, licenseId='null', properties={license_id=b9d9140e-0732-4a96-b4be-215597377a10, mac_address=E0-AC-CB-70-20-12, oxd_id=7707b645-8d40-413b-901a-4205d2b8bdad, client_name=null, client_id=@!CCE8.2DCA.4896.A4D1!0001!7102.A1E6!0008!2ABA.C410.F9AC.1B74, is_client_local=true}, appMetadata=AppMetadata{appName='oxd', appVersiom='3.1.1', programmingLanguage='null', data={server_name=oxD Python Test Client, git.tags=, git.commit.user.name=Yuriy Zabrovarnyy, git.commit.id.abbrev=3f336ea, git.branch=origin/version_3.1.1, git.commit.id.describe-short=3f336ea, git.commit.id.describe=3f336ea, git.commit.id=3f336eac2c7f91a1c14859f16b0c0e3926860a79, git.commit.message.short=removed claims_redirect_uri from tests, git.build.user.email=Unknown, git.commit.user.email=yzabrovarniy@gmail.com, git.commit.time=09.11.2017 @ 05:22:17 EST, git.build.time=27.11.2017 @ 09:52:17 EST, git.build.user.name=Unknown, git.dirty=true, git.commit.message.full=removed claims_redirect_uri from tests
  273. , git.remote.origin.url=https://github.com/GluuFederation/oxD}}}
  274. 2018-01-12 17:35:36,974 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  275. 2018-01-12 17:35:37,223 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  276. 2018-01-12 17:35:37,831 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='7c2a5467-b90c-4674-9864-6979ebf0e0e0', refreshToken='null', expiresIn=299}
  277. 2018-01-12 17:35:37,835 TRACE [org.xdi.service.cache.RedisStandaloneProvider] put - key: 7707b645-8d40-413b-901a-4205d2b8bdad, status: OK
  278. 2018-01-12 17:35:38,924 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /api/photos/, http methods: [GET], id: 119891c9-ef58-4500-8813-6a71310f15b2
  279. 2018-01-12 17:35:38,925 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 7c2a5467-b90c-4674-9864-6979ebf0e0e0
  280. 2018-01-12 17:35:38,972 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /api/photos/, http methods: [POST], id: 2ec85fbf-f618-417a-ad2d-395d0644f148
  281. 2018-01-12 17:35:38,973 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 7c2a5467-b90c-4674-9864-6979ebf0e0e0
  282. 2018-01-12 17:35:39,016 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /api/photos/, http methods: [GET, POST], id: 3a4d1fdc-c967-418a-8069-4a827a271fa9
  283. 2018-01-12 17:35:39,021 TRACE [org.xdi.service.cache.RedisStandaloneProvider] put - key: 7707b645-8d40-413b-901a-4205d2b8bdad, status: OK
  284. 2018-01-12 17:35:39,027 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"7707b645-8d40-413b-901a-4205d2b8bdad"}}
  285. 2018-01-12 17:35:39,028 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling...
  286. 2018-01-12 17:35:39,028 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage:
  287. 2018-01-12 17:35:43,535 TRACE [org.xdi.oxd.server.license.LicenseService] Updated statistic. oxdId: 7707b645-8d40-413b-901a-4205d2b8bdad
  288. 2018-01-12 17:36:32,646 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0155, commandSize: 155
  289. 2018-01-12 17:36:32,648 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"params": {"path": "/api/photos/", "oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "http_method": "GET", "rpt": null}, "command": "uma_rs_check_access"}', m_leftString=''}
  290. 2018-01-12 17:36:32,649 TRACE [org.xdi.oxd.server.Processor] Command: {"params": {"path": "/api/photos/", "oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "http_method": "GET", "rpt": null}, "command": "uma_rs_check_access"}
  291. 2018-01-12 17:36:32,654 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  292. 2018-01-12 17:37:00,112 TRACE [org.xdi.oxd.server.service.DiscoveryService] Uma discovery response: UmaConfiguration{claimsInteractionEndpoint='https://gluu.example.com/oxauth/restv1/uma/gather_claims', umaProfilesSupported=[], permissionEndpoint='https://gluu.example.com/oxauth/restv1/host/rsrc_pr', resourceRegistrationEndpoint='https://gluu.example.com/oxauth/restv1/host/rsrc/resource_set', scopeEndpoint='https://gluu.example.com/oxauth/restv1/uma/scopes'} OAuth2Discovery{issuer='https://gluu.example.com', authorizationEndpoint='https://gluu.example.com/oxauth/restv1/authorize', tokenEndpoint='https://gluu.example.com/oxauth/restv1/token', jwksUri='https://gluu.example.com/oxauth/restv1/jwks', registrationEndpoint='https://gluu.example.com/oxauth/restv1/register', responseTypesSupported=[code, id_token, token], grantTypesSupported=[authorization_code, implicit, client_credentials, urn:ietf:params:oauth:grant-type:uma-ticket], tokenEndpointAuthMethodsSupported=[client_secret_basic, client_secret_post, client_secret_jwt, private_key_jwt], tokenEndpointAuthSigningAlgValuesSupported=[HS256, HS384, HS512, RS256, RS384, RS512, ES256, ES384, ES512], serviceDocumentation='http://gluu.org/docs', uiLocalesSupported=[en, es], opPolicyUri='http://ox.gluu.org/doku.php?id=oxauth:policy', opTosUri='http://ox.gluu.org/doku.php?id=oxauth:tos', introspectionEndpoint='https://gluu.example.com/oxauth/restv1/rpt/status', codeChallengeMethodsSupported=null}
  293. 2018-01-12 17:37:00,118 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  294. 2018-01-12 17:37:00,142 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 7c2a5467-b90c-4674-9864-6979ebf0e0e0
  295. 2018-01-12 17:37:13,982 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: null, status: RptStatusResponse{active=false, expiresAt=null, issuedAt=null, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=null}
  296. 2018-01-12 17:37:18,457 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 7c2a5467-b90c-4674-9864-6979ebf0e0e0
  297. 2018-01-12 17:37:18,705 ERROR [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Failed to register permission ticket.
  298. org.jboss.resteasy.client.ClientResponseFailure: RESTEASY003150: Error status 400 Bad Request returned
  299.     at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:581)
  300.     at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:572)
  301.     at org.jboss.resteasy.client.core.BaseClientResponse.checkFailureStatus(BaseClientResponse.java:566)
  302.     at org.jboss.resteasy.client.core.extractors.BodyEntityExtractor.extractEntity(BodyEntityExtractor.java:39)
  303.     at org.jboss.resteasy.client.core.ClientInvoker.invoke(ClientInvoker.java:128)
  304.     at org.jboss.resteasy.client.core.ClientProxy.invoke(ClientProxy.java:89)
  305.     at com.sun.proxy.$Proxy47.registerPermission(Unknown Source)
  306.     at org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor.registerTicketResponse(RptPreProcessInterceptor.java:177)
  307.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:117)
  308.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:34)
  309.     at org.xdi.oxd.server.Processor.process(Processor.java:76)
  310.     at org.xdi.oxd.server.Processor.process(Processor.java:51)
  311.     at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:55)
  312.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  313.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  314.     at java.lang.Thread.run(Thread.java:745)
  315. 2018-01-12 17:37:18,726 ERROR [org.xdi.oxd.server.Processor]
  316. java.lang.NullPointerException
  317.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:121)
  318.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:34)
  319.     at org.xdi.oxd.server.Processor.process(Processor.java:76)
  320.     at org.xdi.oxd.server.Processor.process(Processor.java:51)
  321.     at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:55)
  322.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  323.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  324.     at java.lang.Thread.run(Thread.java:745)
  325. 2018-01-12 17:37:18,728 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"internal_error","details":null,"error_description":"Unknown internal server error occurs."}}
  326. 2018-01-12 17:37:18,729 ERROR [org.xdi.oxd.server.SocketProcessor] Quit. Enable to process command.
  327. 2018-01-12 17:40:36,506 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor...
  328. 2018-01-12 17:40:36,521 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling...
  329. 2018-01-12 17:40:36,521 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage:
  330. 2018-01-12 17:40:36,522 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0153, commandSize: 153
  331. 2018-01-12 17:40:36,522 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"params": {"path": "/api/photos/", "oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "http_method": "GET", "rpt": ""}, "command": "uma_rs_check_access"}', m_leftString=''}
  332. 2018-01-12 17:40:36,522 TRACE [org.xdi.oxd.server.Processor] Command: {"params": {"path": "/api/photos/", "oxd_id": "7707b645-8d40-413b-901a-4205d2b8bdad", "http_method": "GET", "rpt": ""}, "command": "uma_rs_check_access"}
  333. 2018-01-12 17:40:36,537 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  334. 2018-01-12 17:40:36,603 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 7c2a5467-b90c-4674-9864-6979ebf0e0e0
  335. 2018-01-12 17:40:36,784 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: , status: RptStatusResponse{active=false, expiresAt=null, issuedAt=null, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=null}
  336. 2018-01-12 17:40:36,996 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client.
  337. 2018-01-12 17:40:37,164 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='8f488713-4aa6-4178-82bb-9401f4538b7c', refreshToken='null', expiresIn=299}
  338. 2018-01-12 17:40:37,172 TRACE [org.xdi.service.cache.RedisStandaloneProvider] put - key: 7707b645-8d40-413b-901a-4205d2b8bdad, status: OK
  339. 2018-01-12 17:40:41,643 ERROR [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Failed to register permission ticket.
  340. org.jboss.resteasy.client.ClientResponseFailure: RESTEASY003150: Error status 400 Bad Request returned
  341.     at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:581)
  342.     at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:572)
  343.     at org.jboss.resteasy.client.core.BaseClientResponse.checkFailureStatus(BaseClientResponse.java:566)
  344.     at org.jboss.resteasy.client.core.extractors.BodyEntityExtractor.extractEntity(BodyEntityExtractor.java:39)
  345.     at org.jboss.resteasy.client.core.ClientInvoker.invoke(ClientInvoker.java:128)
  346.     at org.jboss.resteasy.client.core.ClientProxy.invoke(ClientProxy.java:89)
  347.     at com.sun.proxy.$Proxy47.registerPermission(Unknown Source)
  348.     at org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor.registerTicketResponse(RptPreProcessInterceptor.java:177)
  349.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:117)
  350.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:34)
  351.     at org.xdi.oxd.server.Processor.process(Processor.java:76)
  352.     at org.xdi.oxd.server.Processor.process(Processor.java:51)
  353.     at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:55)
  354.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  355.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  356.     at java.lang.Thread.run(Thread.java:745)
  357. 2018-01-12 17:40:41,648 ERROR [org.xdi.oxd.server.Processor]
  358. java.lang.NullPointerException
  359.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:121)
  360.     at org.xdi.oxd.server.op.RsCheckAccessOperation.execute(RsCheckAccessOperation.java:34)
  361.     at org.xdi.oxd.server.Processor.process(Processor.java:76)
  362.     at org.xdi.oxd.server.Processor.process(Processor.java:51)
  363.     at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:55)
  364.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
  365.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
  366.     at java.lang.Thread.run(Thread.java:745)
  367. 2018-01-12 17:40:41,650 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"internal_error","details":null,"error_description":"Unknown internal server error occurs."}}
  368. 2018-01-12 17:40:41,651 ERROR [org.xdi.oxd.server.SocketProcessor] Quit. Enable to process command.
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!