Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

listing a collection in federation results in a "no results found" traceback #132

Closed
bh9 opened this issue Dec 6, 2019 · 10 comments
Closed
Assignees
Labels
Milestone

Comments

@bh9
Copy link
Contributor

bh9 commented Dec 6, 2019

Steps to reproduce:
authenticate with 1 zone
using Navigation Tools, navigate to a collection in another zone
observe 500

06-Dec-2019 14:41:54.950 SEVERE [http-nio-8080-exec-6] org.apache.catalina.core.StandardWrapperValve.invoke Servlet.service() for servlet [mvc-dispatcher] in context with path [/metalnx] threw exception [Request processing failed; nested exception is com.emc.metalnx.core.domain.exceptions.DataGridException: error getting permission listing] with root cause
	org.irods.jargon.core.exception.DataNotFoundException: no result found
		at org.irods.jargon.core.query.AbstractIRODSQueryResultSet.getFirstResult(AbstractIRODSQueryResultSet.java:102)
		at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:162)
		at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:120)
		at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:441)
		at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:417)
		at org.irods.jargon.core.pub.CollectionAOImpl.listPermissionsForCollection(CollectionAOImpl.java:1914)
		at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:391)
		at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:365)
		at com.emc.metalnx.services.irods.PermissionsServiceImpl.resolveMostPermissiveAccessForUser(PermissionsServiceImpl.java:302)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
		at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
		at java.base/java.lang.reflect.Method.invoke(Method.java:566)
		at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:333)
		at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
		at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
		at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
		at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:282)
		at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
		at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
		at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:213)
		at com.sun.proxy.$Proxy50.resolveMostPermissiveAccessForUser(Unknown Source)
		at com.emc.metalnx.controller.BrowseController.getCollBrowserView(BrowseController.java:920)
		at com.emc.metalnx.controller.BrowseController.getSubDirectories(BrowseController.java:240)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
		at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
		at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
		at java.base/java.lang.reflect.Method.invoke(Method.java:566)
		at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:205)
		at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:133)
		at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:97)
		at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:827)
		at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:738)
		at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85)
		at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:967)
		at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:901)
		at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:970)
		at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:872)
		at javax.servlet.http.HttpServlet.service(HttpServlet.java:660)
		at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:846)
		at javax.servlet.http.HttpServlet.service(HttpServlet.java:741)
		at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
		at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
		at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
		at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
		at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330)
		at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118)
		at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:154)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:199)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:50)
		at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
		at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
		at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192)
		at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160)
		at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347)
		at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263)
		at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
		at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
		at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:197)
		at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
		at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
		at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
		at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199)
		at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
		at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:645)
		at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
		at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81)
		at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:678)
		at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
		at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
		at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:609)
		at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
		at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:810)
		at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1506)
		at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
		at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
		at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
		at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
		at java.base/java.lang.Thread.run(Thread.java:834)
@bh9
Copy link
Contributor Author

bh9 commented Dec 6, 2019

@kript suggests this might be related to the strictAcl settings

@kript
Copy link

kript commented Dec 6, 2019

In that all our Zones have a rule of acAclPolicy { } i.e. we don't use Strict ACL's

@michael-conway
Copy link
Contributor

michael-conway commented Dec 6, 2019 via email

@bh9
Copy link
Contributor Author

bh9 commented Dec 6, 2019

interesting update: metalnx will list the master zone (which is the one you authenticate with), plus 2 of our federated zones, but not the other 2

@michael-conway michael-conway self-assigned this Dec 6, 2019
@bh9
Copy link
Contributor Author

bh9 commented Dec 6, 2019

I've turned on both debugging options I could find, and captured the following output when triggering the problem:

