Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_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 9.1.3-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.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_9_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_9_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.001047/0.001047, allocations: 110.4 kB / 16.42 MB, free: 6.508 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.001068/0.001068, allocations: 185.5 kB / 17.35 MB, free: 5.738 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.266/1.266, allocations: 222.9 MB / 241.1 MB, free: 15.18 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo): time 2.142/2.142, allocations: 390.3 MB / 0.6655 GB, free: 0.6484 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.001354/0.001354, allocations: 60.14 kB / 0.9516 GB, free: 7.594 MB / 0.7325 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.6775/0.6789, allocations: 140.4 MB / 1.089 GB, free: 5.5 MB / 0.8575 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF): time 0.2383/0.9172, allocations: 233.5 MB / 1.317 GB, free: 4.004 MB / 1.045 GB
Notification: Performance of NFInst.instExpressions: time 0.06124/0.9784, allocations: 43.43 MB / 1.359 GB, free: 8.445 MB / 1.092 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01037/0.9888, allocations: 350.1 kB / 1.359 GB, free: 8.102 MB / 1.092 GB
Notification: Performance of NFTyping.typeComponents: time 0.01837/1.007, allocations: 7.604 MB / 1.367 GB, free: 472 kB / 1.092 GB
Notification: Performance of NFTyping.typeBindings: time 0.01774/1.025, allocations: 6.582 MB / 1.373 GB, free: 9.855 MB / 1.107 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01468/1.04, allocations: 6.112 MB / 1.379 GB, free: 3.75 MB / 1.107 GB
Notification: Performance of NFFlatten.flatten: time 0.05189/1.092, allocations: 49.06 MB / 1.427 GB, free: 2.605 MB / 1.154 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02936/1.121, allocations: 23.84 MB / 1.45 GB, free: 10.61 MB / 1.186 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0312/1.152, allocations: 21.66 MB / 1.472 GB, free: 4.898 MB / 1.201 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02462/1.177, allocations: 17.2 MB / 1.488 GB, free: 3.645 MB / 1.217 GB
Notification: Performance of NFPackage.collectConstants: time 0.009154/1.186, allocations: 3.441 MB / 1.492 GB, free: 204 kB / 1.217 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01847/1.205, allocations: 8.054 MB / 1.5 GB, free: 8.137 MB / 1.232 GB
Notification: Performance of NFScalarize.scalarize: time 0.01497/1.22, allocations: 11.32 MB / 1.511 GB, free: 12.79 MB / 1.248 GB
Notification: Performance of NFVerifyModel.verify: time 0.02608/1.246, allocations: 16.69 MB / 1.527 GB, free: 12.02 MB / 1.264 GB
Notification: Performance of NFConvertDAE.convert: time 0.06177/1.307, allocations: 57.41 MB / 1.583 GB, free: 2.461 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 6.101e-06/1.308, allocations: 3.938 kB / 1.583 GB, free: 2.457 MB / 1.311 GB
Notification: Performance of FrontEnd: time 1.663e-06/1.308, allocations: 4 kB / 1.583 GB, free: 2.453 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.001319/1.309, allocations: 0 / 1.583 GB, free: 2.453 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: 2562
 * Number of variables: 2562
