Skip to Content.
Sympa Menu

grouper-users - Re: [grouper-users] gsh -xmlimport failing with "Error doing include/exclude on group"

Subject: Grouper Users - Open Discussion List

List archive

Re: [grouper-users] gsh -xmlimport failing with "Error doing include/exclude on group"


Chronological Thread 
  • From: Dominique Petitpierre <>
  • To: "" <>, Chris Hyzer <>
  • Subject: Re: [grouper-users] gsh -xmlimport failing with "Error doing include/exclude on group"
  • Date: Thu, 14 Mar 2013 16:54:01 +0100
  • Authentication-results: sfpop-ironport02.merit.edu; dkim=neutral (message not signed) header.i=none
  • Organization: University of Geneva

Hello,

On 03/14/2013 05:04 AM, Chris Hyzer wrote:

Can you send me the full stack trace?

Here it is:

grouper import: reading document: /var/tmp/all-grouper-test1.xml, version: 1.6.3
XML file contains 539 742 records
23:49:55: Beginning import: database contains 477 records
23:50:25: completed 1 827 of 539 742 (0%) estimated time done: 02:17
23:50:55: completed 3 912 of 539 742 (1%) estimated time done: 02:07
23:51:25: completed 6 099 of 539 742 (1%) estimated time done: 02:02
23:51:55: completed 8 267 of 539 742 (2%) estimated time done: 02:00
23:52:25: completed 10 366 of 539 742 (2%) estimated time done: 02:00
23:52:55: completed 12 716 of 539 742 (2%) estimated time done: 01:57
23:53:25: completed 14 944 of 539 742 (3%) estimated time done: 01:56
23:53:55: completed 17 289 of 539 742 (3%) estimated time done: 01:54
23:54:25: completed 19 227 of 539 742 (4%) estimated time done: 01:56
23:54:55: completed 20 838 of 539 742 (4%) estimated time done: 01:59
23:55:25: completed 22 563 of 539 742 (4%) estimated time done: 02:01
23:55:55: completed 24 732 of 539 742 (5%) estimated time done: 02:00
23:56:25: completed 26 970 of 539 742 (5%) estimated time done: 02:00
23:56:55: completed 28 794 of 539 742 (5%) estimated time done: 02:01
23:57:25: completed 30 571 of 539 742 (6%) estimated time done: 02:02
23:57:55: completed 32 613 of 539 742 (6%) estimated time done: 02:02
23:58:25: completed 34 717 of 539 742 (6%) estimated time done: 02:02
23:58:55: completed 36 871 of 539 742 (7%) estimated time done: 02:01
23:59:25: completed 39 012 of 539 742 (7%) estimated time done: 02:01
23:59:55: completed 41 177 of 539 742 (8%) estimated time done: 02:01
00:00:25: completed 42 774 of 539 742 (8%) estimated time done: 02:02
00:00:55: completed 44 499 of 539 742 (8%) estimated time done: 02:03
00:01:25: completed 45 499 of 539 742 (8%) estimated time done: 02:06
Wrote record report log to: /var/tmp/grouperImportRecordReport_2013_03_12__23_48_35_249.txt
Exception in thread "main" java.lang.RuntimeException: java.lang.reflect.InvocationTargetException
at edu.internet2.middleware.grouper.app.gsh.GrouperShell.handleSpecialCase(GrouperShell.java:214)
at edu.internet2.middleware.grouper.app.gsh.GrouperShell.main(GrouperShell.java:144)
at edu.internet2.middleware.grouper.app.gsh.GrouperShellWrapper.main(GrouperShellWrapper.java:31)
Caused by: java.lang.reflect.InvocationTargetException
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at edu.internet2.middleware.grouper.app.gsh.GrouperShell.handleSpecialCase(GrouperShell.java:204)
... 2 more
Caused by: java.lang.RuntimeException: Problem reading file: /var/tmp/all-grouper-test1.xml,
Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: true, grouperTransactionType: NONE
at edu.internet2.middleware.grouper.xml.importXml.XmlImportMain$3.callback(XmlImportMain.java:567)
at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)
at edu.internet2.middleware.grouper.xml.importXml.XmlImportMain.processXmlSecondPass(XmlImportMain.java:504)
at edu.internet2.middleware.grouper.xml.importXml.XmlImportMain.processXml(XmlImportMain.java:252)
at edu.internet2.middleware.grouper.xml.importXml.XmlImportMain.processXml(XmlImportMain.java:206)
at edu.internet2.middleware.grouper.xml.importXml.XmlImportGsh.main(XmlImportGsh.java:130)
... 7 more
Caused by: org.dom4j.DocumentException: Error doing include/exclude on group: bd89a126c69447c28dbc3c628d695cc6,
Hook GroupTypeTupleIncludeExcludeHook.groupTypeTuplePostInsert id: ROHXS09B,
Exception in save: edu.internet2.middleware.grouper.GroupTypeTuple, edu.internet2.middleware.grouper.hibernate.ByObject@1293073,
Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: false, grouperTransactionType: READ_WRITE_NEW,
Exception in save: edu.internet2.middleware.grouper.GroupTypeTuple, ByObjectStatic, query: ', cacheable: null, cacheRegion: null, entityName: null, tx type: null Nested exception: Error doing include/exclude on group: bd89a126c69447c28dbc3c628d695cc6,
Hook GroupTypeTupleIncludeExcludeHook.groupTypeTuplePostInsert id: ROHXS09B,
Exception in save: edu.internet2.middleware.grouper.GroupTypeTuple, edu.internet2.middleware.grouper.hibernate.ByObject@1293073,
Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: false, grouperTransactionType: READ_WRITE_NEW,
Exception in save: edu.internet2.middleware.grouper.GroupTypeTuple, ByObjectStatic, query: ', cacheable: null, cacheRegion: null, entityName: null, tx type: null
at org.dom4j.io.SAXReader.read(SAXReader.java:484)
at org.dom4j.io.SAXReader.read(SAXReader.java:365)
at edu.internet2.middleware.grouper.xml.importXml.XmlImportMain$3.callback(XmlImportMain.java:549)
... 12 more