2019-12-06 16:55:53 INFO  CollectionController:129 - indexViaUrl()
2019-12-06 16:55:53 INFO  CollectionController:131 - dp Header requestHeader is :: 
2019-12-06 16:55:53 INFO  CollectionController:138 - path provided...go to:Optional[%2Fseq-dev]
2019-12-06 16:55:53 INFO  CollectionController:145 - myPath:{}/seq-dev
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
2019-12-06 16:55:53 INFO  CollectionController:151 - loggedUser:DataGridUser [id=4, dataGridId=22580771, username=bh9, additionalInfo=Sanger1-dev, enabled=true, firstName=, lastName=, locale=en, forceFileOverwriting=false, userType=rodsuser, advancedView=false, bookmarks=[], metadataUnitView=false, bookmarks=[], favorites=[]]
2019-12-06 16:55:53 DEBUG CollectionController:160 - no ui mode specified
2019-12-06 16:55:53 DEBUG CollectionController:162 - isUserAdmin? false
2019-12-06 16:55:53 INFO  CollectionController:164 - as uiMode:user
2019-12-06 16:55:53 INFO  CollectionServiceImpl:169 - isDataObject()
2019-12-06 16:55:53 INFO  CollectionServiceImpl:149 - isCollection()
2019-12-06 16:55:53 INFO  CollectionServiceImpl:154 - getting objStat
2019-12-06 16:55:53 INFO  IRODSServicesImpl:245 - getCollectionAndDataObjectListAndSearchAO()
2019-12-06 16:55:53 DEBUG IRODSServicesImpl:249 - irodsAccount used:irods://[email protected]:1247
2019-12-06 16:55:53 DEBUG IRODSServicesImpl:250 - authScheme:STANDARD
2019-12-06 16:55:54 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:54 DEBUG CollectionServiceImpl:158 - objStat:ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:54 INFO  CollectionController:184 - is collection...continue to collection management
2019-12-06 16:55:54 DEBUG CollectionServiceImpl:902 - Getting current user's home directory
2019-12-06 16:55:54 DEBUG CollectionServiceImpl:914 - Getting public directory
2019-12-06 16:55:54 INFO  ResourceServiceImpl:50 - Find all resources in the grid
2019-12-06 16:55:54 INFO  ResourceAOImpl:451 - findAllComposable() - post 4.0 server
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:114 - executeIRODSQueryAndCloseResult()
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:RESC_ID
   selectFieldNumericTranslation:301
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_NAME
   selectFieldNumericTranslation:302
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_ZONE_NAME
   selectFieldNumericTranslation:303
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_TYPE_NAME
   selectFieldNumericTranslation:304
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_CLASS_NAME
   selectFieldNumericTranslation:305
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_LOC
   selectFieldNumericTranslation:306
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_VAULT_PATH
   selectFieldNumericTranslation:307
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_FREE_SPACE
   selectFieldNumericTranslation:308
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_FREE_SPACE_TIME
   selectFieldNumericTranslation:314
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:RESC_INFO
   selectFieldNumericTranslation:309
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:302
	 selectFieldColumnName:RESC_NAME
	 operator:NOT_EQUAL
	 value:'bundleResc'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:54 INFO  GenQueryProcessor:90 - continuation value: 0
2019-12-06 16:55:54 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:54 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 3
2019-12-06 16:55:54 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:54 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:54 INFO  GenQueryProcessor:160 - no results to close, ignore
2019-12-06 16:55:54 INFO  ZoneAOImpl:166 - zone query:org.irods.jargon.core.pub.ZoneAOImpl@201a699e
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:114 - executeIRODSQueryAndCloseResult()
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:54 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSQuery:
   queryString:select ZONE_ID,ZONE_NAME,ZONE_TYPE,ZONE_CONNECTION,ZONE_COMMENT,ZONE_CREATE_TIME,ZONE_MODIFY_TIME where ZONE_NAME = 'Sanger1-dev'
    numberOfResultsDesired:500
2019-12-06 16:55:54 INFO  GenQueryProcessor:90 - continuation value: 0
2019-12-06 16:55:54 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:54 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:54 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:54 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:54 INFO  GenQueryProcessor:160 - no results to close, ignore
2019-12-06 16:55:54 INFO  ResourceServiceImpl:118 - getDataGridResource()
2019-12-06 16:55:54 DEBUG ResourceServiceImpl:151 - got dataGridResource:DataGridResource [id=10027, name=demoResc, zone=Sanger1-dev, type=minimum_free_space_for_create_in_bytes=26422302720, path=/usr/local/iRODS/Vault, freeSpace=422807363584, freeSpaceTimeStamp=Tue Dec 03 13:50:29 GMT 2019, children=[], parent=Sanger1-dev, status=, host=irods-sanger1-dev.internal.sanger.ac.uk, totalRecords=0, contextString=minimum_free_space_for_create_in_bytes=26422302720]
2019-12-06 16:55:54 INFO  ResourceServiceImpl:118 - getDataGridResource()
2019-12-06 16:55:54 DEBUG ResourceServiceImpl:151 - got dataGridResource:DataGridResource [id=15985, name=data, zone=Sanger1-dev, type=minimum_free_space_for_create_in_bytes=26422302720, path=/data, freeSpace=422807363584, freeSpaceTimeStamp=Tue Dec 03 13:50:29 GMT 2019, children=[], parent=Sanger1-dev, status=, host=irods-sanger1-dev, totalRecords=0, contextString=minimum_free_space_for_create_in_bytes=26422302720]
2019-12-06 16:55:54 INFO  ResourceServiceImpl:118 - getDataGridResource()
2019-12-06 16:55:54 DEBUG ResourceServiceImpl:151 - got dataGridResource:DataGridResource [id=19100766, name=wtsiuser, zone=Sanger1-dev, type=minimum_free_space_for_create_in_bytes=620294963, path=/wtsiuser, freeSpace=2667016192, freeSpaceTimeStamp=Tue Dec 03 13:50:29 GMT 2019, children=[], parent=Sanger1-dev, status=, host=irods-sanger1-dev, totalRecords=0, contextString=minimum_free_space_for_create_in_bytes=620294963]
2019-12-06 16:55:54 DEBUG ResourceServiceImpl:71 - got all resources
2019-12-06 16:55:54 DEBUG ResourceServiceImpl:74 - sorted...
2019-12-06 16:55:54 INFO  CollectionController:204 - displaying collections/collectionManagement
2019-12-06 16:55:54 INFO  ConfigServiceImpl:98 - getGlobalConfig()
2019-12-06 16:55:54 DEBUG ConfigServiceImpl:104 - globalConfig:GlobalConfig [ticketsEnabled=false, uploadRulesEnabled=false, handleNoAccessViaProxy=false]
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
2019-12-06 16:55:54 DEBUG HttpResponseHandlerInterceptor:70 - added user prefs to model as 'dataGridUser':DataGridUser [id=4, dataGridId=22580771, username=bh9, additionalInfo=Sanger1-dev, enabled=true, firstName=, lastName=, locale=en, forceFileOverwriting=false, userType=rodsuser, advancedView=false, bookmarks=[], metadataUnitView=false, bookmarks=[], favorites=[]]
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        datagridus0_.id as id1_4_,
        datagridus0_.created_at as created_2_4_,
        datagridus0_.is_collection as is_colle3_4_,
        datagridus0_.name as name4_4_,
        datagridus0_.path as path5_4_,
        datagridus0_.path_hash as path_has6_4_,
        datagridus0_.user_id as user_id7_4_ 
    from
        user_favorites datagridus0_ 
    where
        user_id=? 
        and (
            datagridus0_.path like ?
        ) 
        and is_collection=true 
    order by
        datagridus0_.path asc limit ?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        datagridus0_.id as id1_3_,
        datagridus0_.created_at as created_2_3_,
        datagridus0_.is_collection as is_colle3_3_,
        datagridus0_.is_notified as is_notif4_3_,
        datagridus0_.name as name5_3_,
        datagridus0_.path as path6_3_,
        datagridus0_.user_id as user_id7_3_ 
    from
        user_bookmarks datagridus0_ 
    where
        user_id=? 
        and (
            datagridus0_.path like ?
        ) 
        and is_collection=true 
    order by
        datagridus0_.path asc limit ?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
