Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_8_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 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.Case900FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF")
translateModel(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8_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.001039/0.001039, allocations: 105.2 kB / 16.36 MB, free: 6.258 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.001103/0.001103, allocations: 191.1 kB / 17.3 MB, free: 5.848 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.117/1.117, allocations: 205.1 MB / 223.2 MB, free: 12.2 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.529/1.529, allocations: 292.3 MB / 0.5496 GB, free: 17.51 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.00136/0.00136, allocations: 67.91 kB / 0.6729 GB, free: 5.809 MB / 0.545 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1659/0.1672, allocations: 114.5 MB / 0.7848 GB, free: 3.145 MB / 0.6544 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF): time 0.4664/0.6337, allocations: 227 MB / 1.007 GB, free: 6.785 MB / 0.795 GB
Notification: Performance of NFInst.instExpressions: time 0.052/0.6857, allocations: 42.02 MB / 1.048 GB, free: 12.66 MB / 0.8419 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.009032/0.6948, allocations: 322.1 kB / 1.048 GB, free: 12.34 MB / 0.8419 GB
Notification: Performance of NFTyping.typeComponents: time 0.01538/0.7102, allocations: 7.491 MB / 1.055 GB, free: 4.816 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.01558/0.7258, allocations: 6.236 MB / 1.061 GB, free: 14.56 MB / 0.8575 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01204/0.7379, allocations: 5.834 MB / 1.067 GB, free: 8.742 MB / 0.8575 GB
Notification: Performance of NFFlatten.flatten: time 0.04483/0.7827, allocations: 48.52 MB / 1.114 GB, free: 8.18 MB / 0.9044 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02565/0.8084, allocations: 23.34 MB / 1.137 GB, free: 0.7656 MB / 0.92 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.6869/1.495, allocations: 20.68 MB / 1.157 GB, free: 37.68 MB / 0.92 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02646/1.522, allocations: 16.98 MB / 1.174 GB, free: 34.77 MB / 0.92 GB
Notification: Performance of NFPackage.collectConstants: time 0.009727/1.532, allocations: 3.349 MB / 1.177 GB, free: 34.77 MB / 0.92 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0174/1.549, allocations: 7.376 MB / 1.184 GB, free: 34.77 MB / 0.92 GB
Notification: Performance of NFScalarize.scalarize: time 0.0139/1.563, allocations: 11.5 MB / 1.196 GB, free: 33.63 MB / 0.92 GB
Notification: Performance of NFVerifyModel.verify: time 0.02349/1.586, allocations: 16.65 MB / 1.212 GB, free: 29.16 MB / 0.92 GB
Notification: Performance of NFConvertDAE.convert: time 0.0453/1.632, allocations: 54.9 MB / 1.265 GB, free: 13.73 MB / 0.92 GB
Notification: Performance of FrontEnd - DAE generated: time 5.41e-06/1.632, allocations: 0.9688 kB / 1.265 GB, free: 13.73 MB / 0.92 GB
Notification: Performance of FrontEnd: time 1.383e-06/1.632, allocations: 0.75 kB / 1.265 GB, free: 13.73 MB / 0.92 GB
Notification: Performance of Transformations before backend: time 0.00113/1.633, allocations: 0.9688 kB / 1.265 GB, free: 13.73 MB / 0.92 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2500
 * Number of variables: 2500
