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.001322/0.001322, allocations: 116 kB / 17.09 MB, free: 5.977 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.001417/0.001417, allocations: 198.8 kB / 18.03 MB, free: 5.051 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.291/1.291, allocations: 222.9 MB / 241.7 MB, free: 15.09 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7202/0.7202, allocations: 115.3 MB / 407.2 MB, free: 6.121 MB / 318.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001226/0.001226, allocations: 62.67 kB / 0.5583 GB, free: 32.2 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1627/0.1639, allocations: 76.18 MB / 0.6327 GB, free: 4.391 MB / 478.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Geothermal.Aquifer.Examples.CoolingOffice): time 0.1126/0.2765, allocations: 109.1 MB / 0.7392 GB, free: 6.93 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.4428/0.7193, allocations: 34.61 MB / 0.7731 GB, free: 11.25 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.007184/0.7266, allocations: 142.1 kB / 0.7732 GB, free: 11.25 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.008445/0.735, allocations: 2.767 MB / 0.7759 GB, free: 11.19 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.01669/0.7517, allocations: 5.518 MB / 0.7813 GB, free: 10.98 MB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01097/0.7627, allocations: 3.987 MB / 0.7852 GB, free: 10.89 MB / 0.5762 GB
Notification: Performance of NFFlatten.flatten: time 0.173/0.9357, allocations: 189.1 MB / 0.9698 GB, free: 5.594 MB / 0.7325 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1431/1.079, allocations: 76.81 MB / 1.045 GB, free: 9.512 MB / 0.8106 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.9311/2.01, allocations: 57.35 MB / 1.101 GB, free: 77.44 MB / 0.8107 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.07618/2.086, allocations: 48.89 MB / 1.149 GB, free: 69.39 MB / 0.8107 GB
Notification: Performance of NFPackage.collectConstants: time 0.02561/2.112, allocations: 6.847 MB / 1.155 GB, free: 69.39 MB / 0.8107 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04513/2.157, allocations: 9.967 MB / 1.165 GB, free: 69.39 MB / 0.8107 GB
Notification: Performance of NFScalarize.scalarize: time 0.03428/2.191, allocations: 18.04 MB / 1.183 GB, free: 67.14 MB / 0.8107 GB
Notification: Performance of NFVerifyModel.verify: time 0.07033/2.262, allocations: 36.73 MB / 1.218 GB, free: 47.62 MB / 0.8107 GB
Notification: Performance of NFConvertDAE.convert: time 0.1225/2.384, allocations: 105.8 MB / 1.322 GB, free: 11.96 MB / 0.8575 GB
Notification: Performance of FrontEnd - DAE generated: time 8.777e-06/2.384, allocations: 0 / 1.322 GB, free: 11.96 MB / 0.8575 GB
Notification: Performance of FrontEnd: time 2.043e-06/2.384, allocations: 0 / 1.322 GB, free: 11.96 MB / 0.8575 GB
Notification: Performance of Transformations before backend: time 0.003965/2.388, allocations: 0 / 1.322 GB, free: 11.96 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 0.2044/2.593, allocations: 66.35 MB / 1.387 GB, free: 10.41 MB / 0.92 GB
Notification: Performance of prepare preOptimizeDAE: time 6.192e-05/2.593, allocations: 12.03 kB / 1.387 GB, free: 10.4 MB / 0.92 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05369/2.646, allocations: 7.265 MB / 1.394 GB, free: 3.059 MB / 0.92 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.9205/3.567, allocations: 65.07 MB / 1.457 GB, free: 304.9 MB / 0.9202 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003364/3.57, allocations: 3.002 MB / 1.46 GB, free: 303.6 MB / 0.9202 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0173/3.588, allocations: 4.147 MB / 1.464 GB, free: 301.6 MB / 0.9202 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1481/3.736, allocations: 62.59 MB / 1.525 GB, free: 237.2 MB / 0.9202 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.002709/3.739, allocations: 262.5 kB / 1.526 GB, free: 236.9 MB / 0.9202 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01369/3.752, allocations: 1.707 MB / 1.527 GB, free: 235.2 MB / 0.9202 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001364/3.754, allocations: 1.831 MB / 1.529 GB, free: 233.4 MB / 0.9202 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1069/3.861, allocations: 51.02 MB / 1.579 GB, free: 182.4 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.183/5.044, allocations: 263 MB / 1.836 GB, free: 192.6 MB / 0.9204 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.05959/5.103, allocations: 30.39 MB / 1.865 GB, free: 188 MB / 0.9204 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.03254/5.136, allocations: 19.33 MB / 1.884 GB, free: 181.1 MB / 0.9204 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1656/5.302, allocations: 60.06 MB / 1.943 GB, free: 147.4 MB / 0.9204 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0005527/5.302, allocations: 397.7 kB / 1.943 GB, free: 147 MB / 0.9204 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02243/5.325, allocations: 1.925 MB / 1.945 GB, free: 146 MB / 0.9204 GB
Notification: Performance of pre-optimization done (n=2335): time 7.007e-05/5.325, allocations: 0 / 1.945 GB, free: 146 MB / 0.9204 GB
Notification: Performance of matching and sorting (n=2672): time 0.2994/5.624, allocations: 116.9 MB / 2.059 GB, free: 30.23 MB / 0.9204 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002636/5.625, allocations: 324.3 kB / 2.06 GB, free: 29.9 MB / 0.9204 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1083/5.733, allocations: 44.97 MB / 2.104 GB, free: 1.184 MB / 0.936 GB
Notification: Performance of collectPreVariables (initialization): time 0.008035/5.741, allocations: 0.5954 MB / 2.104 GB, free: 0.582 MB / 0.936 GB
Notification: Performance of collectInitialEqns (initialization): time 0.5557/6.297, allocations: 24.11 MB / 2.128 GB, free: 282.1 MB / 0.936 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01928/6.316, allocations: 9.511 MB / 2.137 GB, free: 276.1 MB / 0.936 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.04427/6.36, allocations: 8.637 MB / 2.145 GB, free: 272.2 MB / 0.936 GB
Notification: Performance of setup shared object (initialization): time 6.091e-05/6.36, allocations: 301.1 kB / 2.146 GB, free: 271.9 MB / 0.936 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04786/6.408, allocations: 21.66 MB / 2.167 GB, free: 264.3 MB / 0.936 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.06114/6.469, allocations: 30.19 MB / 2.196 GB, free: 247.4 MB / 0.936 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1052/6.575, allocations: 50.05 MB / 2.245 GB, free: 217.5 MB / 0.936 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0006065/6.575, allocations: 64 kB / 2.245 GB, free: 217.5 MB / 0.936 GB
Notification: Performance of matching and sorting (n=5524) (initialization): time 0.1824/6.758, allocations: 68.18 MB / 2.312 GB, free: 153.9 MB / 0.936 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0008722/6.759, allocations: 100 kB / 2.312 GB, free: 153.8 MB / 0.936 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001121/6.76, allocations: 231.9 kB / 2.312 GB, free: 153.6 MB / 0.936 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02466/6.785, allocations: 10.68 MB / 2.323 GB, free: 142.5 MB / 0.936 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04683/6.832, allocations: 11.18 MB / 2.334 GB, free: 131.3 MB / 0.936 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.7283/7.56, allocations: 309.4 MB / 2.636 GB, free: 75.67 MB / 0.936 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05405/7.614, allocations: 2.658 MB / 2.638 GB, free: 74.99 MB / 0.936 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01145/7.625, allocations: 1.758 MB / 2.64 GB, free: 74.65 MB / 0.936 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.09395/7.719, allocations: 40.34 MB / 2.679 GB, free: 60.17 MB / 0.936 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.06051/7.78, allocations: 29.93 MB / 2.709 GB, free: 43.28 MB / 0.936 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.09967/7.88, allocations: 49.56 MB / 2.757 GB, free: 1.328 MB / 0.936 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0004708/7.88, allocations: 64 kB / 2.757 GB, free: 1.266 MB / 0.936 GB
Notification: Performance of matching and sorting (n=5524) (initialization_lambda0): time 0.858/8.738, allocations: 67.52 MB / 2.823 GB, free: 243.2 MB / 0.9985 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002786/8.739, allocations: 100.9 kB / 2.823 GB, free: 243.2 MB / 0.9985 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0005816/8.739, allocations: 222.8 kB / 2.823 GB, free: 243.2 MB / 0.9985 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02962/8.769, allocations: 10.66 MB / 2.834 GB, free: 241.9 MB / 0.9985 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04949/8.818, allocations: 11.16 MB / 2.845 GB, free: 241.9 MB / 0.9985 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1969/9.015, allocations: 309.7 MB / 3.147 GB, free: 13.74 MB / 1.03 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05493/9.07, allocations: 2.656 MB / 3.15 GB, free: 12.76 MB / 1.03 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01102/9.081, allocations: 1.744 MB / 3.151 GB, free: 12.41 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.02148/9.103, allocations: 2.485 MB / 3.154 GB, free: 10.55 MB / 1.03 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02286/9.126, allocations: 4.077 MB / 3.158 GB, free: 10.55 MB / 1.03 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1415/9.267, allocations: 45.3 MB / 3.202 GB, free: 380 kB / 1.045 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.8529/10.12, allocations: 37.67 MB / 3.239 GB, free: 273.4 MB / 1.061 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001896/10.12, allocations: 0 / 3.239 GB, free: 273.4 MB / 1.061 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03985/10.16, allocations: 9.111 MB / 3.248 GB, free: 273.4 MB / 1.061 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.02694/10.19, allocations: 0.5505 MB / 3.248 GB, free: 273.4 MB / 1.061 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.04726/10.23, allocations: 1.95 MB / 3.25 GB, free: 273.3 MB / 1.061 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01008/10.24, allocations: 2.007 MB / 3.252 GB, free: 273.3 MB / 1.061 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.1368/10.38, allocations: 50.57 MB / 3.302 GB, free: 267.6 MB / 1.061 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.1447/10.53, allocations: 54.52 MB / 3.355 GB, free: 248.5 MB / 1.061 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.03378/10.56, allocations: 16.32 MB / 3.371 GB, free: 235.3 MB / 1.061 GB
Notification: Performance of sorting global known variables: time 0.07495/10.64, allocations: 31.2 MB / 3.401 GB, free: 204.3 MB / 1.061 GB
Notification: Performance of Backend: time 1.242e-06/10.64, allocations: 0 / 3.401 GB, free: 204.3 MB / 1.061 GB
Notification: Performance of simCode: created initialization part: time 0.2385/10.87, allocations: 106.5 MB / 3.505 GB, free: 96.08 MB / 1.061 GB
Notification: Performance of SimCode: time 1.012/11.89, allocations: 141.2 MB / 3.643 GB, free: 249.6 MB / 1.092 GB
Notification: Performance of Templates: time 2.82/14.71, allocations: 1.143 GB / 4.786 GB, free: 103.2 MB / 1.155 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)