Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.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.Cases6xx.Case680FF,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.Cases6xx.Case680FF")
translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF,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.Cases6xx.Case680FF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001599/0.001599, allocations: 113.9 kB / 16.42 MB, free: 6.449 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.001775/0.001775, allocations: 192.3 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.284/1.284, allocations: 222.9 MB / 241 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.137/2.137, allocations: 390.3 MB / 0.6655 GB, free: 0.6406 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.00131/0.00131, allocations: 60.09 kB / 0.9516 GB, free: 7.578 MB / 0.7325 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7172/0.7185, allocations: 140.4 MB / 1.089 GB, free: 5.488 MB / 0.8575 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF): time 0.2414/0.9599, allocations: 233.5 MB / 1.317 GB, free: 3.984 MB / 1.045 GB
Notification: Performance of NFInst.instExpressions: time 0.0632/1.023, allocations: 43.43 MB / 1.359 GB, free: 8.426 MB / 1.092 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0108/1.034, allocations: 350.2 kB / 1.359 GB, free: 8.082 MB / 1.092 GB
Notification: Performance of NFTyping.typeComponents: time 0.01756/1.052, allocations: 7.643 MB / 1.367 GB, free: 416 kB / 1.092 GB
Notification: Performance of NFTyping.typeBindings: time 0.01743/1.069, allocations: 6.583 MB / 1.373 GB, free: 9.797 MB / 1.107 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01428/1.083, allocations: 6.111 MB / 1.379 GB, free: 3.691 MB / 1.107 GB
Notification: Performance of NFFlatten.flatten: time 0.04986/1.133, allocations: 49.43 MB / 1.428 GB, free: 2.184 MB / 1.154 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02864/1.162, allocations: 23.97 MB / 1.451 GB, free: 10.05 MB / 1.186 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02928/1.191, allocations: 21.78 MB / 1.472 GB, free: 4.23 MB / 1.201 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02362/1.215, allocations: 17.26 MB / 1.489 GB, free: 2.91 MB / 1.217 GB
Notification: Performance of NFPackage.collectConstants: time 0.009081/1.224, allocations: 3.481 MB / 1.492 GB, free: 15.42 MB / 1.232 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01878/1.243, allocations: 8.089 MB / 1.5 GB, free: 7.332 MB / 1.232 GB
Notification: Performance of NFScalarize.scalarize: time 0.01587/1.259, allocations: 11.52 MB / 1.512 GB, free: 11.79 MB / 1.248 GB
Notification: Performance of NFVerifyModel.verify: time 0.02677/1.285, allocations: 16.85 MB / 1.528 GB, free: 10.85 MB / 1.264 GB
Notification: Performance of NFConvertDAE.convert: time 0.06229/1.348, allocations: 58.05 MB / 1.585 GB, free: 0.6484 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 5.69e-06/1.348, allocations: 0 / 1.585 GB, free: 0.6484 MB / 1.311 GB
Notification: Performance of FrontEnd: time 1.503e-06/1.348, allocations: 0 / 1.585 GB, free: 0.6484 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.001228/1.349, allocations: 0 / 1.585 GB, free: 0.6484 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: 2584
 * Number of variables: 2584
