Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_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 10.1.1-maint.10.0.x/package.mo", uses=false)
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/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_10_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_10_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.001542/0.001542, allocations: 115.9 kB / 16.42 MB, free: 6.488 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.001801/0.001801, allocations: 185.7 kB / 17.35 MB, free: 5.762 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.307/1.307, allocations: 222.9 MB / 241.1 MB, free: 15.07 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.446/2.446, allocations: 437 MB / 0.7112 GB, free: 4.457 MB / 0.5762 GB
Notification: Performance of FrontEnd - loaded program: time 0.001289/0.00129, allocations: 71.84 kB / 1.019 GB, free: 9.398 MB / 0.795 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7502/0.7515, allocations: 151.6 MB / 1.167 GB, free: 3.906 MB / 0.8887 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF): time 0.2482/0.9997, allocations: 237.5 MB / 1.399 GB, free: 5.52 MB / 1.123 GB
Notification: Performance of NFInst.instExpressions: time 0.06962/1.069, allocations: 44.18 MB / 1.442 GB, free: 9.207 MB / 1.17 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01281/1.082, allocations: 354.1 kB / 1.443 GB, free: 8.859 MB / 1.17 GB
Notification: Performance of NFTyping.typeComponents: time 0.01924/1.102, allocations: 7.709 MB / 1.45 GB, free: 1.113 MB / 1.17 GB
Notification: Performance of NFTyping.typeBindings: time 0.01925/1.121, allocations: 6.866 MB / 1.457 GB, free: 10.21 MB / 1.186 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01577/1.137, allocations: 6.167 MB / 1.463 GB, free: 4.059 MB / 1.186 GB
Notification: Performance of NFFlatten.flatten: time 0.05645/1.193, allocations: 53.49 MB / 1.515 GB, free: 14.48 MB / 1.248 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.03261/1.226, allocations: 24.64 MB / 1.539 GB, free: 5.672 MB / 1.264 GB
Notification: Performance of NFEvalConstants.evaluate: time 1.219/2.445, allocations: 23.35 MB / 1.562 GB, free: 42.83 MB / 1.279 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02698/2.472, allocations: 18.77 MB / 1.58 GB, free: 42.83 MB / 1.279 GB
Notification: Performance of NFPackage.collectConstants: time 0.01244/2.484, allocations: 3.75 MB / 1.584 GB, free: 42.83 MB / 1.279 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02396/2.508, allocations: 8.416 MB / 1.592 GB, free: 42.83 MB / 1.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.01874/2.527, allocations: 12.55 MB / 1.605 GB, free: 41.91 MB / 1.279 GB
Notification: Performance of NFVerifyModel.verify: time 0.0335/2.56, allocations: 18.3 MB / 1.622 GB, free: 37.26 MB / 1.279 GB
Notification: Performance of NFConvertDAE.convert: time 0.0627/2.623, allocations: 63.07 MB / 1.684 GB, free: 28.69 MB / 1.279 GB
Notification: Performance of FrontEnd - DAE generated: time 5.1e-06/2.623, allocations: 0 / 1.684 GB, free: 28.69 MB / 1.279 GB
Notification: Performance of FrontEnd: time 2.255e-06/2.623, allocations: 0.7188 kB / 1.684 GB, free: 28.69 MB / 1.279 GB
Notification: Performance of Transformations before backend: time 0.001876/2.625, allocations: 0 / 1.684 GB, free: 28.69 MB / 1.279 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.1158/2.741, allocations: 40.54 MB / 1.724 GB, free: 6.309 MB / 1.279 GB
Notification: Performance of prepare preOptimizeDAE: time 6.895e-05/2.741, allocations: 10.2 kB / 1.724 GB, free: 6.309 MB / 1.279 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06414/2.805, allocations: 12.38 MB / 1.736 GB, free: 0.5977 MB / 1.279 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.09029/2.895, allocations: 42.46 MB / 1.777 GB, free: 6.922 MB / 1.326 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001711/2.897, allocations: 1.676 MB / 1.779 GB, free: 5.094 MB / 1.326 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.007115/2.904, allocations: 1.335 MB / 1.78 GB, free: 3.762 MB / 1.326 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06088/2.965, allocations: 23.15 MB / 1.803 GB, free: 12.17 MB / 1.358 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003891/2.966, allocations: 24.56 kB / 1.803 GB, free: 12.16 MB / 1.358 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.004375/2.97, allocations: 448 kB / 1.803 GB, free: 11.72 MB / 1.358 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003845/2.97, allocations: 402.8 kB / 1.804 GB, free: 11.33 MB / 1.358 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.06336/3.034, allocations: 24.63 MB / 1.828 GB, free: 2.773 MB / 1.373 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.2514/3.285, allocations: 123.7 MB / 1.948 GB, free: 4.555 MB / 1.498 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03909/3.324, allocations: 17.4 MB / 1.965 GB, free: 3.109 MB / 1.514 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02503/3.349, allocations: 11.18 MB / 1.976 GB, free: 7.906 MB / 1.529 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001907/3.351, allocations: 1.004 MB / 1.977 GB, free: 6.773 MB / 1.529 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02596/3.377, allocations: 10.79 MB / 1.988 GB, free: 11.94 MB / 1.545 GB
Notification: Performance of pre-optimization done (n=818): time 1.292e-05/3.377, allocations: 4 kB / 1.988 GB, free: 11.94 MB / 1.545 GB
Notification: Performance of matching and sorting (n=818): time 0.1072/3.485, allocations: 37.53 MB / 2.025 GB, free: 6.086 MB / 1.576 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003501/3.485, allocations: 373.3 kB / 2.025 GB, free: 5.68 MB / 1.576 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1252/3.61, allocations: 55.56 MB / 2.079 GB, free: 14.28 MB / 1.639 GB
Notification: Performance of collectPreVariables (initialization): time 0.004191/3.614, allocations: 285.7 kB / 2.079 GB, free: 13.99 MB / 1.639 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02462/3.639, allocations: 12.89 MB / 2.092 GB, free: 1.121 MB / 1.639 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007661/3.647, allocations: 2.875 MB / 2.095 GB, free: 14.27 MB / 1.654 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01955/3.666, allocations: 5.872 MB / 2.101 GB, free: 8.395 MB / 1.654 GB
Notification: Performance of setup shared object (initialization): time 9.364e-05/3.666, allocations: 309.1 kB / 2.101 GB, free: 8.09 MB / 1.654 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.05248/3.719, allocations: 36.45 MB / 2.136 GB, free: 3.625 MB / 1.686 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.6978/4.417, allocations: 41.04 MB / 2.176 GB, free: 0.6883 GB / 1.717 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.08217/4.499, allocations: 52.52 MB / 2.228 GB, free: 0.6768 GB / 1.717 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003812/4.499, allocations: 32.12 kB / 2.228 GB, free: 0.6768 GB / 1.717 GB
Notification: Performance of matching and sorting (n=2497) (initialization): time 0.1389/4.638, allocations: 85.36 MB / 2.311 GB, free: 0.6193 GB / 1.717 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006058/4.639, allocations: 195.5 kB / 2.311 GB, free: 0.6191 GB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09476/4.734, allocations: 62.29 MB / 2.372 GB, free: 0.5645 GB / 1.717 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1116/4.845, allocations: 37.19 MB / 2.409 GB, free: 0.5284 GB / 1.717 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02433/4.87, allocations: 4.727 MB / 2.413 GB, free: 0.5239 GB / 1.717 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02501/4.895, allocations: 32.87 MB / 2.445 GB, free: 0.4898 GB / 1.717 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03725/4.932, allocations: 5.183 MB / 2.45 GB, free: 497.2 MB / 1.717 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00512/4.937, allocations: 0.671 MB / 2.451 GB, free: 496.5 MB / 1.717 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.06704/5.004, allocations: 50.26 MB / 2.5 GB, free: 446.2 MB / 1.717 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.04695/5.051, allocations: 40.99 MB / 2.54 GB, free: 403.6 MB / 1.717 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.07008/5.121, allocations: 52.42 MB / 2.591 GB, free: 349.5 MB / 1.717 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001152/5.122, allocations: 32 kB / 2.591 GB, free: 349.5 MB / 1.717 GB
Notification: Performance of matching and sorting (n=2497) (initialization_lambda0): time 0.1213/5.243, allocations: 84.38 MB / 2.674 GB, free: 265.6 MB / 1.717 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002588/5.243, allocations: 199.9 kB / 2.674 GB, free: 265.4 MB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.08938/5.333, allocations: 62.04 MB / 2.734 GB, free: 203.2 MB / 1.717 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009106/5.342, allocations: 3.276 MB / 2.738 GB, free: 199.9 MB / 1.717 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02314/5.365, allocations: 4.68 MB / 2.742 GB, free: 195.2 MB / 1.717 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02837/5.393, allocations: 32.33 MB / 2.774 GB, free: 161.4 MB / 1.717 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04016/5.433, allocations: 4.838 MB / 2.779 GB, free: 156.7 MB / 1.717 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004942/5.438, allocations: 0.6729 MB / 2.779 GB, free: 156.1 MB / 1.717 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.008736/5.447, allocations: 1.178 MB / 2.78 GB, free: 154.9 MB / 1.717 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0107/5.458, allocations: 2.542 MB / 2.783 GB, free: 152.3 MB / 1.717 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0669/5.525, allocations: 26.44 MB / 2.809 GB, free: 125.8 MB / 1.717 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04976/5.575, allocations: 21.89 MB / 2.83 GB, free: 103.7 MB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.04782/5.623, allocations: 20.97 MB / 2.85 GB, free: 82.63 MB / 1.717 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02307/5.646, allocations: 7.865 MB / 2.858 GB, free: 74.71 MB / 1.717 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01229/5.658, allocations: 295.8 kB / 2.858 GB, free: 74.42 MB / 1.717 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02696/5.685, allocations: 2.839 MB / 2.861 GB, free: 71.57 MB / 1.717 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002718/5.688, allocations: 0.5291 MB / 2.862 GB, free: 71.04 MB / 1.717 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.07586/5.764, allocations: 28.68 MB / 2.89 GB, free: 42.39 MB / 1.717 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.07811/5.842, allocations: 36.49 MB / 2.925 GB, free: 5.766 MB / 1.717 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.6442/6.486, allocations: 11.14 MB / 2.936 GB, free: 0.6727 GB / 1.717 GB
Notification: Performance of sorting global known variables: time 0.06849/6.554, allocations: 38.52 MB / 2.974 GB, free: 0.6668 GB / 1.717 GB
Notification: Performance of Backend: time 1.162e-06/6.555, allocations: 0 / 2.974 GB, free: 0.6668 GB / 1.717 GB
Notification: Performance of simCode: created initialization part: time 0.1384/6.693, allocations: 93.94 MB / 3.066 GB, free: 0.611 GB / 1.717 GB
Notification: Performance of SimCode: time 0.1467/6.84, allocations: 78.18 MB / 3.142 GB, free: 0.5438 GB / 1.717 GB
Notification: Performance of Templates: time 1.907/8.747, allocations: 0.6578 GB / 3.8 GB, free: 0.6008 GB / 1.717 GB
make -j1 -f Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.makefile
(rm -f Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; mkfifo Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; head -c 1048576 < Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe >> ../files/Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.sim & ./Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_10_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe 2>&1)