Hibernate: 
    select
        datagridus0_.id as id1_4_,
        datagridus0_.created_at as created_2_4_,
        datagridus0_.is_collection as is_colle3_4_,
        datagridus0_.name as name4_4_,
        datagridus0_.path as path5_4_,
        datagridus0_.path_hash as path_has6_4_,
        datagridus0_.user_id as user_id7_4_ 
    from
        user_favorites datagridus0_ 
    where
        user_id=? 
        and (
            datagridus0_.path like ?
        ) 
        and is_collection=true 
    order by
        datagridus0_.path asc limit ?
Hibernate: 
    select
        datagridus0_.id as id1_3_,
        datagridus0_.created_at as created_2_3_,
        datagridus0_.is_collection as is_colle3_3_,
        datagridus0_.is_notified as is_notif4_3_,
        datagridus0_.name as name5_3_,
        datagridus0_.path as path6_3_,
        datagridus0_.user_id as user_id7_3_ 
    from
        user_bookmarks datagridus0_ 
    where
        user_id=? 
        and (
            datagridus0_.path like ?
        ) 
        and is_collection=true 
    order by
        datagridus0_.path asc limit ?
2019-12-06 16:55:55 INFO  BrowseController:233 - getSubDirectories()
2019-12-06 16:55:55 INFO  BrowseController:234 - model:{}
2019-12-06 16:55:55 INFO  BrowseController:235 - path:/seq-dev
2019-12-06 16:55:55 INFO  BrowseController:237 - Get subdirectories of /seq-dev
2019-12-06 16:55:55 INFO  BrowseController:874 - getCollBrowserView()
2019-12-06 16:55:55 INFO  BrowseController:876 - model:{}
2019-12-06 16:55:55 INFO  BrowseController:877 - path:/seq-dev
2019-12-06 16:55:55 INFO  BrowseController:879 - find collection by name:/seq-dev
2019-12-06 16:55:55 INFO  CollectionServiceImpl:455 - findByName()
2019-12-06 16:55:55 INFO  CollectionServiceImpl:462 - Find collection or data object by name: /seq-dev
2019-12-06 16:55:55 INFO  IRODSServicesImpl:245 - getCollectionAndDataObjectListAndSearchAO()
2019-12-06 16:55:55 DEBUG IRODSServicesImpl:249 - irodsAccount used:irods://[email protected]:1247
2019-12-06 16:55:55 DEBUG IRODSServicesImpl:250 - authScheme:STANDARD
2019-12-06 16:55:55 INFO  CollectionAndDataObjectListAndSearchAOImpl:127 - getCollectionAndDataObjectListingEntryAtGivenAbsolutePathWithHeuristicPathGuessing()
2019-12-06 16:55:55 INFO  CollectionAndDataObjectListAndSearchAOImpl:1439 - retrieveObjectStatForPathWithHeuristicPathGuessing()
2019-12-06 16:55:55 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:55 INFO  IRODSFileImpl:262 - setting file name, given path = /seq-dev
2019-12-06 16:55:55 INFO  IRODSFileImpl:263 - detected local separator = /
2019-12-06 16:55:55 INFO  IRODSFileImpl:292 - file name was set as: seq-dev
2019-12-06 16:55:55 INFO  CollectionAndDataObjectListAndSearchAOImpl:160 - created entry for path as: CollectionAndDataObjectListingEntry [parentPath=/, pathOrName=/seq-dev, description=, specialObjectPath=, objectType=COLLECTION, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, dataSize=0, ownerName=rodsBoot, ownerZone=seq-dev, userFilePermission=[], id=10000, specColType=NORMAL]
2019-12-06 16:55:55 INFO  CollectionServiceImpl:869 - mapListingEntryToDataGridCollectionAndDataObject()
2019-12-06 16:55:55 DEBUG CollectionServiceImpl:871 - Mapping a CollectionAndDataObjectListingEntry into a DataGridCollectionAndDataObject
2019-12-06 16:55:55 INFO  BrowseController:897 - currentPath:/seq-dev
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
2019-12-06 16:55:55 INFO  BrowseController:916 - this is not a proxy, so gather permission info
2019-12-06 16:55:55 INFO  CollectionServiceImpl:246 - getPermissionsForPath()
2019-12-06 16:55:55 INFO  IRODSServicesImpl:245 - getCollectionAndDataObjectListAndSearchAO()
2019-12-06 16:55:55 DEBUG IRODSServicesImpl:249 - irodsAccount used:irods://[email protected]:1247
2019-12-06 16:55:55 DEBUG IRODSServicesImpl:250 - authScheme:STANDARD
2019-12-06 16:55:55 INFO  CollectionAndDataObjectListAndSearchAOImpl:1326 - getFullObjectForType for path:/seq-dev
2019-12-06 16:55:55 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:55 INFO  CollectionAndDataObjectListAndSearchAOImpl:1331 - determined effectiveAbsolutePathToBe:/seq-dev
2019-12-06 16:55:55 INFO  CollectionAOImpl:909 - findGivenObjStat()
2019-12-06 16:55:55 INFO  CollectionAOImpl:915 - objStat:ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:55 INFO  CollectionAOImpl:928 - absPath for querying iCAT:/seq-dev
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:COLL_ID
   selectFieldNumericTranslation:500
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_NAME
   selectFieldNumericTranslation:501
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_PARENT_NAME
   selectFieldNumericTranslation:502
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_OWNER_NAME
   selectFieldNumericTranslation:503
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_OWNER_ZONE
   selectFieldNumericTranslation:504
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_MAP_ID
   selectFieldNumericTranslation:505
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_INHERITANCE
   selectFieldNumericTranslation:506
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_COMMENTS
   selectFieldNumericTranslation:507
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_CREATE_TIME
   selectFieldNumericTranslation:508
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_MODIFY_TIME
   selectFieldNumericTranslation:509
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:501
	 selectFieldColumnName:COLL_NAME
	 operator:EQUAL
	 value:'/seq-dev'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:55 INFO  GenQueryProcessor:90 - continuation value: 1
