Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.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/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-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(IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice")
translateModel(IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001188/0.001188, allocations: 108 kB / 17.08 MB, free: 5.816 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.00117/0.00117, allocations: 200.2 kB / 18.03 MB, free: 4.93 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.392/1.392, allocations: 222.9 MB / 241.7 MB, free: 15.17 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.8461/0.8461, allocations: 122.8 MB / 414.7 MB, free: 15.77 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001276/0.001276, allocations: 68.17 kB / 0.5691 GB, free: 28.72 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.173/0.1743, allocations: 77.93 MB / 0.6452 GB, free: 9.02 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice): time 0.5221/0.6964, allocations: 109.1 MB / 0.7517 GB, free: 11.43 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.05009/0.7466, allocations: 34.62 MB / 0.7855 GB, free: 10.98 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00742/0.7541, allocations: 146.2 kB / 0.7857 GB, free: 10.98 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.009484/0.7636, allocations: 2.81 MB / 0.7884 GB, free: 10.92 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.01761/0.7812, allocations: 5.766 MB / 0.794 GB, free: 9.48 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01029/0.7915, allocations: 3.995 MB / 0.7979 GB, free: 7.527 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.17/0.9616, allocations: 189.4 MB / 0.9829 GB, free: 12.44 MB / 0.7481 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1423/1.104, allocations: 76.82 MB / 1.058 GB, free: 356 kB / 0.8106 GB
Notification: Performance of NFEvalConstants.evaluate: time 1.14/2.244, allocations: 57.36 MB / 1.114 GB, free: 75.81 MB / 0.8107 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1043/2.348, allocations: 48.88 MB / 1.162 GB, free: 67.74 MB / 0.8107 GB
Notification: Performance of NFPackage.collectConstants: time 0.03429/2.383, allocations: 6.848 MB / 1.168 GB, free: 67.74 MB / 0.8107 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06736/2.45, allocations: 9.974 MB / 1.178 GB, free: 67.74 MB / 0.8107 GB
Notification: Performance of NFScalarize.scalarize: time 0.05374/2.504, allocations: 18.03 MB / 1.196 GB, free: 65.49 MB / 0.8107 GB
Notification: Performance of NFVerifyModel.verify: time 0.1078/2.612, allocations: 36.73 MB / 1.232 GB, free: 44.83 MB / 0.8107 GB
Notification: Performance of NFConvertDAE.convert: time 0.1547/2.767, allocations: 105.8 MB / 1.335 GB, free: 1.406 MB / 0.8575 GB
Notification: Performance of FrontEnd - DAE generated: time 6.512e-06/2.767, allocations: 0 / 1.335 GB, free: 1.406 MB / 0.8575 GB
Notification: Performance of FrontEnd: time 1.162e-06/2.767, allocations: 4 kB / 1.335 GB, free: 1.402 MB / 0.8575 GB
Notification: Performance of Transformations before backend: time 0.004443/2.771, allocations: 0 / 1.335 GB, free: 1.402 MB / 0.8575 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 10359
 * Number of variables: 10359
Notification: Performance of Generate backend data structure: time 1.232/4.003, allocations: 66.35 MB / 1.4 GB, free: 311.2 MB / 0.9202 GB
Notification: Performance of prepare preOptimizeDAE: time 5.968e-05/4.003, allocations: 10.19 kB / 1.4 GB, free: 311.2 MB / 0.9202 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05095/4.054, allocations: 7.263 MB / 1.407 GB, free: 309.5 MB / 0.9202 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.1609/4.215, allocations: 65.08 MB / 1.47 GB, free: 272.7 MB / 0.9202 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003075/4.218, allocations: 2.996 MB / 1.473 GB, free: 269.7 MB / 0.9202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01914/4.237, allocations: 4.146 MB / 1.477 GB, free: 265.6 MB / 0.9202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1602/4.397, allocations: 62.58 MB / 1.539 GB, free: 201.1 MB / 0.9202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.003114/4.4, allocations: 265.7 kB / 1.539 GB, free: 200.9 MB / 0.9202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01855/4.419, allocations: 1.713 MB / 1.54 GB, free: 199.2 MB / 0.9202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001677/4.421, allocations: 1.824 MB / 1.542 GB, free: 197.4 MB / 0.9202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1255/4.546, allocations: 51.03 MB / 1.592 GB, free: 146.3 MB / 0.9202 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 1.672/6.218, allocations: 263 MB / 1.849 GB, free: 195.7 MB / 0.9517 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.06722/6.285, allocations: 30.39 MB / 1.879 GB, free: 193.2 MB / 0.9517 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03831/6.324, allocations: 19.32 MB / 1.897 GB, free: 186.3 MB / 0.9517 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1969/6.52, allocations: 60.06 MB / 1.956 GB, free: 152.5 MB / 0.9517 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0006144/6.521, allocations: 399.6 kB / 1.956 GB, free: 152.2 MB / 0.9517 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02939/6.551, allocations: 1.922 MB / 1.958 GB, free: 151.8 MB / 0.9517 GB
Notification: Performance of pre-optimization done (n=2335): time 0.0001277/6.551, allocations: 0 / 1.958 GB, free: 151.8 MB / 0.9517 GB
Notification: Performance of matching and sorting (n=2672): time 0.3143/6.865, allocations: 116.9 MB / 2.072 GB, free: 47.79 MB / 0.9517 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.00017/6.865, allocations: 328.3 kB / 2.073 GB, free: 47.46 MB / 0.9517 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.09383/6.959, allocations: 44.96 MB / 2.117 GB, free: 2.746 MB / 0.9517 GB
Notification: Performance of collectPreVariables (initialization): time 0.01084/6.97, allocations: 0.5915 MB / 2.117 GB, free: 2.148 MB / 0.9517 GB
Notification: Performance of collectInitialEqns (initialization): time 0.6888/7.659, allocations: 24.11 MB / 2.141 GB, free: 287.8 MB / 0.9517 GB
Notification: Performance of collectInitialBindings (initialization): time 0.02018/7.679, allocations: 9.516 MB / 2.15 GB, free: 281.9 MB / 0.9517 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.04551/7.725, allocations: 8.641 MB / 2.159 GB, free: 278 MB / 0.9517 GB
Notification: Performance of setup shared object (initialization): time 7.128e-05/7.725, allocations: 301.1 kB / 2.159 GB, free: 277.7 MB / 0.9517 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.05143/7.776, allocations: 21.66 MB / 2.18 GB, free: 270 MB / 0.9517 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05513/7.831, allocations: 30.2 MB / 2.209 GB, free: 253.1 MB / 0.9517 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1146/7.946, allocations: 50.05 MB / 2.258 GB, free: 223.7 MB / 0.9517 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004786/7.946, allocations: 64 kB / 2.258 GB, free: 223.7 MB / 0.9517 GB
Notification: Performance of matching and sorting (n=5524) (initialization): time 0.1658/8.112, allocations: 68.18 MB / 2.325 GB, free: 160.4 MB / 0.9517 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001743/8.112, allocations: 100 kB / 2.325 GB, free: 160.3 MB / 0.9517 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002944/8.113, allocations: 224 kB / 2.325 GB, free: 160.1 MB / 0.9517 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02324/8.136, allocations: 10.69 MB / 2.336 GB, free: 149 MB / 0.9517 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04746/8.183, allocations: 11.18 MB / 2.347 GB, free: 137.8 MB / 0.9517 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8666/9.05, allocations: 309.4 MB / 2.649 GB, free: 84.41 MB / 0.9517 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05696/9.107, allocations: 2.665 MB / 2.651 GB, free: 83.74 MB / 0.9517 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01231/9.119, allocations: 1.766 MB / 2.653 GB, free: 83.39 MB / 0.9517 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.09109/9.211, allocations: 40.32 MB / 2.693 GB, free: 68.92 MB / 0.9517 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.05537/9.266, allocations: 29.94 MB / 2.722 GB, free: 52.06 MB / 0.9517 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.09771/9.364, allocations: 49.56 MB / 2.77 GB, free: 11.71 MB / 0.9517 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0004213/9.364, allocations: 67.98 kB / 2.77 GB, free: 11.64 MB / 0.9517 GB
Notification: Performance of matching and sorting (n=5524) (initialization_lambda0): time 0.186/9.55, allocations: 67.52 MB / 2.836 GB, free: 9.777 MB / 1.014 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0005277/9.551, allocations: 100 kB / 2.836 GB, free: 9.68 MB / 1.014 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0006974/9.552, allocations: 231.9 kB / 2.836 GB, free: 9.453 MB / 1.014 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.6783/10.23, allocations: 10.65 MB / 2.847 GB, free: 259.4 MB / 1.014 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0528/10.28, allocations: 11.16 MB / 2.858 GB, free: 259.4 MB / 1.014 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1934/10.48, allocations: 309.7 MB / 3.16 GB, free: 0.7148 MB / 1.014 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05203/10.53, allocations: 2.674 MB / 3.163 GB, free: 15.73 MB / 1.03 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01115/10.54, allocations: 1.742 MB / 3.165 GB, free: 15.39 MB / 1.03 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1015
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (4786):
 * Single equations (assignments): 4620
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 162
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 160 systems
   {(1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,14), (1,14)}
Notification: Performance of prepare postOptimizeDAE: time 0.01684/10.56, allocations: 2.479 MB / 3.167 GB, free: 13.52 MB / 1.03 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01834/10.57, allocations: 4.085 MB / 3.171 GB, free: 13.52 MB / 1.03 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1336/10.71, allocations: 46.16 MB / 3.216 GB, free: 1.523 MB / 1.045 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.7273/11.44, allocations: 37.68 MB / 3.253 GB, free: 269.7 MB / 1.077 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001794/11.44, allocations: 4.719 kB / 3.253 GB, free: 269.7 MB / 1.077 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03926/11.48, allocations: 9.11 MB / 3.262 GB, free: 269.6 MB / 1.077 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.02078/11.5, allocations: 0.5444 MB / 3.262 GB, free: 269.6 MB / 1.077 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.04523/11.54, allocations: 1.95 MB / 3.264 GB, free: 269.5 MB / 1.077 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.008215/11.55, allocations: 2.008 MB / 3.266 GB, free: 269.5 MB / 1.077 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.1228/11.67, allocations: 50.57 MB / 3.315 GB, free: 266.3 MB / 1.077 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1248/11.8, allocations: 54.51 MB / 3.369 GB, free: 252.5 MB / 1.077 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.03532/11.83, allocations: 16.31 MB / 3.385 GB, free: 246.8 MB / 1.077 GB
Notification: Performance of sorting global known variables: time 0.07219/11.9, allocations: 31.2 MB / 3.415 GB, free: 225.2 MB / 1.077 GB
Notification: Performance of Backend: time 2.044e-06/11.9, allocations: 0 / 3.415 GB, free: 225.2 MB / 1.077 GB
Notification: Performance of simCode: created initialization part: time 0.2072/12.11, allocations: 106.5 MB / 3.519 GB, free: 117.2 MB / 1.077 GB
Notification: Performance of SimCode: time 0.3531/12.47, allocations: 141.2 MB / 3.657 GB, free: 9.543 MB / 1.108 GB
Notification: Performance of Templates: time 3.517/15.98, allocations: 1.143 GB / 4.8 GB, free: 138.5 MB / 1.17 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.sim & ./IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe 2>&1)