Notification: Performance of Generate backend data structure: time 0.9714/2.32, allocations: 37.65 MB / 1.622 GB, free: 393.5 MB / 1.311 GB
Notification: Performance of prepare preOptimizeDAE: time 4.688e-05/2.32, allocations: 11.19 kB / 1.622 GB, free: 393.5 MB / 1.311 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06308/2.384, allocations: 12.25 MB / 1.634 GB, free: 391.7 MB / 1.311 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06971/2.453, allocations: 40.37 MB / 1.673 GB, free: 368.8 MB / 1.311 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001169/2.455, allocations: 1.604 MB / 1.675 GB, free: 367.6 MB / 1.311 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00515/2.46, allocations: 1.228 MB / 1.676 GB, free: 367.2 MB / 1.311 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0461/2.506, allocations: 21.16 MB / 1.696 GB, free: 347 MB / 1.311 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003176/2.506, allocations: 15.88 kB / 1.696 GB, free: 346.9 MB / 1.311 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00302/2.509, allocations: 412 kB / 1.697 GB, free: 346.5 MB / 1.311 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002187/2.509, allocations: 367 kB / 1.697 GB, free: 346.2 MB / 1.311 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04983/2.559, allocations: 23.26 MB / 1.72 GB, free: 322.9 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.723, allocations: 100.6 MB / 1.818 GB, free: 219.9 MB / 1.311 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02808/2.751, allocations: 15.54 MB / 1.833 GB, free: 204.3 MB / 1.311 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01842/2.769, allocations: 10.46 MB / 1.843 GB, free: 193.8 MB / 1.311 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00128/2.771, allocations: 1.007 MB / 1.844 GB, free: 192.7 MB / 1.311 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01984/2.791, allocations: 9.962 MB / 1.854 GB, free: 182.6 MB / 1.311 GB
Notification: Performance of pre-optimization done (n=752): time 1.217e-05/2.791, allocations: 0 / 1.854 GB, free: 182.6 MB / 1.311 GB
Notification: Performance of matching and sorting (n=752): time 0.08024/2.871, allocations: 34.51 MB / 1.888 GB, free: 147.8 MB / 1.311 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002018/2.871, allocations: 369.5 kB / 1.888 GB, free: 147.3 MB / 1.311 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.08285/2.954, allocations: 53.42 MB / 1.94 GB, free: 94.11 MB / 1.311 GB
Notification: Performance of collectPreVariables (initialization): time 0.003123/2.957, allocations: 269.7 kB / 1.941 GB, free: 93.84 MB / 1.311 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01401/2.971, allocations: 12.39 MB / 1.953 GB, free: 81.4 MB / 1.311 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003962/2.975, allocations: 2.584 MB / 1.955 GB, free: 78.78 MB / 1.311 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.007705/2.983, allocations: 5.652 MB / 1.961 GB, free: 73.11 MB / 1.311 GB
Notification: Performance of setup shared object (initialization): time 3.179e-05/2.983, allocations: 301.9 kB / 1.961 GB, free: 72.81 MB / 1.311 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.03738/3.02, allocations: 34.61 MB / 1.995 GB, free: 38.17 MB / 1.311 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.04344/3.064, allocations: 38.91 MB / 2.033 GB, free: 13.73 MB / 1.326 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.07661/3.14, allocations: 49.39 MB / 2.081 GB, free: 10.72 MB / 1.373 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001068/3.141, allocations: 32 kB / 2.081 GB, free: 10.69 MB / 1.373 GB
Notification: Performance of matching and sorting (n=2365) (initialization): time 0.6425/3.783, allocations: 81.15 MB / 2.16 GB, free: 456.2 MB / 1.404 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002513/3.783, allocations: 197.4 kB / 2.161 GB, free: 456.1 MB / 1.404 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.08204/3.865, allocations: 58.51 MB / 2.218 GB, free: 433.7 MB / 1.404 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1102/3.976, allocations: 36.9 MB / 2.254 GB, free: 400.7 MB / 1.404 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02141/3.997, allocations: 4.257 MB / 2.258 GB, free: 396.6 MB / 1.404 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02275/4.02, allocations: 32.36 MB / 2.29 GB, free: 362.3 MB / 1.404 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02885/4.049, allocations: 3.844 MB / 2.293 GB, free: 359.1 MB / 1.404 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003697/4.053, allocations: 0.6386 MB / 2.294 GB, free: 358.5 MB / 1.404 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0545/4.107, allocations: 47.73 MB / 2.341 GB, free: 310.8 MB / 1.404 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.04023/4.147, allocations: 38.86 MB / 2.378 GB, free: 270.4 MB / 1.404 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.06014/4.207, allocations: 49.29 MB / 2.427 GB, free: 219.6 MB / 1.404 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001014/4.208, allocations: 28 kB / 2.427 GB, free: 219.6 MB / 1.404 GB
Notification: Performance of matching and sorting (n=2365) (initialization_lambda0): time 0.1071/4.315, allocations: 80.13 MB / 2.505 GB, free: 140.7 MB / 1.404 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002194/4.315, allocations: 189.6 kB / 2.505 GB, free: 140.6 MB / 1.404 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.07565/4.391, allocations: 58.28 MB / 2.562 GB, free: 82.17 MB / 1.404 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007765/4.398, allocations: 2.98 MB / 2.565 GB, free: 79.15 MB / 1.404 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0203/4.419, allocations: 4.2 MB / 2.569 GB, free: 74.95 MB / 1.404 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02567/4.444, allocations: 31.86 MB / 2.6 GB, free: 41.79 MB / 1.404 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02864/4.473, allocations: 3.515 MB / 2.604 GB, free: 38.7 MB / 1.404 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003511/4.477, allocations: 0.6284 MB / 2.604 GB, free: 38.07 MB / 1.404 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 482
 * 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 (1580):
 * Single equations (assignments): 1525
 * 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.005335/4.482, allocations: 1.132 MB / 2.605 GB, free: 36.94 MB / 1.404 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.007868/4.49, allocations: 2.452 MB / 2.608 GB, free: 34.52 MB / 1.404 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05397/4.544, allocations: 25.13 MB / 2.632 GB, free: 9.285 MB / 1.404 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.04768/4.592, allocations: 20.82 MB / 2.653 GB, free: 4.277 MB / 1.42 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.5913/5.183, allocations: 26.3 MB / 2.678 GB, free: 474.3 MB / 1.42 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02074/5.204, allocations: 7.115 MB / 2.685 GB, free: 471.9 MB / 1.42 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01003/5.214, allocations: 235.3 kB / 2.685 GB, free: 471.8 MB / 1.42 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.023/5.237, allocations: 2.038 MB / 2.687 GB, free: 471.7 MB / 1.42 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002312/5.239, allocations: 0.4979 MB / 2.688 GB, free: 471.6 MB / 1.42 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.07148/5.311, allocations: 25.87 MB / 2.713 GB, free: 464.7 MB / 1.42 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.07123/5.382, allocations: 33.48 MB / 2.746 GB, free: 449.8 MB / 1.42 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01881/5.401, allocations: 10.17 MB / 2.756 GB, free: 444.3 MB / 1.42 GB
Notification: Performance of sorting global known variables: time 0.05365/5.454, allocations: 37.03 MB / 2.792 GB, free: 409.3 MB / 1.42 GB
Notification: Performance of Backend: time 1.152e-06/5.454, allocations: 0 / 2.792 GB, free: 409.3 MB / 1.42 GB
Notification: Performance of simCode: created initialization part: time 0.1211/5.576, allocations: 89.79 MB / 2.88 GB, free: 320 MB / 1.42 GB
Notification: Performance of SimCode: time 0.125/5.701, allocations: 73.5 MB / 2.951 GB, free: 246.8 MB / 1.42 GB
Notification: Performance of Templates: time 1.695/7.395, allocations: 0.5995 GB / 3.551 GB, free: 170.9 MB / 1.42 GB
make -j1 -f Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.makefile
(rm -f Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.pipe ; mkfifo Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.pipe ; head -c 1048576 < Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.pipe >> ../files/Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.sim & ./Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases6xx.Case680FF.pipe 2>&1)