Notification: Performance of Generate backend data structure: time 0.083/1.716, allocations: 36.27 MB / 1.301 GB, free: 13.35 MB / 0.9513 GB
Notification: Performance of prepare preOptimizeDAE: time 4.176e-05/1.716, allocations: 13 kB / 1.301 GB, free: 13.34 MB / 0.9513 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04691/1.763, allocations: 10.23 MB / 1.311 GB, free: 3.199 MB / 0.9513 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06613/1.829, allocations: 39.87 MB / 1.35 GB, free: 12.09 MB / 0.9982 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001213/1.83, allocations: 1.586 MB / 1.351 GB, free: 10.36 MB / 0.9982 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003983/1.834, allocations: 1.222 MB / 1.353 GB, free: 9.137 MB / 0.9982 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.036/1.87, allocations: 18.97 MB / 1.371 GB, free: 5.828 MB / 1.014 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001565/1.87, allocations: 20.66 kB / 1.371 GB, free: 5.82 MB / 1.014 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00116/1.872, allocations: 407.1 kB / 1.372 GB, free: 5.426 MB / 1.014 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002342/1.872, allocations: 348 kB / 1.372 GB, free: 5.086 MB / 1.014 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04252/1.914, allocations: 20.87 MB / 1.392 GB, free: 296 kB / 1.029 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.1545/2.069, allocations: 93.45 MB / 1.483 GB, free: 2 MB / 1.123 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02842/2.097, allocations: 14 MB / 1.497 GB, free: 3.984 MB / 1.139 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01591/2.113, allocations: 8.688 MB / 1.506 GB, free: 11.27 MB / 1.154 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001365/2.115, allocations: 0.9474 MB / 1.507 GB, free: 10.19 MB / 1.154 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01508/2.13, allocations: 8.185 MB / 1.515 GB, free: 1.949 MB / 1.154 GB
Notification: Performance of pre-optimization done (n=714): time 8.065e-06/2.13, allocations: 0 / 1.515 GB, free: 1.949 MB / 1.154 GB
Notification: Performance of matching and sorting (n=714): time 0.06487/2.195, allocations: 28.54 MB / 1.542 GB, free: 5.082 MB / 1.186 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002294/2.195, allocations: 383 kB / 1.543 GB, free: 4.656 MB / 1.186 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.4096/2.605, allocations: 53.63 MB / 1.595 GB, free: 476.6 MB / 1.186 GB
Notification: Performance of collectPreVariables (initialization): time 0.00285/2.607, allocations: 256 kB / 1.595 GB, free: 476.4 MB / 1.186 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0175/2.625, allocations: 12.32 MB / 1.607 GB, free: 468.4 MB / 1.186 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004132/2.629, allocations: 2.517 MB / 1.61 GB, free: 467.2 MB / 1.186 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01146/2.641, allocations: 5.602 MB / 1.615 GB, free: 464.9 MB / 1.186 GB
Notification: Performance of setup shared object (initialization): time 3.651e-05/2.641, allocations: 301.1 kB / 1.616 GB, free: 464.6 MB / 1.186 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.03362/2.674, allocations: 32.87 MB / 1.648 GB, free: 453.5 MB / 1.186 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.03689/2.711, allocations: 37.36 MB / 1.684 GB, free: 428.5 MB / 1.186 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04941/2.761, allocations: 46.73 MB / 1.73 GB, free: 385 MB / 1.186 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000116/2.761, allocations: 36 kB / 1.73 GB, free: 385 MB / 1.186 GB
Notification: Performance of matching and sorting (n=2364) (initialization): time 0.08847/2.849, allocations: 77.01 MB / 1.805 GB, free: 309.6 MB / 1.186 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000174/2.849, allocations: 189.6 kB / 1.805 GB, free: 309.5 MB / 1.186 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.05922/2.909, allocations: 54.89 MB / 1.859 GB, free: 254.6 MB / 1.186 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008825/2.918, allocations: 3.618 MB / 1.862 GB, free: 251 MB / 1.186 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01551/2.933, allocations: 3.636 MB / 1.866 GB, free: 247.4 MB / 1.186 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02014/2.953, allocations: 28.77 MB / 1.894 GB, free: 217.1 MB / 1.186 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0342/2.987, allocations: 3.353 MB / 1.897 GB, free: 213.8 MB / 1.186 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00347/2.991, allocations: 0.6189 MB / 1.898 GB, free: 213.2 MB / 1.186 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.04934/3.04, allocations: 45.9 MB / 1.943 GB, free: 167.3 MB / 1.186 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03536/3.076, allocations: 37.34 MB / 1.979 GB, free: 128.5 MB / 1.186 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0485/3.124, allocations: 46.69 MB / 2.025 GB, free: 80.14 MB / 1.186 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.835e-05/3.124, allocations: 32 kB / 2.025 GB, free: 80.11 MB / 1.186 GB
Notification: Performance of matching and sorting (n=2364) (initialization_lambda0): time 0.4215/3.546, allocations: 77.46 MB / 2.101 GB, free: 445.8 MB / 1.186 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002104/3.546, allocations: 192.1 kB / 2.101 GB, free: 445.8 MB / 1.186 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.07208/3.618, allocations: 55.35 MB / 2.155 GB, free: 439 MB / 1.186 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009034/3.627, allocations: 3.545 MB / 2.158 GB, free: 438.3 MB / 1.186 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01669/3.644, allocations: 3.631 MB / 2.162 GB, free: 438.2 MB / 1.186 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0327/3.677, allocations: 39.22 MB / 2.2 GB, free: 413.6 MB / 1.186 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02585/3.703, allocations: 3.014 MB / 2.203 GB, free: 413.4 MB / 1.186 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00331/3.706, allocations: 0.6243 MB / 2.204 GB, free: 413.3 MB / 1.186 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 517
 * Number of states: 0 ()
 * Number of discrete variables: 25 (TRooAnn.mode,$PRE.TRooAnn.mode,$whenCondition1,TRooHou.mode,$PRE.TRooHou.mode,$whenCondition2,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],weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,$whenCondition3,$whenCondition4)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1550):
 * Single equations (assignments): 1501
 * 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
   {(10,45)}
