org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'.

Kuali JIRA | Erik Meade | 4 years ago
tip
Your exception is missing from the Samebug knowledge base.
Here are the best solutions we found on the Internet.
Click on the to mark the helpful solution and get rewards for you help.
  1. 0

    DocumentTypeXmlExporterTest and RuleXmlExporterTest see stack trace way down in the output (not the Failed to ingest xml doc at the top. {code} Failed org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.testExportActionConfig Failing for the past 12 builds (Since #294 ) Took 26 sec. add description Error Message java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml Stacktrace org.kuali.rice.kew.api.WorkflowRuntimeException: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:212) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) Caused by: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:150) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) ... 34 more Standard Output Alternate Log4j config resource does not exist! /var/lib/jenkins/workspace/rice-2.1-integration-test-mysql/log4j.properties Using default log4j configuration: classpath:rice-testharness-default-log4j.properties 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - # Starting test DocumentTypeXmlExporterTest.testExportActionConfig... 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - # [Memory] max: 1069416448, total: 224092160, free: 37865440 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:26:45,662 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Clearing tables for schema RICE_21_IT@IP-10-118-83-152.EC2.INTERNAL 2012-12-07 17:26:45,675 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Clearing contents using statement ->DELETE FROM kren_chnl_prodcr_t<- Clearing contents using statement ->DELETE FROM kren_chnl_subscrp_t<- Clearing contents using statement ->DELETE FROM kren_chnl_t<- Clearing contents using statement ->DELETE FROM kren_cntnt_typ_t<- Clearing contents using statement ->DELETE FROM kren_msg_deliv_t<- Clearing contents using statement ->DELETE FROM kren_msg_t<- Clearing contents using statement ->DELETE FROM kren_ntfctn_msg_deliv_t<- Clearing contents using statement ->DELETE FROM kren_ntfctn_t<- Clearing contents using statement ->DELETE FROM kren_prio_t<- Clearing contents using statement ->DELETE FROM kren_prodcr_t<- Clearing contents using statement ->DELETE FROM kren_recip_deliv_t<- Clearing contents using statement ->DELETE FROM kren_recip_list_t<- Clearing contents using statement ->DELETE FROM kren_recip_prefs_t<- Clearing contents using statement ->DELETE FROM kren_recip_t<- Clearing contents using statement ->DELETE FROM kren_rvwer_t<- Clearing contents using statement ->DELETE FROM kren_sndr_t<- Clearing contents using statement ->DELETE FROM krew_actn_itm_t<- Clearing contents using statement ->DELETE FROM krew_actn_rqst_t<- Clearing contents using statement ->DELETE FROM krew_actn_tkn_t<- Clearing contents using statement ->DELETE FROM krew_app_doc_stat_tran_t<- Clearing contents using statement ->DELETE FROM krew_att_t<- Clearing contents using statement ->DELETE FROM krew_attr_defn_t<- Clearing contents using statement ->DELETE FROM krew_dlgn_rsp_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_cntnt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_dt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_flt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_long_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_t<- Clearing contents using statement ->DELETE FROM krew_doc_lnk_t<- Clearing contents using statement ->DELETE FROM krew_doc_nte_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_app_doc_stat_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_app_stat_cat_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_attr_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_plcy_reln_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_proc_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_t<- Clearing contents using statement ->DELETE FROM krew_edl_assctn_t<- Clearing contents using statement ->DELETE FROM krew_edl_def_t<- Clearing contents using statement ->DELETE FROM krew_edl_dmp_t<- Clearing contents using statement ->DELETE FROM krew_edl_fld_dmp_t<- Clearing contents using statement ->DELETE FROM krew_init_rte_node_instn_t<- Clearing contents using statement ->DELETE FROM krew_out_box_itm_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_attr_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_dlgt_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_mbr_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_proto_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_st_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_cfg_parm_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_lnk_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_st_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_lnk_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_t<- Clearing contents using statement ->DELETE FROM krew_rule_attr_t<- Clearing contents using statement ->DELETE FROM krew_rule_expr_t<- Clearing contents using statement ->DELETE FROM krew_rule_ext_t<- Clearing contents using statement ->DELETE FROM krew_rule_ext_val_t<- Clearing contents using statement ->DELETE FROM krew_rule_rsp_t<- Clearing contents using statement ->DELETE FROM krew_rule_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_attr_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_optn_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_t<- Clearing contents using statement ->DELETE FROM krew_typ_attr_t<- Clearing contents using statement ->DELETE FROM krew_typ_t<- Clearing contents using statement ->DELETE FROM krew_usr_optn_t<- Clearing contents using statement ->DELETE FROM krms_actn_attr_t<- Clearing contents using statement ->DELETE FROM krms_actn_t<- Clearing contents using statement ->DELETE FROM krms_agenda_attr_t<- Clearing contents using statement ->DELETE FROM krms_agenda_itm_t<- Clearing contents using statement ->DELETE FROM krms_agenda_t<- Clearing contents using statement ->DELETE FROM krms_attr_defn_t<- Clearing contents using statement ->DELETE FROM krms_cmpnd_prop_props_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_attr_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_actn_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_agenda_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_func_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_rule_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_term_spec_t<- Clearing contents using statement ->DELETE FROM krms_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_func_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_func_parm_t<- Clearing contents using statement ->DELETE FROM krms_func_t<- Clearing contents using statement ->DELETE FROM krms_prop_parm_t<- Clearing contents using statement ->DELETE FROM krms_prop_t<- Clearing contents using statement ->DELETE FROM krms_rule_attr_t<- Clearing contents using statement ->DELETE FROM krms_rule_t<- Clearing contents using statement ->DELETE FROM krms_term_parm_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_attr_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_input_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_parm_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_t<- Clearing contents using statement ->DELETE FROM krms_term_spec_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_term_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_t<- Clearing contents using statement ->DELETE FROM krms_typ_attr_t<- Clearing contents using statement ->DELETE FROM krms_typ_t<- Clearing contents using statement ->DELETE FROM krsb_bam_parm_t<- Clearing contents using statement ->DELETE FROM krsb_bam_t<- Clearing contents using statement ->DELETE FROM krsb_msg_pyld_t<- Clearing contents using statement ->DELETE FROM krsb_msg_que_t<- Clearing contents using statement ->DELETE FROM krsb_qrtz_blob_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_calendars<- Clearing contents using statement ->DELETE FROM krsb_qrtz_cron_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_fired_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_job_details<- Clearing contents using statement ->DELETE FROM krsb_qrtz_job_listeners<- Clearing contents using statement ->DELETE FROM krsb_qrtz_locks<- Clearing contents using statement ->DELETE FROM krsb_qrtz_paused_trigger_grps<- Clearing contents using statement ->DELETE FROM krsb_qrtz_scheduler_state<- Clearing contents using statement ->DELETE FROM krsb_qrtz_simple_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_trigger_listeners<- Clearing contents using statement ->DELETE FROM krsb_qrtz_triggers<- Clearing contents using statement ->DELETE FROM krsb_svc_def_t<- Clearing contents using statement ->DELETE FROM krsb_svc_dscrptr_t<- 2012-12-07 17:26:46,105 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Tables successfully cleared for schema RICE_21_IT@IP-10-118-83-152.EC2.INTERNAL 2012-12-07 17:26:46,122 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Time to clear tables: 0:00:00.480 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - ################################ 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - #classpath:org/kuali/rice/kew/test/DefaultPerTestData.sql 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - ################################ 2012-12-07 17:26:46,407 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4750 name 'KualiDocument' (current = true) 2012-12-07 17:26:46,434 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4751 name 'RiceDocument' (current = true) 2012-12-07 17:26:46,460 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4750 name 'KualiDocument' (current = true) 2012-12-07 17:26:46,530 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4752 name 'DocumentTypeDocument' (current = true) 2012-12-07 17:26:46,534 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4751 name 'RiceDocument' (current = true) 2012-12-07 17:26:46,714 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,744 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4754 name 'TestDocumentType2' (current = true) 2012-12-07 17:26:46,793 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,821 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4755 name 'TestDocumentType3' (current = true) 2012-12-07 17:26:46,871 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,959 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: TestRule1 2012-12-07 17:26:46,975 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4309 2012-12-07 17:26:46,975 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:47,649 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4756 name 'SuperUserActionInvalidPostProcessor' (current = true) 2012-12-07 17:26:47,706 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:47,775 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4758 name 'CreatedDocumentInactive' (current = true) 2012-12-07 17:26:47,781 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:47,911 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4759 name 'BlanketApprovePolicyTest' (current = true) 2012-12-07 17:26:47,918 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:48,171 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4760 name 'BlanketApproveSequentialTest' (current = true) 2012-12-07 17:26:48,177 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:49,014 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4761 name 'BlanketApproveParallelTest' (current = true) 2012-12-07 17:26:49,021 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:49,102 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4762 name 'SuperUserApproveActionRequestFyiTest' (current = true) 2012-12-07 17:26:49,228 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4763 name 'SuperUserApproveActionRequestApproveTest' (current = true) 2012-12-07 17:26:49,650 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:49,703 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4765 name 'NotificationTestChild' (current = true) 2012-12-07 17:26:49,851 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:49,889 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4766 name 'RecallTest' (current = true) 2012-12-07 17:26:50,045 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,094 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4767 name 'RecallTestRestricted' (current = true) 2012-12-07 17:26:50,099 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4766 name 'RecallTest' (current = true) 2012-12-07 17:26:50,186 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4768 name 'RecallTestOnlyAdhoc' (current = true) 2012-12-07 17:26:50,236 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4769 name 'RecallWithPrevNotifyTest' (current = true) 2012-12-07 17:26:50,385 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,438 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4770 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:26:50,714 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,757 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4771 name 'RecallWithoutPendingNotifyTest' (current = true) 2012-12-07 17:26:50,766 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4770 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:26:50,821 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4772 name 'RecallWithThirdPartyNotifyTest' (current = true) 2012-12-07 17:26:50,969 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,020 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4773 name 'SUDisapproveWithNotificationTest' (current = true) 2012-12-07 17:26:51,168 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,216 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4774 name 'SUApproveFinalNodeDisallowed' (current = true) 2012-12-07 17:26:51,368 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,676 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4775 name 'BlanketApproveMandatoryNodeTest' (current = true) 2012-12-07 17:26:51,683 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:51,840 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4776 name 'SaveActionEventTest' (current = true) 2012-12-07 17:26:52,004 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4777 name 'SaveActionEventTestNonInitiator' (current = true) 2012-12-07 17:26:52,168 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4778 name 'TakeWorkgroupAuthorityDoc' (current = true) 2012-12-07 17:26:52,489 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4779 name 'MoveSequentialTest' (current = true) 2012-12-07 17:26:52,496 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:52,859 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4780 name 'MoveInProcessTest' (current = true) 2012-12-07 17:26:52,866 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:53,036 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4781 name 'AdhocRouteTest' (current = true) 2012-12-07 17:26:53,139 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4782 name 'AdhocRouteTest-PriorityParallel' (current = true) 2012-12-07 17:26:53,303 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4783 name 'PreApprovalTest' (current = true) 2012-12-07 17:26:53,941 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4784 name 'VariablesTest' (current = true) 2012-12-07 17:26:54,177 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4785 name 'InitiatorRoleApprovalTest' (current = true) 2012-12-07 17:26:54,532 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4786 name 'BlanketApproveThroughRoleAndWorkgroupTest' (current = true) 2012-12-07 17:26:54,846 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4787 name 'SUApproveDocument' (current = true) 2012-12-07 17:26:54,924 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4788 name 'SUApproveDocumentNotifications' (current = true) 2012-12-07 17:26:54,985 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4787 name 'SUApproveDocument' (current = true) 2012-12-07 17:26:55,288 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4789 name 'RouteLogActionTest' (current = true) 2012-12-07 17:26:55,410 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4790 name 'RouteLogActionTestTrivial' (current = true) 2012-12-07 17:26:55,479 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4791 name 'AdHocNoNodeTest' (current = true) 2012-12-07 17:26:56,017 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4792 name 'ReturnToPreviousWithSplitTest' (current = true) 2012-12-07 17:26:56,024 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:56,105 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,110 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,136 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,153 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,156 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,160 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,169 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,178 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,187 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,195 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,204 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,230 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,230 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,241 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,258 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,258 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,259 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,278 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,281 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,323 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,343 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,345 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,348 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,350 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,372 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,374 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,377 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,379 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,401 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,403 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,406 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,455 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,457 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,479 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,482 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,484 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,759 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template and SuperUserActionInvalidPostProcessor Rule 1 2012-12-07 17:26:56,774 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4326 2012-12-07 17:26:56,774 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,775 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template and SuperUserActionInvalidPostProcessor Rule 2 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4327 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 1 2012-12-07 17:26:56,805 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4328 2012-12-07 17:26:56,805 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,806 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 3 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4329 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 2 2012-12-07 17:26:56,836 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4330 2012-12-07 17:26:56,836 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,837 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template Rule 1 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4331 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument3Template Rule 1 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4332 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument4Template Rule 1 2012-12-07 17:26:56,883 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4333 2012-12-07 17:26:56,883 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,884 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentFinalTemplate Rule 1 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4334 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Ack1Template Rule 1 2012-12-07 17:26:56,914 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4335 2012-12-07 17:26:56,914 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,915 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Ack2Template Rule 1 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4336 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify First Rule 1 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Saved rule: 4337 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Time to make current 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Left Branch Rule 1 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4338 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Right Branch Rule 1 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4339 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Final Rule 1 2012-12-07 17:26:57,019 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 33ms, Saved rule: 4340 2012-12-07 17:26:57,020 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 34ms, Time to make current 2012-12-07 17:26:57,020 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate SaveAction Rule 1 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4341 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate SaveAction Rule 2 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4342 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: TakeWorkgroupAuthorityDoc route to TestWorkgroup 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4343 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required AdhocRouteTest 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4344 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest1 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4345 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest2 2012-12-07 17:26:57,112 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4346 2012-12-07 17:26:57,112 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,113 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest3 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4347 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest4 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4348 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest1 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4349 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest2 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4350 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest3 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4351 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest4 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4352 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest1 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4353 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest2 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 26ms, Saved rule: 4354 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 26ms, Time to make current 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest3 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4355 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest4 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4356 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Rule 1 2012-12-07 17:26:57,293 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4357 2012-12-07 17:26:57,293 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,294 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Rule 2 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4358 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Workgroup Rule 1 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4359 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Workgroup Rule 2 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4360 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: SUApproveDocument rule 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4361 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: SUApproveDocument rule WorkflowDocument3Template 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4362 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT WorkflowDocumentTemplate Rule 1 2012-12-07 17:26:57,387 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4363 2012-12-07 17:26:57,387 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,393 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT WorkflowDocumentTemplate Rule 2 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 23ms, Saved rule: 4364 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 23ms, Time to make current 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT Ack1Template Rule 1 2012-12-07 17:26:57,426 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4365 2012-12-07 17:26:57,426 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,492 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:57,499 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:57,519 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify left Branch Prim Del 2012-12-07 17:26:57,544 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Saved rule: 4366 2012-12-07 17:26:57,550 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 31ms, Time to make current 2012-12-07 17:26:57,550 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Left Branch Sec Del 2012-12-07 17:26:57,566 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4368 2012-12-07 17:26:57,572 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 22ms, Time to make current 2012-12-07 17:26:57,572 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Right Delegation 2012-12-07 17:26:57,587 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4370 2012-12-07 17:26:57,594 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 22ms, Time to make current 2012-12-07 17:26:57,822 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:57,830 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4750 name 'KualiDocument' (current = false) 2012-12-07 17:26:57,915 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4793 name 'KualiDocument' (current = true) 2012-12-07 17:26:57,932 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4751 name 'RiceDocument' (parent = 4793, current = true) 2012-12-07 17:26:58,038 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for KualiDocument 2012-12-07 17:26:58,186 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:58,194 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4751 name 'RiceDocument' (current = false) 2012-12-07 17:26:58,204 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4794 name 'RiceDocument' (current = true) 2012-12-07 17:26:58,243 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4752 name 'DocumentTypeDocument' (parent = 4794, current = true) 2012-12-07 17:26:58,288 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4793 name 'KualiDocument' (current = true) 2012-12-07 17:26:58,381 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RiceDocument 2012-12-07 17:26:58,621 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:58,628 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4752 name 'DocumentTypeDocument' (current = false) 2012-12-07 17:26:58,685 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4795 name 'DocumentTypeDocument' (current = true) 2012-12-07 17:26:58,701 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4794 name 'RiceDocument' (current = true) 2012-12-07 17:26:58,798 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for DocumentTypeDocument 2012-12-07 17:26:59,121 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:59,138 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4753 name 'TestDocumentType' (current = false) 2012-12-07 17:26:59,267 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:26:59,295 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4754 name 'TestDocumentType2' (parent = 4796, current = true) 2012-12-07 17:26:59,297 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4755 name 'TestDocumentType3' (parent = 4796, current = true) 2012-12-07 17:26:59,404 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType 2012-12-07 17:26:59,662 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:59,669 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4754 name 'TestDocumentType2' (current = false) 2012-12-07 17:26:59,679 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4797 name 'TestDocumentType2' (current = true) 2012-12-07 17:26:59,723 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:26:59,815 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType2 2012-12-07 17:27:00,152 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:00,160 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4755 name 'TestDocumentType3' (current = false) 2012-12-07 17:27:00,170 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4798 name 'TestDocumentType3' (current = true) 2012-12-07 17:27:00,199 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:27:00,298 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType3 2012-12-07 17:27:00,788 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:00,797 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4756 name 'SuperUserActionInvalidPostProcessor' (current = false) 2012-12-07 17:27:01,010 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4799 name 'SuperUserActionInvalidPostProcessor' (current = true) 2012-12-07 17:27:01,129 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserActionInvalidPostProcessor 2012-12-07 17:27:01,548 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:01,555 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4757 name 'BlanketApproveTest' (current = false) 2012-12-07 17:27:01,577 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:01,738 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4758 name 'CreatedDocumentInactive' (parent = 4800, current = true) 2012-12-07 17:27:01,766 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4759 name 'BlanketApprovePolicyTest' (parent = 4800, current = true) 2012-12-07 17:27:01,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4760 name 'BlanketApproveSequentialTest' (parent = 4800, current = true) 2012-12-07 17:27:01,812 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4761 name 'BlanketApproveParallelTest' (parent = 4800, current = true) 2012-12-07 17:27:01,836 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4775 name 'BlanketApproveMandatoryNodeTest' (parent = 4800, current = true) 2012-12-07 17:27:01,860 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4779 name 'MoveSequentialTest' (parent = 4800, current = true) 2012-12-07 17:27:01,915 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4780 name 'MoveInProcessTest' (parent = 4800, current = true) 2012-12-07 17:27:01,938 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4792 name 'ReturnToPreviousWithSplitTest' (parent = 4800, current = true) 2012-12-07 17:27:02,038 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveTest 2012-12-07 17:27:02,194 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:02,202 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4758 name 'CreatedDocumentInactive' (current = false) 2012-12-07 17:27:02,258 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4801 name 'CreatedDocumentInactive' (current = true) 2012-12-07 17:27:02,279 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:02,378 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for CreatedDocumentInactive 2012-12-07 17:27:02,651 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:02,659 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4759 name 'BlanketApprovePolicyTest' (current = false) 2012-12-07 17:27:02,758 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4802 name 'BlanketApprovePolicyTest' (current = true) 2012-12-07 17:27:02,769 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:02,866 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApprovePolicyTest 2012-12-07 17:27:03,292 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:03,300 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4760 name 'BlanketApproveSequentialTest' (current = false) 2012-12-07 17:27:03,476 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4803 name 'BlanketApproveSequentialTest' (current = true) 2012-12-07 17:27:03,487 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:03,653 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveSequentialTest 2012-12-07 17:27:04,481 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:04,489 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4761 name 'BlanketApproveParallelTest' (current = false) 2012-12-07 17:27:05,126 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4804 name 'BlanketApproveParallelTest' (current = true) 2012-12-07 17:27:05,136 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:05,239 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveParallelTest 2012-12-07 17:27:06,060 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:06,068 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4762 name 'SuperUserApproveActionRequestFyiTest' (current = false) 2012-12-07 17:27:06,125 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4805 name 'SuperUserApproveActionRequestFyiTest' (current = true) 2012-12-07 17:27:06,234 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserApproveActionRequestFyiTest 2012-12-07 17:27:06,425 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:06,433 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4763 name 'SuperUserApproveActionRequestApproveTest' (current = false) 2012-12-07 17:27:06,581 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4806 name 'SuperUserApproveActionRequestApproveTest' (current = true) 2012-12-07 17:27:06,681 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserApproveActionRequestApproveTest 2012-12-07 17:27:07,088 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:07,095 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4764 name 'NotificationTest' (current = false) 2012-12-07 17:27:07,374 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:07,468 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4765 name 'NotificationTestChild' (parent = 4807, current = true) 2012-12-07 17:27:07,470 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4766 name 'RecallTest' (parent = 4807, current = true) 2012-12-07 17:27:07,474 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4769 name 'RecallWithPrevNotifyTest' (parent = 4807, current = true) 2012-12-07 17:27:07,480 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4770 name 'IntermediateRecallDocType' (parent = 4807, current = true) 2012-12-07 17:27:07,487 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4772 name 'RecallWithThirdPartyNotifyTest' (parent = 4807, current = true) 2012-12-07 17:27:07,491 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4773 name 'SUDisapproveWithNotificationTest' (parent = 4807, current = true) 2012-12-07 17:27:07,495 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4774 name 'SUApproveFinalNodeDisallowed' (parent = 4807, current = true) 2012-12-07 17:27:07,601 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for NotificationTest 2012-12-07 17:27:08,079 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:08,087 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4765 name 'NotificationTestChild' (current = false) 2012-12-07 17:27:08,103 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4808 name 'NotificationTestChild' (current = true) 2012-12-07 17:27:08,144 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:08,245 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for NotificationTestChild 2012-12-07 17:27:08,771 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:08,778 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4766 name 'RecallTest' (current = false) 2012-12-07 17:27:08,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4809 name 'RecallTest' (current = true) 2012-12-07 17:27:08,807 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4767 name 'RecallTestRestricted' (parent = 4809, current = true) 2012-12-07 17:27:08,832 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:08,928 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTest 2012-12-07 17:27:09,475 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:09,482 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4767 name 'RecallTestRestricted' (current = false) 2012-12-07 17:27:09,499 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4810 name 'RecallTestRestricted' (current = true) 2012-12-07 17:27:09,515 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4809 name 'RecallTest' (current = true) 2012-12-07 17:27:09,608 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTestRestricted 2012-12-07 17:27:10,016 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:10,024 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4768 name 'RecallTestOnlyAdhoc' (current = false) 2012-12-07 17:27:10,080 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4811 name 'RecallTestOnlyAdhoc' (current = true) 2012-12-07 17:27:10,187 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTestOnlyAdhoc 2012-12-07 17:27:10,353 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:10,360 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4769 name 'RecallWithPrevNotifyTest' (current = false) 2012-12-07 17:27:10,376 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4812 name 'RecallWithPrevNotifyTest' (current = true) 2012-12-07 17:27:10,404 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:10,496 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallWithPrevNotifyTest 2012-12-07 17:27:11,036 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:11,044 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4770 name 'IntermediateRecallDocType' (current = false) 2012-12-07 17:27:11,066 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4813 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:27:11,082 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4771 name 'RecallWithoutPendingNotifyTest' (parent = 4813, current = true) 2012-12-07 17:27:11,107 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:11,206 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for IntermediateRecallDocType 2012-12-07 17:27:11,751 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:11,758 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4771 name 'RecallWithoutPendingNotifyTest' (current = false) 2012-12-07 17:27:11,769 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4814 name 'RecallWithoutPendingNotifyTest' (current = true) 2012-12-07 17:27:11,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4813 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:27:11,882 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallWithoutPendingNotifyTest 2012-12-07 17:27:12,207 [main] ERROR org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl - Error parsing xml doc UnitTestTemp8620775585280899372.xml:UnitTestTemp8620775585280899372.xml org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'. at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source) at org.apache.xerces.util.ErrorHandlerWrapper.error(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator$XSIErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.reportSchemaError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.elementLocallyValidType(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.processElementContent(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.handleEndElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.endElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanEndElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:124) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:143) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:164) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:225) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:205) at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:147) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) 2012-12-07 17:27:12,208 [main] ERROR org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl - Error validating doc: UnitTestTemp8620775585280899372.xml:UnitTestTemp8620775585280899372.xml org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'. at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source) at org.apache.xerces.util.ErrorHandlerWrapper.error(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator$XSIErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.reportSchemaError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.elementLocallyValidType(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.processElementContent(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.handleEndElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.endElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanEndElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:124) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:143) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:164) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:225) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:205) at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:147) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.RiceTestCase$4 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.lifecycles.PerTestDataLoaderLifecycle 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.kew.test.KEWTestCase$ClearCacheLifecycle 2012-12-07 17:27:12,210 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/PrincipalType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{ExternalIdentifierType} 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{AffiliationType} 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleMemberType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityPrivacyPreferencesType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityNamePrincipalNameType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmploymentStatus} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/GroupMemberType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/DelegateTypeType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EntityType} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{AddressType} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/PermissionType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/KimTypeType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{NameType} 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmploymentType} 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/ResponsibilityType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityDefaultType 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{PhoneType} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{CitizenshipStatus} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/DelegateMemberType 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/TemplateType{Responsibility} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleResponsibilityType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/GroupType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/TemplateType{Permission} 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmailType} 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleMembershipType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RoutePathType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleDelegationType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleResponsibilityType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleTemplateType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/DocumentTypeType 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/ExtensionDefinitionType 2012-12-07 17:27:12,217 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.ClearDatabaseLifecycle 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.test.RiceTestCase - # ...finished test DocumentTypeXmlExporterTest.testExportActionConfig 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # [Memory] max: 1069416448, total: 224092160, free: 39582240 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to load test-specific test data: 0 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to run test-specific setup: 0 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to start all Lifecycles: 1360 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## {code}

    Kuali JIRA | 4 years ago | Erik Meade
    org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'.
  2. 0

    DocumentTypeXmlExporterTest and RuleXmlExporterTest see stack trace way down in the output (not the Failed to ingest xml doc at the top. {code} Failed org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.testExportActionConfig Failing for the past 12 builds (Since #294 ) Took 26 sec. add description Error Message java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml Stacktrace org.kuali.rice.kew.api.WorkflowRuntimeException: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:212) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) Caused by: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:150) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) ... 34 more Standard Output Alternate Log4j config resource does not exist! /var/lib/jenkins/workspace/rice-2.1-integration-test-mysql/log4j.properties Using default log4j configuration: classpath:rice-testharness-default-log4j.properties 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - # Starting test DocumentTypeXmlExporterTest.testExportActionConfig... 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - # [Memory] max: 1069416448, total: 224092160, free: 37865440 2012-12-07 17:26:45,641 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:26:45,662 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Clearing tables for schema RICE_21_IT@IP-10-118-83-152.EC2.INTERNAL 2012-12-07 17:26:45,675 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Clearing contents using statement ->DELETE FROM kren_chnl_prodcr_t<- Clearing contents using statement ->DELETE FROM kren_chnl_subscrp_t<- Clearing contents using statement ->DELETE FROM kren_chnl_t<- Clearing contents using statement ->DELETE FROM kren_cntnt_typ_t<- Clearing contents using statement ->DELETE FROM kren_msg_deliv_t<- Clearing contents using statement ->DELETE FROM kren_msg_t<- Clearing contents using statement ->DELETE FROM kren_ntfctn_msg_deliv_t<- Clearing contents using statement ->DELETE FROM kren_ntfctn_t<- Clearing contents using statement ->DELETE FROM kren_prio_t<- Clearing contents using statement ->DELETE FROM kren_prodcr_t<- Clearing contents using statement ->DELETE FROM kren_recip_deliv_t<- Clearing contents using statement ->DELETE FROM kren_recip_list_t<- Clearing contents using statement ->DELETE FROM kren_recip_prefs_t<- Clearing contents using statement ->DELETE FROM kren_recip_t<- Clearing contents using statement ->DELETE FROM kren_rvwer_t<- Clearing contents using statement ->DELETE FROM kren_sndr_t<- Clearing contents using statement ->DELETE FROM krew_actn_itm_t<- Clearing contents using statement ->DELETE FROM krew_actn_rqst_t<- Clearing contents using statement ->DELETE FROM krew_actn_tkn_t<- Clearing contents using statement ->DELETE FROM krew_app_doc_stat_tran_t<- Clearing contents using statement ->DELETE FROM krew_att_t<- Clearing contents using statement ->DELETE FROM krew_attr_defn_t<- Clearing contents using statement ->DELETE FROM krew_dlgn_rsp_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_cntnt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_dt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_flt_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_long_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_ext_t<- Clearing contents using statement ->DELETE FROM krew_doc_hdr_t<- Clearing contents using statement ->DELETE FROM krew_doc_lnk_t<- Clearing contents using statement ->DELETE FROM krew_doc_nte_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_app_doc_stat_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_app_stat_cat_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_attr_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_plcy_reln_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_proc_t<- Clearing contents using statement ->DELETE FROM krew_doc_typ_t<- Clearing contents using statement ->DELETE FROM krew_edl_assctn_t<- Clearing contents using statement ->DELETE FROM krew_edl_def_t<- Clearing contents using statement ->DELETE FROM krew_edl_dmp_t<- Clearing contents using statement ->DELETE FROM krew_edl_fld_dmp_t<- Clearing contents using statement ->DELETE FROM krew_init_rte_node_instn_t<- Clearing contents using statement ->DELETE FROM krew_out_box_itm_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_attr_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_dlgt_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_mbr_t<- Clearing contents using statement ->DELETE FROM krew_ppl_flw_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_proto_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_st_t<- Clearing contents using statement ->DELETE FROM krew_rte_brch_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_cfg_parm_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_lnk_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_st_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_instn_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_lnk_t<- Clearing contents using statement ->DELETE FROM krew_rte_node_t<- Clearing contents using statement ->DELETE FROM krew_rule_attr_t<- Clearing contents using statement ->DELETE FROM krew_rule_expr_t<- Clearing contents using statement ->DELETE FROM krew_rule_ext_t<- Clearing contents using statement ->DELETE FROM krew_rule_ext_val_t<- Clearing contents using statement ->DELETE FROM krew_rule_rsp_t<- Clearing contents using statement ->DELETE FROM krew_rule_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_attr_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_optn_t<- Clearing contents using statement ->DELETE FROM krew_rule_tmpl_t<- Clearing contents using statement ->DELETE FROM krew_typ_attr_t<- Clearing contents using statement ->DELETE FROM krew_typ_t<- Clearing contents using statement ->DELETE FROM krew_usr_optn_t<- Clearing contents using statement ->DELETE FROM krms_actn_attr_t<- Clearing contents using statement ->DELETE FROM krms_actn_t<- Clearing contents using statement ->DELETE FROM krms_agenda_attr_t<- Clearing contents using statement ->DELETE FROM krms_agenda_itm_t<- Clearing contents using statement ->DELETE FROM krms_agenda_t<- Clearing contents using statement ->DELETE FROM krms_attr_defn_t<- Clearing contents using statement ->DELETE FROM krms_cmpnd_prop_props_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_attr_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_actn_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_agenda_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_func_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_rule_typ_t<- Clearing contents using statement ->DELETE FROM krms_cntxt_vld_term_spec_t<- Clearing contents using statement ->DELETE FROM krms_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_func_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_func_parm_t<- Clearing contents using statement ->DELETE FROM krms_func_t<- Clearing contents using statement ->DELETE FROM krms_prop_parm_t<- Clearing contents using statement ->DELETE FROM krms_prop_t<- Clearing contents using statement ->DELETE FROM krms_rule_attr_t<- Clearing contents using statement ->DELETE FROM krms_rule_t<- Clearing contents using statement ->DELETE FROM krms_term_parm_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_attr_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_input_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_parm_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_rslvr_t<- Clearing contents using statement ->DELETE FROM krms_term_spec_ctgry_t<- Clearing contents using statement ->DELETE FROM krms_term_spec_t<- Clearing contents using statement ->DELETE FROM krms_term_t<- Clearing contents using statement ->DELETE FROM krms_typ_attr_t<- Clearing contents using statement ->DELETE FROM krms_typ_t<- Clearing contents using statement ->DELETE FROM krsb_bam_parm_t<- Clearing contents using statement ->DELETE FROM krsb_bam_t<- Clearing contents using statement ->DELETE FROM krsb_msg_pyld_t<- Clearing contents using statement ->DELETE FROM krsb_msg_que_t<- Clearing contents using statement ->DELETE FROM krsb_qrtz_blob_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_calendars<- Clearing contents using statement ->DELETE FROM krsb_qrtz_cron_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_fired_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_job_details<- Clearing contents using statement ->DELETE FROM krsb_qrtz_job_listeners<- Clearing contents using statement ->DELETE FROM krsb_qrtz_locks<- Clearing contents using statement ->DELETE FROM krsb_qrtz_paused_trigger_grps<- Clearing contents using statement ->DELETE FROM krsb_qrtz_scheduler_state<- Clearing contents using statement ->DELETE FROM krsb_qrtz_simple_triggers<- Clearing contents using statement ->DELETE FROM krsb_qrtz_trigger_listeners<- Clearing contents using statement ->DELETE FROM krsb_qrtz_triggers<- Clearing contents using statement ->DELETE FROM krsb_svc_def_t<- Clearing contents using statement ->DELETE FROM krsb_svc_dscrptr_t<- 2012-12-07 17:26:46,105 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Tables successfully cleared for schema RICE_21_IT@IP-10-118-83-152.EC2.INTERNAL 2012-12-07 17:26:46,122 [main] INFO org.kuali.rice.test.ClearDatabaseLifecycle - Time to clear tables: 0:00:00.480 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - ################################ 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - #classpath:org/kuali/rice/kew/test/DefaultPerTestData.sql 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - # 2012-12-07 17:26:46,136 [main] INFO org.kuali.rice.test.SQLDataLoader - ################################ 2012-12-07 17:26:46,407 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4750 name 'KualiDocument' (current = true) 2012-12-07 17:26:46,434 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4751 name 'RiceDocument' (current = true) 2012-12-07 17:26:46,460 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4750 name 'KualiDocument' (current = true) 2012-12-07 17:26:46,530 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4752 name 'DocumentTypeDocument' (current = true) 2012-12-07 17:26:46,534 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4751 name 'RiceDocument' (current = true) 2012-12-07 17:26:46,714 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,744 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4754 name 'TestDocumentType2' (current = true) 2012-12-07 17:26:46,793 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,821 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4755 name 'TestDocumentType3' (current = true) 2012-12-07 17:26:46,871 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4753 name 'TestDocumentType' (current = true) 2012-12-07 17:26:46,959 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: TestRule1 2012-12-07 17:26:46,975 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4309 2012-12-07 17:26:46,975 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:47,649 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4756 name 'SuperUserActionInvalidPostProcessor' (current = true) 2012-12-07 17:26:47,706 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:47,775 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4758 name 'CreatedDocumentInactive' (current = true) 2012-12-07 17:26:47,781 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:47,911 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4759 name 'BlanketApprovePolicyTest' (current = true) 2012-12-07 17:26:47,918 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:48,171 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4760 name 'BlanketApproveSequentialTest' (current = true) 2012-12-07 17:26:48,177 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:49,014 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4761 name 'BlanketApproveParallelTest' (current = true) 2012-12-07 17:26:49,021 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:49,102 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4762 name 'SuperUserApproveActionRequestFyiTest' (current = true) 2012-12-07 17:26:49,228 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4763 name 'SuperUserApproveActionRequestApproveTest' (current = true) 2012-12-07 17:26:49,650 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:49,703 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4765 name 'NotificationTestChild' (current = true) 2012-12-07 17:26:49,851 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:49,889 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4766 name 'RecallTest' (current = true) 2012-12-07 17:26:50,045 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,094 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4767 name 'RecallTestRestricted' (current = true) 2012-12-07 17:26:50,099 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4766 name 'RecallTest' (current = true) 2012-12-07 17:26:50,186 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4768 name 'RecallTestOnlyAdhoc' (current = true) 2012-12-07 17:26:50,236 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4769 name 'RecallWithPrevNotifyTest' (current = true) 2012-12-07 17:26:50,385 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,438 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4770 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:26:50,714 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:50,757 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4771 name 'RecallWithoutPendingNotifyTest' (current = true) 2012-12-07 17:26:50,766 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4770 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:26:50,821 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4772 name 'RecallWithThirdPartyNotifyTest' (current = true) 2012-12-07 17:26:50,969 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,020 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4773 name 'SUDisapproveWithNotificationTest' (current = true) 2012-12-07 17:26:51,168 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,216 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4774 name 'SUApproveFinalNodeDisallowed' (current = true) 2012-12-07 17:26:51,368 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4764 name 'NotificationTest' (current = true) 2012-12-07 17:26:51,676 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4775 name 'BlanketApproveMandatoryNodeTest' (current = true) 2012-12-07 17:26:51,683 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:51,840 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4776 name 'SaveActionEventTest' (current = true) 2012-12-07 17:26:52,004 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4777 name 'SaveActionEventTestNonInitiator' (current = true) 2012-12-07 17:26:52,168 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4778 name 'TakeWorkgroupAuthorityDoc' (current = true) 2012-12-07 17:26:52,489 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4779 name 'MoveSequentialTest' (current = true) 2012-12-07 17:26:52,496 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:52,859 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4780 name 'MoveInProcessTest' (current = true) 2012-12-07 17:26:52,866 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:53,036 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4781 name 'AdhocRouteTest' (current = true) 2012-12-07 17:26:53,139 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4782 name 'AdhocRouteTest-PriorityParallel' (current = true) 2012-12-07 17:26:53,303 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4783 name 'PreApprovalTest' (current = true) 2012-12-07 17:26:53,941 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4784 name 'VariablesTest' (current = true) 2012-12-07 17:26:54,177 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4785 name 'InitiatorRoleApprovalTest' (current = true) 2012-12-07 17:26:54,532 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4786 name 'BlanketApproveThroughRoleAndWorkgroupTest' (current = true) 2012-12-07 17:26:54,846 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4787 name 'SUApproveDocument' (current = true) 2012-12-07 17:26:54,924 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4788 name 'SUApproveDocumentNotifications' (current = true) 2012-12-07 17:26:54,985 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4787 name 'SUApproveDocument' (current = true) 2012-12-07 17:26:55,288 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4789 name 'RouteLogActionTest' (current = true) 2012-12-07 17:26:55,410 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4790 name 'RouteLogActionTestTrivial' (current = true) 2012-12-07 17:26:55,479 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4791 name 'AdHocNoNodeTest' (current = true) 2012-12-07 17:26:56,017 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4792 name 'ReturnToPreviousWithSplitTest' (current = true) 2012-12-07 17:26:56,024 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4757 name 'BlanketApproveTest' (current = true) 2012-12-07 17:26:56,105 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,110 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,136 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,153 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,156 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,160 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,169 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,178 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,187 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,195 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,204 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,230 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,230 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,241 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,258 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,258 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,259 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,278 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,281 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,323 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,343 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,345 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,348 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,350 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,372 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,374 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,377 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,379 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,401 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,403 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,406 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,455 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,457 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,479 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,482 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,484 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:56,759 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template and SuperUserActionInvalidPostProcessor Rule 1 2012-12-07 17:26:56,774 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4326 2012-12-07 17:26:56,774 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,775 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template and SuperUserActionInvalidPostProcessor Rule 2 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4327 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,790 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 1 2012-12-07 17:26:56,805 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4328 2012-12-07 17:26:56,805 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,806 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 3 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4329 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,821 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate Rule 2 2012-12-07 17:26:56,836 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4330 2012-12-07 17:26:56,836 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,837 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument2Template Rule 1 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4331 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,852 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument3Template Rule 1 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4332 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:56,868 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocument4Template Rule 1 2012-12-07 17:26:56,883 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4333 2012-12-07 17:26:56,883 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,884 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentFinalTemplate Rule 1 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4334 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,899 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Ack1Template Rule 1 2012-12-07 17:26:56,914 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4335 2012-12-07 17:26:56,914 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,915 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Ack2Template Rule 1 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4336 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,930 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify First Rule 1 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Saved rule: 4337 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Time to make current 2012-12-07 17:26:56,955 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Left Branch Rule 1 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4338 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:56,970 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Right Branch Rule 1 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4339 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:56,986 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Final Rule 1 2012-12-07 17:26:57,019 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 33ms, Saved rule: 4340 2012-12-07 17:26:57,020 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 34ms, Time to make current 2012-12-07 17:26:57,020 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate SaveAction Rule 1 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4341 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,035 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: WorkflowDocumentTemplate SaveAction Rule 2 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4342 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,051 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: TakeWorkgroupAuthorityDoc route to TestWorkgroup 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4343 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,066 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required AdhocRouteTest 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4344 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,082 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest1 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4345 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,097 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest2 2012-12-07 17:26:57,112 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4346 2012-12-07 17:26:57,112 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,113 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest3 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4347 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,128 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required PreApprovalTest4 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4348 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,144 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest1 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4349 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,159 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest2 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4350 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,175 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest3 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4351 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,190 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required VariablesTest4 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4352 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,206 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest1 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4353 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,221 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest2 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 26ms, Saved rule: 4354 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 26ms, Time to make current 2012-12-07 17:26:57,247 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest3 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4355 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,262 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: required InitiatorRoleApprovalTest4 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4356 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,278 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Rule 1 2012-12-07 17:26:57,293 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4357 2012-12-07 17:26:57,293 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,294 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Rule 2 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4358 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,309 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Workgroup Rule 1 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4359 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,325 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: BA Notification Workgroup Rule 2 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4360 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,340 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: SUApproveDocument rule 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4361 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,356 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: SUApproveDocument rule WorkflowDocument3Template 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4362 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Time to make current 2012-12-07 17:26:57,371 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT WorkflowDocumentTemplate Rule 1 2012-12-07 17:26:57,387 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4363 2012-12-07 17:26:57,387 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,393 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT WorkflowDocumentTemplate Rule 2 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 23ms, Saved rule: 4364 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 23ms, Time to make current 2012-12-07 17:26:57,410 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: RLAT Ack1Template Rule 1 2012-12-07 17:26:57,426 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4365 2012-12-07 17:26:57,426 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Time to make current 2012-12-07 17:26:57,492 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:57,499 [main] WARN org.kuali.rice.kew.xml.CommonXmlParser - Rule XML is using deprecated element 'user', please use 'principalName' instead. 2012-12-07 17:26:57,519 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify left Branch Prim Del 2012-12-07 17:26:57,544 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 25ms, Saved rule: 4366 2012-12-07 17:26:57,550 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 31ms, Time to make current 2012-12-07 17:26:57,550 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Left Branch Sec Del 2012-12-07 17:26:57,566 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 16ms, Saved rule: 4368 2012-12-07 17:26:57,572 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 22ms, Time to make current 2012-12-07 17:26:57,572 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 0ms, Preparing rule: Notify Right Delegation 2012-12-07 17:26:57,587 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 15ms, Saved rule: 4370 2012-12-07 17:26:57,594 [main] INFO org.kuali.rice.kew.util.PerformanceLogger - Time: 22ms, Time to make current 2012-12-07 17:26:57,822 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:57,830 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4750 name 'KualiDocument' (current = false) 2012-12-07 17:26:57,915 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4793 name 'KualiDocument' (current = true) 2012-12-07 17:26:57,932 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4751 name 'RiceDocument' (parent = 4793, current = true) 2012-12-07 17:26:58,038 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for KualiDocument 2012-12-07 17:26:58,186 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:58,194 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4751 name 'RiceDocument' (current = false) 2012-12-07 17:26:58,204 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4794 name 'RiceDocument' (current = true) 2012-12-07 17:26:58,243 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4752 name 'DocumentTypeDocument' (parent = 4794, current = true) 2012-12-07 17:26:58,288 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4793 name 'KualiDocument' (current = true) 2012-12-07 17:26:58,381 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RiceDocument 2012-12-07 17:26:58,621 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:58,628 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4752 name 'DocumentTypeDocument' (current = false) 2012-12-07 17:26:58,685 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4795 name 'DocumentTypeDocument' (current = true) 2012-12-07 17:26:58,701 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4794 name 'RiceDocument' (current = true) 2012-12-07 17:26:58,798 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for DocumentTypeDocument 2012-12-07 17:26:59,121 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:59,138 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4753 name 'TestDocumentType' (current = false) 2012-12-07 17:26:59,267 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:26:59,295 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4754 name 'TestDocumentType2' (parent = 4796, current = true) 2012-12-07 17:26:59,297 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4755 name 'TestDocumentType3' (parent = 4796, current = true) 2012-12-07 17:26:59,404 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType 2012-12-07 17:26:59,662 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:26:59,669 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4754 name 'TestDocumentType2' (current = false) 2012-12-07 17:26:59,679 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4797 name 'TestDocumentType2' (current = true) 2012-12-07 17:26:59,723 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:26:59,815 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType2 2012-12-07 17:27:00,152 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:00,160 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4755 name 'TestDocumentType3' (current = false) 2012-12-07 17:27:00,170 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4798 name 'TestDocumentType3' (current = true) 2012-12-07 17:27:00,199 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4796 name 'TestDocumentType' (current = true) 2012-12-07 17:27:00,298 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for TestDocumentType3 2012-12-07 17:27:00,788 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:00,797 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4756 name 'SuperUserActionInvalidPostProcessor' (current = false) 2012-12-07 17:27:01,010 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4799 name 'SuperUserActionInvalidPostProcessor' (current = true) 2012-12-07 17:27:01,129 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserActionInvalidPostProcessor 2012-12-07 17:27:01,548 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:01,555 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4757 name 'BlanketApproveTest' (current = false) 2012-12-07 17:27:01,577 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:01,738 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4758 name 'CreatedDocumentInactive' (parent = 4800, current = true) 2012-12-07 17:27:01,766 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4759 name 'BlanketApprovePolicyTest' (parent = 4800, current = true) 2012-12-07 17:27:01,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4760 name 'BlanketApproveSequentialTest' (parent = 4800, current = true) 2012-12-07 17:27:01,812 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4761 name 'BlanketApproveParallelTest' (parent = 4800, current = true) 2012-12-07 17:27:01,836 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4775 name 'BlanketApproveMandatoryNodeTest' (parent = 4800, current = true) 2012-12-07 17:27:01,860 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4779 name 'MoveSequentialTest' (parent = 4800, current = true) 2012-12-07 17:27:01,915 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4780 name 'MoveInProcessTest' (parent = 4800, current = true) 2012-12-07 17:27:01,938 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4792 name 'ReturnToPreviousWithSplitTest' (parent = 4800, current = true) 2012-12-07 17:27:02,038 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveTest 2012-12-07 17:27:02,194 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:02,202 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4758 name 'CreatedDocumentInactive' (current = false) 2012-12-07 17:27:02,258 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4801 name 'CreatedDocumentInactive' (current = true) 2012-12-07 17:27:02,279 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:02,378 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for CreatedDocumentInactive 2012-12-07 17:27:02,651 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:02,659 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4759 name 'BlanketApprovePolicyTest' (current = false) 2012-12-07 17:27:02,758 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4802 name 'BlanketApprovePolicyTest' (current = true) 2012-12-07 17:27:02,769 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:02,866 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApprovePolicyTest 2012-12-07 17:27:03,292 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:03,300 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4760 name 'BlanketApproveSequentialTest' (current = false) 2012-12-07 17:27:03,476 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4803 name 'BlanketApproveSequentialTest' (current = true) 2012-12-07 17:27:03,487 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:03,653 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveSequentialTest 2012-12-07 17:27:04,481 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:04,489 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4761 name 'BlanketApproveParallelTest' (current = false) 2012-12-07 17:27:05,126 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4804 name 'BlanketApproveParallelTest' (current = true) 2012-12-07 17:27:05,136 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4800 name 'BlanketApproveTest' (current = true) 2012-12-07 17:27:05,239 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for BlanketApproveParallelTest 2012-12-07 17:27:06,060 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:06,068 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4762 name 'SuperUserApproveActionRequestFyiTest' (current = false) 2012-12-07 17:27:06,125 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4805 name 'SuperUserApproveActionRequestFyiTest' (current = true) 2012-12-07 17:27:06,234 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserApproveActionRequestFyiTest 2012-12-07 17:27:06,425 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:06,433 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4763 name 'SuperUserApproveActionRequestApproveTest' (current = false) 2012-12-07 17:27:06,581 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4806 name 'SuperUserApproveActionRequestApproveTest' (current = true) 2012-12-07 17:27:06,681 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for SuperUserApproveActionRequestApproveTest 2012-12-07 17:27:07,088 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:07,095 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4764 name 'NotificationTest' (current = false) 2012-12-07 17:27:07,374 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:07,468 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4765 name 'NotificationTestChild' (parent = 4807, current = true) 2012-12-07 17:27:07,470 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4766 name 'RecallTest' (parent = 4807, current = true) 2012-12-07 17:27:07,474 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4769 name 'RecallWithPrevNotifyTest' (parent = 4807, current = true) 2012-12-07 17:27:07,480 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4770 name 'IntermediateRecallDocType' (parent = 4807, current = true) 2012-12-07 17:27:07,487 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4772 name 'RecallWithThirdPartyNotifyTest' (parent = 4807, current = true) 2012-12-07 17:27:07,491 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4773 name 'SUDisapproveWithNotificationTest' (parent = 4807, current = true) 2012-12-07 17:27:07,495 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4774 name 'SUApproveFinalNodeDisallowed' (parent = 4807, current = true) 2012-12-07 17:27:07,601 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for NotificationTest 2012-12-07 17:27:08,079 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:08,087 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4765 name 'NotificationTestChild' (current = false) 2012-12-07 17:27:08,103 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4808 name 'NotificationTestChild' (current = true) 2012-12-07 17:27:08,144 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:08,245 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for NotificationTestChild 2012-12-07 17:27:08,771 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:08,778 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4766 name 'RecallTest' (current = false) 2012-12-07 17:27:08,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4809 name 'RecallTest' (current = true) 2012-12-07 17:27:08,807 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4767 name 'RecallTestRestricted' (parent = 4809, current = true) 2012-12-07 17:27:08,832 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:08,928 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTest 2012-12-07 17:27:09,475 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:09,482 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4767 name 'RecallTestRestricted' (current = false) 2012-12-07 17:27:09,499 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4810 name 'RecallTestRestricted' (current = true) 2012-12-07 17:27:09,515 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4809 name 'RecallTest' (current = true) 2012-12-07 17:27:09,608 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTestRestricted 2012-12-07 17:27:10,016 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:10,024 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4768 name 'RecallTestOnlyAdhoc' (current = false) 2012-12-07 17:27:10,080 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4811 name 'RecallTestOnlyAdhoc' (current = true) 2012-12-07 17:27:10,187 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallTestOnlyAdhoc 2012-12-07 17:27:10,353 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:10,360 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4769 name 'RecallWithPrevNotifyTest' (current = false) 2012-12-07 17:27:10,376 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4812 name 'RecallWithPrevNotifyTest' (current = true) 2012-12-07 17:27:10,404 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:10,496 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallWithPrevNotifyTest 2012-12-07 17:27:11,036 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:11,044 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4770 name 'IntermediateRecallDocType' (current = false) 2012-12-07 17:27:11,066 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4813 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:27:11,082 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved child document type Id 4771 name 'RecallWithoutPendingNotifyTest' (parent = 4813, current = true) 2012-12-07 17:27:11,107 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4807 name 'NotificationTest' (current = true) 2012-12-07 17:27:11,206 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for IntermediateRecallDocType 2012-12-07 17:27:11,751 [main] INFO org.kuali.rice.kns.util.MaintenanceUtils - starting checkForLockingDocument (by Maintainable) 2012-12-07 17:27:11,758 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saving old document type Id 4771 name 'RecallWithoutPendingNotifyTest' (current = false) 2012-12-07 17:27:11,769 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved current document type Id 4814 name 'RecallWithoutPendingNotifyTest' (current = true) 2012-12-07 17:27:11,789 [main] INFO org.kuali.rice.kew.doctype.service.impl.DocumentTypeServiceImpl - Saved parent document type Id 4813 name 'IntermediateRecallDocType' (current = true) 2012-12-07 17:27:11,882 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - checking export for RecallWithoutPendingNotifyTest 2012-12-07 17:27:12,207 [main] ERROR org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl - Error parsing xml doc UnitTestTemp8620775585280899372.xml:UnitTestTemp8620775585280899372.xml org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'. at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source) at org.apache.xerces.util.ErrorHandlerWrapper.error(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator$XSIErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.reportSchemaError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.elementLocallyValidType(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.processElementContent(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.handleEndElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.endElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanEndElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:124) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:143) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:164) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:225) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:205) at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:147) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) 2012-12-07 17:27:12,208 [main] ERROR org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl - Error validating doc: UnitTestTemp8620775585280899372.xml:UnitTestTemp8620775585280899372.xml org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'. at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source) at org.apache.xerces.util.ErrorHandlerWrapper.error(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator$XSIErrorReporter.reportError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.reportSchemaError(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.elementLocallyValidType(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.processElementContent(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.handleEndElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.endElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanEndElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:124) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:143) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:164) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:225) at org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:205) at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:147) at org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210) at org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59) at org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46) at org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) 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 org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.RiceTestCase$4 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.lifecycles.PerTestDataLoaderLifecycle 2012-12-07 17:27:12,210 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.kew.test.KEWTestCase$ClearCacheLifecycle 2012-12-07 17:27:12,210 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/PrincipalType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{ExternalIdentifierType} 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{AffiliationType} 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleMemberType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityPrivacyPreferencesType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityNamePrincipalNameType 2012-12-07 17:27:12,211 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmploymentStatus} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/GroupMemberType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/DelegateTypeType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EntityType} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{AddressType} 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/PermissionType 2012-12-07 17:27:12,212 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/KimTypeType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{NameType} 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmploymentType} 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/ResponsibilityType 2012-12-07 17:27:12,213 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/EntityDefaultType 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{PhoneType} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{CitizenshipStatus} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/DelegateMemberType 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/TemplateType{Responsibility} 2012-12-07 17:27:12,214 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleResponsibilityType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/GroupType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/TemplateType{Permission} 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleType 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/CodedAttributeType{EmailType} 2012-12-07 17:27:12,215 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kim/v2_0/RoleMembershipType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RoutePathType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleDelegationType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleResponsibilityType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/RuleTemplateType 2012-12-07 17:27:12,216 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/DocumentTypeType 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest - Clearing cache: http://rice.kuali.org/kew/v2_0/ExtensionDefinitionType 2012-12-07 17:27:12,217 [main] WARN org.kuali.rice.test.RiceTestCase - Attempting to stop a lifecycle class org.kuali.rice.test.ClearDatabaseLifecycle 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## 2012-12-07 17:27:12,217 [main] INFO org.kuali.rice.test.RiceTestCase - # ...finished test DocumentTypeXmlExporterTest.testExportActionConfig 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # [Memory] max: 1069416448, total: 224092160, free: 39582240 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to load test-specific test data: 0 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to run test-specific setup: 0 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - # Time to start all Lifecycles: 1360 2012-12-07 17:27:12,218 [main] INFO org.kuali.rice.test.RiceTestCase - ############################################################## {code}

    Kuali JIRA | 4 years ago | Erik Meade
    org.xml.sax.SAXParseException: cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'.
  3. 0

    Xmlread parsing issues

    Stack Overflow | 3 years ago | matlab-oh-no
    org.xml.sax.SAXParseException: The declaration for the entity "ContentType" must end with '&gt;'.
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    SAXParseException: El destino de la instrucción de proceso correspondiente "[xX][mM][lL]" no está permitido.

    GitHub | 4 years ago | elmer-garduno
    org.xml.sax.SAXParseException: El destino de la instrucción de proceso correspondiente &quot;[xX][mM][lL]&quot; no está permitido.
  6. 0

    OFBiz - User - Help! DCC throw out org.xml.sax.SAXParseException: Character reference "&#55357" is an invalid XML character.

    nabble.com | 9 months ago
    org.xml.sax.SAXParseException: Character reference "&#55357" is an invalid XML character.

  1. SteVio1989 1 times, last 1 week ago
33 unregistered visitors
Not finding the right solution?
Take a tour to get the most out of Samebug.

Tired of useless tips?

Automated exception search integrated into your IDE

Root Cause Analysis

  1. org.xml.sax.SAXParseException

    cvc-maxLength-valid: Value '<config> <recipients xmlns:dt="ns:workflow/DocumentType" xmlns:r="ns:workflow/Rule" xsi:schemaLocation="ns:workflow/Rule resource:Rule"> <r:principalName>quickstart</r:principalName> <r:user>quickstart</r:user> <role name="Technical Administrator" namespace="KR-SYS"/> </recipients> </config>' with length = '292' is not facet-valid with respect to maxLength '255' for type 'ShortStringType'.

    at org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException()
  2. Xerces2-j
    DocumentBuilderImpl.parse
    1. org.apache.xerces.util.ErrorHandlerWrapper.createSAXParseException(Unknown Source)
    2. org.apache.xerces.util.ErrorHandlerWrapper.error(Unknown Source)
    3. org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
    4. org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
    5. org.apache.xerces.impl.XMLErrorReporter.reportError(Unknown Source)
    6. org.apache.xerces.impl.xs.XMLSchemaValidator$XSIErrorReporter.reportError(Unknown Source)
    7. org.apache.xerces.impl.xs.XMLSchemaValidator.reportSchemaError(Unknown Source)
    8. org.apache.xerces.impl.xs.XMLSchemaValidator.elementLocallyValidType(Unknown Source)
    9. org.apache.xerces.impl.xs.XMLSchemaValidator.processElementContent(Unknown Source)
    10. org.apache.xerces.impl.xs.XMLSchemaValidator.handleEndElement(Unknown Source)
    11. org.apache.xerces.impl.xs.XMLSchemaValidator.endElement(Unknown Source)
    12. org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanEndElement(Unknown Source)
    13. org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source)
    14. org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source)
    15. org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
    16. org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source)
    17. org.apache.xerces.parsers.XMLParser.parse(Unknown Source)
    18. org.apache.xerces.parsers.DOMParser.parse(Unknown Source)
    19. org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source)
    19 frames
  3. Java RT
    DocumentBuilder.parse
    1. javax.xml.parsers.DocumentBuilder.parse(DocumentBuilder.java:124)
    1 frame
  4. org.kuali.rice
    XmlExporterTestCase.testExportActionConfig
    1. org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:143)
    2. org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.validate(XmlIngesterServiceImpl.java:164)
    3. org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:225)
    4. org.kuali.rice.core.impl.impex.xml.XmlIngesterServiceImpl.ingest(XmlIngesterServiceImpl.java:205)
    5. org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:147)
    6. org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210)
    7. org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59)
    8. org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27)
    8 frames
  5. Java RT
    Method.invoke
    1. sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    2. sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    3. sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    4. java.lang.reflect.Method.invoke(Method.java:597)
    4 frames
  6. JUnit
    BlockJUnit4ClassRunner.runChild
    1. org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45)
    2. org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
    3. org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42)
    4. org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
    5. org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
    6. org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30)
    7. org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)
    8. org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68)
    8 frames
  7. org.kuali.rice
    RiceUnitTestClassRunner.runChild
    1. org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:46)
    2. org.kuali.rice.test.runners.RiceUnitTestClassRunner.runChild(RiceUnitTestClassRunner.java:34)
    2 frames
  8. JUnit
    ParentRunner.run
    1. org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
    2. org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
    3. org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
    4. org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
    5. org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
    6. org.junit.runners.ParentRunner.run(ParentRunner.java:300)
    6 frames
  9. JUnit4 Provider
    JUnit4Provider.invoke
    1. org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252)
    2. org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141)
    3. org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112)
    3 frames
  10. Java RT
    Method.invoke
    1. sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    2. sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
    3. sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
    4. java.lang.reflect.Method.invoke(Method.java:597)
    4 frames
  11. SureFire
    ForkedBooter.main
    1. org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)
    2. org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)
    3. org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)
    4. org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115)
    5. org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)
    5 frames