2019-12-06 16:55:55 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:55 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:55 INFO  GenQueryProcessor:108 - auto closing result set
2019-12-06 16:55:55 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:55 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:55 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:55 INFO  CollectionAOHelper:148 - collection built 

2019-12-06 16:55:55 INFO  CollectionAOHelper:149 - Collection [collectionId=10000, collectionName=/seq-dev, objectPath=, collectionParentName=/, collectionOwnerName=rodsBoot, collectionOwnerZone=seq-dev, collectionMapId=0, collectionInheritance=, comments=, info1=, info2=, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, isProxy=false]
2019-12-06 16:55:55 INFO  CollectionAOImpl:1624 - getPermissionForCollection for absPath:/seq-dev
2019-12-06 16:55:55 INFO  CollectionAOImpl:1625 - userName:bh9
2019-12-06 16:55:55 INFO  CollectionAOImpl:1699 - getPermissionForUserName with irodsCollectionAbsolutePath: /seq-dev
2019-12-06 16:55:55 INFO  CollectionAOImpl:1700 -    userName:bh9
2019-12-06 16:55:55 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:COLL_ACCESS_USER_NAME
   selectFieldNumericTranslation:1300
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_ACCESS_USER_ZONE
   selectFieldNumericTranslation:1301
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_ACCESS_USER_ID
   selectFieldNumericTranslation:713
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_ACCESS_TYPE
   selectFieldNumericTranslation:710
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:501
	 selectFieldColumnName:COLL_NAME
	 operator:EQUAL
	 value:'/seq-dev', GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:1300
	 selectFieldColumnName:COLL_ACCESS_USER_NAME
	 operator:EQUAL
	 value:'bh9'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:55 INFO  GenQueryProcessor:114 - response from IRODS call indicates no rows found
2019-12-06 16:55:55 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:55 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:55 INFO  GenQueryProcessor:160 - no results to close, ignore
2019-12-06 16:55:55 INFO  CollectionAOImpl:1858 - no data found for user ACL
2019-12-06 16:55:55 INFO  CollectionAOImpl:1720 - see if there is a permission based on group membership...
2019-12-06 16:55:55 INFO  CollectionAOImpl:1724 - is set to use specific query for group permissions via isUsingSpecQueryForDataObjPermissionsForUserInGroup()
2019-12-06 16:55:55 INFO  CollectionAOImpl:1737 - findPermissionForUserGrantedThroughUserGroup()
2019-12-06 16:55:55 INFO  IRODSFileImpl:262 - setting file name, given path = /seq-dev
2019-12-06 16:55:55 INFO  IRODSFileImpl:263 - detected local separator = /
2019-12-06 16:55:55 INFO  IRODSFileImpl:292 - file name was set as: seq-dev
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:430 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:133 - findSpecificQueryByAlias()
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:145 - alias:listUserACLForCollectionViaGroup
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:146 - zoneHint:
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:372 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:55 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:55 INFO  SpecificQueryAOImpl:590 - closeResultSet()
2019-12-06 16:55:55 WARN  CollectionAOImpl:1773 - no result due to specific query error, return null
2019-12-06 16:55:55 INFO  CollectionAOImpl:1629 - no permission found, return 'none'
2019-12-06 16:55:55 INFO  CollectionServiceImpl:789 - getInheritanceOptionForCollection()
2019-12-06 16:55:55 INFO  CollectionServiceImpl:795 - collPath:/seq-dev
2019-12-06 16:55:55 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:55 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:COLL_INHERITANCE
   selectFieldNumericTranslation:506
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:501
	 selectFieldColumnName:COLL_NAME
	 operator:EQUAL
	 value:'/seq-dev'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:56 INFO  GenQueryProcessor:90 - continuation value: 3
