Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.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/NcDataReader2 2.5.1-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo", uses=false)
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 2.5.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(BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF")
translateModel(BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001493/0.001493, allocations: 110.5 kB / 16.42 MB, free: 6.504 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.00172/0.00172, allocations: 185.4 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.292/1.292, allocations: 222.9 MB / 241.1 MB, free: 15.2 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 2.5.1-master/package.mo): time 0.002128/0.002128, allocations: 288.7 kB / 291.5 MB, free: 14.48 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems 2.0.0-master/package.mo): time 0.9183/0.9183, allocations: 169.3 MB / 0.499 GB, free: 4.434 MB / 382.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.003143/0.003143, allocations: 87.86 kB / 0.6821 GB, free: 6.988 MB / 494.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3777/0.3809, allocations: 86.98 MB / 0.767 GB, free: 3.914 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF): time 0.01051/0.3914, allocations: 9.748 MB / 0.7766 GB, free: 10.12 MB / 0.545 GB
Notification: Performance of NFInst.instExpressions: time 0.146/0.5375, allocations: 153.2 MB / 0.9262 GB, free: 332 kB / 0.6856 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001581/0.5391, allocations: 39.81 kB / 0.9262 GB, free: 292 kB / 0.6856 GB
Notification: Performance of NFTyping.typeComponents: time 0.00169/0.5408, allocations: 1.205 MB / 0.9274 GB, free: 15.07 MB / 0.7012 GB
Notification: Performance of NFTyping.typeBindings: time 0.002727/0.5435, allocations: 1.446 MB / 0.9288 GB, free: 13.62 MB / 0.7012 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003282/0.5468, allocations: 2 MB / 0.9307 GB, free: 11.63 MB / 0.7012 GB
Notification: Performance of NFFlatten.flatten: time 0.008312/0.5552, allocations: 10.15 MB / 0.9407 GB, free: 1.461 MB / 0.7012 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.008763/0.5639, allocations: 8.791 MB / 0.9492 GB, free: 8.559 MB / 0.7169 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004951/0.5689, allocations: 5.096 MB / 0.9542 GB, free: 3.449 MB / 0.7169 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.3358/0.9048, allocations: 5.699 MB / 0.9598 GB, free: 15.69 MB / 0.7169 GB
Notification: Performance of NFPackage.collectConstants: time 0.003757/0.9086, allocations: 1.791 MB / 0.9615 GB, free: 15.69 MB / 0.7169 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003487/0.9121, allocations: 2.219 MB / 0.9637 GB, free: 15.69 MB / 0.7169 GB
Notification: Performance of NFScalarize.scalarize: time 0.002398/0.9145, allocations: 3.319 MB / 0.9669 GB, free: 15.69 MB / 0.7169 GB
Notification: Performance of NFVerifyModel.verify: time 0.005525/0.92, allocations: 5.674 MB / 0.9725 GB, free: 15.69 MB / 0.7169 GB
Notification: Performance of NFConvertDAE.convert: time 0.01223/0.9322, allocations: 19.24 MB / 0.9913 GB, free: 14.2 MB / 0.7169 GB
Notification: Performance of FrontEnd - DAE generated: time 5.41e-06/0.9323, allocations: 1 kB / 0.9913 GB, free: 14.2 MB / 0.7169 GB
Notification: Performance of FrontEnd: time 1.883e-06/0.9323, allocations: 3.375 kB / 0.9913 GB, free: 14.2 MB / 0.7169 GB
Notification: Performance of Transformations before backend: time 0.0003701/0.9327, allocations: 0 / 0.9913 GB, free: 14.2 MB / 0.7169 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2188
 * Number of variables: 2188
