Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001339/0.001339, allocations: 82.09 kB / 19.18 MB, free: 2.527 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001535/0.001535, allocations: 173.7 kB / 22.46 MB, free: 5.355 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.436/1.436, allocations: 177.2 MB / 202.8 MB, free: 9.172 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:686:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-531:694:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 1.178/1.178, allocations: 154.7 MB / 413.9 MB, free: 5.168 MB / 382.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice")
translateModel(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.393e-06/1.393e-06, allocations: 0 / 0.6187 GB, free: 4.59 MB / 0.5294 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.353e-05/2.493e-05, allocations: 5.516 kB / 0.6187 GB, free: 4.586 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice): time 0.4571/0.4571, allocations: 99.5 MB / 0.7159 GB, free: 19.64 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.05116/0.5083, allocations: 32.33 MB / 0.7475 GB, free: 15.85 MB / 0.6075 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008428/0.5167, allocations: 189.1 kB / 0.7477 GB, free: 15.85 MB / 0.6075 GB
Notification: Performance of NFTyping.typeComponents: time 0.009471/0.5262, allocations: 2.791 MB / 0.7504 GB, free: 14.91 MB / 0.6075 GB
Notification: Performance of NFTyping.typeBindings: time 0.0204/0.5466, allocations: 5.891 MB / 0.7561 GB, free: 12.22 MB / 0.6075 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01219/0.5588, allocations: 4.126 MB / 0.7602 GB, free: 10.27 MB / 0.6075 GB
Notification: Performance of NFFlatten.flatten: time 0.2253/0.7841, allocations: 201.8 MB / 0.9573 GB, free: 15.02 MB / 0.795 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.1177/0.9018, allocations: 51.77 MB / 1.008 GB, free: 11.03 MB / 0.8419 GB
Notification: Performance of NFEvalConstants.evaluate: time 1.058/1.96, allocations: 82.07 MB / 1.088 GB, free: 47.16 MB / 0.8419 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1063/2.066, allocations: 49.72 MB / 1.137 GB, free: 44.45 MB / 0.8419 GB
Notification: Performance of NFPackage.collectConstants: time 0.03285/2.099, allocations: 6.808 MB / 1.143 GB, free: 44.45 MB / 0.8419 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.05821/2.157, allocations: 9.44 MB / 1.152 GB, free: 44.45 MB / 0.8419 GB
Notification: Performance of NFScalarize.scalarize: time 0.04777/2.205, allocations: 18.89 MB / 1.171 GB, free: 41.61 MB / 0.8419 GB
Notification: Performance of NFVerifyModel.verify: time 0.08625/2.291, allocations: 25.68 MB / 1.196 GB, free: 38.07 MB / 0.8419 GB
Notification: Performance of NFConvertDAE.convert: time 0.1601/2.452, allocations: 107.6 MB / 1.301 GB, free: 1.523 MB / 0.9044 GB
Notification: Performance of FrontEnd - DAE generated: time 9.538e-06/2.452, allocations: 6.719 kB / 1.301 GB, free: 1.52 MB / 0.9044 GB
Notification: Performance of FrontEnd: time 3.707e-06/2.452, allocations: 7.984 kB / 1.301 GB, free: 1.512 MB / 0.9044 GB
Notification: Performance of Transformations before backend: time 0.004168/2.456, allocations: 0 / 1.301 GB, free: 1.512 MB / 0.9044 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 10353
 * Number of variables: 10353
