Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.1-master/package.mo", uses=false)
Using package Buildings with version 11.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.1-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|TRooHou.y|staRes.minT.Min|staRes.minT.Mean|staRes.minT.Max|staRes.maxT.Min|staRes.maxT.Mean|staRes.maxT.Max|TRooAnn.y|staRes.meanT.Min|staRes.meanT.Mean|staRes.meanT.Max",fileNamePrefix="Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF")
translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|TRooHou.y|staRes.minT.Min|staRes.minT.Mean|staRes.minT.Max|staRes.maxT.Min|staRes.maxT.Mean|staRes.maxT.Max|TRooAnn.y|staRes.meanT.Min|staRes.meanT.Mean|staRes.meanT.Max",fileNamePrefix="Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001411/0.001411, allocations: 115.5 kB / 16.42 MB, free: 5.988 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00154/0.00154, allocations: 184.5 kB / 17.35 MB, free: 5.602 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.322/1.322, allocations: 222.9 MB / 241.1 MB, free: 15.17 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.1-master/package.mo): time 2.831/2.831, allocations: 469.4 MB / 0.7428 GB, free: 3.57 MB / 0.6075 GB
Notification: Performance of FrontEnd - loaded program: time 0.002336/0.002336, allocations: 59.91 kB / 1.066 GB, free: 13.72 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.8097/0.8121, allocations: 159.1 MB / 1.221 GB, free: 2.309 MB / 0.9356 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF): time 0.2616/1.074, allocations: 237.4 MB / 1.453 GB, free: 4.02 MB / 1.17 GB
Notification: Performance of NFInst.instExpressions: time 0.07253/1.146, allocations: 44.17 MB / 1.496 GB, free: 7.703 MB / 1.217 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01309/1.159, allocations: 354.1 kB / 1.496 GB, free: 7.355 MB / 1.217 GB
Notification: Performance of NFTyping.typeComponents: time 0.02035/1.18, allocations: 7.713 MB / 1.504 GB, free: 15.61 MB / 1.232 GB
Notification: Performance of NFTyping.typeBindings: time 0.02187/1.202, allocations: 6.868 MB / 1.51 GB, free: 8.711 MB / 1.232 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01688/1.219, allocations: 6.17 MB / 1.517 GB, free: 2.547 MB / 1.232 GB
Notification: Performance of NFFlatten.flatten: time 1.555/2.774, allocations: 53.48 MB / 1.569 GB, free: 20.52 MB / 1.279 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0337/2.808, allocations: 24.65 MB / 1.593 GB, free: 17.21 MB / 1.279 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0355/2.843, allocations: 23.36 MB / 1.616 GB, free: 15.9 MB / 1.279 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03022/2.874, allocations: 18.77 MB / 1.634 GB, free: 12.92 MB / 1.279 GB
Notification: Performance of NFPackage.collectConstants: time 0.01529/2.889, allocations: 3.754 MB / 1.638 GB, free: 12.92 MB / 1.279 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02563/2.915, allocations: 8.416 MB / 1.646 GB, free: 12.92 MB / 1.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.02016/2.935, allocations: 12.55 MB / 1.658 GB, free: 11.73 MB / 1.279 GB
Notification: Performance of NFVerifyModel.verify: time 0.03446/2.969, allocations: 18.3 MB / 1.676 GB, free: 7.082 MB / 1.279 GB
Notification: Performance of NFConvertDAE.convert: time 0.07314/3.043, allocations: 63.06 MB / 1.738 GB, free: 5.34 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 5.3e-06/3.043, allocations: 0 / 1.738 GB, free: 5.34 MB / 1.311 GB
Notification: Performance of FrontEnd: time 2.024e-06/3.043, allocations: 0 / 1.738 GB, free: 5.34 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.001695/3.044, allocations: 8 kB / 1.738 GB, free: 5.332 MB / 1.311 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2791
 * Number of variables: 2791