Notification: Performance of Generate backend data structure: time 0.02815/0.9608, allocations: 10.52 MB / 1.002 GB, free: 10.73 MB / 0.7169 GB
Notification: Performance of prepare preOptimizeDAE: time 3.971e-05/0.9609, allocations: 8.562 kB / 1.002 GB, free: 10.73 MB / 0.7169 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0536/1.014, allocations: 10.86 MB / 1.012 GB, free: 7.039 MB / 0.7169 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01023/1.025, allocations: 5.524 MB / 1.018 GB, free: 5.426 MB / 0.7169 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005268/1.025, allocations: 0.626 MB / 1.018 GB, free: 5.402 MB / 0.7169 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003652/1.029, allocations: 0.8132 MB / 1.019 GB, free: 5.395 MB / 0.7169 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04403/1.073, allocations: 17.75 MB / 1.036 GB, free: 10.94 MB / 0.7325 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001301/1.073, allocations: 0 / 1.036 GB, free: 10.94 MB / 0.7325 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00124/1.074, allocations: 348 kB / 1.037 GB, free: 10.6 MB / 0.7325 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001323/1.075, allocations: 204 kB / 1.037 GB, free: 10.4 MB / 0.7325 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05862/1.133, allocations: 22.9 MB / 1.059 GB, free: 3.469 MB / 0.7481 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.07115/1.204, allocations: 39.24 MB / 1.097 GB, free: 12.71 MB / 0.795 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03571/1.24, allocations: 13.79 MB / 1.111 GB, free: 14.93 MB / 0.8106 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01948/1.26, allocations: 7.637 MB / 1.118 GB, free: 7.277 MB / 0.8106 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001131/1.261, allocations: 0.6398 MB / 1.119 GB, free: 6.594 MB / 0.8106 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02072/1.282, allocations: 8.564 MB / 1.127 GB, free: 13.96 MB / 0.8262 GB
Notification: Performance of pre-optimization done (n=643): time 8.566e-06/1.282, allocations: 4 kB / 1.127 GB, free: 13.96 MB / 0.8262 GB
Notification: Performance of matching and sorting (n=643): time 0.05682/1.338, allocations: 21.86 MB / 1.149 GB, free: 7.984 MB / 0.8419 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001441/1.339, allocations: 274 kB / 1.149 GB, free: 7.668 MB / 0.8419 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006964/1.345, allocations: 5.228 MB / 1.154 GB, free: 2.422 MB / 0.8419 GB
Notification: Performance of collectPreVariables (initialization): time 0.001047/1.347, allocations: 157.7 kB / 1.154 GB, free: 2.262 MB / 0.8419 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002641/1.349, allocations: 3.046 MB / 1.157 GB, free: 15.19 MB / 0.8575 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002206/1.351, allocations: 2.082 MB / 1.159 GB, free: 13.13 MB / 0.8575 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002766/1.354, allocations: 1.237 MB / 1.16 GB, free: 11.88 MB / 0.8575 GB
Notification: Performance of setup shared object (initialization): time 9.153e-05/1.354, allocations: 305.1 kB / 1.161 GB, free: 11.58 MB / 0.8575 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01734/1.372, allocations: 6.752 MB / 1.167 GB, free: 4.816 MB / 0.8575 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01785/1.389, allocations: 7.272 MB / 1.174 GB, free: 13.48 MB / 0.8731 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.03453/1.424, allocations: 13.48 MB / 1.188 GB, free: 15.9 MB / 0.8887 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.298e-05/1.424, allocations: 4 kB / 1.188 GB, free: 15.89 MB / 0.8887 GB
Notification: Performance of matching and sorting (n=744) (initialization): time 0.03848/1.463, allocations: 15.02 MB / 1.202 GB, free: 0.8242 MB / 0.8887 GB
Notification: Performance of prepare postOptimizeDAE: time 2.755e-05/1.463, allocations: 11.98 kB / 1.202 GB, free: 0.8125 MB / 0.8887 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.042e-05/1.463, allocations: 4 kB / 1.202 GB, free: 0.8086 MB / 0.8887 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0009137/1.464, allocations: 209 kB / 1.203 GB, free: 0.6016 MB / 0.8887 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01294/1.477, allocations: 2.318 MB / 1.205 GB, free: 14.35 MB / 0.9044 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004274/1.481, allocations: 3.933 MB / 1.209 GB, free: 10.16 MB / 0.9044 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01077/1.492, allocations: 1.575 MB / 1.21 GB, free: 8.574 MB / 0.9044 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008254/1.492, allocations: 299.4 kB / 1.21 GB, free: 8.285 MB / 0.9044 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * Number of states: 0 ()
 * Number of discrete variables: 76 ($PRE.daiComBESTESTFF.nextTimeEvent,$whenCondition1,daiComBESTESTFF.nextTimeEventScaled,$PRE.daiComBESTESTFF.nextTimeEventScaled,daiComBESTESTFF.nextTimeEvent,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,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,zon5R1C.opa.HDifTilRof.incAng.decAng.tNext,$PRE.zon5R1C.opa.HDifTilRof.incAng.decAng.tNext,zon5R1C.opa.HDirTilRoo.incAng.decAng.tNext,$PRE.zon5R1C.opa.HDirTilRoo.incAng.decAng.tNext,zon5R1C.opa.HDifTil[4].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDifTil[4].incAng.decAng.tNext,zon5R1C.opa.HDifTil[3].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDifTil[3].incAng.decAng.tNext,zon5R1C.opa.HDifTil[2].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDifTil[2].incAng.decAng.tNext,zon5R1C.opa.HDifTil[1].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDifTil[1].incAng.decAng.tNext,zon5R1C.opa.HDirTil[4].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDirTil[4].incAng.decAng.tNext,zon5R1C.opa.HDirTil[3].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDirTil[3].incAng.decAng.tNext,zon5R1C.opa.HDirTil[2].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDirTil[2].incAng.decAng.tNext,zon5R1C.opa.HDirTil[1].incAng.decAng.tNext,$PRE.zon5R1C.opa.HDirTil[1].incAng.decAng.tNext,zon5R1C.win.HDifTil[4].incAng.decAng.tNext,$PRE.zon5R1C.win.HDifTil[4].incAng.decAng.tNext,zon5R1C.win.HDifTil[3].incAng.decAng.tNext,$PRE.zon5R1C.win.HDifTil[3].incAng.decAng.tNext,zon5R1C.win.HDifTil[2].incAng.decAng.tNext,$PRE.zon5R1C.win.HDifTil[2].incAng.decAng.tNext,zon5R1C.win.HDifTil[1].incAng.decAng.tNext,$PRE.zon5R1C.win.HDifTil[1].incAng.decAng.tNext,zon5R1C.win.HDirTil[4].incAng.decAng.tNext,$PRE.zon5R1C.win.HDirTil[4].incAng.decAng.tNext,zon5R1C.win.HDirTil[3].incAng.decAng.tNext,$PRE.zon5R1C.win.HDirTil[3].incAng.decAng.tNext,zon5R1C.win.HDirTil[2].incAng.decAng.tNext,$PRE.zon5R1C.win.HDirTil[2].incAng.decAng.tNext,zon5R1C.win.HDirTil[1].incAng.decAng.tNext,$PRE.zon5R1C.win.HDirTil[1].incAng.decAng.tNext,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20,$whenCondition21,$whenCondition22,$whenCondition23,$whenCondition24)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (731):
 * Single equations (assignments): 729
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,11,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.005353/1.498, allocations: 2.958 MB / 1.213 GB, free: 5.379 MB / 0.9044 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001491/1.499, allocations: 0.5363 MB / 1.214 GB, free: 4.836 MB / 0.9044 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04479/1.544, allocations: 16.83 MB / 1.23 GB, free: 3.922 MB / 0.92 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.786e-05/1.544, allocations: 31.88 kB / 1.23 GB, free: 3.891 MB / 0.92 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.275e-05/1.544, allocations: 0 / 1.23 GB, free: 3.891 MB / 0.92 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004091/1.548, allocations: 2.339 MB / 1.233 GB, free: 1.523 MB / 0.92 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00133/1.55, allocations: 191.3 kB / 1.233 GB, free: 1.336 MB / 0.92 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009464/1.559, allocations: 1.224 MB / 1.234 GB, free: 116 kB / 0.92 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001033/1.56, allocations: 496.8 kB / 1.234 GB, free: 15.62 MB / 0.9356 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.04393/1.604, allocations: 16.13 MB / 1.25 GB, free: 15.53 MB / 0.9512 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.05648/1.661, allocations: 24.91 MB / 1.275 GB, free: 6.496 MB / 0.9669 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01702/1.678, allocations: 7.443 MB / 1.282 GB, free: 15.03 MB / 0.9825 GB
Notification: Performance of sorting global known variables: time 0.006468/1.684, allocations: 4.029 MB / 1.286 GB, free: 11 MB / 0.9825 GB
Notification: Performance of Backend: time 5.61e-07/1.684, allocations: 3.094 kB / 1.286 GB, free: 11 MB / 0.9825 GB
Notification: Performance of simCode: created initialization part: time 0.02683/1.711, allocations: 9.824 MB / 1.295 GB, free: 1.676 MB / 0.9825 GB
Notification: Performance of SimCode: time 0.0219/1.733, allocations: 14.47 MB / 1.309 GB, free: 3.191 MB / 0.9981 GB
Notification: Performance of Templates: time 0.437/2.17, allocations: 140.3 MB / 1.447 GB, free: 333.5 MB / 0.9981 GB
make -j1 -f BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.makefile
(rm -f BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.pipe ; mkfifo BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.pipe >> ../files/BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.sim & ./BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildingSystems_BuildingSystems.ThermalZones.ISO13790.Validation.BESTEST.Case900FF.pipe 2>&1)