I used oxd-server library and protect my resources. I used the below code ``` RsProtectResponse resp = null; final RsProtectParams commandParams = new RsProtectParams(); commandParams.setOxdId(oxdId); RsResource resource1 = new RsResource(); resource1.setPath("/movie"); Condition condition1 = new Condition(); condition1.setHttpMethods(Arrays.asList("GET")); condition1.setScopes(Arrays.asList("https://cyber.oxd.info:8443/GluuServerTest/user/profile")); condition1.setTicketScopes(Arrays.asList("https://cyber.oxd.info:8443/GluuServerTest/user/profile")); resource1.setConditions(Arrays.asList(condition1)); commandParams.setResources(Arrays.asList(resource1)); final Command command = new Command(CommandType.RS_PROTECT).setParamsObject(commandParams); resp = executeCommand(command, RsProtectResponse.class); return resp; ``` And use below code for checking access ``` final RsCheckAccessParams params = new RsCheckAccessParams(); params.setOxdId(oxdId); params.setHttpMethod(httpMethod); params.setPath(path); params.setRpt(((RpGetRptResponse)getRpt(oxdId)).getRpt()); Command command = new Command(CommandType.RS_CHECK_ACCESS); command.setParamsObject(params); final RsCheckAccessResponse resp = executeCommand(command, RsCheckAccessResponse.class); return resp; ``` now when I run this code with("POST","/movie") I got error that this method not protected which is correct. but when I run this code with("GET","/movie") I got access denied. Is there anything I did wrong? this oxd-server.logs ``` 2017-04-24 18:25:20,884 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:25:20,895 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:25:20,896 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:25:21,916 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 18:25:21,916 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"191b3b1e-2a85-4c62-bf26-743162ae92dc","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 18:25:21,916 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"191b3b1e-2a85-4c62-bf26-743162ae92dc","acr_values":null,"hd":null}} 2017-04-24 18:25:32,400 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!6BA6.95BD.03BE.9775&redirect_uri=https://google.com&scope=openid+uma_protection+uma_authorization&state=u80jjnaul06r98cgho8scvqc8p&nonce=pmsfab1cu3l4aita6tiiurgl4u"}} 2017-04-24 18:25:32,401 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:25:32,401 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:25:32,579 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:25:32,580 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 18:32:55,925 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:32:55,988 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:32:56,000 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:32:56,370 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0660, commandSize: 660 2017-04-24 18:32:56,415 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"register_site","params":{"scope":["openid","uma_protection","uma_authorization"],"contacts":null,"op_host":"https://cyber.gluu.info","authorization_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/user/profile/","post_logout_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/","redirect_uris":null,"response_types":null,"client_id":null,"client_secret":null,"client_name":null,"client_jwks_uri":null,"client_token_endpoint_auth_method":null,"client_request_uris":null,"client_logout_uris":null,"client_sector_identifier_uri":null,"ui_locales":null,"claims_locales":null,"acr_values":null,"grant_types":null,"trusted_client":false}}', m_leftString=''} 2017-04-24 18:32:56,416 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"register_site","params":{"scope":["openid","uma_protection","uma_authorization"],"contacts":null,"op_host":"https://cyber.gluu.info","authorization_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/user/profile/","post_logout_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/","redirect_uris":null,"response_types":null,"client_id":null,"client_secret":null,"client_name":null,"client_jwks_uri":null,"client_token_endpoint_auth_method":null,"client_request_uris":null,"client_logout_uris":null,"client_sector_identifier_uri":null,"ui_locales":null,"claims_locales":null,"acr_values":null,"grant_types":null,"trusted_client":false}} 2017-04-24 18:32:56,417 INFO [org.xdi.oxd.server.op.RegisterSiteOperation] Creating site configuration ... 2017-04-24 18:32:56,436 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 18:32:59,561 TRACE [org.xdi.oxd.server.op.RegisterSiteOperation] Registered client for site - client_id: @!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E 2017-04-24 18:32:59,578 INFO [org.xdi.oxd.server.op.RegisterSiteOperation] Site configuration created: SiteConfiguration{acrValues=[], oxdId='1035f095-1374-4924-a6b6-448af24f7027', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E', clientSecret='4ee7c60f-df28-4e10-85d4-578cf529f65b', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=null, aatCreatedAt=null, aatExpiresIn=0, pat=null, patCreatedAt=null, patExpiresIn=0, umaProtectedResources=[], rpt=null, rptCreatedAt=null, rptExpiresIn=null, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 18:32:59,580 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","op_host":"https://cyber.gluu.info"}} 2017-04-24 18:32:59,580 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:32:59,581 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:32:59,660 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:32:59,670 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 18:36:30,335 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:36:30,399 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:36:30,407 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:36:30,878 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 18:36:30,890 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 18:36:30,890 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","acr_values":null,"hd":null}} 2017-04-24 18:36:30,891 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=8ikeco8j5ttkeiafim1vpmc0t&nonce=1s50ggio8as8ojubdn1bjc6f1n"}} 2017-04-24 18:36:30,892 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:36:30,912 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:36:30,955 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:36:30,955 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 18:37:35,367 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:37:35,396 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:37:35,396 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:37:35,397 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0173, commandSize: 173 2017-04-24 18:37:35,397 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"5b9e464c-962a-44bd-9215-e6108ffd403a","state":"8ikeco8j5ttkeiafim1vpmc0t","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}}', m_leftString=''} 2017-04-24 18:37:35,397 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"5b9e464c-962a-44bd-9215-e6108ffd403a","state":"8ikeco8j5ttkeiafim1vpmc0t","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}} 2017-04-24 18:37:35,481 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 18:37:47,693 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"5dde273b-ea47-4f7e-b34b-743304b9cec4","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUUxMUQuMkNDQi43ODUzLjZGOEUiLCJleHAiOjE0OTMwNDgyNTgsImlhdCI6MTQ5MzA0NDY1OCwibm9uY2UiOiIxczUwZ2dpbzhhczhvanViZG4xYmpjNmYxbiIsImF1dGhfdGltZSI6MTQ5MzA0NDYyOCwiYXRfaGFzaCI6IkFTQzdYd3dkeVVLenBkSi1YekpWalEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.OMt1IPUtso6J0tTqEWbRssLOPPqdraKPz4bszfYzOmGs-VyX6j6ZoANbl3vfFJJ57xEzum0KkNfIYV6Ea3oKldA1nyaXyCsnD_rUHuWJ4RxOn8TaoJFjAWIYugf2K2ZV4T11MKZHm8uGGz17GEJLo44Rh8PbS8Iusq-1lBcWAZEyjA04GF3hc13Fl0Fa3zLLLuTPvegp5o7nPJpOl86T9UV5hsBPNYQci49yYdGqAcfcoV2bHHWk4j9sYlGwpoYR6Db-AZPVD2M3GuNlYPjES_lzV13Ah9dw26GfdXXbJz-0e7Kh7YFTBzOd21Q2rceea2RqYF-yfdnY9qmlibaaew","refresh_token":"4f4430dc-62c6-497d-b23a-481048bad227","id_token_claims":{"at_hash":["ASC7XwwdyUKzpdJ-XzJVjQ"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493044628"],"iss":["https://cyber.gluu.info"],"exp":["1493048258"],"iat":["1493044658"],"nonce":["1s50ggio8as8ojubdn1bjc6f1n"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 18:37:47,694 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:37:47,694 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:37:47,862 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:37:47,862 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 18:38:00,729 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:38:00,752 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:38:00,753 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:38:00,753 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 18:38:00,754 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","access_token":"5dde273b-ea47-4f7e-b34b-743304b9cec4"}}', m_leftString=''} 2017-04-24 18:38:00,754 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","access_token":"5dde273b-ea47-4f7e-b34b-743304b9cec4"}} 2017-04-24 18:38:00,778 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 18:38:03,539 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 18:38:03,540 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:38:03,542 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:38:03,685 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:38:03,686 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 18:38:42,141 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 18:38:42,152 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:38:42,152 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:38:42,166 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0292, commandSize: 292 2017-04-24 18:38:42,166 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_logout_uri","params":{"state":"5e8c3406-6953-4524-acf7-206cc2bb3634","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","id_token_hint":"4a573b1a-b3cb-453e-be31-bd6324efe5fe","post_logout_redirect_uri":"https://google.com","session_state":"3efe3648-9978-439e-8912-9c2cac5708e0"}}', m_leftString=''} 2017-04-24 18:38:42,166 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_logout_uri","params":{"state":"5e8c3406-6953-4524-acf7-206cc2bb3634","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","id_token_hint":"4a573b1a-b3cb-453e-be31-bd6324efe5fe","post_logout_redirect_uri":"https://google.com","session_state":"3efe3648-9978-439e-8912-9c2cac5708e0"}} 2017-04-24 18:38:42,173 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"uri":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/end_session?id_token_hint=4a573b1a-b3cb-453e-be31-bd6324efe5fe&post_logout_redirect_uri=https%3A%2F%2Fgoogle.com&state=5e8c3406-6953-4524-acf7-206cc2bb3634&session_state=3efe3648-9978-439e-8912-9c2cac5708e0"}} 2017-04-24 18:38:42,173 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 18:38:42,173 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 18:38:42,176 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 18:38:42,176 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 19:10:06,339 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 19:10:13,854 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:10:14,018 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:10:16,125 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 19:10:16,136 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 19:10:16,239 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","acr_values":null,"hd":null}} 2017-04-24 19:10:20,284 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=6bceg6j3m5qfc2q8mve24ubnpu&nonce=iu2et07hdl8jojjqfkon7cp54f"}} 2017-04-24 19:10:20,284 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:10:20,284 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:10:20,463 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 19:10:20,464 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 19:10:42,988 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 19:10:43,073 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:10:43,074 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:10:43,075 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 19:10:43,076 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"472979bd-9f58-4221-a7e9-2e62f5e52404","state":"6bceg6j3m5qfc2q8mve24ubnpu","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}}', m_leftString=''} 2017-04-24 19:10:43,076 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"472979bd-9f58-4221-a7e9-2e62f5e52404","state":"6bceg6j3m5qfc2q8mve24ubnpu","oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}} 2017-04-24 19:10:43,414 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 19:11:01,879 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"310471c6-fd3f-4d97-82c9-a313825a3c0a","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUUxMUQuMkNDQi43ODUzLjZGOEUiLCJleHAiOjE0OTMwNTAzMDgsImlhdCI6MTQ5MzA0NjcwOCwibm9uY2UiOiJpdTJldDA3aGRsOGpvampxZmtvbjdjcDU0ZiIsImF1dGhfdGltZSI6MTQ5MzA0NjY4NiwiYXRfaGFzaCI6IlJFZnYxSWhrRmotSzhFTGRnNlJubFEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.WyPUwCfqpcRVbEn1ZqCSYO4yej3eE5lUJ50x_ALP-sRE6fqtDc1yKpQYV2kBG1CO-DpvT2JF9UMwzF5VbdrTluVMaFN6PB6gnveZ3KsclPUjT_mdtCNphDpPF5wAwkVV9xNWYyiPVzK83eRXeQ8fpGCbqv5188j0sYSsJogaJ57XQNKwjkjJxt0fZvodbRIYcAs7MO_Clj6gf6BpaEpzI7bHUEL-0FQOfexAuwpys55y6eL8b9GeAD09-gqNlU2B7zfXtcvA_ayQ8kD-vOdyIQCZJ1JvL0JlVRT1KRPnaKPn2WCFj7sjeXoouobgEUWwkC5iqZfc4K-KHBCtlDzfzw","refresh_token":"aedf4cd1-43d3-47b8-b24c-b51c5e823774","id_token_claims":{"at_hash":["REfv1IhkFj-K8ELdg6RnlQ"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!E11D.2CCB.7853.6F8E"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493046686"],"iss":["https://cyber.gluu.info"],"exp":["1493050308"],"iat":["1493046708"],"nonce":["iu2et07hdl8jojjqfkon7cp54f"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 19:11:01,880 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:11:01,880 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:11:02,126 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 19:11:02,126 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 19:11:02,335 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 19:11:02,532 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:11:02,532 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:11:02,533 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 19:11:02,533 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","access_token":"310471c6-fd3f-4d97-82c9-a313825a3c0a"}}', m_leftString=''} 2017-04-24 19:11:02,534 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027","access_token":"310471c6-fd3f-4d97-82c9-a313825a3c0a"}} 2017-04-24 19:11:02,830 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 19:11:03,063 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 19:11:03,063 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:11:03,064 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:11:04,948 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 19:11:04,949 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 19:11:24,937 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 19:11:25,130 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 19:11:25,130 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 19:11:25,131 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0224, commandSize: 224 2017-04-24 19:11:25,131 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://fuck.com"],"ticketScopes":["https://fuck.com"]}]}],"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}}', m_leftString=''} 2017-04-24 19:11:25,180 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://fuck.com"],"ticketScopes":["https://fuck.com"]}]}],"oxd_id":"1035f095-1374-4924-a6b6-448af24f7027"}} 2017-04-24 19:11:33,296 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 1nw8da5lvutjnamlagmmy2rc1][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 19:11:36,587 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 19:11:36,924 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='a022f142-0a37-4f83-ae36-366fdf539bd6', refreshToken='null', expiresIn=299} 2017-04-24 19:11:38,804 ERROR [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Error status 406 Not Acceptable returned org.jboss.resteasy.client.ClientResponseFailure: Error status 406 Not Acceptable returned at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:523) at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:514) at org.jboss.resteasy.client.core.BaseClientResponse.checkFailureStatus(BaseClientResponse.java:508) at org.jboss.resteasy.client.core.extractors.BodyEntityExtractor.extractEntity(BodyEntityExtractor.java:38) at org.jboss.resteasy.client.core.ClientInvoker.invoke(ClientInvoker.java:126) at org.jboss.resteasy.client.core.ClientProxy.invoke(ClientProxy.java:88) at com.sun.proxy.$Proxy46.addResourceSet(Unknown Source) at org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar.register(ResourceRegistrar.java:89) at org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar.register(ResourceRegistrar.java:50) at org.xdi.oxd.server.op.RsProtectOperation.execute(RsProtectOperation.java:58) at org.xdi.oxd.server.op.RsProtectOperation.execute(RsProtectOperation.java:31) at org.xdi.oxd.server.Processor.process(Processor.java:80) at org.xdi.oxd.server.Processor.process(Processor.java:55) at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:60) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2017-04-24 19:11:38,832 ERROR [org.xdi.oxd.server.Processor] Error status 406 Not Acceptable returned org.jboss.resteasy.client.ClientResponseFailure: Error status 406 Not Acceptable returned at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:523) at org.jboss.resteasy.client.core.BaseClientResponse.createResponseFailure(BaseClientResponse.java:514) at org.jboss.resteasy.client.core.BaseClientResponse.checkFailureStatus(BaseClientResponse.java:508) at org.jboss.resteasy.client.core.extractors.BodyEntityExtractor.extractEntity(BodyEntityExtractor.java:38) at org.jboss.resteasy.client.core.ClientInvoker.invoke(ClientInvoker.java:126) at org.jboss.resteasy.client.core.ClientProxy.invoke(ClientProxy.java:88) at com.sun.proxy.$Proxy46.addResourceSet(Unknown Source) at org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar.register(ResourceRegistrar.java:89) at org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar.register(ResourceRegistrar.java:50) at org.xdi.oxd.server.op.RsProtectOperation.execute(RsProtectOperation.java:58) at org.xdi.oxd.server.op.RsProtectOperation.execute(RsProtectOperation.java:31) at org.xdi.oxd.server.Processor.process(Processor.java:80) at org.xdi.oxd.server.Processor.process(Processor.java:55) at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:60) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2017-04-24 19:11:38,832 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"internal_error","error_description":"Unknown internal server error occurs."}} 2017-04-24 19:11:38,857 ERROR [org.xdi.oxd.server.SocketProcessor] Quit. Enable to process command. 2017-04-24 20:14:32,524 INFO [org.xdi.oxd.server.ServerLauncher] Starting... 2017-04-24 20:14:32,526 INFO [org.xdi.oxd.server.ServerLauncher] commit: 0eda70e831db6c16919ee7cbee4aa90a5fbeb6fa, branch: origin/version_3.0.1, build time:24.02.2017 @ 08:50:18 EST 2017-04-24 20:14:32,907 TRACE [org.xdi.oxd.server.service.ConfigurationService] Try to load configuration from system property: oxd.server.config, value: D:\\oxd-server\\conf\\oxd-conf.json 2017-04-24 20:14:33,124 TRACE [org.xdi.oxd.server.service.ConfigurationService] Configuration loaded successfully from system property: oxd.server.config. 2017-04-24 20:14:33,125 TRACE [org.xdi.oxd.server.service.ConfigurationService] Configuration: Configuration{port=8099, timeOutInSeconds=0, jettyPort=0, startJetty=false, localhostOnly=true, useClientAuthenticationForPat=true, useClientAuthenticationForAat=true, trustAllCerts=true, keyStorePath='', keyStorePassword='', licenseId='58d2683a-1d1c-4c83-89a2-94d962964429', publicKey='odcVePtJXPSGILbssA1rDTYlVkmi2G7QwwOvohaZ3yFjx0IV2+j2zbSgdqp0mlhznEi6AJP0jzk8Fh+/sCwipTNoiyHYGHlkLF4pH9Gk8wQbcMjRo3G55ifJBWLSS5DxKgoU7KX7HLJgvK7CgiOVpyJSHhuggc+AEICIlw+0OXwREv7dgrSK4l1bZ1xBpr/wXbZfd5cNDOP2bChNTn9d3OoUL4XAFEaByB0SkKEmnbBRysl6xW4BcyZgw51lADshTPoou2nVOXELJvLQQ1eIguBGrsrapk0CpcNsyT+rbRYOHXO5THB8za67AvKZjdKEYXHLzB3v1UMG3qr0Lm1FcqkHXbWbe5DbEAI+nUEq/uy+ZGDxV5LuLE3dFkKfsFUdoM4YRNgsmK0NkD0b2bOUYg==', publicPassword='B6JdpTcjt8yIHBEzEVV5', licensePassword='oP3KdCqngZGuSOCYFiMv', supportGoogleLogout='true', stateExpirationInMinutes='5', nonceExpirationInMinutes='5'} 2017-04-24 20:14:33,431 INFO [org.xdi.oxd.server.service.ConfigurationService] Configuration directory: D:\\oxd-server\\conf\ 2017-04-24 20:14:33,436 TRACE [org.xdi.oxd.server.service.SiteConfigurationService] Loading site file name: oxd-default-site-config.json 2017-04-24 20:14:33,534 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: D:\oxd-server\bin\.oxd-license 2017-04-24 20:14:33,542 TRACE [org.xdi.oxd.server.license.LicenseFile] MAC ADDRESS set to : B4-B5-2F-98-76-FC 2017-04-24 20:14:33,544 TRACE [org.xdi.oxd.server.license.LicenseService] Validating license ... 2017-04-24 20:14:33,545 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: D:\oxd-server\bin\.oxd-license 2017-04-24 20:14:33,546 TRACE [org.xdi.oxd.server.license.LicenseFile] MAC ADDRESS set to : B4-B5-2F-98-76-FC 2017-04-24 20:14:33,599 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:14:40,461 TRACE [org.xdi.oxd.server.license.LicenseService] License is validated successfully. 2017-04-24 20:14:40,540 TRACE [org.xdi.oxd.server.license.LicenseService] License data: LicenseMetadata{creationDate=Fri Apr 14 16:24:11 GMT+04:00 2017, licenseId=null, autoupdate=true, active=true, product=oxd, licenseName='Elvin Garibzade', expirationDate=Sat Apr 14 16:24:11 GMT+04:00 2018, licenseCountLimit=9999, customerName=Elvin Garibzade, emails=[]} 2017-04-24 20:14:40,641 TRACE [org.xdi.oxd.server.license.LicenseService] Validating license ... 2017-04-24 20:14:40,643 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: D:\oxd-server\bin\.oxd-license 2017-04-24 20:14:40,796 TRACE [org.xdi.oxd.server.license.LicenseFile] MAC ADDRESS set to : B4-B5-2F-98-76-FC 2017-04-24 20:14:40,797 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:14:41,659 TRACE [org.xdi.oxd.server.license.LicenseService] License is validated successfully. 2017-04-24 20:14:41,673 TRACE [org.xdi.oxd.server.license.LicenseService] License data: LicenseMetadata{creationDate=Fri Apr 14 16:24:11 GMT+04:00 2017, licenseId=null, autoupdate=true, active=true, product=oxd, licenseName='Elvin Garibzade', expirationDate=Sat Apr 14 16:24:11 GMT+04:00 2018, licenseCountLimit=9999, customerName=Elvin Garibzade, emails=[]} 2017-04-24 20:14:41,682 INFO [org.xdi.oxd.server.service.SocketService] Server socket is bound to port: 8099, with timeout: 0 seconds. Start listening for notifications. 2017-04-24 20:16:45,425 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:16:45,510 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:16:45,511 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:16:46,164 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0660, commandSize: 660 2017-04-24 20:16:46,167 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"register_site","params":{"scope":["openid","uma_protection","uma_authorization"],"contacts":null,"op_host":"https://cyber.gluu.info","authorization_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/user/profile/","post_logout_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/","redirect_uris":null,"response_types":null,"client_id":null,"client_secret":null,"client_name":null,"client_jwks_uri":null,"client_token_endpoint_auth_method":null,"client_request_uris":null,"client_logout_uris":null,"client_sector_identifier_uri":null,"ui_locales":null,"claims_locales":null,"acr_values":null,"grant_types":null,"trusted_client":false}}', m_leftString=''} 2017-04-24 20:16:46,168 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"register_site","params":{"scope":["openid","uma_protection","uma_authorization"],"contacts":null,"op_host":"https://cyber.gluu.info","authorization_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/user/profile/","post_logout_redirect_uri":"https://cyber.oxd.info:8443/GluuServerTest/","redirect_uris":null,"response_types":null,"client_id":null,"client_secret":null,"client_name":null,"client_jwks_uri":null,"client_token_endpoint_auth_method":null,"client_request_uris":null,"client_logout_uris":null,"client_sector_identifier_uri":null,"ui_locales":null,"claims_locales":null,"acr_values":null,"grant_types":null,"trusted_client":false}} 2017-04-24 20:16:46,235 INFO [org.xdi.oxd.server.op.RegisterSiteOperation] Creating site configuration ... 2017-04-24 20:16:46,238 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:16:46,834 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 1xe5hodkeu2hj1iszkiec5enov][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/openid-configuration" 2017-04-24 20:16:46,848 TRACE [org.xdi.oxd.server.service.DiscoveryService] Discovery response: { "issuer": "https://cyber.gluu.info", "authorization_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize", "token_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/token", "userinfo_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/userinfo", "clientinfo_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/clientinfo", "check_session_iframe": "https://cyber.gluu.info/oxauth/opiframe", "end_session_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/end_session", "jwks_uri": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/jwks", "registration_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/register", "validate_token_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/validate", "id_generation_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/id", "introspection_endpoint": "https://cyber.gluu.info/oxauth/seam/resource/restv1/introspection", "scopes_supported": [ "clientinfo", "email", "user_name", "openid", "address", "permission", "profile", "mobile_phone", "phone" ], "response_types_supported": [ "code", "code id_token", "token", "token id_token", "code token", "code token id_token", "id_token" ], "grant_types_supported": [ "authorization_code", "implicit", "urn:ietf:params:oauth:grant-type:jwt-bearer" ], "acr_values_supported": ["auth_ldap_server"], "auth_level_mapping": {"-1": ["auth_ldap_server"]}, "subject_types_supported": [ "public", "pairwise" ], "userinfo_signing_alg_values_supported": [ "HS256", "HS384", "HS512", "RS256", "RS384", "RS512", "ES256", "ES384", "ES512" ], "userinfo_encryption_alg_values_supported": [ "RSA1_5", "RSA-OAEP", "A128KW", "A256KW" ], "userinfo_encryption_enc_values_supported": [ "RSA1_5", "RSA-OAEP", "A128KW", "A256KW" ], "id_token_signing_alg_values_supported": [ "HS256", "HS384", "HS512", "RS256", "RS384", "RS512", "ES256", "ES384", "ES512" ], "id_token_encryption_alg_values_supported": [ "RSA1_5", "RSA-OAEP", "A128KW", "A256KW" ], "id_token_encryption_enc_values_supported": [ "A128CBC+HS256", "A256CBC+HS512", "A128GCM", "A256GCM" ], "request_object_signing_alg_values_supported": [ "none", "HS256", "HS384", "HS512", "RS256", "RS384", "RS512", "ES256", "ES384", "ES512" ], "request_object_encryption_alg_values_supported": [ "RSA1_5", "RSA-OAEP", "A128KW", "A256KW" ], "request_object_encryption_enc_values_supported": [ "A128CBC+HS256", "A256CBC+HS512", "A128GCM", "A256GCM" ], "token_endpoint_auth_methods_supported": [ "client_secret_basic", "client_secret_post", "client_secret_jwt", "private_key_jwt" ], "token_endpoint_auth_signing_alg_values_supported": [ "HS256", "HS384", "HS512", "RS256", "RS384", "RS512", "ES256", "ES384", "ES512" ], "display_values_supported": [ "page", "popup" ], "claim_types_supported": ["normal"], "claims_supported": [ "birthdate", "country", "name", "email", "email_verified", "given_name", "gender", "inum", "family_name", "updated_at", "locale", "middle_name", "nickname", "phone_number_verified", "picture", "preferred_username", "profile", "zoneinfo", "user_name", "website" ], "service_documentation": "http://gluu.org/docs", "claims_locales_supported": ["en"], "ui_locales_supported": [ "en", "es" ], "scope_to_claims_mapping": [ {"clientinfo": [ "name", "inum" ]}, {"email": [ "email_verified", "email" ]}, {"user_name": ["user_name"]}, {"uma_authorization": []}, {"openid": []}, {"uma_protection": []}, {"address": [ "formatted", "postal_code", "street_address", "locality", "country", "region" ]}, {"permission": []}, {"profile": [ "name", "family_name", "given_name", "middle_name", "nickname", "preferred_username", "profile", "picture", "website", "gender", "birthdate", "zoneinfo", "locale", "updated_at" ]}, {"mobile_phone": ["phone_mobile_number"]}, {"phone": [ "phone_number_verified", "phone_number" ]} ], "claims_parameter_supported": true, "request_parameter_supported": true, "request_uri_parameter_supported": true, "require_request_uri_registration": false, "op_policy_uri": "http://ox.gluu.org/doku.php?id=oxauth:policy", "op_tos_uri": "http://ox.gluu.org/doku.php?id=oxauth:tos", "frontchannel_logout_supported": "true", "frontchannel_logout_session_supported": true } 2017-04-24 20:16:46,877 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:16:47,081 TRACE [org.xdi.oxd.server.op.RegisterSiteOperation] Registered client for site - client_id: @!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959 2017-04-24 20:16:47,180 INFO [org.xdi.oxd.server.op.RegisterSiteOperation] Site configuration created: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=null, aatCreatedAt=null, aatExpiresIn=0, pat=null, patCreatedAt=null, patExpiresIn=0, umaProtectedResources=[], rpt=null, rptCreatedAt=null, rptExpiresIn=null, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 20:16:47,196 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","op_host":"https://cyber.gluu.info"}} 2017-04-24 20:16:47,197 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:16:47,198 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:16:47,272 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:16:47,272 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:18:05,639 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:18:05,641 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:05,642 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:05,644 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 20:18:05,645 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 20:18:05,645 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 20:18:05,712 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=b0dog45m479qbs8ag6mk23u9jg&nonce=pgsfa3ilucmjtqqljcfkn63jg6"}} 2017-04-24 20:18:05,714 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:05,714 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:05,718 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:18:05,718 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:18:15,601 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:18:15,604 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:15,604 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:15,605 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 20:18:15,605 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"0bc91cd5-7d5b-48bf-b28e-f88890927544","state":"b0dog45m479qbs8ag6mk23u9jg","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 20:18:15,606 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"0bc91cd5-7d5b-48bf-b28e-f88890927544","state":"b0dog45m479qbs8ag6mk23u9jg","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:18:15,616 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:18:16,813 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"a680218e-ba3d-4887-abec-c7d3964f6fe4","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTQzNDksImlhdCI6MTQ5MzA1MDc0OSwibm9uY2UiOiJwZ3NmYTNpbHVjbWp0cXFsamNma242M2pnNiIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6IjdSM2tqOEktZjBEZ2xZTXVDbXNIcFEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.fvx4eyUM7lxGhvk_HxcunetPNFX5vFMEyu9bEDaUsVRqkyKSlEsXpDP-A4QBl6g9zP4hwHkjsPI3JNRKzdbG-ANLXgzZq2zsEYiPWGk__MUP2Px9ZGfp5rfQUBIV2J4Awd7T3W04o8Dxb0igSXUuqI6QDkgSeN-FHUMLPEt-aClOLAfpnJnT0_iDabr-EPbC4cCQbx-naU-7udfZhco8re21IaqTmtroABNtKYF6NZ4sNgQ8-oxh_Ls00cX3QlHZKthKRLUV5e2zDt5llWK3LIQALtxs99t-Mk-_DrajCHDzuxezFdMUE5gVcnymf4xKRRiH1gdi4lAJE8Dmi13qDw","refresh_token":"b35c3435-f5f2-41bd-b626-5b85816e4247","id_token_claims":{"at_hash":["7R3kj8I-f0DglYMuCmsHpQ"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493054349"],"iat":["1493050749"],"nonce":["pgsfa3ilucmjtqqljcfkn63jg6"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 20:18:16,815 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:16,815 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:16,861 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:18:16,862 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:18:16,863 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:18:16,865 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:16,865 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:16,866 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 20:18:16,866 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"a680218e-ba3d-4887-abec-c7d3964f6fe4"}}', m_leftString=''} 2017-04-24 20:18:16,867 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"a680218e-ba3d-4887-abec-c7d3964f6fe4"}} 2017-04-24 20:18:16,872 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:18:16,959 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 20:18:16,960 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:16,960 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:16,989 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:18:16,989 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:18:16,997 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:18:16,998 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:16,998 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:17,012 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 20:18:17,013 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 20:18:17,013 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:18:17,149 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: fl94xh80dd3wv0h7xg74nklh][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 20:18:17,207 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:18:17,395 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='83e7c671-6177-4165-b2ac-a0770ecd7aab', refreshToken='null', expiresIn=299} 2017-04-24 20:18:17,587 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493050751406 2017-04-24 20:18:17,600 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:18:17,600 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:18:17,601 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:18:17,603 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:18:17,603 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:43:33,218 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:43:33,274 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:33,276 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:33,823 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 20:43:33,824 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 20:43:33,825 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 20:43:33,827 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=cd11283s43eap6heee2ce8e5e1&nonce=eubfs1j0t4pbcdk7jbcuinchpp"}} 2017-04-24 20:43:33,829 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:33,829 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:33,941 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:43:33,941 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:43:34,220 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:43:34,273 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:34,273 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:34,275 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 20:43:34,276 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"e2706f65-cfcd-4964-93c8-fa78ebea100d","state":"cd11283s43eap6heee2ce8e5e1","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 20:43:34,276 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"e2706f65-cfcd-4964-93c8-fa78ebea100d","state":"cd11283s43eap6heee2ce8e5e1","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:43:34,277 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:43:34,645 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"f8a1d06f-cfe1-43dc-b0ec-d05f472bb02b","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTU4NjgsImlhdCI6MTQ5MzA1MjI2OCwibm9uY2UiOiJldWJmczFqMHQ0cGJjZGs3amJjdWluY2hwcCIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6IjdFUVhPTC1aQXdISWFxMFRrbG44UEEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.DymeDxfkGSmQIq7hGitUNP-r821j_uIIVg0SufXTFtyRz1znLc_xipmLoKrBjZH7qxln1qcApo7jySh03tdgacgsYKYb-KW1RpbncYJY9LogTsf_rtjp4VZcoZKX1z77M6_Bxe2HM1En4vtXb_dI8uTRhL8nzY34F0Cs2yPuZIJx2P1mGcVSN-vFFxNPi0-6xdCtyJUjl4haj27jFenbuwrnOROFHYJjMSwGmkdbvAjUP7rERCTZ2g7bBNGWm1L3vbcPca12HOEUry3F3p9B_GY0jnkcTch70LQvJ-AlgRme78X12RktB_eH6FamElYA3Ru1VbiuXVdf-byiuHm6Ug","refresh_token":"69d32e9d-8532-4b49-b66a-0ded47a77c5d","id_token_claims":{"at_hash":["7EQXOL-ZAwHIaq0Tkln8PA"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493055868"],"iat":["1493052268"],"nonce":["eubfs1j0t4pbcdk7jbcuinchpp"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 20:43:34,649 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:34,649 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:34,728 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:43:34,729 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:43:34,735 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:43:34,738 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:34,738 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:34,739 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 20:43:34,739 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"f8a1d06f-cfe1-43dc-b0ec-d05f472bb02b"}}', m_leftString=''} 2017-04-24 20:43:34,740 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"f8a1d06f-cfe1-43dc-b0ec-d05f472bb02b"}} 2017-04-24 20:43:34,740 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:43:34,891 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 20:43:34,891 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:34,891 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:34,936 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:43:34,938 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:43:34,945 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:43:34,947 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:34,947 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:34,981 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 20:43:34,991 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 20:43:34,992 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:43:35,102 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: c3nnni8ghr221eyqddqjxbgpi][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 20:43:35,109 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:43:35,265 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='020349bc-f863-4817-a108-7afc0107ffba', refreshToken='null', expiresIn=299} 2017-04-24 20:43:35,297 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493052269372 2017-04-24 20:43:35,301 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 20:43:35,302 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:43:35,302 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:43:35,305 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:43:35,306 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:44:22,509 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:44:22,560 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:22,563 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:22,627 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 20:44:22,628 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 20:44:22,635 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 20:44:22,640 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:44:22,828 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 196q2893e00ftp0sr4827kpl5][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 20:44:22,840 TRACE [org.xdi.oxd.server.service.DiscoveryService] Uma discovery response: MetadataConfiguration [version=1.0, issuer=https://cyber.gluu.info, patProfilesSupported=[bearer], aatProfilesSupported=[bearer], rptProfilesSupported=[https://docs.kantarainitiative.org/uma/profiles/uma-token-bearer-1.0], patGrantTypesSupported=[authorization_code, implicit, client_credentials], aatGrantTypesSupported=[authorization_code, implicit, client_credentials], claimProfilesSupported=[openid], umaProfilesSupported=[], tokenEndpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/token, introspection_endpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/rpt/status, resourceSetRegistrationEndpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/host/rsrc/resource_set, permissionRegistrationEndpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/host/rsrc_pr, rptEndpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/requester/rpt, authorization_request_endpoint=https://cyber.gluu.info/oxauth/seam/resource/restv1/requester/perm, rpt_as_jwt=false] 2017-04-24 20:44:22,841 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:44:22,877 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:44:23,108 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='3d6d890d-bb22-4949-b61a-1fa2e3b58e31', refreshToken='null', expiresIn=299} 2017-04-24 20:44:23,262 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:44:23,270 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 020349bc-f863-4817-a108-7afc0107ffba 2017-04-24 20:44:23,502 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 20:50:17 GMT+04:00 2017, issuedAt=Mon Apr 24 20:45:17 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 20:44:23,503 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT is successfully obtained from AS. RPT: ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5 2017-04-24 20:44:23,507 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5"}} 2017-04-24 20:44:23,510 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:23,511 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:23,514 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:44:23,514 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:44:23,525 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:44:23,533 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:23,534 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:23,556 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0213, commandSize: 213 2017-04-24 20:44:23,557 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 20:44:23,557 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 20:44:23,564 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:44:23,569 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 020349bc-f863-4817-a108-7afc0107ffba 2017-04-24 20:44:23,733 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 20:50:17 GMT+04:00 2017, issuedAt=Mon Apr 24 20:45:17 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 20:44:23,940 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: djqqkxxcnvdtw0sgo6ve6vs4][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 20:44:23,949 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 020349bc-f863-4817-a108-7afc0107ffba 2017-04-24 20:44:24,026 DEBUG [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Ticket registered, UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="bb21ba3a-a2f2-4eeb-a872-539f98351a80" 2017-04-24 20:44:24,051 DEBUG [org.xdi.oxd.server.op.RsCheckAccessOperation] Access denied for path: /movie and httpMethod: GET. Ticket is registered: RsCheckResponse{access='denied', wwwAuthenticateHeader='UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="bb21ba3a-a2f2-4eeb-a872-539f98351a80"', ticket='bb21ba3a-a2f2-4eeb-a872-539f98351a80'} 2017-04-24 20:44:24,053 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access":"denied","ticket":"bb21ba3a-a2f2-4eeb-a872-539f98351a80","www-authenticate_header":"UMA realm=\"rs\",as_uri=\"https://cyber.gluu.info\",error=\"insufficient_scope\",ticket=\"bb21ba3a-a2f2-4eeb-a872-539f98351a80\""}} 2017-04-24 20:44:24,054 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:24,054 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:24,063 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:44:24,063 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:44:41,722 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:44:41,734 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:41,736 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:41,758 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 20:44:41,759 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 20:44:41,766 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 20:44:41,774 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT from site configuration, RPT: ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, site: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=3d6d890d-bb22-4949-b61a-1fa2e3b58e31, aatCreatedAt=Mon Apr 24 20:44:23 GMT+04:00 2017, aatExpiresIn=299, pat=020349bc-f863-4817-a108-7afc0107ffba, patCreatedAt=Mon Apr 24 20:43:35 GMT+04:00 2017, patExpiresIn=299, umaProtectedResources=[UmaResource{id='1493050751406', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493052269372', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}], rpt=ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, rptCreatedAt=Mon Apr 24 20:45:17 GMT+04:00 2017, rptExpiresIn=Mon Apr 24 20:50:17 GMT+04:00 2017, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 20:44:41,776 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5"}} 2017-04-24 20:44:41,777 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:41,779 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:41,785 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:44:41,798 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:44:41,833 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:44:41,849 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:41,850 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:41,873 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0214, commandSize: 214 2017-04-24 20:44:41,874 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}}', m_leftString=''} 2017-04-24 20:44:41,882 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}} 2017-04-24 20:44:41,883 ERROR [org.xdi.oxd.server.op.RsCheckAccessOperation] Resource is not protected with path: /movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/ 2017-04-24 20:44:41,884 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"invalid_request","error_description":"Resource is not protected with path: /movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/"}} 2017-04-24 20:44:41,885 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:44:41,885 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:44:41,919 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:44:41,921 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:45:44,587 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:45:44,589 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:45:44,589 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:45:44,590 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 20:45:44,590 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 20:45:44,591 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 20:45:44,592 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT from site configuration, RPT: ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, site: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=3d6d890d-bb22-4949-b61a-1fa2e3b58e31, aatCreatedAt=Mon Apr 24 20:44:23 GMT+04:00 2017, aatExpiresIn=299, pat=020349bc-f863-4817-a108-7afc0107ffba, patCreatedAt=Mon Apr 24 20:43:35 GMT+04:00 2017, patExpiresIn=299, umaProtectedResources=[UmaResource{id='1493050751406', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493052269372', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}], rpt=ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, rptCreatedAt=Mon Apr 24 20:45:17 GMT+04:00 2017, rptExpiresIn=Mon Apr 24 20:50:17 GMT+04:00 2017, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 20:45:44,593 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5"}} 2017-04-24 20:45:44,593 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:45:44,594 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:45:44,599 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:45:44,601 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 20:45:44,605 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 20:45:44,615 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:45:44,616 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:45:44,620 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0213, commandSize: 213 2017-04-24 20:45:44,621 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 20:45:44,622 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 20:45:44,623 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 20:45:44,627 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 020349bc-f863-4817-a108-7afc0107ffba 2017-04-24 20:45:44,787 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: ae90ae55-1e23-47cc-99f5-a014714a84ba/D4DE.32E7.E48D.5DA3.FF78.05C6.77BF.7DA5, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 20:50:17 GMT+04:00 2017, issuedAt=Mon Apr 24 20:45:17 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 20:45:44,944 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: zi30uzoppqhy1xdn8by91bock][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 20:45:44,947 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 020349bc-f863-4817-a108-7afc0107ffba 2017-04-24 20:45:44,978 DEBUG [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Ticket registered, UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="97f5ee05-bdd9-4f92-8342-b440a353dcd8" 2017-04-24 20:45:44,979 DEBUG [org.xdi.oxd.server.op.RsCheckAccessOperation] Access denied for path: /movie and httpMethod: GET. Ticket is registered: RsCheckResponse{access='denied', wwwAuthenticateHeader='UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="97f5ee05-bdd9-4f92-8342-b440a353dcd8"', ticket='97f5ee05-bdd9-4f92-8342-b440a353dcd8'} 2017-04-24 20:45:44,980 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access":"denied","ticket":"97f5ee05-bdd9-4f92-8342-b440a353dcd8","www-authenticate_header":"UMA realm=\"rs\",as_uri=\"https://cyber.gluu.info\",error=\"insufficient_scope\",ticket=\"97f5ee05-bdd9-4f92-8342-b440a353dcd8\""}} 2017-04-24 20:45:44,981 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 20:45:44,981 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 20:45:44,985 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 20:45:44,985 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:26,421 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:26,495 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:26,495 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:27,269 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 21:10:27,270 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 21:10:27,270 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 21:10:27,273 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=hn0pufcjtpkdt20hiqo34atkae&nonce=5nd7is7grn6koies0apsciep7t"}} 2017-04-24 21:10:27,274 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:27,274 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:27,327 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:27,327 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:27,592 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:27,595 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:27,595 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:27,603 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:10:27,603 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"d078ef24-7dd7-49b0-be8c-d22be5e86e82","state":"hn0pufcjtpkdt20hiqo34atkae","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:10:27,605 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"d078ef24-7dd7-49b0-be8c-d22be5e86e82","state":"hn0pufcjtpkdt20hiqo34atkae","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:10:27,607 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:28,358 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"8f304078-1b1e-40d7-8e98-240994a33901","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTc0ODEsImlhdCI6MTQ5MzA1Mzg4MSwibm9uY2UiOiI1bmQ3aXM3Z3JuNmtvaWVzMGFwc2NpZXA3dCIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6IjJQUlZGd2hvcWE1N0dRSjVMV19UalEiLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.TWvT1OPs3n6pGTE00ShjEyQ99wXXAWTl4Mr-xBnTzzkBIgRXh3t4MclqS3vgI_XLe-C82dih27Tm_ySZTKYaDTPwyXw-bg6swkHc8a1Jg628dMbksl3_sQ8_vO-M8Nezp3wz2_NC0ot1kEVI6_n6osozLsvL7wD9JV1XN88cu2POe0YwXmgXUn_LJc5i1gOYjLp8Ig1UAXPvGKCIM_7_bAwTsWpg4MS6Nj4S5QeUQHlrA1MbSzryQmMdZIphzvWpFXHWWiMs8mfIHB8Bg6T24QBfJmEF_jBNqTkNUMUcvsRcT0m9f6IGhFwVkOlR240CmbszUddzZuQ4ZEibNysYZQ","refresh_token":"eeef3bbb-4cfc-4d2d-8c06-ce81841bf86e","id_token_claims":{"at_hash":["2PRVFwhoqa57GQJ5LW_TjQ"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493057481"],"iat":["1493053881"],"nonce":["5nd7is7grn6koies0apsciep7t"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 21:10:28,361 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:28,361 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:28,469 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:28,469 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:28,470 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:28,489 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:28,489 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:28,490 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 21:10:28,537 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"8f304078-1b1e-40d7-8e98-240994a33901"}}', m_leftString=''} 2017-04-24 21:10:28,538 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"8f304078-1b1e-40d7-8e98-240994a33901"}} 2017-04-24 21:10:28,540 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:28,677 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 21:10:28,677 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:28,678 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:28,721 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:28,721 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:28,727 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:28,729 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:28,730 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:28,740 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 21:10:28,743 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:10:28,744 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:10:28,845 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 1jhe44q7se1jbgx6s2q6j8fxi][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:10:28,856 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:29,020 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='9de7df1c-4f1d-4af7-b157-42936aebcd3a', refreshToken='null', expiresIn=299} 2017-04-24 21:10:29,071 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493053883242 2017-04-24 21:10:29,101 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:10:29,101 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:29,101 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:29,107 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:29,119 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:34,730 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:34,731 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:34,731 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:34,733 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:10:34,733 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:10:34,733 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:10:34,735 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:42,881 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:44,183 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='abf8fe53-64bb-4fa7-883f-ba5a4d52b6b9', refreshToken='null', expiresIn=299} 2017-04-24 21:10:44,293 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:10:44,348 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 9de7df1c-4f1d-4af7-b157-42936aebcd3a 2017-04-24 21:10:44,584 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:16:38 GMT+04:00 2017, issuedAt=Mon Apr 24 21:11:38 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:10:44,584 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT is successfully obtained from AS. RPT: d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2 2017-04-24 21:10:44,586 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2"}} 2017-04-24 21:10:44,587 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:44,587 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:44,588 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:44,589 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:10:44,640 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:10:44,641 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:44,642 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:44,642 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0218, commandSize: 218 2017-04-24 21:10:44,642 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2","path":"/user/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 21:10:44,643 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2","path":"/user/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 21:10:44,643 ERROR [org.xdi.oxd.server.op.RsCheckAccessOperation] Resource is not protected with path: /user/movie and httpMethod: GET. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/ 2017-04-24 21:10:44,644 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"invalid_request","error_description":"Resource is not protected with path: /user/movie and httpMethod: GET. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/"}} 2017-04-24 21:10:44,644 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:10:44,645 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:10:44,681 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:10:44,681 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:12,015 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:12,018 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:12,018 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:12,021 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:11:12,022 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"d078ef24-7dd7-49b0-be8c-d22be5e86e82","state":"hn0pufcjtpkdt20hiqo34atkae","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:11:12,022 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"d078ef24-7dd7-49b0-be8c-d22be5e86e82","state":"hn0pufcjtpkdt20hiqo34atkae","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:11:12,197 ERROR [org.xdi.oxd.server.Processor] ErrorResponseException{errorResponseCode=ErrorResponseCode{value='bad_request', description=''state' is not registered.'}} at org.xdi.oxd.server.op.GetTokensByCodeOperation.validate(GetTokensByCodeOperation.java:117) at org.xdi.oxd.server.op.GetTokensByCodeOperation.execute(GetTokensByCodeOperation.java:47) at org.xdi.oxd.server.op.GetTokensByCodeOperation.execute(GetTokensByCodeOperation.java:32) at org.xdi.oxd.server.Processor.process(Processor.java:80) at org.xdi.oxd.server.Processor.process(Processor.java:55) at org.xdi.oxd.server.SocketProcessor.run(SocketProcessor.java:60) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2017-04-24 21:11:13,539 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"bad_request","error_description":"'state' is not registered."}} 2017-04-24 21:11:13,540 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:13,541 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:13,569 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:13,578 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:18,116 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:18,118 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:18,119 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:18,132 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 21:11:18,133 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 21:11:18,133 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 21:11:18,136 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=qen12ih4qq03r6g8bapr115olf&nonce=f93pb39isepth9cp5pa5262sh"}} 2017-04-24 21:11:18,137 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:18,137 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:18,142 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:18,143 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:18,451 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:18,452 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:18,453 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:18,453 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:11:18,453 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"dad5e619-3a60-43fd-8e22-eadc0ef6cf8a","state":"qen12ih4qq03r6g8bapr115olf","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:11:18,454 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"dad5e619-3a60-43fd-8e22-eadc0ef6cf8a","state":"qen12ih4qq03r6g8bapr115olf","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:11:18,455 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:11:18,878 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"f53620a8-d254-4100-81da-7644fe93db71","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTc1MzIsImlhdCI6MTQ5MzA1MzkzMiwibm9uY2UiOiJmOTNwYjM5aXNlcHRoOWNwNXBhNTI2MnNoIiwiYXV0aF90aW1lIjoxNDkzMDQ5NzA1LCJhdF9oYXNoIjoiZDgwR2tpYkdFeTQtLTZTWEhKX0s4ZyIsIm94VmFsaWRhdGlvblVSSSI6Imh0dHBzOi8vY3liZXIuZ2x1dS5pbmZvL294YXV0aC9vcGlmcmFtZSIsIm94T3BlbklEQ29ubmVjdFZlcnNpb24iOiJvcGVuaWRjb25uZWN0LTEuMCIsInN1YiI6InRVMlVxdy1OcDRXYkVQcDdHcVc4Q1lJZDM4bnQ4U2lZUy03QllwN05jRFUifQ.imE7kBd5PMTmy4qHXcgkMnEKlNX3BuYQIdcK9WWHEmENzKHxCIfIXYsw4z9pzOw2M327PQd_CGGFQq_WWyXZnbmzf-4cAQGqX6H1N6sCXGCibEadARjF3v0Uz29kMD9B41bcej2BhGd4cVEQBAno43nst5TfeLvQ_WuG9xq4w1Ns5no0fVNraBXBeU099QZjNBGPOtNf7r-EOje6XhNDzagGL9gd4aCLnB6PfxzjnhU5QZlPDYsyMroTi6KPbQlVfensT7BIw5wnGtClIHQLeqkfvSwUIj-h1BxjH2VSViUkPYq-gUoZXeF2kBMNgIssdr_9FhaUNLF96E2Qyu4O1Q","refresh_token":"99db193c-a42a-49a5-8b64-643f7eb2e757","id_token_claims":{"at_hash":["d80GkibGEy4--6SXHJ_K8g"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493057532"],"iat":["1493053932"],"nonce":["f93pb39isepth9cp5pa5262sh"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 21:11:18,880 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:18,880 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:18,889 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:18,890 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:18,890 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:18,896 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:18,896 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:18,898 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 21:11:18,898 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"f53620a8-d254-4100-81da-7644fe93db71"}}', m_leftString=''} 2017-04-24 21:11:18,898 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"f53620a8-d254-4100-81da-7644fe93db71"}} 2017-04-24 21:11:18,899 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:11:19,052 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 21:11:19,053 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:19,054 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:19,056 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:19,057 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:19,058 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:19,084 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:19,084 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:19,085 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 21:11:19,085 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:11:19,085 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:11:19,245 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 1jzone3xaa52rkk4tcjuwoang][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:11:19,259 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 9de7df1c-4f1d-4af7-b157-42936aebcd3a 2017-04-24 21:11:19,286 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493053933468 2017-04-24 21:11:19,296 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:11:19,296 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:19,297 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:19,298 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:19,299 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:20,907 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:20,912 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:20,913 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:20,946 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:11:20,947 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:11:20,948 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:11:20,949 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT from site configuration, RPT: d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2, site: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=abf8fe53-64bb-4fa7-883f-ba5a4d52b6b9, aatCreatedAt=Mon Apr 24 21:10:44 GMT+04:00 2017, aatExpiresIn=299, pat=9de7df1c-4f1d-4af7-b157-42936aebcd3a, patCreatedAt=Mon Apr 24 21:10:29 GMT+04:00 2017, patExpiresIn=299, umaProtectedResources=[UmaResource{id='1493050751406', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493052269372', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053883242', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053933468', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}], rpt=d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2, rptCreatedAt=Mon Apr 24 21:11:38 GMT+04:00 2017, rptExpiresIn=Mon Apr 24 21:16:38 GMT+04:00 2017, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 21:11:20,951 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2"}} 2017-04-24 21:11:20,952 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:20,953 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:20,966 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:11:20,966 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:20,967 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:11:20,968 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:20,969 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:20,971 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0219, commandSize: 219 2017-04-24 21:11:20,972 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2","path":"/user/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}}', m_leftString=''} 2017-04-24 21:11:20,972 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"d6e98f96-95eb-4631-9931-cda74f699cb8/FC36.737C.22D5.DC5D.D2F5.F23B.9331.08F2","path":"/user/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}} 2017-04-24 21:11:20,974 ERROR [org.xdi.oxd.server.op.RsCheckAccessOperation] Resource is not protected with path: /user/movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/ 2017-04-24 21:11:20,977 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"invalid_request","error_description":"Resource is not protected with path: /user/movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/"}} 2017-04-24 21:11:20,978 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:11:20,979 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:11:20,996 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:11:21,002 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:14:41,716 TRACE [org.xdi.oxd.server.license.LicenseService] Validating license ... 2017-04-24 21:14:41,717 DEBUG [org.xdi.oxd.server.license.LicenseFile] License file location: D:\oxd-server\bin\.oxd-license 2017-04-24 21:14:41,735 TRACE [org.xdi.oxd.server.license.LicenseFile] MAC ADDRESS set to : B4-B5-2F-98-76-FC 2017-04-24 21:14:41,735 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:14:42,858 TRACE [org.xdi.oxd.server.license.LicenseService] License is validated successfully. 2017-04-24 21:14:42,859 TRACE [org.xdi.oxd.server.license.LicenseService] License data: LicenseMetadata{creationDate=Fri Apr 14 16:24:11 GMT+04:00 2017, licenseId=null, autoupdate=true, active=true, product=oxd, licenseName='Elvin Garibzade', expirationDate=Sat Apr 14 16:24:11 GMT+04:00 2018, licenseCountLimit=9999, customerName=Elvin Garibzade, emails=[]} 2017-04-24 21:17:47,080 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:47,098 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:47,099 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:47,777 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 21:17:47,779 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 21:17:47,779 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 21:17:47,782 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=nn3i2fdrkavc9sh9k9n2d3s59v&nonce=iplhte2ehn86btg9vptdm6gqg7"}} 2017-04-24 21:17:47,783 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:47,783 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:47,889 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:47,890 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:17:48,407 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:48,408 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:48,410 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:48,425 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:17:48,426 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"63a6319c-ba2d-43eb-a51f-94e0155aa08d","state":"nn3i2fdrkavc9sh9k9n2d3s59v","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:17:48,431 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"63a6319c-ba2d-43eb-a51f-94e0155aa08d","state":"nn3i2fdrkavc9sh9k9n2d3s59v","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:17:48,433 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:48,873 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"a0efb108-95e7-472d-83b1-ece2b1c919cf","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTc5MjIsImlhdCI6MTQ5MzA1NDMyMiwibm9uY2UiOiJpcGxodGUyZWhuODZidGc5dnB0ZG02Z3FnNyIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6Im5vOXRUTXprNmg1V09kVWxLN0d3bmciLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.JEQ0-9iwpVnlpuaR3sOqZuzkEeM-wn5kReVD6p4fTC7Zi3Q0oSm3Qt3JpyJPRNWPmj8DSvVgA6a3KKNBUVD8k9AWUdq99CeHDZ3cgl5zclm3VQq-DxG65IC0CFpKLlOc-clGHjqlw0NdPIdIg0BpK8Q1z_74HeA0x95Ap4apqdHdnWETiQHxjUpMBE_qPF2EAUJEE2xGxMM-M6OE9qdMsU7tdZEYvsp-DBRAZ1R7fwHqxVTFaANuXIc0CT-SGd9YnWk_xvRKB7A-8vwYR7mCb0dVPN-swSE-VBib8yb12GWaZ2QkDwEdjc-sLwerNJl1DadA94zpvBZUh6KsKg_paQ","refresh_token":"06b643b8-a136-443b-a5a9-228ef2079182","id_token_claims":{"at_hash":["no9tTMzk6h5WOdUlK7Gwng"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493057922"],"iat":["1493054322"],"nonce":["iplhte2ehn86btg9vptdm6gqg7"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 21:17:48,876 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:48,877 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:48,959 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:48,963 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:17:48,961 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:48,969 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:48,970 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:48,971 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 21:17:48,971 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"a0efb108-95e7-472d-83b1-ece2b1c919cf"}}', m_leftString=''} 2017-04-24 21:17:48,971 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"a0efb108-95e7-472d-83b1-ece2b1c919cf"}} 2017-04-24 21:17:48,977 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:49,067 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 21:17:49,069 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:49,070 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:49,112 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:49,114 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:17:49,121 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:49,127 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:49,128 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:49,148 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 21:17:49,149 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:17:49,150 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:17:49,362 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 16v877frtbgcp2761eokevumz][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:17:49,383 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:49,618 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='f3dbd0a9-c0ee-445f-bf7a-d0247287b713', refreshToken='null', expiresIn=299} 2017-04-24 21:17:49,664 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493054323874 2017-04-24 21:17:49,671 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:17:49,674 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:49,675 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:49,679 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:49,680 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:17:52,975 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:52,980 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:52,980 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:52,986 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:17:52,986 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:17:52,987 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:17:52,988 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:52,992 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:53,222 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='1a1999c8-331f-4240-bde8-819554ee338d', refreshToken='null', expiresIn=299} 2017-04-24 21:17:53,391 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:53,395 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: f3dbd0a9-c0ee-445f-bf7a-d0247287b713 2017-04-24 21:17:53,569 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:23:47 GMT+04:00 2017, issuedAt=Mon Apr 24 21:18:47 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:17:53,570 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT is successfully obtained from AS. RPT: bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64 2017-04-24 21:17:53,572 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64"}} 2017-04-24 21:17:53,575 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:53,576 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:53,583 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:53,584 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:17:53,584 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:17:53,589 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:53,589 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:53,590 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0213, commandSize: 213 2017-04-24 21:17:53,591 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 21:17:53,591 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 21:17:53,593 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:17:53,666 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: f3dbd0a9-c0ee-445f-bf7a-d0247287b713 2017-04-24 21:17:53,773 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:23:47 GMT+04:00 2017, issuedAt=Mon Apr 24 21:18:47 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:17:53,906 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 1im6vc9dusq2k1omdevzl5tqv][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:17:53,910 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: f3dbd0a9-c0ee-445f-bf7a-d0247287b713 2017-04-24 21:17:53,936 DEBUG [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Ticket registered, UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="78175dd8-899d-4f8d-b397-acc6608e4dde" 2017-04-24 21:17:53,937 DEBUG [org.xdi.oxd.server.op.RsCheckAccessOperation] Access denied for path: /movie and httpMethod: GET. Ticket is registered: RsCheckResponse{access='denied', wwwAuthenticateHeader='UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="78175dd8-899d-4f8d-b397-acc6608e4dde"', ticket='78175dd8-899d-4f8d-b397-acc6608e4dde'} 2017-04-24 21:17:53,938 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access":"denied","ticket":"78175dd8-899d-4f8d-b397-acc6608e4dde","www-authenticate_header":"UMA realm=\"rs\",as_uri=\"https://cyber.gluu.info\",error=\"insufficient_scope\",ticket=\"78175dd8-899d-4f8d-b397-acc6608e4dde\""}} 2017-04-24 21:17:53,939 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:17:53,941 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:17:53,946 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:17:53,953 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:18:11,172 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:18:11,187 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:18:11,188 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:18:11,195 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:18:11,195 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:18:11,198 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:18:11,202 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT from site configuration, RPT: bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64, site: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=1a1999c8-331f-4240-bde8-819554ee338d, aatCreatedAt=Mon Apr 24 21:17:53 GMT+04:00 2017, aatExpiresIn=299, pat=f3dbd0a9-c0ee-445f-bf7a-d0247287b713, patCreatedAt=Mon Apr 24 21:17:49 GMT+04:00 2017, patExpiresIn=299, umaProtectedResources=[UmaResource{id='1493050751406', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493052269372', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053883242', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053933468', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493054323874', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}], rpt=bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64, rptCreatedAt=Mon Apr 24 21:18:47 GMT+04:00 2017, rptExpiresIn=Mon Apr 24 21:23:47 GMT+04:00 2017, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 21:18:11,207 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64"}} 2017-04-24 21:18:11,212 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:18:11,212 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:18:11,214 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:18:11,214 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:18:11,214 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:18:11,217 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:18:11,217 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:18:11,218 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0214, commandSize: 214 2017-04-24 21:18:11,219 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}}', m_leftString=''} 2017-04-24 21:18:11,220 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"bf0485a2-ca8b-4ed3-8d86-386151f9a7b8/D97B.D663.369F.8768.1907.2A1D.B06C.AB64","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"POST"}} 2017-04-24 21:18:11,221 ERROR [org.xdi.oxd.server.op.RsCheckAccessOperation] Resource is not protected with path: /movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/ 2017-04-24 21:18:11,222 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"error","data":{"error":"invalid_request","error_description":"Resource is not protected with path: /movie and httpMethod: POST. Please protect your resource first with uma_rs_protect command. Check details on https://www.gluu.org/docs-oxd/"}} 2017-04-24 21:18:11,225 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:18:11,233 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:18:11,250 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:18:11,251 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:00,594 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:00,597 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:00,598 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:01,347 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 21:25:01,348 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 21:25:01,350 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 21:25:01,352 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=mu9vciq6fmg6h5e61ktl95g31n&nonce=q0lg5q9akk61rds9bn51mlpc60"}} 2017-04-24 21:25:01,354 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:01,354 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:01,482 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:01,483 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:01,749 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:01,754 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:01,756 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:01,761 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:25:01,776 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"5a263831-8ec3-419e-a6fd-460c24942916","state":"mu9vciq6fmg6h5e61ktl95g31n","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:25:01,778 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"5a263831-8ec3-419e-a6fd-460c24942916","state":"mu9vciq6fmg6h5e61ktl95g31n","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:25:01,780 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:02,126 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"fe802942-0e9c-4e93-b8cf-48f713886fd0","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTgzNTYsImlhdCI6MTQ5MzA1NDc1Niwibm9uY2UiOiJxMGxnNXE5YWtrNjFyZHM5Ym41MW1scGM2MCIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6IllDWnQxX0oxRDJQMU5JMHgwNGdNa2ciLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.JtwAPrXbYd4nYPhYoqMjrL6ivGDqgukTSNsj6NUtGz-vGerT7RfzLi8REzRGT-9qNkTGCIoFyiyCvadq1sGABAXypb2Et4GtXdHjWStJyQjIIJm6GfaRqJKFrjIGBlvRQ-9mqco-TIwAyZ-pO6byOMZ6yIJ4jtaIFf6HbA_kQVHr-mWP-Ic-3GRrL7u57tQlPxisOB-mdhF9NnhaEuEjip-Z3umccbzS7UDeO3BEQo7pQoZg1dtWqOWIgkkysArirXin7J_F9sKhnp_N9D7BIn3QsuQWLfWUi4-Qb4Yu5DCPyBU0EnEbCbGZZYgpZCcE2XFH20wXlQv4fxdfsGSR1w","refresh_token":"51935b9f-744d-4340-bb67-c29a9e256538","id_token_claims":{"at_hash":["YCZt1_J1D2P1NI0x04gMkg"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493058356"],"iat":["1493054756"],"nonce":["q0lg5q9akk61rds9bn51mlpc60"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 21:25:02,128 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:02,129 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:02,201 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:02,206 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:02,203 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:02,210 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:02,210 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:02,213 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 21:25:02,214 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"fe802942-0e9c-4e93-b8cf-48f713886fd0"}}', m_leftString=''} 2017-04-24 21:25:02,214 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"fe802942-0e9c-4e93-b8cf-48f713886fd0"}} 2017-04-24 21:25:02,216 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:02,303 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 21:25:02,304 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:02,305 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:02,360 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:02,361 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:02,370 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:02,379 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:02,379 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:02,398 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 21:25:02,401 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:25:02,403 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:25:02,497 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: h3rpixuerohjkfmzi6e3imua][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:25:02,504 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:02,695 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='56ce28f2-e566-4acf-8755-a9c828258048', refreshToken='null', expiresIn=299} 2017-04-24 21:25:02,732 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493054756971 2017-04-24 21:25:02,734 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:25:02,737 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:02,738 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:02,743 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:02,744 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:08,741 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:08,746 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:08,746 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:08,757 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:25:08,757 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:25:08,758 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:25:08,760 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:08,766 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:08,936 TRACE [org.xdi.oxd.server.service.UmaTokenService] Obtained token with client authentication: UmaToken{token='c70e7735-4882-432d-8731-551644b6b3ab', refreshToken='null', expiresIn=299} 2017-04-24 21:25:09,072 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:09,125 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:25:09,266 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT 67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:31:03 GMT+04:00 2017, issuedAt=Mon Apr 24 21:26:03 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:25:09,267 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT is successfully obtained from AS. RPT: 67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20 2017-04-24 21:25:09,269 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20"}} 2017-04-24 21:25:09,270 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:09,271 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:09,273 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:09,277 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:25:09,275 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:25:09,285 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:09,286 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:09,286 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0213, commandSize: 213 2017-04-24 21:25:09,287 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 21:25:09,288 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 21:25:09,320 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:25:09,328 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:25:09,483 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: 67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:31:03 GMT+04:00 2017, issuedAt=Mon Apr 24 21:26:03 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:25:09,693 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 2ozk5xuuaryf1kr9c667mhbx2][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:25:09,731 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:25:09,786 DEBUG [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Ticket registered, UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="ecdc09b3-d4ac-4bbd-8b9b-dc5ea612d4af" 2017-04-24 21:25:09,787 DEBUG [org.xdi.oxd.server.op.RsCheckAccessOperation] Access denied for path: /movie and httpMethod: GET. Ticket is registered: RsCheckResponse{access='denied', wwwAuthenticateHeader='UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="ecdc09b3-d4ac-4bbd-8b9b-dc5ea612d4af"', ticket='ecdc09b3-d4ac-4bbd-8b9b-dc5ea612d4af'} 2017-04-24 21:25:09,788 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access":"denied","ticket":"ecdc09b3-d4ac-4bbd-8b9b-dc5ea612d4af","www-authenticate_header":"UMA realm=\"rs\",as_uri=\"https://cyber.gluu.info\",error=\"insufficient_scope\",ticket=\"ecdc09b3-d4ac-4bbd-8b9b-dc5ea612d4af\""}} 2017-04-24 21:25:09,790 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:25:09,791 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:25:09,804 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:25:09,805 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:52,356 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:52,378 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:52,379 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:52,989 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0149, commandSize: 149 2017-04-24 21:26:52,990 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}}', m_leftString=''} 2017-04-24 21:26:52,990 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_authorization_url","params":{"prompt":null,"scope":null,"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","acr_values":null,"hd":null}} 2017-04-24 21:26:52,994 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"authorization_url":"https://cyber.gluu.info/oxauth/seam/resource/restv1/oxauth/authorize?response_type=code&client_id=@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959&redirect_uri=https://cyber.oxd.info:8443/GluuServerTest/user/profile/&scope=openid+uma_protection+uma_authorization&state=31bjolapniv728463jiqofpbpk&nonce=7tvhpglo0oe6c9s5o0594v998m"}} 2017-04-24 21:26:53,008 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:53,009 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:53,110 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:53,113 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:53,401 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:53,452 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:53,462 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:53,469 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0174, commandSize: 174 2017-04-24 21:26:53,475 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_tokens_by_code","params":{"code":"a18b7d08-eef3-48a0-ad17-e90b9a611bfe","state":"31bjolapniv728463jiqofpbpk","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:26:53,476 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_tokens_by_code","params":{"code":"a18b7d08-eef3-48a0-ad17-e90b9a611bfe","state":"31bjolapniv728463jiqofpbpk","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:26:53,481 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:26:53,903 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access_token":"6ff18f74-d223-4134-9a73-8edb35f6fab9","expires_in":299,"id_token":"eyJraWQiOiI0N2Y1NWY4Ni05OTgzLTQ2ZWItODhlNy04MzU4ZjhiMGYwZTYiLCJ0eXAiOiJKV1QiLCJhbGciOiJSUzI1NiJ9.eyJpc3MiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mbyIsImF1ZCI6IkAhNkQ3NC5FNkUxLjRBRkIuNUY3OSEwMDAxIUNEREYuNDQ4QSEwMDA4IUVGNjkuRTVBOS43NjcyLkI5NTkiLCJleHAiOjE0OTMwNTg0NjcsImlhdCI6MTQ5MzA1NDg2Nywibm9uY2UiOiI3dHZocGdsbzBvZTZjOXM1bzA1OTR2OTk4bSIsImF1dGhfdGltZSI6MTQ5MzA0OTcwNSwiYXRfaGFzaCI6Ii1KYUxwN0lHd3dNTkNUZTA3eFdwUHciLCJveFZhbGlkYXRpb25VUkkiOiJodHRwczovL2N5YmVyLmdsdXUuaW5mby9veGF1dGgvb3BpZnJhbWUiLCJveE9wZW5JRENvbm5lY3RWZXJzaW9uIjoib3BlbmlkY29ubmVjdC0xLjAiLCJzdWIiOiJ0VTJVcXctTnA0V2JFUHA3R3FXOENZSWQzOG50OFNpWVMtN0JZcDdOY0RVIn0.GzZsolkTJbryVKby-wWL5FOmYWHQtI__HPoZb6BENNt44fSKf_Gx3W66IC9nqbWpv03bc2MbvkgDcZIGQG8ssRz5Qf3mB_kwrLmxjqzo5tLELBqArFS96RDfvYIWrmnla8HZMeOWdsXOW4SEuqBJjPNcIAbRRBC_loBAHcrQJC6A720UBVzv0XVu1yufrhCF68IRHVrqrJMdX1E1gYOY0Uq6zpCykLBUUTb-YMOQSrzF00T-rxFG_Nvu1LYSCaP-vN4rOrf4gr7A3KSE358bI4qKYcoTFQqDIr7wYyuBaNfkOLbHheRlNLDl2JC8UsurzJXcSiox4oIy4vISbVT1Uw","refresh_token":"a6946976-cef3-4562-b8e9-878555030681","id_token_claims":{"at_hash":["-JaLp7IGwwMNCTe07xWpPw"],"aud":["@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959"],"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"],"auth_time":["1493049705"],"iss":["https://cyber.gluu.info"],"exp":["1493058467"],"iat":["1493054867"],"nonce":["7tvhpglo0oe6c9s5o0594v998m"],"oxValidationURI":["https://cyber.gluu.info/oxauth/opiframe"],"oxOpenIDConnectVersion":["openidconnect-1.0"]}}} 2017-04-24 21:26:53,905 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:53,907 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:53,986 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:53,988 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:53,991 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:53,997 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:53,997 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:54,000 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0140, commandSize: 140 2017-04-24 21:26:54,000 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"6ff18f74-d223-4134-9a73-8edb35f6fab9"}}', m_leftString=''} 2017-04-24 21:26:54,001 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"get_user_info","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","access_token":"6ff18f74-d223-4134-9a73-8edb35f6fab9"}} 2017-04-24 21:26:54,005 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:26:54,188 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"claims":{"sub":["tU2Uqw-Np4WbEPp7GqW8CYId38nt8SiYS-7BYp7NcDU"]}}} 2017-04-24 21:26:54,190 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:54,190 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:54,235 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:54,240 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:54,249 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:54,253 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:54,254 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:54,280 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0302, commandSize: 302 2017-04-24 21:26:54,281 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}}', m_leftString=''} 2017-04-24 21:26:54,281 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_protect","params":{"resources":[{"path":"/movie","conditions":[{"httpMethods":["GET"],"scopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"],"ticketScopes":["https://cyber.oxd.info:8443/GluuServerTest/user/profile"]}]}],"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:26:54,346 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: j4fywmomqjj81cartjyvty0q5][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:26:54,353 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:26:54,376 DEBUG [org.xdi.oxd.rs.protect.resteasy.ResourceRegistrar] Registered resource, path: /movie, http methods: [GET], id: 1493054868632 2017-04-24 21:26:54,378 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a"}} 2017-04-24 21:26:54,378 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:54,379 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:54,381 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:54,382 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:57,157 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:57,162 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:57,163 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:57,175 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0105, commandSize: 105 2017-04-24 21:26:57,176 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}}', m_leftString=''} 2017-04-24 21:26:57,177 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rp_get_rpt","params":{"oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","force_new":false}} 2017-04-24 21:26:57,178 DEBUG [org.xdi.oxd.server.service.UmaTokenService] RPT from site configuration, RPT: 67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20, site: SiteConfiguration{acrValues=[], oxdId='6ad53b8b-c583-499b-acee-803ef0ab9a7a', opHost='https://cyber.gluu.info', authorizationRedirectUri='https://cyber.oxd.info:8443/GluuServerTest/user/profile/', applicationType='web', sectorIdentifierUri='null', redirectUris=[https://cyber.oxd.info:8443/GluuServerTest/user/profile/], responseTypes=[code], clientId='@!6D74.E6E1.4AFB.5F79!0001!CDDF.448A!0008!EF69.E5A9.7672.B959', clientSecret='11cdda2e-2210-4237-8fde-b1d50ccc406a', clientJwksUri='', scope=[openid, uma_protection, uma_authorization], uiLocales=[en], claimsLocales=[en], grantType=[authorization_code], contacts=[], aat=c70e7735-4882-432d-8731-551644b6b3ab, aatCreatedAt=Mon Apr 24 21:25:08 GMT+04:00 2017, aatExpiresIn=299, pat=56ce28f2-e566-4acf-8755-a9c828258048, patCreatedAt=Mon Apr 24 21:25:02 GMT+04:00 2017, patExpiresIn=299, umaProtectedResources=[UmaResource{id='1493050751406', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493052269372', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053883242', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493053933468', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493054323874', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493054756971', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}, UmaResource{id='1493054868632', path='/movie', httpMethods=[GET], scopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile], ticketScopes=[https://cyber.oxd.info:8443/GluuServerTest/user/profile]}], rpt=67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20, rptCreatedAt=Mon Apr 24 21:26:03 GMT+04:00 2017, rptExpiresIn=Mon Apr 24 21:31:03 GMT+04:00 2017, gat=null, gatCreatedAt=null, gatExpiresIn=null} 2017-04-24 21:26:57,187 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20"}} 2017-04-24 21:26:57,189 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:57,192 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:57,193 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:57,193 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. 2017-04-24 21:26:57,195 DEBUG [org.xdi.oxd.server.service.SocketService] Start new SocketProcessor... 2017-04-24 21:26:57,225 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:57,225 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:57,231 TRACE [org.xdi.oxd.common.CoreUtils] Parsed sizeString: 0213, commandSize: 213 2017-04-24 21:26:57,231 TRACE [org.xdi.oxd.common.CoreUtils] Read result: ReadResult{m_command='{"command":"uma_rs_check_access","params":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}}', m_leftString=''} 2017-04-24 21:26:57,232 TRACE [org.xdi.oxd.server.Processor] Command: {"command":"uma_rs_check_access","params":{"rpt":"67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20","path":"/movie","oxd_id":"6ad53b8b-c583-499b-acee-803ef0ab9a7a","http_method":"GET"}} 2017-04-24 21:26:57,233 TRACE [org.xdi.oxd.server.service.HttpService] Created TRUST_ALL client. 2017-04-24 21:26:57,238 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:26:57,389 TRACE [org.xdi.oxd.server.op.RsCheckAccessOperation] RPT: 67d75860-39eb-4fd1-ac72-4e0aff47283d/3E50.D9AB.F603.559B.30F7.68D4.31C9.1F20, status: RptStatusResponse{active=true, expiresAt=Mon Apr 24 21:31:03 GMT+04:00 2017, issuedAt=Mon Apr 24 21:26:03 GMT+04:00 2017, nbf=null, clientId=null, sub=null, aud=null, iss=null, jti=null, permissions=[]} 2017-04-24 21:26:57,484 WARN [org.apache.http.client.protocol.ResponseProcessCookies] Cookie rejected: "[version: 0][name: JSESSIONID][value: 18uyts5vcgl31xfdek0628msq][domain: cyber.gluu.info][path: /oxauth][expiry: null]". Illegal path attribute "/oxauth". Path of origin: "/.well-known/uma-configuration" 2017-04-24 21:26:57,487 DEBUG [org.xdi.oxd.server.service.UmaTokenService] PAT from site configuration, PAT: 56ce28f2-e566-4acf-8755-a9c828258048 2017-04-24 21:26:57,515 DEBUG [org.xdi.oxd.rs.protect.resteasy.RptPreProcessInterceptor] Ticket registered, UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="005085ac-8697-42de-8a32-7b20f8bfa329" 2017-04-24 21:26:57,517 DEBUG [org.xdi.oxd.server.op.RsCheckAccessOperation] Access denied for path: /movie and httpMethod: GET. Ticket is registered: RsCheckResponse{access='denied', wwwAuthenticateHeader='UMA realm="rs",as_uri="https://cyber.gluu.info",error="insufficient_scope",ticket="005085ac-8697-42de-8a32-7b20f8bfa329"', ticket='005085ac-8697-42de-8a32-7b20f8bfa329'} 2017-04-24 21:26:57,518 TRACE [org.xdi.oxd.server.Processor] Send back response: {"status":"ok","data":{"access":"denied","ticket":"005085ac-8697-42de-8a32-7b20f8bfa329","www-authenticate_header":"UMA realm=\"rs\",as_uri=\"https://cyber.gluu.info\",error=\"insufficient_scope\",ticket=\"005085ac-8697-42de-8a32-7b20f8bfa329\""}} 2017-04-24 21:26:57,519 TRACE [org.xdi.oxd.server.SocketProcessor] Socket processor handling... 2017-04-24 21:26:57,519 TRACE [org.xdi.oxd.common.CoreUtils] commandSize: -1, stringStorage: 2017-04-24 21:26:57,530 TRACE [org.xdi.oxd.common.CoreUtils] End of stream. Quit. 2017-04-24 21:26:57,535 TRACE [org.xdi.oxd.server.SocketProcessor] Quit. Read result is null or command string is blank. ```