Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.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.Case980FF,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.Case980FF")
translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF,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.Case980FF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00149/0.00149, allocations: 115.3 kB / 16.42 MB, free: 6.02 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.001642/0.001642, allocations: 186.1 kB / 17.35 MB, free: 5.633 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.398/1.398, allocations: 222.9 MB / 241 MB, free: 15.16 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.1-master/package.mo): time 2.545/2.545, allocations: 461.9 MB / 0.7355 GB, free: 11.08 MB / 0.6075 GB
Notification: Performance of FrontEnd - loaded program: time 0.001333/0.001333, allocations: 59.91 kB / 1.055 GB, free: 7.641 MB / 0.8262 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7724/0.7737, allocations: 157.4 MB / 1.209 GB, free: 13.62 MB / 0.9356 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF): time 0.2686/1.042, allocations: 237.8 MB / 1.441 GB, free: 14.93 MB / 1.17 GB
Notification: Performance of NFInst.instExpressions: time 0.07831/1.121, allocations: 44.69 MB / 1.484 GB, free: 2.109 MB / 1.201 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01541/1.136, allocations: 354.1 kB / 1.485 GB, free: 1.762 MB / 1.201 GB
Notification: Performance of NFTyping.typeComponents: time 0.02082/1.157, allocations: 8.059 MB / 1.493 GB, free: 9.664 MB / 1.217 GB
Notification: Performance of NFTyping.typeBindings: time 0.02132/1.178, allocations: 6.79 MB / 1.499 GB, free: 2.844 MB / 1.217 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01747/1.196, allocations: 6.157 MB / 1.505 GB, free: 12.7 MB / 1.232 GB
Notification: Performance of NFFlatten.flatten: time 1.437/2.633, allocations: 72.78 MB / 1.576 GB, free: 20.59 MB / 1.279 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.03913/2.672, allocations: 30.49 MB / 1.606 GB, free: 17.25 MB / 1.279 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04766/2.72, allocations: 32.54 MB / 1.638 GB, free: 15.64 MB / 1.279 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.05013/2.77, allocations: 33.91 MB / 1.671 GB, free: 12.55 MB / 1.279 GB
Notification: Performance of NFPackage.collectConstants: time 0.02262/2.793, allocations: 7.842 MB / 1.679 GB, free: 12.55 MB / 1.279 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03441/2.827, allocations: 12.5 MB / 1.691 GB, free: 12.55 MB / 1.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.03614/2.864, allocations: 25.95 MB / 1.716 GB, free: 0.9102 MB / 1.279 GB
Notification: Performance of NFVerifyModel.verify: time 0.06529/2.929, allocations: 33.44 MB / 1.749 GB, free: 0.7305 MB / 1.311 GB
Notification: Performance of NFConvertDAE.convert: time 0.1461/3.075, allocations: 127.3 MB / 1.873 GB, free: 2.809 MB / 1.436 GB
Notification: Performance of FrontEnd - DAE generated: time 6.682e-06/3.075, allocations: 0 / 1.873 GB, free: 2.809 MB / 1.436 GB
Notification: Performance of FrontEnd: time 1.723e-06/3.075, allocations: 0 / 1.873 GB, free: 2.809 MB / 1.436 GB
Notification: Performance of Transformations before backend: time 0.001966/3.077, allocations: 7.75 kB / 1.873 GB, free: 2.805 MB / 1.436 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4066
 * Number of variables: 4066