Notification: Performance of Generate backend data structure: time 0.21/2.666, allocations: 67.91 MB / 1.367 GB, free: 14.28 MB / 0.9825 GB
Notification: Performance of prepare preOptimizeDAE: time 5.446e-05/2.666, allocations: 10.84 kB / 1.367 GB, free: 14.27 MB / 0.9825 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05505/2.721, allocations: 8.344 MB / 1.375 GB, free: 5.883 MB / 0.9825 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.9479/3.669, allocations: 66.42 MB / 1.44 GB, free: 334.2 MB / 0.9983 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00412/3.673, allocations: 2.995 MB / 1.443 GB, free: 332.8 MB / 0.9983 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.02187/3.695, allocations: 4.157 MB / 1.447 GB, free: 331.5 MB / 0.9983 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1886/3.883, allocations: 74.87 MB / 1.52 GB, free: 269.3 MB / 0.9983 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.00365/3.887, allocations: 269.8 kB / 1.521 GB, free: 269 MB / 0.9983 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01913/3.906, allocations: 1.708 MB / 1.522 GB, free: 267.4 MB / 0.9983 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00179/3.908, allocations: 1.819 MB / 1.524 GB, free: 265.5 MB / 0.9983 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1245/4.032, allocations: 50.66 MB / 1.574 GB, free: 214.9 MB / 0.9983 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.4091/4.441, allocations: 198.7 MB / 1.768 GB, free: 18.13 MB / 0.9983 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.8625/5.304, allocations: 28.5 MB / 1.795 GB, free: 310 MB / 0.9985 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.04947/5.353, allocations: 17.78 MB / 1.813 GB, free: 305.3 MB / 0.9985 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.172/5.525, allocations: 51.68 MB / 1.863 GB, free: 288 MB / 0.9985 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0008366/5.526, allocations: 395.1 kB / 1.864 GB, free: 287.8 MB / 0.9985 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02694/5.553, allocations: 1.96 MB / 1.866 GB, free: 287.4 MB / 0.9985 GB
Notification: Performance of pre-optimization done (n=2331): time 0.0002064/5.553, allocations: 1.312 kB / 1.866 GB, free: 287.4 MB / 0.9985 GB
Notification: Performance of matching and sorting (n=2666): time 0.3026/5.856, allocations: 116.9 MB / 1.98 GB, free: 195.7 MB / 0.9985 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001844/5.856, allocations: 332.2 kB / 1.98 GB, free: 195.4 MB / 0.9985 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.1004/5.957, allocations: 45.03 MB / 2.024 GB, free: 151.1 MB / 0.9985 GB
Notification: Performance of collectPreVariables (initialization): time 0.008366/5.965, allocations: 0.5954 MB / 2.025 GB, free: 150.5 MB / 0.9985 GB
Notification: Performance of collectInitialEqns (initialization): time 0.02832/5.993, allocations: 24.65 MB / 2.049 GB, free: 128.3 MB / 0.9985 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01702/6.01, allocations: 9.932 MB / 2.058 GB, free: 119 MB / 0.9985 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.03286/6.043, allocations: 8.45 MB / 2.067 GB, free: 110.5 MB / 0.9985 GB
Notification: Performance of setup shared object (initialization): time 5.378e-05/6.043, allocations: 309 kB / 2.067 GB, free: 110.2 MB / 0.9985 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04114/6.084, allocations: 20.9 MB / 2.087 GB, free: 89.31 MB / 0.9985 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.05197/6.136, allocations: 29.84 MB / 2.116 GB, free: 56.22 MB / 0.9985 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.7066/6.843, allocations: 48.64 MB / 2.164 GB, free: 256.8 MB / 0.9985 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003844/6.843, allocations: 63.69 kB / 2.164 GB, free: 256.8 MB / 0.9985 GB
Notification: Performance of matching and sorting (n=5384) (initialization): time 0.1942/7.037, allocations: 66.54 MB / 2.229 GB, free: 240.2 MB / 0.9985 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006302/7.038, allocations: 101.6 kB / 2.229 GB, free: 240.2 MB / 0.9985 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0008433/7.039, allocations: 225.5 kB / 2.229 GB, free: 240.2 MB / 0.9985 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02636/7.065, allocations: 10.74 MB / 2.24 GB, free: 236.2 MB / 0.9985 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04954/7.115, allocations: 11.06 MB / 2.251 GB, free: 235.4 MB / 0.9985 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1929/7.308, allocations: 287.7 MB / 2.532 GB, free: 8.41 MB / 1.045 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05503/7.363, allocations: 2.647 MB / 2.534 GB, free: 5.812 MB / 1.045 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01255/7.375, allocations: 1.765 MB / 2.536 GB, free: 4.047 MB / 1.045 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.7469/8.122, allocations: 39.71 MB / 2.575 GB, free: 264.8 MB / 1.045 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.06273/8.185, allocations: 29.76 MB / 2.604 GB, free: 253.7 MB / 1.045 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.09881/8.284, allocations: 48.44 MB / 2.651 GB, free: 228.8 MB / 1.045 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0006057/8.284, allocations: 64.41 kB / 2.651 GB, free: 228.8 MB / 1.045 GB
Notification: Performance of matching and sorting (n=5384) (initialization_lambda0): time 0.1832/8.468, allocations: 66.2 MB / 2.716 GB, free: 187.8 MB / 1.045 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000195/8.468, allocations: 100 kB / 2.716 GB, free: 187.7 MB / 1.045 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003511/8.468, allocations: 224 kB / 2.716 GB, free: 187.5 MB / 1.045 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02432/8.492, allocations: 10.69 MB / 2.727 GB, free: 176.4 MB / 1.045 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04696/8.539, allocations: 11.03 MB / 2.737 GB, free: 165.6 MB / 1.045 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8044/9.344, allocations: 288 MB / 3.019 GB, free: 196.8 MB / 1.108 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04871/9.392, allocations: 2.66 MB / 3.021 GB, free: 196.8 MB / 1.108 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0108/9.403, allocations: 1.741 MB / 3.023 GB, free: 196.8 MB / 1.108 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 (4710):
 * Single equations (assignments): 4546
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * 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.01546/9.419, allocations: 2.456 MB / 3.025 GB, free: 196.7 MB / 1.108 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02/9.439, allocations: 4.278 MB / 3.029 GB, free: 196.7 MB / 1.108 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1005/9.539, allocations: 43.99 MB / 3.072 GB, free: 194.3 MB / 1.108 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002217/9.539, allocations: 260.3 kB / 3.073 GB, free: 194.3 MB / 1.108 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0004811/9.54, allocations: 103.2 kB / 3.073 GB, free: 194.3 MB / 1.108 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.07551/9.615, allocations: 37.32 MB / 3.109 GB, free: 181 MB / 1.108 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.2045/9.82, allocations: 82.44 MB / 3.19 GB, free: 141.1 MB / 1.108 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0002524/9.82, allocations: 4 kB / 3.19 GB, free: 141.1 MB / 1.108 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.03056/9.851, allocations: 6.503 MB / 3.196 GB, free: 134.8 MB / 1.108 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02104/9.872, allocations: 9.438 MB / 3.205 GB, free: 124.9 MB / 1.108 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.007505/9.879, allocations: 0.691 MB / 3.206 GB, free: 124.2 MB / 1.108 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.7798/10.66, allocations: 284.8 MB / 3.484 GB, free: 203.1 MB / 1.17 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.655e-06/10.66, allocations: 5.828 kB / 3.484 GB, free: 203.1 MB / 1.17 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1199/10.78, allocations: 63 MB / 3.546 GB, free: 195.9 MB / 1.17 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03133/10.81, allocations: 9.567 MB / 3.555 GB, free: 193.4 MB / 1.17 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01559/10.83, allocations: 0.5402 MB / 3.555 GB, free: 193 MB / 1.17 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.03792/10.86, allocations: 2.045 MB / 3.557 GB, free: 192.6 MB / 1.17 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.008206/10.87, allocations: 2 MB / 3.559 GB, free: 192.3 MB / 1.17 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.007024/10.88, allocations: 1.311 MB / 3.561 GB, free: 192.1 MB / 1.17 GB
Notification: Performance of sorting global known variables: time 0.06468/10.94, allocations: 31.04 MB / 3.591 GB, free: 182 MB / 1.17 GB
Notification: Performance of sort global known variables: time 3.41e-07/10.94, allocations: 3.781 kB / 3.591 GB, free: 182 MB / 1.17 GB
Notification: Performance of remove unused functions: time 0.1293/11.07, allocations: 25.7 MB / 3.616 GB, free: 171 MB / 1.17 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 166 (hea.vol.dynBal.U,aquWel.pumCol.vol.dynBal.U,aquWel.pumCol.motSpe.y,aquWel.pumHot.vol.dynBal.U,aquWel.pumHot.motSpe.y,aquWel.resAqu.mExc,aquWel.heaCapCol[1].T,aquWel.heaCapCol[2].T,aquWel.heaCapCol[3].T,aquWel.heaCapCol[4].T,aquWel.heaCapCol[5].T,aquWel.heaCapCol[6].T,aquWel.heaCapCol[7].T,aquWel.heaCapCol[8].T,aquWel.heaCapCol[9].T,aquWel.heaCapCol[10].T,aquWel.heaCapCol[11].T,aquWel.heaCapCol[12].T,aquWel.heaCapCol[13].T,aquWel.heaCapCol[14].T,aquWel.heaCapCol[15].T,aquWel.heaCapCol[16].T,aquWel.heaCapCol[17].T,aquWel.heaCapCol[18].T,aquWel.heaCapCol[19].T,aquWel.heaCapCol[20].T,aquWel.heaCapCol[21].T,aquWel.heaCapCol[22].T,aquWel.heaCapCol[23].T,aquWel.heaCapCol[24].T,aquWel.heaCapCol[25].T,aquWel.heaCapCol[26].T,aquWel.heaCapCol[27].T,aquWel.heaCapCol[28].T,aquWel.heaCapCol[29].T,aquWel.heaCapCol[30].T,aquWel.heaCapCol[31].T,aquWel.heaCapCol[32].T,aquWel.heaCapCol[33].T,aquWel.heaCapCol[34].T,aquWel.heaCapCol[35].T,aquWel.heaCapCol[36].T,aquWel.heaCapCol[37].T,aquWel.heaCapCol[38].T,aquWel.heaCapCol[39].T,aquWel.heaCapCol[40].T,aquWel.heaCapCol[41].T,aquWel.heaCapCol[42].T,aquWel.heaCapCol[43].T,aquWel.heaCapCol[44].T,aquWel.heaCapCol[45].T,aquWel.heaCapCol[46].T,aquWel.heaCapCol[47].T,aquWel.heaCapCol[48].T,aquWel.heaCapCol[49].T,aquWel.heaCapCol[50].T,aquWel.heaCapCol[51].T,aquWel.heaCapCol[52].T,aquWel.heaCapCol[53].T,aquWel.heaCapCol[54].T,aquWel.heaCapCol[55].T,aquWel.heaCapCol[56].T,aquWel.heaCapCol[57].T,aquWel.heaCapCol[58].T,aquWel.heaCapCol[59].T,aquWel.heaCapCol[60].T,aquWel.heaCapCol[61].T,aquWel.heaCapCol[62].T,aquWel.heaCapCol[63].T,aquWel.heaCapCol[64].T,aquWel.heaCapCol[65].T,aquWel.heaCapCol[66].T,aquWel.heaCapCol[67].T,aquWel.heaCapCol[68].T,aquWel.heaCapCol[69].T,aquWel.heaCapCol[70].T,aquWel.heaCapCol[71].T,aquWel.heaCapCol[72].T,aquWel.heaCapCol[73].T,aquWel.heaCapCol[74].T,aquWel.heaCapCol[75].T,aquWel.heaCapCol[76].T,aquWel.heaCapCol[77].T,aquWel.heaCapCol[78].T,aquWel.heaCapCol[79].T,aquWel.heaCapCol[80].T,aquWel.heaCapHot[1].T,aquWel.heaCapHot[2].T,aquWel.heaCapHot[3].T,aquWel.heaCapHot[4].T,aquWel.heaCapHot[5].T,aquWel.heaCapHot[6].T,aquWel.heaCapHot[7].T,aquWel.heaCapHot[8].T,aquWel.heaCapHot[9].T,aquWel.heaCapHot[10].T,aquWel.heaCapHot[11].T,aquWel.heaCapHot[12].T,aquWel.heaCapHot[13].T,aquWel.heaCapHot[14].T,aquWel.heaCapHot[15].T,aquWel.heaCapHot[16].T,aquWel.heaCapHot[17].T,aquWel.heaCapHot[18].T,aquWel.heaCapHot[19].T,aquWel.heaCapHot[20].T,aquWel.heaCapHot[21].T,aquWel.heaCapHot[22].T,aquWel.heaCapHot[23].T,aquWel.heaCapHot[24].T,aquWel.heaCapHot[25].T,aquWel.heaCapHot[26].T,aquWel.heaCapHot[27].T,aquWel.heaCapHot[28].T,aquWel.heaCapHot[29].T,aquWel.heaCapHot[30].T,aquWel.heaCapHot[31].T,aquWel.heaCapHot[32].T,aquWel.heaCapHot[33].T,aquWel.heaCapHot[34].T,aquWel.heaCapHot[35].T,aquWel.heaCapHot[36].T,aquWel.heaCapHot[37].T,aquWel.heaCapHot[38].T,aquWel.heaCapHot[39].T,aquWel.heaCapHot[40].T,aquWel.heaCapHot[41].T,aquWel.heaCapHot[42].T,aquWel.heaCapHot[43].T,aquWel.heaCapHot[44].T,aquWel.heaCapHot[45].T,aquWel.heaCapHot[46].T,aquWel.heaCapHot[47].T,aquWel.heaCapHot[48].T,aquWel.heaCapHot[49].T,aquWel.heaCapHot[50].T,aquWel.heaCapHot[51].T,aquWel.heaCapHot[52].T,aquWel.heaCapHot[53].T,aquWel.heaCapHot[54].T,aquWel.heaCapHot[55].T,aquWel.heaCapHot[56].T,aquWel.heaCapHot[57].T,aquWel.heaCapHot[58].T,aquWel.heaCapHot[59].T,aquWel.heaCapHot[60].T,aquWel.heaCapHot[61].T,aquWel.heaCapHot[62].T,aquWel.heaCapHot[63].T,aquWel.heaCapHot[64].T,aquWel.heaCapHot[65].T,aquWel.heaCapHot[66].T,aquWel.heaCapHot[67].T,aquWel.heaCapHot[68].T,aquWel.heaCapHot[69].T,aquWel.heaCapHot[70].T,aquWel.heaCapHot[71].T,aquWel.heaCapHot[72].T,aquWel.heaCapHot[73].T,aquWel.heaCapHot[74].T,aquWel.heaCapHot[75].T,aquWel.heaCapHot[76].T,aquWel.heaCapHot[77].T,aquWel.heaCapHot[78].T,aquWel.heaCapHot[79].T,aquWel.heaCapHot[80].T)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (2034):
 * Single equations (assignments): 1865
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 7
 * 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,17)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.009315/11.08, allocations: 1.939 MB / 3.618 GB, free: 169.6 MB / 1.17 GB