And here is some extracts of an old style xmlexport relevant to the
uuid bd89a126c69447c28dbc3c628d695cc6 showing the group structure (in
a more readily legible way than the new style export :-) ) :

.....................
<!-- &apos;unige.ch:affiliation:unigechpersonaffiliation:secfacses_systemOfRecordAndIncludes&apos; -->
<group extension='secfacses_systemOfRecordAndIncludes'
displayExtension='secfacses system of record and includes'

name='unige.ch:affiliation:unigechpersonaffiliation:secfacses_systemOfRecordAndIncludes'

displayName='UniGE:Affiliation:unigeChPersonAffiliation:secfacses system of record and includes'
id='4cff5f0952964b108dc671c9972658f1'
contextId='4496d0fc555e4eceaf69f4d8f6ff1f8e'
>
<description>Internal utility group for group secfacses which facilitates the group math for the include and exclude lists</description>
<internalAttributes>
<internalAttribute name='parentStem'>unige.ch:affiliation:unigechpersonaffiliation</internalAttribute>
<internalAttribute name='createSubject'>
<subject id='GrouperSystem' type='application' source='g:isa'/>
</internalAttribute>
<internalAttribute name='createTime'>1310662283847</internalAttribute> <!-- Thu Jul 14 18:51:23 MEST 2011 -->
<internalAttribute name='modifySubject'>
<subject id='GrouperSystem' type='application' source='g:isa'/>
</internalAttribute>
<internalAttribute name='modifyTime'>1310662283960</internalAttribute> <!-- Thu Jul 14 18:51:23 MEST 2011 -->
</internalAttributes>

<list field='members' groupType='base'>
<subject identifier='unige.ch:affiliation:unigechpersonaffiliation:secfacses_systemOfRecord' type='group' source='g:gsa' immediate='true' id='bd89a126c69447c28dbc3c628d695cc6'
/>

<subject identifier='unige.ch:affiliation:unigechpersonaffiliation:secfacses_includes' type='group' source='g:gsa' immediate='true' id='a596e5dadba949d8b7288367e98f746f'
/>

<subject ' type='person' source='people-test.unige.ch' immediate='false'
/>
.....................
<!-- &apos;unige.ch:affiliation:unigechpersonaffiliation:secfacses_systemOfRecord&apos; -->
<group extension='secfacses_systemOfRecord'
displayExtension='secfacses_systemOfRecord'

name='unige.ch:affiliation:unigechpersonaffiliation:secfacses_systemOfRecord'

displayName='UniGE:Affiliation:unigeChPersonAffiliation:secfacses_systemOfRecord'
id='bd89a126c69447c28dbc3c628d695cc6'
contextId='6c3f7b968a9a4ec883e710bcb3783369'
>
<description>secfacses_systemOfRecord auto-created by grouperLoader</description>
<internalAttributes>
<internalAttribute name='parentStem'>unige.ch:affiliation:unigechpersonaffiliation</internalAttribute>
<internalAttribute name='createSubject'>
<subject id='GrouperSystem' type='application' source='g:isa'/>
</internalAttribute>
<internalAttribute name='createTime'>1310662283272</internalAttribute> <!-- Thu Jul 14 18:51:23 MEST 2011 -->
<internalAttribute name='modifySubject'>
<subject id='GrouperSystem' type='application' source='g:isa'/>
</internalAttribute>
<internalAttribute name='modifyTime'>1310662283404</internalAttribute> <!-- Thu Jul 14 18:51:23 MEST 2011 -->
</internalAttributes>

<groupTypes>
<groupType name='addIncludeExclude'>
</groupType>

</groupTypes>
<list field='members' groupType='base'>
<subject ' type='person' source='people-test.unige.ch' immediate='true'
/>
.....................



In case it matters, the group secfacses_systemOfRecord is created and provisioned by a
grouperLoader job (SQL_GROUP_LIST).


Thanks for your help!

--
Mr Dominique Petitpierre, user=Dominique.Petitpierre domain=unige.ch
IT Division, University of Geneva, Switzerland



Archive powered by MHonArc 2.6.16.

Top of Page