Notification: Performance of Generate backend data structure: time 0.2116/3.289, allocations: 66.56 MB / 1.938 GB, free: 292 kB / 1.498 GB
Notification: Performance of prepare preOptimizeDAE: time 5.464e-05/3.289, allocations: 8.031 kB / 1.938 GB, free: 284 kB / 1.498 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.08054/3.369, allocations: 13.25 MB / 1.951 GB, free: 2.961 MB / 1.514 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1467/3.516, allocations: 66.4 MB / 2.016 GB, free: 1.512 MB / 1.576 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002522/3.519, allocations: 2.108 MB / 2.018 GB, free: 15.25 MB / 1.592 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01873/3.537, allocations: 2.042 MB / 2.02 GB, free: 13.21 MB / 1.592 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.09141/3.629, allocations: 36.31 MB / 2.055 GB, free: 7.785 MB / 1.623 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0006461/3.629, allocations: 61.69 kB / 2.056 GB, free: 7.762 MB / 1.623 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.006295/3.636, allocations: 0.6655 MB / 2.056 GB, free: 7.098 MB / 1.623 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005456/3.636, allocations: 0.5589 MB / 2.057 GB, free: 6.539 MB / 1.623 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.08359/3.72, allocations: 33.68 MB / 2.09 GB, free: 5.145 MB / 1.654 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 1.492/5.212, allocations: 0.534 GB / 2.624 GB, free: 79.59 MB / 1.748 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05243/5.264, allocations: 31.85 MB / 2.655 GB, free: 47.65 MB / 1.748 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03005/5.295, allocations: 16.66 MB / 2.671 GB, free: 30.97 MB / 1.748 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00249/5.297, allocations: 1.057 MB / 2.672 GB, free: 29.79 MB / 1.748 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.03622/5.333, allocations: 17.23 MB / 2.689 GB, free: 12.51 MB / 1.748 GB
Notification: Performance of pre-optimization done (n=1328): time 2.675e-05/5.333, allocations: 0 / 2.689 GB, free: 12.51 MB / 1.748 GB
Notification: Performance of matching and sorting (n=1328): time 0.1539/5.487, allocations: 53.51 MB / 2.741 GB, free: 7.055 MB / 1.795 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004156/5.488, allocations: 426.1 kB / 2.742 GB, free: 6.594 MB / 1.795 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1659/5.654, allocations: 70.95 MB / 2.811 GB, free: 15.83 MB / 1.873 GB
Notification: Performance of collectPreVariables (initialization): time 0.01176/5.665, allocations: 405.7 kB / 2.811 GB, free: 15.43 MB / 1.873 GB
Notification: Performance of collectInitialEqns (initialization): time 0.03585/5.701, allocations: 16.66 MB / 2.827 GB, free: 14.79 MB / 1.889 GB
Notification: Performance of collectInitialBindings (initialization): time 0.08209/5.783, allocations: 5.31 MB / 2.833 GB, free: 9.523 MB / 1.889 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0452/5.829, allocations: 8.343 MB / 2.841 GB, free: 1.172 MB / 1.889 GB
Notification: Performance of setup shared object (initialization): time 9.123e-05/5.829, allocations: 309.1 kB / 2.841 GB, free: 0.8672 MB / 1.889 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 1.018/6.847, allocations: 77.39 MB / 2.917 GB, free: 0.5356 GB / 1.92 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.11/6.957, allocations: 83.96 MB / 2.999 GB, free: 0.5274 GB / 1.92 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1367/7.094, allocations: 103.1 MB / 3.099 GB, free: 465.6 MB / 1.92 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002561/7.094, allocations: 48.75 kB / 3.099 GB, free: 465.6 MB / 1.92 GB
Notification: Performance of matching and sorting (n=3517) (initialization): time 0.2657/7.36, allocations: 170.3 MB / 3.266 GB, free: 298 MB / 1.92 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003094/7.36, allocations: 217.6 kB / 3.266 GB, free: 297.8 MB / 1.92 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1994/7.56, allocations: 143.8 MB / 3.406 GB, free: 153.9 MB / 1.92 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1098/7.67, allocations: 37.26 MB / 3.443 GB, free: 116.6 MB / 1.92 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.05333/7.723, allocations: 9.079 MB / 3.452 GB, free: 107.8 MB / 1.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02593/7.749, allocations: 33.01 MB / 3.484 GB, free: 72.91 MB / 1.92 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.3248/8.074, allocations: 29.92 MB / 3.513 GB, free: 43.75 MB / 1.92 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01411/8.088, allocations: 0.9678 MB / 3.514 GB, free: 42.79 MB / 1.92 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 1.803/9.891, allocations: 95.81 MB / 3.608 GB, free: 0.5296 GB / 1.936 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.1017/9.992, allocations: 83.9 MB / 3.69 GB, free: 0.5212 GB / 1.936 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.1247/10.12, allocations: 103 MB / 3.79 GB, free: 465.5 MB / 1.936 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002411/10.12, allocations: 48 kB / 3.79 GB, free: 465.5 MB / 1.936 GB
Notification: Performance of matching and sorting (n=3517) (initialization_lambda0): time 0.2374/10.35, allocations: 169.3 MB / 3.955 GB, free: 299.6 MB / 1.936 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002666/10.36, allocations: 225.6 kB / 3.956 GB, free: 299.4 MB / 1.936 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.1878/10.54, allocations: 143.7 MB / 4.096 GB, free: 155.6 MB / 1.936 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009249/10.55, allocations: 3.371 MB / 4.099 GB, free: 152.2 MB / 1.936 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04944/10.6, allocations: 9.023 MB / 4.108 GB, free: 143.5 MB / 1.936 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02643/10.63, allocations: 32.46 MB / 4.14 GB, free: 109.9 MB / 1.936 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.3471/10.98, allocations: 29.48 MB / 4.169 GB, free: 80.34 MB / 1.936 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01359/10.99, allocations: 0.964 MB / 4.17 GB, free: 79.38 MB / 1.936 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 776
 * 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 (2720):
 * Single equations (assignments): 2665
 * 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.03369/11.02, allocations: 1.616 MB / 4.171 GB, free: 78.08 MB / 1.936 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02277/11.05, allocations: 3.193 MB / 4.174 GB, free: 74.84 MB / 1.936 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.09326/11.14, allocations: 39.57 MB / 4.213 GB, free: 35.21 MB / 1.936 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0732/11.21, allocations: 33.2 MB / 4.245 GB, free: 1.855 MB / 1.936 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.9858/12.2, allocations: 38.7 MB / 4.283 GB, free: 0.539 GB / 1.951 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.04292/12.24, allocations: 17.75 MB / 4.3 GB, free: 0.5387 GB / 1.951 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01579/12.26, allocations: 0.841 MB / 4.301 GB, free: 0.5387 GB / 1.951 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.1781/12.43, allocations: 15.24 MB / 4.316 GB, free: 0.5387 GB / 1.951 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.004569/12.44, allocations: 0.8046 MB / 4.317 GB, free: 0.5387 GB / 1.951 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.121/12.56, allocations: 50.1 MB / 4.366 GB, free: 0.5377 GB / 1.951 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1408/12.7, allocations: 60.78 MB / 4.425 GB, free: 0.5244 GB / 1.951 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.05584/12.76, allocations: 19.2 MB / 4.444 GB, free: 0.5093 GB / 1.951 GB
Notification: Performance of sorting global known variables: time 0.1209/12.88, allocations: 49.15 MB / 4.492 GB, free: 472.6 MB / 1.951 GB
Notification: Performance of Backend: time 1.483e-06/12.88, allocations: 1.953 kB / 4.492 GB, free: 472.6 MB / 1.951 GB
Notification: Performance of simCode: created initialization part: time 0.2487/13.13, allocations: 179.1 MB / 4.667 GB, free: 295 MB / 1.951 GB
Notification: Performance of SimCode: time 0.3571/13.48, allocations: 116.8 MB / 4.781 GB, free: 178.6 MB / 1.951 GB
Notification: Performance of Templates: time 4.645/18.13, allocations: 1.63 GB / 6.411 GB, free: 431.7 MB / 1.998 GB
make -j1 -f Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.makefile
(rm -f Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.pipe ; mkfifo Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.pipe ; head -c 1048576 < Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.pipe >> ../files/Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.sim & ./Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case980FF.pipe 2>&1)