2019-12-06 16:55:56 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:56 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:56 INFO  GenQueryProcessor:108 - auto closing result set
2019-12-06 16:55:56 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:56 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:56 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:56 INFO  PermissionsServiceImpl:290 - resolveMostPermissiveAccessForUser()
2019-12-06 16:55:56 INFO  PermissionsServiceImpl:299 - obtaining user groups for user:bh9
2019-12-06 16:55:56 INFO  UserGroupAOImpl:423 - find user group with user name: bh9
2019-12-06 16:55:56 INFO  IRODSGenQueryExecutorImpl:114 - executeIRODSQueryAndCloseResult()
2019-12-06 16:55:56 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:56 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:USER_GROUP_NAME
   selectFieldNumericTranslation:901
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:USER_GROUP_ID
   selectFieldNumericTranslation:900
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:202
	 selectFieldColumnName:USER_NAME
	 operator:EQUAL
	 value:'bh9'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:56 INFO  GenQueryProcessor:90 - continuation value: 0
2019-12-06 16:55:56 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:56 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 2
2019-12-06 16:55:56 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:56 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:56 INFO  GenQueryProcessor:160 - no results to close, ignore
2019-12-06 16:55:56 INFO  PermissionsServiceImpl:301 - obtaining acls list for object:/seq-dev
2019-12-06 16:55:56 INFO  IRODSServicesImpl:245 - getCollectionAndDataObjectListAndSearchAO()
2019-12-06 16:55:56 DEBUG IRODSServicesImpl:249 - irodsAccount used:irods://[email protected]:1247
2019-12-06 16:55:56 DEBUG IRODSServicesImpl:250 - authScheme:STANDARD
2019-12-06 16:55:56 INFO  CollectionAndDataObjectListAndSearchAOImpl:1326 - getFullObjectForType for path:/seq-dev
2019-12-06 16:55:56 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:56 INFO  CollectionAndDataObjectListAndSearchAOImpl:1331 - determined effectiveAbsolutePathToBe:/seq-dev
2019-12-06 16:55:56 INFO  CollectionAOImpl:909 - findGivenObjStat()
2019-12-06 16:55:56 INFO  CollectionAOImpl:915 - objStat:ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:56 INFO  CollectionAOImpl:928 - absPath for querying iCAT:/seq-dev
2019-12-06 16:55:56 INFO  IRODSGenQueryExecutorImpl:130 - executeIRODSQueryAndCloseResultInZone()
2019-12-06 16:55:56 INFO  IRODSGenQueryExecutorImpl:140 - query: IRODSGenQueryFromBuilder [irodsGenQueryBuilderData=IRODSGenQueryBuilderQueryData [selectFields=[Select field
   selectFieldColumnName:COLL_ID
   selectFieldNumericTranslation:500
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_NAME
   selectFieldNumericTranslation:501
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_PARENT_NAME
   selectFieldNumericTranslation:502
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_OWNER_NAME
   selectFieldNumericTranslation:503
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_OWNER_ZONE
   selectFieldNumericTranslation:504
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_MAP_ID
   selectFieldNumericTranslation:505
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_INHERITANCE
   selectFieldNumericTranslation:506
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_COMMENTS
   selectFieldNumericTranslation:507
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_CREATE_TIME
   selectFieldNumericTranslation:508
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD, Select field
   selectFieldColumnName:COLL_MODIFY_TIME
   selectFieldNumericTranslation:509
   selectFieldType:FIELD
   selectFieldSource:DEFINED_QUERY_FIELD], conditions=[GenQueryBuilderCondition
	 selectFieldSource:DEFINED_QUERY_FIELD
	 selectFieldNumericTranslation:501
	 selectFieldColumnName:COLL_NAME
	 operator:EQUAL
	 value:'/seq-dev'], orderByFields=[], distinct=true, upperCase=false, computeTotalRowCount=false]]
2019-12-06 16:55:56 INFO  GenQueryProcessor:90 - continuation value: 4
2019-12-06 16:55:56 INFO  GenQueryProcessor:100 - total records:0
2019-12-06 16:55:56 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:56 INFO  GenQueryProcessor:108 - auto closing result set
2019-12-06 16:55:56 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:56 INFO  GenQueryProcessor:121 - auto closing result set
2019-12-06 16:55:56 INFO  GenQueryProcessor:154 - getting more results for query
2019-12-06 16:55:56 INFO  CollectionAOHelper:148 - collection built 