Notification: Performance of prepare postOptimizeDAE: time 0.002796/3.709, allocations: 1.09 MB / 2.205 GB, free: 412.7 MB / 1.186 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006718/3.715, allocations: 2.462 MB / 2.207 GB, free: 412.7 MB / 1.186 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03766/3.753, allocations: 20.36 MB / 2.227 GB, free: 406 MB / 1.186 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02801/3.781, allocations: 16.37 MB / 2.243 GB, free: 400.4 MB / 1.186 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.576e-05/3.781, allocations: 0 / 2.243 GB, free: 400.4 MB / 1.186 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01595/3.797, allocations: 6.816 MB / 2.25 GB, free: 397 MB / 1.186 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.007989/3.805, allocations: 205.6 kB / 2.25 GB, free: 396.8 MB / 1.186 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01697/3.822, allocations: 1.531 MB / 2.251 GB, free: 396.8 MB / 1.186 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001341/3.824, allocations: 473.4 kB / 2.252 GB, free: 396.7 MB / 1.186 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.04111/3.865, allocations: 19.83 MB / 2.271 GB, free: 378.4 MB / 1.186 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.04599/3.911, allocations: 27.93 MB / 2.298 GB, free: 350.5 MB / 1.186 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01201/3.923, allocations: 8.195 MB / 2.306 GB, free: 342.3 MB / 1.186 GB
Notification: Performance of sorting global known variables: time 0.05469/3.977, allocations: 37.17 MB / 2.343 GB, free: 305.3 MB / 1.186 GB
Notification: Performance of Backend: time 7.01e-07/3.978, allocations: 0 / 2.343 GB, free: 305.3 MB / 1.186 GB
Notification: Performance of simCode: created initialization part: time 0.1041/4.082, allocations: 86.96 MB / 2.428 GB, free: 219.1 MB / 1.186 GB
Notification: Performance of SimCode: time 0.1123/4.194, allocations: 73.67 MB / 2.5 GB, free: 145.8 MB / 1.186 GB
Notification: Performance of Templates: time 1.25/5.444, allocations: 0.5599 GB / 3.059 GB, free: 120.5 MB / 1.186 GB
make -j1 -f Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.makefile
(rm -f Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; mkfifo Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe ; head -c 1048576 < Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe >> ../files/Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.sim & ./Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8_Buildings.ThermalZones.Detailed.Validation.BESTEST.Cases9xx.Case900FF.pipe 2>&1)