org.kuali.rice.kew.api.WorkflowRuntimeException: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml

Kuali JIRA | Erik Meade | 4 years ago
  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.kuali.rice.kew.api.WorkflowRuntimeException: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml
  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.kuali.rice.kew.api.WorkflowRuntimeException: java.lang.RuntimeException: Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml
  3. 0

    TooManyOpenFiles during a stress test

    GitHub | 2 years ago | sammefford
    java.lang.RuntimeException: Failed to read file: /space/builder/linux64/b8_0/qa/testdata/4.0/non-geo-bucket/doc-592.xml
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    TooManyOpenFiles during a stress test

    GitHub | 2 years ago | sammefford
    java.lang.RuntimeException: Failed to read file: /space/builder/linux64/b8_0/qa/testdata/4.0/non-geo-bucket/doc-2273.xml
  6. 0

    TooManyOpenFiles during a stress test

    GitHub | 2 years ago | sammefford
    java.lang.RuntimeException: Failed to read file: /space/builder/linux64/b8_0/qa/testdata/4.0/non-geo-bucket/doc-2327.xml

    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. java.lang.RuntimeException

      Failed to ingest xml doc: UnitTestTemp8620775585280899372.xml

      at org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream()
    2. org.kuali.rice
      XmlExporterTestCase.testExportActionConfig
      1. org.kuali.rice.kew.batch.KEWXmlDataLoader.loadXmlStream(KEWXmlDataLoader.java:150)
      2. org.kuali.rice.kew.test.KEWTestCase.loadXmlStream(KEWTestCase.java:210)
      3. org.kuali.rice.kew.xml.export.DocumentTypeXmlExporterTest.assertExport(DocumentTypeXmlExporterTest.java:59)
      4. org.kuali.rice.kew.xml.export.XmlExporterTestCase.testExportActionConfig(XmlExporterTestCase.java:27)
      4 frames
    3. 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
    4. 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
    5. 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
    6. 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
    7. 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
    8. 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
    9. 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