2019-12-06 16:55:56 INFO  CollectionAOHelper:149 - Collection [collectionId=10000, collectionName=/seq-dev, objectPath=, collectionParentName=/, collectionOwnerName=rodsBoot, collectionOwnerZone=seq-dev, collectionMapId=0, collectionInheritance=, comments=, info1=, info2=, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, isProxy=false]
2019-12-06 16:55:56 DEBUG PermissionsServiceImpl:390 - Getting permission info for collection /seq-dev
2019-12-06 16:55:56 INFO  CollectionAOImpl:1882 - listPermissionsForCollection: /seq-dev
2019-12-06 16:55:56 INFO  CollectionListingUtils:1163 - ObjStat [absolutePath=/seq-dev, objectPath=, objectType=COLLECTION, dataId=10000, checksum=, ownerName=rodsBoot, ownerZone=seq-dev, objSize=0, createdAt=Tue Mar 11 14:21:48 GMT 2014, modifiedAt=Mon Mar 17 14:44:24 GMT 2014, specColType=NORMAL, collectionPath=, cacheDir=, cacheDirty=false, replNumber=0, standInGeneratedObjStat=false]
2019-12-06 16:55:56 INFO  CollectionAOImpl:1898 - specific query supported looking for ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:133 - findSpecificQueryByAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:145 - alias:ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:146 - zoneHint:seq-dev
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:372 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:590 - closeResultSet()
2019-12-06 16:55:56 INFO  CollectionAOImpl:1909 - querying via specific query
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:430 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:133 - findSpecificQueryByAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:145 - alias:ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:146 - zoneHint:
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:372 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 ERROR PermissionsServiceImpl:305 - jargon exception getting permission listing
org.irods.jargon.core.exception.DataNotFoundException: no result found
	at org.irods.jargon.core.query.AbstractIRODSQueryResultSet.getFirstResult(AbstractIRODSQueryResultSet.java:102)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:162)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:120)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:441)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:417)
	at org.irods.jargon.core.pub.CollectionAOImpl.listPermissionsForCollection(CollectionAOImpl.java:1914)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:391)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:365)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.resolveMostPermissiveAccessForUser(PermissionsServiceImpl.java:302)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:333)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
	at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
	at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:282)
	at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:213)
	at com.sun.proxy.$Proxy50.resolveMostPermissiveAccessForUser(Unknown Source)
	at com.emc.metalnx.controller.BrowseController.getCollBrowserView(BrowseController.java:920)
	at com.emc.metalnx.controller.BrowseController.getSubDirectories(BrowseController.java:240)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:205)
	at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:133)
	at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:97)
	at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:827)
	at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:738)
	at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85)
	at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:967)
	at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:901)
	at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:970)
	at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:872)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:660)
	at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:846)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:741)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330)
	at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118)
	at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:154)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:199)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:50)
	at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192)
	at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160)
	at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347)
	at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:197)
	at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:645)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81)
	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:678)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:609)
	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:810)
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1506)
	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.base/java.lang.Thread.run(Thread.java:834)
2019-12-06 16:55:56 ERROR BrowseController:242 - exception getting coll browser view
com.emc.metalnx.core.domain.exceptions.DataGridException: error getting permission listing
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.resolveMostPermissiveAccessForUser(PermissionsServiceImpl.java:306)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:333)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
	at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
	at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:282)
	at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:213)
	at com.sun.proxy.$Proxy50.resolveMostPermissiveAccessForUser(Unknown Source)
	at com.emc.metalnx.controller.BrowseController.getCollBrowserView(BrowseController.java:920)
	at com.emc.metalnx.controller.BrowseController.getSubDirectories(BrowseController.java:240)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:205)
	at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:133)
	at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:97)
	at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:827)
	at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:738)
	at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:85)
	at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:967)
	at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:901)
	at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:970)
	at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:872)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:660)
	at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:846)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:741)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330)
	at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118)
	at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:154)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:199)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:50)
	at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
	at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
	at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192)
	at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160)
	at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347)
	at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:197)
	at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96)
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:645)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:139)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81)
	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:678)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:343)
	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:609)
	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:810)
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1506)
	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
	at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.irods.jargon.core.exception.DataNotFoundException: no result found
	at org.irods.jargon.core.query.AbstractIRODSQueryResultSet.getFirstResult(AbstractIRODSQueryResultSet.java:102)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:162)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.findSpecificQueryByAlias(SpecificQueryAOImpl.java:120)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:441)
	at org.irods.jargon.core.pub.SpecificQueryAOImpl.executeSpecificQueryUsingAlias(SpecificQueryAOImpl.java:417)
	at org.irods.jargon.core.pub.CollectionAOImpl.listPermissionsForCollection(CollectionAOImpl.java:1914)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:391)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.getFilePermissionListForObject(PermissionsServiceImpl.java:365)
	at com.emc.metalnx.services.irods.PermissionsServiceImpl.resolveMostPermissiveAccessForUser(PermissionsServiceImpl.java:302)
	... 89 more
