问题描述：NC6.3预算编制计算慢：全表计算耗时3分30秒：全表计算5张表，其中11月预算编制参照表：60行，计算列4列。单表计算3分20秒：单表计算11月预算编制参照表：60行，计算列4列。企业绩效管理->全面预算->预算编制：预算编制以下是全表计算5张表，其中11月预算编制参照表：60行，计算列4列。以下是单表计算11月预算编制:参照表60行计算列4列问题分析及解决：从NMC中可以看到：在计算的过程中，<当前事件>getconnection出现过30次左右。1400120383425-8934<服务>ncServer<线程名称>http-bio-/172.20.99.77-80-exec-7<业务动作>预算编制-规则计算<线程状态>RUNNABLE<当前事件>getconnection<远程调用方法>nc.itf.tb.task.ITaskRuleExecuteAdapter.executeRuleInWorkBook<耗时>754064949013093181467750152079200083090505<客户端地址>172.20.62.31:57410<操作员>1001A110000000000XJT<调试模式>是从NC日志anonymous中：主要集中在取利润中心的iUFO函数上.方法nc.itf.resa.iufointerface.IRaUfoCallFuncService.callFunc存在多次调用。方法nc.itf.tb.rule.IBusiRuleQuery.queryRuleMemberByPks存在多次调用。nc.itf.tb.rule.IBusiRuleQuery.queryRuleMemberByPks([Ljava.lang.String;){target=http://172.20.99.77:80/ServiceDispatcherServlet/default}存在多次调用。经过和开发沟通,出现多次远程调用的原因是:在预算编制计算时,每个iUFO函数都远程调用一次造成的。因此，减少远程调用，将函数分组，批量计算。计算时间从3份30秒减少到15秒左右。下面是详细日志：$$callid=$$thread=[http-bio-localhost/127.0.0.1-80-exec-3]$$host=$$userid=$$ts=2014-05-1515:29:56$$remotecall=[anonymous]$$debuglevel=DEBUG$$msg=beginmethod:nc.itf.resa.iufointerface.IRaUfoCallFuncService.callFunc([Ljava.lang.String;,[Ljava.lang.String;),ontarget:nc.gejb.resa.cmt.Ra_Local@195fec3withargs:[[Ljava.lang.String;@e944ac,[Ljava.lang.String;@9b7ab8]$$callid=$$thread=[http-bio-localhost/127.0.0.1-80-exec-3]$$host=$$userid=$$ts=2014-05-1515:29:56$$remotecall=[anonymous]$$debuglevel=DEBUG$$msg=errorendmethod:nc.itf.resa.iufointerface.IRaUfoCallFuncService.callFunc([Ljava.lang.String;,[Ljava.lang.String;),ontarget:nc.gejb.resa.cmt.Ra_Local@195fec3,spendtime:313主要集中在取利润中心的iUFO函数上方法nc.itf.resa.iufointerface.IRaUfoCallFuncService.callFunc存在多次调用。方法nc.itf.tb.rule.IBusiRuleQuery.queryRuleMemberByPks;nc.itf.tb.rule.IBusiRuleQuery.queryRuleMemberByPks([Ljava.lang.String;){target=http://172.20.99.77:80/ServiceDispatcherServlet/default}存在多次调用。14:32:06[dbcache-update-thread]DEBUG-beginnc.bs.dbcache.intf.IOutDateVersion.getOutDateVersions(java.lang.String){target=http://172.20.99.77:80/ServiceDispatcherServlet/default}14:32:06[dbcache-update-thread]WARN-TraceRemoteCalljava.lang.Exceptionatnc.bs.framework.rmi.RemoteInvocationHandler.sendRequest(RemoteInvocationHandler.java:235)atnc.bs.framework.rmi.RemoteInvocationHandler.sendRequest(RemoteInvocationHandler.java:114)atnc.bs.framework.rmi.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:84)atcom.sun.proxy.$Proxy27.getOutDateVersions(UnknownSource)atnc.ui.dbcache.version.ClientCacheVersionBS.getOutDateVersions(ClientCacheVersionBS.java:121)atnc.ui.dbcache.version.ClientCacheVersionBS.updateVersions(ClientCacheVersionBS.java:252)atnc.ui.dbcache.plugin.VersionUpdatePlugin.execute(VersionUpdatePlugin.java:25)atnc.ui.dbcache.DBCacheUpdateService.run(DBCacheUpdateService.java:150)atjava.lang.Thread.run(UnknownSource)14:32:06[dbcache-update-thread]DEBUG-beginserialize14:32:06[dbcache-update-thread]DEBUG-endserialize|costtime=1ms14:32:06[dbcache-update-thread]DEBUG-beginwritetonet14:32:06[dbcache-update-thread]DEBUG-endwritetonetsize=441|costtime=0ms14:32:06[dbcache-update-thread]DEBUG-beginreadfromnet14:32:06[dbcache-update-thread]DEBUG-endreadfromnetsize=405|costtime=27ms14:32:06[dbcache-update-thread]DEBUG-begindeserialize14:32:06[dbcache-update-thread]DEBUG-enddeserialize|costtime=0ms14:32:06[dbcache-update-thread]DEBUG-endnc.bs.dbcache.intf.IOutDateVersion.getOutDateVersions(java.lang.String){target=http://172.20.99.77:80/ServiceDispatcherServlet/default}|costtime=33ms14:32:06[dbcache-update-thread]DEBUG-memoryversionchanged,willsaveittofile.14:32:06[VersionFileSaveThread]DEBUG-filelockfetched，starttosaveversionxmlfile:C:\Users\Administrator\NCCACHE\172.20.99.77_-opt-yonyou-home_80\CACHE\test051_version.xml14:32:06[dbcache-update-thread]DEBUG-returnsyncPointVersionTsis:2014-05-1414:30:3414:32:06[dbcache-update-thread]DEBUG-updatevocache,memorycollectforcacheoflongtimenotused...14:32:06[VersionFileSaveThread]DEBUG-filesaved!14:33:06[dbcache-update-thread]DEBUG-updatevocache,memorycollectforcacheoflongtimenotused...14:34:00[AWT-EventQueue-2]DEBUG-endreadfromnetsize=9781|costtime=215108ms.......................nc.bs.mw.sql.IerpConnection.setLastUseMeThread(IerpConnection.java:715)nc.bs.mw.ejbsql.IerpXADataSource.getNewCMTConnection(IerpXADataSource.java:241)nc.bs.mw.ejbsql.IerpXADataSource.getConnectionNeed(IerpXADataSource.java:222)nc.bs.mw.ejbsql.IerpXADataSource.getConnection(IerpXADataSource.java:170)nc.jdbc.framework.DataSourceCenter.getDiffConnection(DataSourceCenter.java:246)nc.jdbc.framework.DataSourceCenter.getConnection(DataSourceCenter.java:210)nc.jdbc.framework.DataSourceCenter.getConnection(DataSourceCenter.java:156)nc.jdbc.framework.ConnectionFactory.getConnection(ConnectionFactory.java:26)nc.bs.dbcache.impl.ServerDBFacade.getConnection(ServerDBFacade.java:81)nc.bs.dbcache.dao.AbstractDao.runQuery(AbstractDao.java:88)nc.bs.dbcache.dao.AbstractDao.runQuery(AbstractDao.java:46)nc.bs.dbcache.impl.ServerDBFacade.findColumnsValuesDirectly(ServerDBFacade.java:285)nc.bs.dbcache.impl.ServerDBFacade.findColumnsValues(ServerDBFacade.java:206)nc.bs.dbcache.impl.ServerDBFacade.findVOs(ServerDBFacade.java:190)sun.reflect.GeneratedMethodAccessor1280.invoke(UnknownSource)sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)java.lang.reflect.Method.invoke(Method.java:597)nc.bs.framework.aop.rt.MethodProceedingJoinpoint$3.invoke(MethodProceedingJoinpoint.java:74)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:184)nc.bs.framework.aop.rt.AdviceHolder.invoke(AdviceHolder.java:188)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:129)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:135)nc.bs.framework.aop.rt.AspectedProxy.invoke(AspectedProxy.java:59)com.sun.proxy.$Proxy15.findVOs(UnknownSource)nc.gejb.uap.cmt.UapEjbBean.findVOs(UapEjbBean.java:549)nc.gejb.uap.cmt.Uap_Local.findVOs(Uap_Local.java:1951)sun.reflect.GeneratedMethodAccessor1280.invoke(UnknownSource)sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)java.lang.reflect.Method.invoke(Method.java:597)nc.bs.framework.ejb.AbstractEJBServiceHandler.invoke(AbstractEJBServiceHandler.java:57)nc.bs.framework.ejb.AbstractEJBServiceHandler.invoke(AbstractEJBServiceHandler.java:36)com.sun.proxy.$Proxy206.findVOs(UnknownSource)nc.ui.dbcache.DBCacheFacade.findVOs(DBCacheFacade.java:91)nc.vo.bd.pub.BDCacheQueryUtil.queryVOs(BDCacheQueryUtil.java:300)nc.vo.bd.pub.BDCacheQueryUtil.queryVOs(BDCacheQueryUtil.java:216)nc.pubimpl.org.cache.AccountingBookPubService_C.queryAccountingBookIDByFinanceOrgIDWithMainAccountBook(AccountingBookPubService_C.java:33)sun.reflect.GeneratedMethodAccessor2305.invoke(UnknownSource)sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)java.lang.reflect.Method.invoke(Method.java:597)nc.bs.framework.aop.rt.MethodProceedingJoinpoint$3.invoke(MethodProceedingJoinpoint.java:74)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:184)nc.bs.framework.aop.rt.AdviceHolder.invoke(AdviceHolder.java:188)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:129)nc.bs.framework.aop.rt.MethodProceedingJoinpoint.proceed(MethodProceedingJoinpoint.java:135)nc.bs.framework.aop.rt.AspectedProxy.invoke(AspectedProxy.java:59)com.sun.proxy.$Proxy509.queryAccountingBookIDByFinanceOrgIDWithMainAccountBook(UnknownSource)nc.pub.tools.bdAdapter.AccountingBookAcc.getDefaultPk_AccountingBook(AccountingBookAcc.java:36)nc.ui.fi.uforeport.BSDataSourceControl.initEnvirment(BSDataSourceControl.java:240)nc.ui.fi.uforeport.BSDataSourceControl.beforePress(BSDataSourceControl.java:92)nc.ui.fi.uforeport.NCFuncForUFO.getClientEnv(NCFuncForUFO.java:352)nc.ui.fi.uforeport.NCFuncForUFO.setCalEnv(NCFuncForUFO.java:701)nc.ms.tb.rule.UFOClientExecutor.getFuncDriver(UFOClientExecutor.java:238)nc.ms.tb.rule.UFOClientExecutor.getBatchUFOResults(UFOClientExecutor.java:251)nc.ms.tb.formula.script.function.UFO.run(UFO.java:86)nc.ms.tb.formula.script.core.parser.FunctionCall.eval(FunctionCall.java:92)nc.ms.tb.formula.script.core.parser.Atom.eval(Atom.java:30)nc.ms.tb.formula.script.core.parser.UnaryExpression.eval(UnaryExpression.java:42)nc.ms.tb.formula.script.core.parser.PowerExpression.eval(PowerExpression.java:36)nc.ms.tb.formula.script.core.parser.MultiExpression.eval(MultiExpression.java:45)nc.ms.tb.formula.script.core.parser.AddExpression.eval(AddExpression.java:54)nc.ms.tb.formula.script.core.parser.RelationExpression.eval(RelationExpression.java:40)nc.ms.tb.formula.script.core.parser.ConditionalAndExpression.eval(ConditionalAndExpression.java:36)nc.ms.tb.formula.script.core.parser.ConditionalOrExpression.eval(ConditionalOrExpression.java:37)nc.ms.tb.formula.script.core.parser.ConditionalExpression.eval(ConditionalExpression.java:45)nc.ms.tb.formula.script.core.parser.TbbFormulaExpression.eval(TbbFormulaExpression.java:151)nc.ms.tb.formula.script.Calculator.evalRule(Calculator.java:221)nc.ms.tb.formula.script.parser.line.SelectScriptLine.execute(SelectScriptLine.java:28)nc.ms.tb.formula.script.parser.ProcedureExecuteBody.execute(ProcedureExecuteBody.java:70)nc.ms.tb.formula.script.parser.TbbScript.execute(TbbScript.java:117)nc.ms.tb.formula.core.ScriptHelper.evalScript(ScriptHelper.java:38)nc.ms.tb.formula.core.PubRuleExecute.executeRuleInWorkBook(PubRuleExecute.java:1447)nc.impl.tb.rule.parser.RuleExecuteImpl.executeRuleInWorkBook(RuleExecuteImpl.java:182).........................
