Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo", uses=false)
Using package Buildings with version 8.1.4 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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.Case950FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF")
translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001139/0.001139, allocations: 108.7 kB / 16.37 MB, free: 6.312 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.00114/0.00114, allocations: 191.2 kB / 17.3 MB, free: 5.871 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.126/1.126, allocations: 205.1 MB / 223.2 MB, free: 12.23 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/package.mo): time 1.561/1.561, allocations: 292.3 MB / 0.5496 GB, free: 17.58 MB / 462.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001305/0.001305, allocations: 71.17 kB / 0.6729 GB, free: 5.855 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.165/0.1664, allocations: 114.6 MB / 0.7848 GB, free: 3.188 MB / 0.6544 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF): time 0.4627/0.6291, allocations: 227.4 MB / 1.007 GB, free: 6.445 MB / 0.795 GB
Notification: Performance of NFInst.instExpressions: time 0.05447/0.6836, allocations: 42.32 MB / 1.048 GB, free: 12.02 MB / 0.8419 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008735/0.6924, allocations: 322.1 kB / 1.049 GB, free: 11.7 MB / 0.8419 GB
Notification: Performance of NFTyping.typeComponents: time 0.0159/0.7083, allocations: 7.593 MB / 1.056 GB, free: 4.07 MB / 0.8419 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Utilities/Strings.mo:216:3-259:11:writable] Warning: Pure function 'Modelica.Utilities.Strings.find' contains a call to impure function 'Modelica.Utilities.Strings.length'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getHeaderElementTMY3.mo:2:1-105:25:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3' contains a call to impure function 'Modelica.Utilities.Streams.readLine'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getLongitudeTMY3.mo:2:1-48:21:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getLongitudeTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getLatitudeTMY3.mo:2:1-48:20:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getLatitudeTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.1.4-maint.8.1.x/BoundaryConditions/WeatherData/BaseClasses/getTimeZoneTMY3.mo:2:1-47:20:writable] Warning: Pure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getTimeZoneTMY3' contains a call to impure function 'Buildings.BoundaryConditions.WeatherData.BaseClasses.getHeaderElementTMY3'.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Utilities/Strings.mo:139:3-175:14:writable] Warning: Pure function 'Modelica.Utilities.Strings.isEmpty' contains a call to impure function 'Modelica.Utilities.Strings.Advanced.skipWhiteSpace'.
Notification: Performance of NFTyping.typeBindings: time 0.01522/0.7236, allocations: 6.301 MB / 1.062 GB, free: 13.74 MB / 0.8575 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01266/0.7362, allocations: 5.931 MB / 1.068 GB, free: 7.828 MB / 0.8575 GB
Notification: Performance of NFFlatten.flatten: time 0.04587/0.7821, allocations: 48.67 MB / 1.115 GB, free: 7.125 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.3874/1.17, allocations: 23.4 MB / 1.138 GB, free: 25.58 MB / 0.92 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02686/1.196, allocations: 20.75 MB / 1.159 GB, free: 24.47 MB / 0.92 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02607/1.223, allocations: 17.06 MB / 1.175 GB, free: 21.54 MB / 0.92 GB
Notification: Performance of NFPackage.collectConstants: time 0.01053/1.233, allocations: 3.353 MB / 1.178 GB, free: 21.54 MB / 0.92 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01833/1.251, allocations: 7.438 MB / 1.186 GB, free: 21.54 MB / 0.92 GB
Notification: Performance of NFScalarize.scalarize: time 0.01461/1.266, allocations: 11.54 MB / 1.197 GB, free: 20.4 MB / 0.92 GB
Notification: Performance of NFVerifyModel.verify: time 0.02317/1.289, allocations: 16.72 MB / 1.213 GB, free: 15.91 MB / 0.92 GB
Notification: Performance of NFConvertDAE.convert: time 0.04253/1.332, allocations: 55.11 MB / 1.267 GB, free: 220 kB / 0.92 GB
Notification: Performance of FrontEnd - DAE generated: time 4.699e-06/1.332, allocations: 0 / 1.267 GB, free: 220 kB / 0.92 GB
Notification: Performance of FrontEnd: time 1.273e-06/1.332, allocations: 0.9688 kB / 1.267 GB, free: 220 kB / 0.92 GB
Notification: Performance of Transformations before backend: time 0.000992/1.333, allocations: 0 / 1.267 GB, free: 220 kB / 0.92 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2506
 * Number of variables: 2506