Notification: Performance of simCode: created initialization part: time 0.1607/11.24, allocations: 111.4 MB / 3.727 GB, free: 68.1 MB / 1.17 GB
Notification: Performance of simCode: created event and clocks part: time 4.332e-05/11.24, allocations: 35.86 kB / 3.727 GB, free: 68.07 MB / 1.17 GB
Notification: Performance of simCode: created simulation system equations: time 0.05221/11.3, allocations: 37.47 MB / 3.763 GB, free: 28.95 MB / 1.17 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1384/11.43, allocations: 26.97 MB / 3.79 GB, free: 3.074 MB / 1.17 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.8526/12.29, allocations: 122.6 MB / 3.909 GB, free: 252.6 MB / 1.217 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.07458/12.36, allocations: 34.38 MB / 3.943 GB, free: 250 MB / 1.217 GB
Notification: Performance of simCode: alias equations: time 0.1029/12.46, allocations: 27.19 MB / 3.97 GB, free: 245.8 MB / 1.217 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0191/12.48, allocations: 3.382 MB / 3.973 GB, free: 245.8 MB / 1.217 GB
Notification: Performance of SimCode: time 1.863e-06/12.48, allocations: 4 kB / 3.973 GB, free: 245.8 MB / 1.217 GB
Notification: Performance of Templates: time 2.68/15.16, allocations: 1.058 GB / 5.03 GB, free: 241.2 MB / 1.327 GB
"
[Timeout remaining time 645]
make -j1 -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; mkfifo IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe >> ../files/IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.sim & ./IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 55.59719197079539]
Failed to read output from testmodel.py, exit status != 0:
15.173748042434454 15.183580104 12.729180016
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f00a29f11e0>