2019-12-06 16:55:56 INFO  HttpErrorController:59 - show500CustomizedPage()
2019-12-06 16:55:56 ERROR HttpErrorController:60 - 500 - Internal Server Error
2019-12-06 16:55:56 INFO  HttpErrorController:61 - httpRequest:org.apache.catalina.core.ApplicationHttpRequest@3a02e983
2019-12-06 16:55:56 INFO  HttpErrorController:62 - model:{}
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.forward.request_uri
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/metalnx/browse/getSubDirectories/
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.forward.context_path
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/metalnx
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.forward.servlet_path
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/browse/getSubDirectories/
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.forward.mapping
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.apache.catalina.core.ApplicationMapping$MappingImpl@538a2c90
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.context.request.async.WebAsyncManager.WEB_ASYNC_MANAGER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.context.request.async.WebAsyncManager@383c3b59
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.error.status_code
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:500
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.CONTEXT
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:WebApplicationContext for namespace 'mvc-dispatcher-servlet': startup date [Fri Dec 06 16:51:00 GMT 2019]; parent: Root WebApplicationContext
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.resource.ResourceUrlProvider
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.resource.ResourceUrlProvider@4147bdda
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:__spring_security_session_mgmt_filter_applied
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:__spring_security_filterSecurityInterceptor_filterApplied
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.error.message
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.error.servlet_name
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:mvc-dispatcher
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.THEME_SOURCE
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:WebApplicationContext for namespace 'mvc-dispatcher-servlet': startup date [Fri Dec 06 16:51:00 GMT 2019]; parent: Root WebApplicationContext
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.LOCALE_RESOLVER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.i18n.SessionLocaleResolver@6797f68
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.context.request.RequestContextListener.REQUEST_ATTRIBUTES
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.apache.catalina.connector.RequestFacade@2e566a5e
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.bestMatchingPattern
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/httpError/500
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.OUTPUT_FLASH_MAP
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:FlashMap [attributes={}, targetRequestPath=null, targetRequestParams={}]
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.pathWithinHandlerMapping
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/httpError/500
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.FLASH_MAP_MANAGER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.support.SessionFlashMapManager@6128cfad
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.uriTemplateVariables
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:{}
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.error.request_uri
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/metalnx/browse/getSubDirectories/
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.THEME_RESOLVER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.theme.FixedThemeResolver@7881d52e
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:javax.servlet.error.exception
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.util.NestedServletException: Request processing failed; nested exception is com.emc.metalnx.core.domain.exceptions.DataGridException: error getting permission listing
2019-12-06 16:55:56 INFO  HttpErrorController:59 - show500CustomizedPage()
2019-12-06 16:55:56 ERROR HttpErrorController:60 - 500 - Internal Server Error
2019-12-06 16:55:56 INFO  HttpErrorController:61 - httpRequest:SecurityContextHolderAwareRequestWrapper[ org.springframework.security.web.context.HttpSessionSecurityContextRepository$Servlet3SaveToSessionRequestWrapper@6dc3997b]
2019-12-06 16:55:56 INFO  HttpErrorController:62 - model:{}
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.context.request.async.WebAsyncManager.WEB_ASYNC_MANAGER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.context.request.async.WebAsyncManager@5f8723f1
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:__spring_security_scpf_applied
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.CONTEXT
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:WebApplicationContext for namespace 'mvc-dispatcher-servlet': startup date [Fri Dec 06 16:51:00 GMT 2019]; parent: Root WebApplicationContext
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.resource.ResourceUrlProvider
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.resource.ResourceUrlProvider@4147bdda
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:__spring_security_session_mgmt_filter_applied
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:characterEncodingFilter.FILTERED
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.security.web.FilterChainProxy.APPLIED
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:__spring_security_filterSecurityInterceptor_filterApplied
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.THEME_SOURCE
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:WebApplicationContext for namespace 'mvc-dispatcher-servlet': startup date [Fri Dec 06 16:51:00 GMT 2019]; parent: Root WebApplicationContext
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.LOCALE_RESOLVER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.i18n.SessionLocaleResolver@6797f68
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.context.request.RequestContextListener.REQUEST_ATTRIBUTES
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.apache.catalina.connector.RequestFacade@2e566a5e
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:(inner bean)#721f58c1.FILTERED
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:true
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.bestMatchingPattern
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/httpError/500/
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.OUTPUT_FLASH_MAP
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:FlashMap [attributes={}, targetRequestPath=null, targetRequestParams={}]
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.pathWithinHandlerMapping
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:/httpError/500/
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.FLASH_MAP_MANAGER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.support.SessionFlashMapManager@6128cfad
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.HandlerMapping.uriTemplateVariables
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:{}
2019-12-06 16:55:56 INFO  HttpErrorController:70 - attribName:org.springframework.web.servlet.DispatcherServlet.THEME_RESOLVER
2019-12-06 16:55:56 INFO  HttpErrorController:71 - attrib:org.springframework.web.servlet.theme.FixedThemeResolver@7881d52e
2019-12-06 16:55:56 INFO  ConfigServiceImpl:98 - getGlobalConfig()
2019-12-06 16:55:56 DEBUG ConfigServiceImpl:104 - globalConfig:GlobalConfig [ticketsEnabled=false, uploadRulesEnabled=false, handleNoAccessViaProxy=false]
Hibernate: 
    select
        datagridus0_.id as id1_6_,
        datagridus0_.additional_info as addition2_6_,
        datagridus0_.advanced_view as advanced3_6_,
        datagridus0_.user_company as user_com4_6_,
        datagridus0_.data_grid_id as data_gri5_6_,
        datagridus0_.user_department as user_dep6_6_,
        datagridus0_.email as email7_6_,
        datagridus0_.enabled as enabled8_6_,
        datagridus0_.first_name as first_na9_6_,
        datagridus0_.forceFileOverwriting as forceFi10_6_,
        datagridus0_.last_name as last_na11_6_,
        datagridus0_.locale as locale12_6_,
        datagridus0_.metadata_unit_view as metadat13_6_,
        datagridus0_.organizational_role as organiz14_6_,
        datagridus0_.password as passwor15_6_,
        datagridus0_.user_title as user_ti16_6_,
        datagridus0_.userProfile_id as userPro19_6_,
        datagridus0_.user_type as user_ty17_6_,
        datagridus0_.username as usernam18_6_ 
    from
        users datagridus0_ 
    where
        datagridus0_.username=? 
        and additional_info=?