Notification: Performance of Generate backend data structure: time 0.08356/1.416, allocations: 36.46 MB / 1.303 GB, free: 4.828 MB / 0.9512 GB
Notification: Performance of prepare preOptimizeDAE: time 3.985e-05/1.417, allocations: 8.031 kB / 1.303 GB, free: 4.82 MB / 0.9512 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04855/1.465, allocations: 10.25 MB / 1.313 GB, free: 10.66 MB / 0.9669 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06472/1.53, allocations: 39.97 MB / 1.352 GB, free: 3.469 MB / 0.9981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001135/1.531, allocations: 1.598 MB / 1.353 GB, free: 1.719 MB / 0.9981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003556/1.535, allocations: 1.214 MB / 1.355 GB, free: 0.5078 MB / 0.9981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03518/1.57, allocations: 19 MB / 1.373 GB, free: 13.17 MB / 1.029 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001521/1.57, allocations: 19.81 kB / 1.373 GB, free: 13.16 MB / 1.029 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001117/1.571, allocations: 414.3 kB / 1.373 GB, free: 12.76 MB / 1.029 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002354/1.571, allocations: 347.1 kB / 1.374 GB, free: 12.42 MB / 1.029 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04198/1.613, allocations: 20.89 MB / 1.394 GB, free: 7.602 MB / 1.045 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.1565/1.77, allocations: 93.59 MB / 1.486 GB, free: 9.188 MB / 1.139 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02767/1.798, allocations: 14.08 MB / 1.499 GB, free: 11.09 MB / 1.154 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01568/1.813, allocations: 8.727 MB / 1.508 GB, free: 2.34 MB / 1.154 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001411/1.815, allocations: 0.9461 MB / 1.509 GB, free: 1.262 MB / 1.154 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01537/1.83, allocations: 8.219 MB / 1.517 GB, free: 8.984 MB / 1.17 GB
Notification: Performance of pre-optimization done (n=721): time 1.027e-05/1.83, allocations: 0 / 1.517 GB, free: 8.984 MB / 1.17 GB
Notification: Performance of matching and sorting (n=721): time 0.0636/1.894, allocations: 28.45 MB / 1.545 GB, free: 12.19 MB / 1.201 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002178/1.894, allocations: 386.5 kB / 1.545 GB, free: 11.77 MB / 1.201 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.4153/2.309, allocations: 53.86 MB / 1.598 GB, free: 489.6 MB / 1.201 GB
Notification: Performance of collectPreVariables (initialization): time 0.002884/2.312, allocations: 264.2 kB / 1.598 GB, free: 489.4 MB / 1.201 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01571/2.328, allocations: 12.39 MB / 1.61 GB, free: 481.3 MB / 1.201 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004112/2.332, allocations: 2.535 MB / 1.612 GB, free: 480.2 MB / 1.201 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.008263/2.34, allocations: 5.626 MB / 1.618 GB, free: 477.9 MB / 1.201 GB
Notification: Performance of setup shared object (initialization): time 3.407e-05/2.34, allocations: 301.1 kB / 1.618 GB, free: 477.6 MB / 1.201 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.03428/2.375, allocations: 32.92 MB / 1.65 GB, free: 466.4 MB / 1.201 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.03787/2.413, allocations: 37.41 MB / 1.687 GB, free: 446.3 MB / 1.201 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0494/2.462, allocations: 46.81 MB / 1.733 GB, free: 402.7 MB / 1.201 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001083/2.462, allocations: 32 kB / 1.733 GB, free: 402.7 MB / 1.201 GB
Notification: Performance of matching and sorting (n=2379) (initialization): time 0.08993/2.552, allocations: 77.13 MB / 1.808 GB, free: 329.8 MB / 1.201 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001998/2.552, allocations: 193.6 kB / 1.808 GB, free: 329.6 MB / 1.201 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.06461/2.617, allocations: 55 MB / 1.862 GB, free: 274.6 MB / 1.201 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009067/2.626, allocations: 3.651 MB / 1.865 GB, free: 271 MB / 1.201 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01631/2.642, allocations: 3.657 MB / 1.869 GB, free: 267.4 MB / 1.201 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01943/2.662, allocations: 28.8 MB / 1.897 GB, free: 237.1 MB / 1.201 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02598/2.688, allocations: 3.361 MB / 1.9 GB, free: 233.8 MB / 1.201 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003148/2.691, allocations: 0.6285 MB / 1.901 GB, free: 233.1 MB / 1.201 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0489/2.74, allocations: 45.99 MB / 1.946 GB, free: 187.2 MB / 1.201 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03534/2.775, allocations: 37.4 MB / 1.982 GB, free: 148.3 MB / 1.201 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.04951/2.825, allocations: 46.77 MB / 2.028 GB, free: 99.84 MB / 1.201 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 9.181e-05/2.825, allocations: 32 kB / 2.028 GB, free: 99.81 MB / 1.201 GB
Notification: Performance of matching and sorting (n=2379) (initialization_lambda0): time 0.09122/2.916, allocations: 77.6 MB / 2.104 GB, free: 22.1 MB / 1.201 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001985/2.916, allocations: 193.6 kB / 2.104 GB, free: 21.9 MB / 1.201 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.3834/3.3, allocations: 55.47 MB / 2.158 GB, free: 464.4 MB / 1.201 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009464/3.309, allocations: 3.556 MB / 2.162 GB, free: 464.3 MB / 1.201 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01739/3.327, allocations: 3.656 MB / 2.165 GB, free: 464.3 MB / 1.201 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03227/3.359, allocations: 39.21 MB / 2.204 GB, free: 442 MB / 1.201 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02585/3.385, allocations: 3.026 MB / 2.207 GB, free: 441.8 MB / 1.201 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003239/3.388, allocations: 0.6311 MB / 2.207 GB, free: 441.6 MB / 1.201 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 518
 * Number of states: 0 ()
 * Number of discrete variables: 30 ($PRE.vent.tab.nextTimeEvent,$whenCondition1,TRooAnn.mode,$PRE.TRooAnn.mode,$whenCondition2,TRooHou.mode,$PRE.TRooHou.mode,$whenCondition3,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,roo.solRadExc.isFlo[11],roo.solRadExc.isFlo[10],roo.solRadExc.isFlo[9],roo.solRadExc.isFlo[8],roo.solRadExc.isFlo[7],roo.solRadExc.isFlo[6],roo.solRadExc.isFlo[5],roo.solRadExc.isFlo[4],roo.solRadExc.isFlo[3],roo.solRadExc.isFlo[2],roo.solRadExc.isFlo[1],vent.tab.nextTimeEventScaled,$PRE.vent.tab.nextTimeEventScaled,vent.tab.nextTimeEvent,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,$whenCondition4,$whenCondition5)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1565):
 * Single equations (assignments): 1516
 * Array equations: 36
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 12
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 11 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%), (18,25,16.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(11,44)}
Notification: Performance of prepare postOptimizeDAE: time 0.002932/3.391, allocations: 1.094 MB / 2.208 GB, free: 441.1 MB / 1.201 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006747/3.398, allocations: 2.478 MB / 2.211 GB, free: 441.1 MB / 1.201 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03928/3.437, allocations: 20.44 MB / 2.231 GB, free: 434.3 MB / 1.201 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02667/3.464, allocations: 16.44 MB / 2.247 GB, free: 428.6 MB / 1.201 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.585e-05/3.464, allocations: 3.969 kB / 2.247 GB, free: 428.6 MB / 1.201 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01501/3.479, allocations: 6.846 MB / 2.253 GB, free: 425.3 MB / 1.201 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.007396/3.486, allocations: 202.8 kB / 2.254 GB, free: 425.2 MB / 1.201 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01642/3.503, allocations: 1.531 MB / 2.255 GB, free: 425.1 MB / 1.201 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001186/3.504, allocations: 480.2 kB / 2.256 GB, free: 425 MB / 1.201 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0374/3.541, allocations: 19.92 MB / 2.275 GB, free: 414.9 MB / 1.201 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.04663/3.588, allocations: 28.04 MB / 2.302 GB, free: 386.8 MB / 1.201 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01196/3.6, allocations: 8.225 MB / 2.31 GB, free: 378.6 MB / 1.201 GB
Notification: Performance of sorting global known variables: time 0.05108/3.651, allocations: 37.27 MB / 2.347 GB, free: 341.5 MB / 1.201 GB
Notification: Performance of Backend: time 1.213e-06/3.651, allocations: 6.812 kB / 2.347 GB, free: 341.5 MB / 1.201 GB
Notification: Performance of simCode: created initialization part: time 0.1025/3.753, allocations: 87.47 MB / 2.432 GB, free: 254.7 MB / 1.201 GB
Notification: Performance of SimCode: time 0.1114/3.865, allocations: 73.99 MB / 2.504 GB, free: 181.1 MB / 1.201 GB
Notification: Performance of Templates: time 1.243/5.107, allocations: 0.5621 GB / 3.067 GB, free: 167 MB / 1.201 GB
make -j1 -f Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.makefile
(rm -f Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.pipe ; mkfifo Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.pipe ; head -c 1048576 < Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.pipe >> ../files/Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.sim & ./Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case950FF.pipe 2>&1)