Notification: Performance of Generate backend data structure: time 0.1214/3.166, allocations: 40.53 MB / 1.777 GB, free: 12.86 MB / 1.358 GB
Notification: Performance of prepare preOptimizeDAE: time 4.634e-05/3.166, allocations: 8.625 kB / 1.777 GB, free: 12.86 MB / 1.358 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06535/3.231, allocations: 12.39 MB / 1.789 GB, free: 428 kB / 1.358 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.09345/3.325, allocations: 42.45 MB / 1.831 GB, free: 6.727 MB / 1.404 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00169/3.326, allocations: 1.675 MB / 1.832 GB, free: 4.898 MB / 1.404 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.007005/3.333, allocations: 1.33 MB / 1.834 GB, free: 3.57 MB / 1.404 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0616/3.395, allocations: 23.16 MB / 1.856 GB, free: 11.97 MB / 1.436 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003565/3.395, allocations: 20.56 kB / 1.856 GB, free: 11.96 MB / 1.436 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.004423/3.4, allocations: 454.5 kB / 1.857 GB, free: 11.52 MB / 1.436 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003725/3.4, allocations: 396.4 kB / 1.857 GB, free: 11.13 MB / 1.436 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.06022/3.46, allocations: 24.64 MB / 1.881 GB, free: 2.574 MB / 1.451 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.2651/3.726, allocations: 123.7 MB / 2.002 GB, free: 4.285 MB / 1.576 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03915/3.765, allocations: 17.4 MB / 2.019 GB, free: 2.844 MB / 1.592 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02728/3.792, allocations: 11.17 MB / 2.03 GB, free: 7.656 MB / 1.608 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.002552/3.795, allocations: 1.004 MB / 2.031 GB, free: 6.523 MB / 1.608 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02806/3.823, allocations: 10.81 MB / 2.041 GB, free: 11.68 MB / 1.623 GB
Notification: Performance of pre-optimization done (n=818): time 2.353e-05/3.823, allocations: 4 kB / 2.041 GB, free: 11.67 MB / 1.623 GB
Notification: Performance of matching and sorting (n=818): time 0.1065/3.929, allocations: 37.51 MB / 2.078 GB, free: 5.84 MB / 1.654 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003607/3.93, allocations: 378.1 kB / 2.078 GB, free: 5.426 MB / 1.654 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1299/4.06, allocations: 55.56 MB / 2.133 GB, free: 14.03 MB / 1.717 GB
Notification: Performance of collectPreVariables (initialization): time 0.004676/4.064, allocations: 281.7 kB / 2.133 GB, free: 13.75 MB / 1.717 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02555/4.09, allocations: 12.89 MB / 2.145 GB, free: 0.8789 MB / 1.717 GB
Notification: Performance of collectInitialBindings (initialization): time 0.008366/4.098, allocations: 2.872 MB / 2.148 GB, free: 14.03 MB / 1.733 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.02044/4.119, allocations: 5.884 MB / 2.154 GB, free: 8.145 MB / 1.733 GB
Notification: Performance of setup shared object (initialization): time 0.0001045/4.119, allocations: 305.1 kB / 2.154 GB, free: 7.844 MB / 1.733 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0543/4.173, allocations: 36.45 MB / 2.19 GB, free: 3.375 MB / 1.764 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.8422/5.016, allocations: 41.04 MB / 2.23 GB, free: 0.6954 GB / 1.764 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.07297/5.089, allocations: 52.52 MB / 2.281 GB, free: 0.6717 GB / 1.764 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001906/5.089, allocations: 32 kB / 2.281 GB, free: 0.6717 GB / 1.764 GB
Notification: Performance of matching and sorting (n=2497) (initialization): time 0.1236/5.212, allocations: 85.36 MB / 2.365 GB, free: 0.5986 GB / 1.764 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002915/5.213, allocations: 199.3 kB / 2.365 GB, free: 0.5984 GB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09123/5.304, allocations: 62.29 MB / 2.426 GB, free: 0.5393 GB / 1.764 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1092/5.413, allocations: 37.18 MB / 2.462 GB, free: 0.503 GB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02461/5.438, allocations: 4.73 MB / 2.467 GB, free: 0.4983 GB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02448/5.462, allocations: 32.88 MB / 2.499 GB, free: 475.3 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03654/5.499, allocations: 5.179 MB / 2.504 GB, free: 470.9 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004876/5.504, allocations: 0.6747 MB / 2.504 GB, free: 470.2 MB / 1.764 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.06527/5.569, allocations: 50.26 MB / 2.554 GB, free: 419.9 MB / 1.764 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.04739/5.617, allocations: 40.98 MB / 2.594 GB, free: 377.3 MB / 1.764 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.07118/5.688, allocations: 52.41 MB / 2.645 GB, free: 323.2 MB / 1.764 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001323/5.688, allocations: 39.17 kB / 2.645 GB, free: 323.2 MB / 1.764 GB
Notification: Performance of matching and sorting (n=2497) (initialization_lambda0): time 0.1186/5.807, allocations: 84.38 MB / 2.727 GB, free: 239.3 MB / 1.764 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002052/5.807, allocations: 197.5 kB / 2.727 GB, free: 239.1 MB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.083/5.89, allocations: 62.04 MB / 2.788 GB, free: 177 MB / 1.764 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008495/5.898, allocations: 3.276 MB / 2.791 GB, free: 173.7 MB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02233/5.921, allocations: 4.68 MB / 2.796 GB, free: 169 MB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02626/5.947, allocations: 32.33 MB / 2.827 GB, free: 135.1 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04039/5.987, allocations: 4.842 MB / 2.832 GB, free: 130.5 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005235/5.993, allocations: 0.6703 MB / 2.833 GB, free: 129.8 MB / 1.764 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 521
 * Number of states: 0 ()
 * Number of discrete variables: 64 (TRooAnn.mode,$PRE.TRooAnn.mode,$whenCondition1,TRooHou.mode,$PRE.TRooHou.mode,$whenCondition2,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,roo.solRadExc.is_flo[11],roo.solRadExc.is_flo[10],roo.solRadExc.is_flo[9],roo.solRadExc.is_flo[8],roo.solRadExc.is_flo[7],roo.solRadExc.is_flo[6],roo.solRadExc.is_flo[5],roo.solRadExc.is_flo[4],roo.solRadExc.is_flo[3],roo.solRadExc.is_flo[2],roo.solRadExc.is_flo[1],weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,$PRE.roo.bouConExtWin.HDifTil[1].incAng.decAng.tNext,roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,$PRE.roo.bouConExtWin.HDirTil[1].incAng.decAng.tNext,roo.bouConExt.HDifTil[4].incAng.decAng.tNext,$PRE.roo.bouConExt.HDifTil[4].incAng.decAng.tNext,roo.bouConExt.HDifTil[3].incAng.decAng.tNext,$PRE.roo.bouConExt.HDifTil[3].incAng.decAng.tNext,roo.bouConExt.HDifTil[2].incAng.decAng.tNext,$PRE.roo.bouConExt.HDifTil[2].incAng.decAng.tNext,roo.bouConExt.HDifTil[1].incAng.decAng.tNext,$PRE.roo.bouConExt.HDifTil[1].incAng.decAng.tNext,roo.bouConExt.HDirTil[4].incAng.decAng.tNext,$PRE.roo.bouConExt.HDirTil[4].incAng.decAng.tNext,roo.bouConExt.HDirTil[3].incAng.decAng.tNext,$PRE.roo.bouConExt.HDirTil[3].incAng.decAng.tNext,roo.bouConExt.HDirTil[2].incAng.decAng.tNext,$PRE.roo.bouConExt.HDirTil[2].incAng.decAng.tNext,roo.bouConExt.HDirTil[1].incAng.decAng.tNext,$PRE.roo.bouConExt.HDirTil[1].incAng.decAng.tNext,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1700):
 * Single equations (assignments): 1645
 * Array equations: 36
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 6
 * Torn equation systems: 12
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 6 systems
   {1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 10 systems
   {(1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(13,44), (1,23)}
Notification: Performance of prepare postOptimizeDAE: time 0.008108/6.001, allocations: 1.179 MB / 2.834 GB, free: 128.6 MB / 1.764 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01101/6.012, allocations: 2.541 MB / 2.836 GB, free: 126.1 MB / 1.764 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0628/6.075, allocations: 26.45 MB / 2.862 GB, free: 99.52 MB / 1.764 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04865/6.123, allocations: 21.89 MB / 2.883 GB, free: 77.46 MB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.04653/6.17, allocations: 20.97 MB / 2.904 GB, free: 56.39 MB / 1.764 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02504/6.195, allocations: 7.865 MB / 2.912 GB, free: 48.48 MB / 1.764 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01253/6.208, allocations: 299.7 kB / 2.912 GB, free: 48.18 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03152/6.239, allocations: 2.844 MB / 2.915 GB, free: 45.34 MB / 1.764 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003551/6.243, allocations: 0.5291 MB / 2.915 GB, free: 44.8 MB / 1.764 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.07382/6.317, allocations: 28.67 MB / 2.943 GB, free: 16.16 MB / 1.764 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.9368/7.253, allocations: 36.48 MB / 2.979 GB, free: 0.676 GB / 1.764 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0214/7.275, allocations: 11.15 MB / 2.99 GB, free: 0.6724 GB / 1.764 GB
Notification: Performance of sorting global known variables: time 0.07333/7.348, allocations: 38.51 MB / 3.027 GB, free: 0.6603 GB / 1.764 GB
Notification: Performance of Backend: time 1.773e-06/7.348, allocations: 0 / 3.027 GB, free: 0.6603 GB / 1.764 GB
Notification: Performance of simCode: created initialization part: time 0.1413/7.49, allocations: 93.93 MB / 3.119 GB, free: 0.5977 GB / 1.764 GB
Notification: Performance of SimCode: time 0.157/7.647, allocations: 78.19 MB / 3.195 GB, free: 0.5237 GB / 1.764 GB
Notification: Performance of Templates: time 2.153/9.8, allocations: 0.6578 GB / 3.853 GB, free: 0.5882 GB / 1.764 GB
make -j1 -f Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.makefile
(rm -f Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; mkfifo Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; head -c 1048576 < Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe >> ../files/Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.sim & ./Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe 2>&1)