Hibernate: 
    select
        favorites0_.user_id as user_id7_6_0_,
        favorites0_.id as id1_4_0_,
        favorites0_.id as id1_4_1_,
        favorites0_.created_at as created_2_4_1_,
        favorites0_.is_collection as is_colle3_4_1_,
        favorites0_.name as name4_4_1_,
        favorites0_.path as path5_4_1_,
        favorites0_.path_hash as path_has6_4_1_,
        favorites0_.user_id as user_id7_4_1_ 
    from
        user_favorites favorites0_ 
    where
        favorites0_.user_id=?
Hibernate: 
    select
        bookmarks0_.user_id as user_id7_6_0_,
        bookmarks0_.id as id1_3_0_,
        bookmarks0_.id as id1_3_1_,
        bookmarks0_.created_at as created_2_3_1_,
        bookmarks0_.is_collection as is_colle3_3_1_,
        bookmarks0_.is_notified as is_notif4_3_1_,
        bookmarks0_.name as name5_3_1_,
        bookmarks0_.path as path6_3_1_,
        bookmarks0_.user_id as user_id7_3_1_ 
    from
        user_bookmarks bookmarks0_ 
    where
        bookmarks0_.user_id=?
2019-12-06 16:55:56 DEBUG HttpResponseHandlerInterceptor:70 - added user prefs to model as 'dataGridUser':DataGridUser [id=4, dataGridId=22580771, username=bh9, additionalInfo=Sanger1-dev, enabled=true, firstName=, lastName=, locale=en, forceFileOverwriting=false, userType=rodsuser, advancedView=false, bookmarks=[], metadataUnitView=false, bookmarks=[], favorites=[]]

@michael-conway
Copy link
Contributor

I see this as the immediate interest:


2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:145 - alias:ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:146 - zoneHint:

That zone hint is blank and therefore would be looking at the wrong zone when issuing the query. Let me follow that thread through, it may solve the problem. The GenQuery has the zone hint, the specific query does not.

@michael-conway
Copy link
Contributor

rewriting the resolveMostPermissiveAccessForUser method in PermissionsServiceImpl with the following signature..

public void resolveMostPermissiveAccessForUser(String irodsAbsolutePath, String userName, String userZone)
throws DataGridException {

This will simplify, we can drop in some explicit cross-zone hint support as well as a transformation of a user across zones into user#zone format. None of this is in the current, overly elaborate method.

@michael-conway
Copy link
Contributor

michael-conway commented Dec 16, 2019

In the stack trace, it appears to be a missing specific query. It's a bit complicated but It looks here that an alias is found with a zone hint of seq-dev and then appears to miss the zone and not find the alias when executing the query proper. The DataNotFound is caused by executing the named query when the alias is missing. This may be complicated by the blank zoneHint shown in the log below. I'm still sorting this out.

An interesting data point would be to inspect whether the SpecificQuery 'ShowCollAcls' is available on each zone (iadmin lsq can show this). Sorting out where that blank zoneHint comes from and whether I've fixed it now will take some time. The federation stuff gives one a migrane after a while ;-)



2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:133 - findSpecificQueryByAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:145 - alias:ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:146 - zoneHint:seq-dev
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:372 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 INFO  QueryResultProcessingUtils:57 - rows returned from iRODS query: 1
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:590 - closeResultSet()
2019-12-06 16:55:56 INFO  CollectionAOImpl:1909 - querying via specific query
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:430 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:133 - findSpecificQueryByAlias()
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:145 - alias:ShowCollAcls
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:146 - zoneHint:
2019-12-06 16:55:56 INFO  SpecificQueryAOImpl:372 - executeSpecificQueryUsingAlias()
2019-12-06 16:55:56 ERROR PermissionsServiceImpl:305 - jargon exception getting permission listing
org.irods.jargon.core.exception.DataNotFoundException: no result found

@bh9
Copy link
Contributor Author

bh9 commented Jan 2, 2020

The zone which gives the 500 does have ShowCollAcls defined, none of the others do. I can reproduce this building from master

@michael-conway michael-conway added this to the 2.1.0 milestone Jan 23, 2020
@michael-conway
Copy link
Contributor

@bh9 talking with @trel it sounds like this was an underlying iRODS specific query provisioning issue and so I'll close this (I was never able to reproduce). Feel free to reopen if the problem reappears. Thanks for the very good logging!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Development

No branches or pull requests

4 participants