Notification: Performance of Generate backend data structure: time 1.203/2.512, allocations: 37.16 MB / 1.619 GB, free: 163.7 MB / 1.311 GB
Notification: Performance of prepare preOptimizeDAE: time 4.6e-05/2.512, allocations: 9.781 kB / 1.619 GB, free: 163.7 MB / 1.311 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06294/2.575, allocations: 12.23 MB / 1.631 GB, free: 162.1 MB / 1.311 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.07395/2.649, allocations: 40.06 MB / 1.67 GB, free: 157.6 MB / 1.311 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001342/2.651, allocations: 1.599 MB / 1.672 GB, free: 156.8 MB / 1.311 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.005273/2.656, allocations: 1.219 MB / 1.673 GB, free: 156.8 MB / 1.311 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04512/2.701, allocations: 20.9 MB / 1.694 GB, free: 142.7 MB / 1.311 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002346/2.701, allocations: 14.28 kB / 1.694 GB, free: 142.7 MB / 1.311 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001903/2.703, allocations: 411 kB / 1.694 GB, free: 142.6 MB / 1.311 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002015/2.703, allocations: 366 kB / 1.694 GB, free: 142.2 MB / 1.311 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0476/2.751, allocations: 23.09 MB / 1.717 GB, free: 121.8 MB / 1.311 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.1637/2.915, allocations: 97.44 MB / 1.812 GB, free: 23.07 MB / 1.311 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02842/2.943, allocations: 15.23 MB / 1.827 GB, free: 7.82 MB / 1.311 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01956/2.963, allocations: 10.34 MB / 1.837 GB, free: 13.46 MB / 1.326 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001566/2.964, allocations: 1 MB / 1.838 GB, free: 12.33 MB / 1.326 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02159/2.986, allocations: 9.821 MB / 1.848 GB, free: 2.461 MB / 1.326 GB
Notification: Performance of pre-optimization done (n=740): time 9.728e-06/2.986, allocations: 4 kB / 1.848 GB, free: 2.457 MB / 1.326 GB
Notification: Performance of matching and sorting (n=740): time 0.08547/3.071, allocations: 33.99 MB / 1.881 GB, free: 112 kB / 1.358 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002618/3.072, allocations: 367.4 kB / 1.881 GB, free: 15.71 MB / 1.373 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.09064/3.162, allocations: 53 MB / 1.933 GB, free: 10.89 MB / 1.42 GB
Notification: Performance of collectPreVariables (initialization): time 0.003163/3.166, allocations: 273.7 kB / 1.933 GB, free: 10.61 MB / 1.42 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01741/3.183, allocations: 12.26 MB / 1.945 GB, free: 14.38 MB / 1.436 GB
Notification: Performance of collectInitialBindings (initialization): time 0.005091/3.188, allocations: 2.536 MB / 1.948 GB, free: 11.87 MB / 1.436 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01237/3.201, allocations: 5.604 MB / 1.953 GB, free: 6.254 MB / 1.436 GB
Notification: Performance of setup shared object (initialization): time 0.0001044/3.201, allocations: 305.1 kB / 1.953 GB, free: 5.953 MB / 1.436 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0487/3.249, allocations: 34.32 MB / 1.987 GB, free: 3.602 MB / 1.467 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0538/3.303, allocations: 38.6 MB / 2.025 GB, free: 11.59 MB / 1.514 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.07458/3.378, allocations: 48.88 MB / 2.072 GB, free: 9.609 MB / 1.561 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001714/3.378, allocations: 28 kB / 2.072 GB, free: 9.582 MB / 1.561 GB
Notification: Performance of matching and sorting (n=2341) (initialization): time 0.1318/3.51, allocations: 80.48 MB / 2.151 GB, free: 9.641 MB / 1.639 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002608/3.51, allocations: 190.8 kB / 2.151 GB, free: 9.449 MB / 1.639 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.711/4.221, allocations: 57.94 MB / 2.208 GB, free: 485.3 MB / 1.654 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1114/4.333, allocations: 36.9 MB / 2.244 GB, free: 484.4 MB / 1.654 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02196/4.355, allocations: 4.159 MB / 2.248 GB, free: 483.4 MB / 1.654 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02367/4.378, allocations: 32.36 MB / 2.279 GB, free: 458 MB / 1.654 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02888/4.407, allocations: 3.656 MB / 2.283 GB, free: 456.9 MB / 1.654 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003835/4.411, allocations: 0.629 MB / 2.284 GB, free: 456.9 MB / 1.654 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.05649/4.468, allocations: 47.33 MB / 2.33 GB, free: 426.6 MB / 1.654 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.04165/4.509, allocations: 38.52 MB / 2.367 GB, free: 389.2 MB / 1.654 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05919/4.569, allocations: 48.78 MB / 2.415 GB, free: 339.3 MB / 1.654 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001198/4.569, allocations: 32 kB / 2.415 GB, free: 339.3 MB / 1.654 GB
Notification: Performance of matching and sorting (n=2341) (initialization_lambda0): time 0.1026/4.671, allocations: 79.49 MB / 2.493 GB, free: 261.7 MB / 1.654 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000187/4.672, allocations: 191.8 kB / 2.493 GB, free: 261.6 MB / 1.654 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.07393/4.746, allocations: 57.71 MB / 2.549 GB, free: 203.8 MB / 1.654 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007817/4.753, allocations: 2.984 MB / 2.552 GB, free: 200.8 MB / 1.654 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01979/4.773, allocations: 4.115 MB / 2.556 GB, free: 196.8 MB / 1.654 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02544/4.799, allocations: 31.84 MB / 2.587 GB, free: 163.8 MB / 1.654 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02922/4.828, allocations: 3.333 MB / 2.591 GB, free: 160.9 MB / 1.654 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003988/4.832, allocations: 0.6246 MB / 2.591 GB, free: 160.3 MB / 1.654 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 476
 * 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 (1556):
 * Single equations (assignments): 1501
 * 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
   {(12,45), (1,11)}
Notification: Performance of prepare postOptimizeDAE: time 0.006741/4.839, allocations: 1.114 MB / 2.592 GB, free: 159.4 MB / 1.654 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.009588/4.848, allocations: 2.435 MB / 2.595 GB, free: 157 MB / 1.654 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05377/4.902, allocations: 24.42 MB / 2.618 GB, free: 132.5 MB / 1.654 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.03961/4.942, allocations: 20.15 MB / 2.638 GB, free: 112.2 MB / 1.654 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.03763/4.979, allocations: 18.95 MB / 2.657 GB, free: 93.13 MB / 1.654 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02022/5, allocations: 7.008 MB / 2.663 GB, free: 86.07 MB / 1.654 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.009251/5.009, allocations: 227.8 kB / 2.664 GB, free: 85.85 MB / 1.654 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02149/5.03, allocations: 1.941 MB / 2.666 GB, free: 83.91 MB / 1.654 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002041/5.033, allocations: 0.4933 MB / 2.666 GB, free: 83.41 MB / 1.654 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.05897/5.091, allocations: 25.39 MB / 2.691 GB, free: 58.02 MB / 1.654 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.07632/5.168, allocations: 32.91 MB / 2.723 GB, free: 24.98 MB / 1.654 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01939/5.187, allocations: 9.976 MB / 2.733 GB, free: 14.98 MB / 1.654 GB
Notification: Performance of sorting global known variables: time 0.05868/5.246, allocations: 36.76 MB / 2.769 GB, free: 10.37 MB / 1.686 GB
Notification: Performance of Backend: time 8.11e-07/5.246, allocations: 0 / 2.769 GB, free: 10.37 MB / 1.686 GB
Notification: Performance of simCode: created initialization part: time 0.6956/5.942, allocations: 89.11 MB / 2.856 GB, free: 0.5015 GB / 1.686 GB
Notification: Performance of SimCode: time 0.1111/6.053, allocations: 72.65 MB / 2.927 GB, free: 466.6 MB / 1.686 GB
Notification: Performance of Templates: time 2.093/8.146, allocations: 0.5907 GB / 3.517 GB, free: 425.5 MB / 1.686 GB
make -j1 -f Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.makefile
(rm -f Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; mkfifo Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; head -c 1048576 < Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe >> ../files/Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.sim & ./Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe 2>&1)