Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.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.001321/0.001321, allocations: 99.64 kB / 18.95 MB, free: 2.668 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.001435/0.001435, allocations: 212 kB / 22.24 MB, free: 5.262 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.419/1.419, allocations: 230.6 MB / 256 MB, free: 7.781 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.002527/0.002527, allocations: 289.1 kB / 312.7 MB, free: 2.574 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.222/1.222, allocations: 180.6 MB / 0.5369 GB, free: 14.55 MB / 382.1 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/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: "" <> buildModelFMU(BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells,fileNamePrefix="BuildingSystems_BuildingSystems_Fluid_Geothermal_Aquifer_Validation_NumberWells",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells,fileNamePrefix="BuildingSystems_BuildingSystems_Fluid_Geothermal_Aquifer_Validation_NumberWells",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: NcDataReader2 requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: BuildingSystems requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002964/0.002964, allocations: 101.7 kB / 0.7395 GB, free: 28.12 MB / 0.5294 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.161/0.164, allocations: 95.39 MB / 0.8326 GB, free: 8.305 MB / 0.5762 GB
Notification: Performance of NFInst.instantiate(BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells): time 0.1723/0.3362, allocations: 149.5 MB / 0.9787 GB, free: 2.254 MB / 0.7169 GB
Notification: Performance of NFInst.instExpressions: time 0.5635/0.8997, allocations: 52.8 MB / 1.03 GB, free: 4.012 MB / 0.7169 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01089/0.9106, allocations: 326.2 kB / 1.031 GB, free: 4.012 MB / 0.7169 GB
Notification: Performance of NFTyping.typeComponents: time 0.01158/0.9222, allocations: 3.999 MB / 1.034 GB, free: 3.938 MB / 0.7169 GB
Notification: Performance of NFTyping.typeBindings: time 0.02613/0.9483, allocations: 9.691 MB / 1.044 GB, free: 2.438 MB / 0.7169 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01614/0.9645, allocations: 6.854 MB / 1.051 GB, free: 15.45 MB / 0.7325 GB
Notification: Performance of NFFlatten.flatten: time 0.2691/1.234, allocations: 257.3 MB / 1.302 GB, free: 8.664 MB / 0.9512 GB
Notification: Performance of NFFlatten.resolveConnections: time 1.185/2.418, allocations: 65.91 MB / 1.366 GB, free: 41.77 MB / 0.9982 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1358/2.554, allocations: 74.84 MB / 1.439 GB, free: 34.28 MB / 0.9982 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.1126/2.667, allocations: 69.74 MB / 1.507 GB, free: 24.11 MB / 0.9982 GB
Notification: Performance of NFPackage.collectConstants: time 0.03066/2.697, allocations: 8.598 MB / 1.516 GB, free: 24.11 MB / 0.9982 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06006/2.757, allocations: 12.08 MB / 1.528 GB, free: 24.11 MB / 0.9982 GB
Notification: Performance of NFScalarize.scalarize: time 0.04121/2.799, allocations: 23.97 MB / 1.551 GB, free: 20.46 MB / 0.9982 GB
Notification: Performance of NFVerifyModel.verify: time 0.08622/2.885, allocations: 37.72 MB / 1.588 GB, free: 9.234 MB / 0.9982 GB
Notification: Performance of NFConvertDAE.convert: time 0.1903/3.075, allocations: 142 MB / 1.727 GB, free: 10.89 MB / 1.123 GB
Notification: Performance of FrontEnd - DAE generated: time 6.883e-06/3.075, allocations: 4 kB / 1.727 GB, free: 10.89 MB / 1.123 GB
Notification: Performance of FrontEnd: time 1.943e-06/3.075, allocations: 0 / 1.727 GB, free: 10.89 MB / 1.123 GB
Notification: Performance of Transformations before backend: time 0.004267/3.079, allocations: 0 / 1.727 GB, free: 10.89 MB / 1.123 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 13128
 * Number of variables: 13128
Notification: Performance of Generate backend data structure: time 1.198/4.278, allocations: 86.47 MB / 1.811 GB, free: 444.8 MB / 1.186 GB
Notification: Performance of prepare preOptimizeDAE: time 7.033e-05/4.278, allocations: 10.62 kB / 1.811 GB, free: 444.8 MB / 1.186 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.03654/4.314, allocations: 6.93 MB / 1.818 GB, free: 442.8 MB / 1.186 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06656/4.381, allocations: 10.96 MB / 1.829 GB, free: 438.4 MB / 1.186 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.2006/4.581, allocations: 95.17 MB / 1.921 GB, free: 359.6 MB / 1.186 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003805/4.585, allocations: 3.808 MB / 1.925 GB, free: 355.8 MB / 1.186 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.02085/4.606, allocations: 5.274 MB / 1.93 GB, free: 350.6 MB / 1.186 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1972/4.803, allocations: 95.11 MB / 2.023 GB, free: 249.1 MB / 1.186 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.004051/4.807, allocations: 343.7 kB / 2.024 GB, free: 248.7 MB / 1.186 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.02111/4.828, allocations: 2.169 MB / 2.026 GB, free: 246.6 MB / 1.186 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002161/4.831, allocations: 2.304 MB / 2.028 GB, free: 244.3 MB / 1.186 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1401/4.971, allocations: 64.54 MB / 2.091 GB, free: 179.7 MB / 1.186 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.535/6.505, allocations: 260.3 MB / 2.345 GB, free: 279.2 MB / 1.186 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.06318/6.568, allocations: 36.7 MB / 2.381 GB, free: 273.8 MB / 1.186 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.04036/6.609, allocations: 22.85 MB / 2.403 GB, free: 264.7 MB / 1.186 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.2389/6.848, allocations: 82.17 MB / 2.484 GB, free: 217.9 MB / 1.186 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0005957/6.848, allocations: 498 kB / 2.484 GB, free: 217.4 MB / 1.186 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.03355/6.882, allocations: 2.562 MB / 2.487 GB, free: 216.3 MB / 1.186 GB
Notification: Performance of pre-optimization done (n=2960): time 8.776e-05/6.882, allocations: 0 / 2.487 GB, free: 216.3 MB / 1.186 GB
Notification: Performance of matching and sorting (n=3386): time 0.3544/7.236, allocations: 148.9 MB / 2.632 GB, free: 70.64 MB / 1.186 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002282/7.237, allocations: 397.9 kB / 2.632 GB, free: 70.23 MB / 1.186 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.121/7.358, allocations: 58.1 MB / 2.689 GB, free: 13.11 MB / 1.186 GB
Notification: Performance of collectPreVariables (initialization): time 0.008889/7.366, allocations: 0.7439 MB / 2.69 GB, free: 12.36 MB / 1.186 GB
Notification: Performance of collectInitialEqns (initialization): time 0.04317/7.41, allocations: 31.74 MB / 2.721 GB, free: 15.91 MB / 1.217 GB
Notification: Performance of collectInitialBindings (initialization): time 0.02458/7.434, allocations: 12.69 MB / 2.733 GB, free: 3.871 MB / 1.217 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 1.111/8.545, allocations: 11.19 MB / 2.744 GB, free: 389.8 MB / 1.218 GB
Notification: Performance of setup shared object (initialization): time 6.297e-05/8.545, allocations: 312.2 kB / 2.744 GB, free: 389.5 MB / 1.218 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.06185/8.607, allocations: 28.6 MB / 2.772 GB, free: 389.1 MB / 1.218 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.07242/8.679, allocations: 40.15 MB / 2.812 GB, free: 369.9 MB / 1.218 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1272/8.806, allocations: 65.83 MB / 2.876 GB, free: 335.6 MB / 1.218 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0005491/8.807, allocations: 85 kB / 2.876 GB, free: 335.6 MB / 1.218 GB
Notification: Performance of matching and sorting (n=7089) (initialization): time 0.2032/9.01, allocations: 89.39 MB / 2.963 GB, free: 258.1 MB / 1.218 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002607/9.01, allocations: 124 kB / 2.963 GB, free: 258 MB / 1.218 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004824/9.011, allocations: 293.4 kB / 2.964 GB, free: 257.7 MB / 1.218 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02996/9.041, allocations: 14.31 MB / 2.978 GB, free: 242.9 MB / 1.218 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.06006/9.101, allocations: 14.2 MB / 2.992 GB, free: 229 MB / 1.218 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.7824/9.883, allocations: 366.6 MB / 3.35 GB, free: 195.6 MB / 1.218 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05657/9.94, allocations: 3.344 MB / 3.353 GB, free: 195.6 MB / 1.218 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01308/9.953, allocations: 2.331 MB / 3.355 GB, free: 195.6 MB / 1.218 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.1002/10.05, allocations: 52.14 MB / 3.406 GB, free: 181.7 MB / 1.218 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.06743/10.12, allocations: 39.64 MB / 3.445 GB, free: 159.1 MB / 1.218 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.1152/10.24, allocations: 64.81 MB / 3.508 GB, free: 122.3 MB / 1.218 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0005071/10.24, allocations: 84 kB / 3.508 GB, free: 122.2 MB / 1.218 GB
Notification: Performance of matching and sorting (n=7089) (initialization_lambda0): time 0.2026/10.44, allocations: 88.06 MB / 3.594 GB, free: 37.58 MB / 1.218 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002438/10.44, allocations: 128 kB / 3.594 GB, free: 37.46 MB / 1.218 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004506/10.44, allocations: 284 kB / 3.594 GB, free: 37.18 MB / 1.218 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.03011/10.47, allocations: 14.27 MB / 3.608 GB, free: 22.29 MB / 1.218 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.05994/10.53, allocations: 14.16 MB / 3.622 GB, free: 8.102 MB / 1.218 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.9052/11.43, allocations: 367.2 MB / 3.981 GB, free: 84.37 MB / 1.296 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05777/11.49, allocations: 3.343 MB / 3.984 GB, free: 83.51 MB / 1.296 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01165/11.5, allocations: 2.293 MB / 3.986 GB, free: 83.05 MB / 1.296 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1302
 * 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 (6109):
 * Single equations (assignments): 5897
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 4
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 204
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 200 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%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (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): 4 systems
   {(1,10), (1,10), (1,10), (1,10)}
Notification: Performance of prepare postOptimizeDAE: time 0.02286/11.53, allocations: 3.103 MB / 3.989 GB, free: 80.8 MB / 1.296 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02566/11.55, allocations: 5.531 MB / 3.995 GB, free: 80.79 MB / 1.296 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1265/11.68, allocations: 57.37 MB / 4.051 GB, free: 58.28 MB / 1.296 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002702/11.68, allocations: 334.7 kB / 4.051 GB, free: 58.27 MB / 1.296 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0005889/11.68, allocations: 126.8 kB / 4.051 GB, free: 58.27 MB / 1.296 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.1072/11.79, allocations: 48.33 MB / 4.098 GB, free: 37.46 MB / 1.296 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.9954/12.78, allocations: 112.8 MB / 4.209 GB, free: 323.6 MB / 1.327 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003172/12.78, allocations: 3.609 kB / 4.209 GB, free: 323.6 MB / 1.327 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.03737/12.82, allocations: 8.261 MB / 4.217 GB, free: 323.6 MB / 1.327 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02678/12.85, allocations: 12.14 MB / 4.229 GB, free: 322.1 MB / 1.327 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.008038/12.86, allocations: 0.898 MB / 4.229 GB, free: 322.1 MB / 1.327 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2233/13.08, allocations: 361.8 MB / 4.583 GB, free: 50.41 MB / 1.358 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.565e-06/13.08, allocations: 3.812 kB / 4.583 GB, free: 50.41 MB / 1.358 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1695/13.25, allocations: 82.05 MB / 4.663 GB, free: 5.137 MB / 1.374 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.05151/13.3, allocations: 12.19 MB / 4.675 GB, free: 11.79 MB / 1.39 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.02244/13.32, allocations: 0.6952 MB / 4.675 GB, free: 11.1 MB / 1.39 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.05165/13.37, allocations: 2.559 MB / 4.678 GB, free: 8.855 MB / 1.39 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.01104/13.38, allocations: 2.548 MB / 4.68 GB, free: 6.301 MB / 1.39 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.01008/13.39, allocations: 1.693 MB / 4.682 GB, free: 4.609 MB / 1.39 GB
Notification: Performance of sorting global known variables: time 0.8406/14.24, allocations: 39.94 MB / 4.721 GB, free: 332.8 MB / 1.39 GB
Notification: Performance of sort global known variables: time 5.01e-07/14.24, allocations: 0 / 4.721 GB, free: 332.8 MB / 1.39 GB
Notification: Performance of remove unused functions: time 0.1764/14.41, allocations: 32.68 MB / 4.753 GB, free: 332.8 MB / 1.39 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * Number of states: 214 (aquWel1.pumCol.vol.dynBal.U,aquWel1.pumCol.filter.s[1],aquWel1.pumCol.filter.s[2],aquWel1.pumHot.vol.dynBal.U,aquWel1.pumHot.filter.s[1],aquWel1.pumHot.filter.s[2],aquWel1.resAqu.mExc,aquWel1.heaCapCol[1].T,aquWel1.heaCapCol[2].T,aquWel1.heaCapCol[3].T,aquWel1.heaCapCol[4].T,aquWel1.heaCapCol[5].T,aquWel1.heaCapCol[6].T,aquWel1.heaCapCol[7].T,aquWel1.heaCapCol[8].T,aquWel1.heaCapCol[9].T,aquWel1.heaCapCol[10].T,aquWel1.heaCapCol[11].T,aquWel1.heaCapCol[12].T,aquWel1.heaCapCol[13].T,aquWel1.heaCapCol[14].T,aquWel1.heaCapCol[15].T,aquWel1.heaCapCol[16].T,aquWel1.heaCapCol[17].T,aquWel1.heaCapCol[18].T,aquWel1.heaCapCol[19].T,aquWel1.heaCapCol[20].T,aquWel1.heaCapCol[21].T,aquWel1.heaCapCol[22].T,aquWel1.heaCapCol[23].T,aquWel1.heaCapCol[24].T,aquWel1.heaCapCol[25].T,aquWel1.heaCapCol[26].T,aquWel1.heaCapCol[27].T,aquWel1.heaCapCol[28].T,aquWel1.heaCapCol[29].T,aquWel1.heaCapCol[30].T,aquWel1.heaCapCol[31].T,aquWel1.heaCapCol[32].T,aquWel1.heaCapCol[33].T,aquWel1.heaCapCol[34].T,aquWel1.heaCapCol[35].T,aquWel1.heaCapCol[36].T,aquWel1.heaCapCol[37].T,aquWel1.heaCapCol[38].T,aquWel1.heaCapCol[39].T,aquWel1.heaCapCol[40].T,aquWel1.heaCapCol[41].T,aquWel1.heaCapCol[42].T,aquWel1.heaCapCol[43].T,aquWel1.heaCapCol[44].T,aquWel1.heaCapCol[45].T,aquWel1.heaCapCol[46].T,aquWel1.heaCapCol[47].T,aquWel1.heaCapCol[48].T,aquWel1.heaCapCol[49].T,aquWel1.heaCapCol[50].T,aquWel1.heaCapHot[1].T,aquWel1.heaCapHot[2].T,aquWel1.heaCapHot[3].T,aquWel1.heaCapHot[4].T,aquWel1.heaCapHot[5].T,aquWel1.heaCapHot[6].T,aquWel1.heaCapHot[7].T,aquWel1.heaCapHot[8].T,aquWel1.heaCapHot[9].T,aquWel1.heaCapHot[10].T,aquWel1.heaCapHot[11].T,aquWel1.heaCapHot[12].T,aquWel1.heaCapHot[13].T,aquWel1.heaCapHot[14].T,aquWel1.heaCapHot[15].T,aquWel1.heaCapHot[16].T,aquWel1.heaCapHot[17].T,aquWel1.heaCapHot[18].T,aquWel1.heaCapHot[19].T,aquWel1.heaCapHot[20].T,aquWel1.heaCapHot[21].T,aquWel1.heaCapHot[22].T,aquWel1.heaCapHot[23].T,aquWel1.heaCapHot[24].T,aquWel1.heaCapHot[25].T,aquWel1.heaCapHot[26].T,aquWel1.heaCapHot[27].T,aquWel1.heaCapHot[28].T,aquWel1.heaCapHot[29].T,aquWel1.heaCapHot[30].T,aquWel1.heaCapHot[31].T,aquWel1.heaCapHot[32].T,aquWel1.heaCapHot[33].T,aquWel1.heaCapHot[34].T,aquWel1.heaCapHot[35].T,aquWel1.heaCapHot[36].T,aquWel1.heaCapHot[37].T,aquWel1.heaCapHot[38].T,aquWel1.heaCapHot[39].T,aquWel1.heaCapHot[40].T,aquWel1.heaCapHot[41].T,aquWel1.heaCapHot[42].T,aquWel1.heaCapHot[43].T,aquWel1.heaCapHot[44].T,aquWel1.heaCapHot[45].T,aquWel1.heaCapHot[46].T,aquWel1.heaCapHot[47].T,aquWel1.heaCapHot[48].T,aquWel1.heaCapHot[49].T,aquWel1.heaCapHot[50].T,aquWel2.pumCol.vol.dynBal.U,aquWel2.pumCol.filter.s[1],aquWel2.pumCol.filter.s[2],aquWel2.pumHot.vol.dynBal.U,aquWel2.pumHot.filter.s[1],aquWel2.pumHot.filter.s[2],aquWel2.resAqu.mExc,aquWel2.heaCapCol[1].T,aquWel2.heaCapCol[2].T,aquWel2.heaCapCol[3].T,aquWel2.heaCapCol[4].T,aquWel2.heaCapCol[5].T,aquWel2.heaCapCol[6].T,aquWel2.heaCapCol[7].T,aquWel2.heaCapCol[8].T,aquWel2.heaCapCol[9].T,aquWel2.heaCapCol[10].T,aquWel2.heaCapCol[11].T,aquWel2.heaCapCol[12].T,aquWel2.heaCapCol[13].T,aquWel2.heaCapCol[14].T,aquWel2.heaCapCol[15].T,aquWel2.heaCapCol[16].T,aquWel2.heaCapCol[17].T,aquWel2.heaCapCol[18].T,aquWel2.heaCapCol[19].T,aquWel2.heaCapCol[20].T,aquWel2.heaCapCol[21].T,aquWel2.heaCapCol[22].T,aquWel2.heaCapCol[23].T,aquWel2.heaCapCol[24].T,aquWel2.heaCapCol[25].T,aquWel2.heaCapCol[26].T,aquWel2.heaCapCol[27].T,aquWel2.heaCapCol[28].T,aquWel2.heaCapCol[29].T,aquWel2.heaCapCol[30].T,aquWel2.heaCapCol[31].T,aquWel2.heaCapCol[32].T,aquWel2.heaCapCol[33].T,aquWel2.heaCapCol[34].T,aquWel2.heaCapCol[35].T,aquWel2.heaCapCol[36].T,aquWel2.heaCapCol[37].T,aquWel2.heaCapCol[38].T,aquWel2.heaCapCol[39].T,aquWel2.heaCapCol[40].T,aquWel2.heaCapCol[41].T,aquWel2.heaCapCol[42].T,aquWel2.heaCapCol[43].T,aquWel2.heaCapCol[44].T,aquWel2.heaCapCol[45].T,aquWel2.heaCapCol[46].T,aquWel2.heaCapCol[47].T,aquWel2.heaCapCol[48].T,aquWel2.heaCapCol[49].T,aquWel2.heaCapCol[50].T,aquWel2.heaCapHot[1].T,aquWel2.heaCapHot[2].T,aquWel2.heaCapHot[3].T,aquWel2.heaCapHot[4].T,aquWel2.heaCapHot[5].T,aquWel2.heaCapHot[6].T,aquWel2.heaCapHot[7].T,aquWel2.heaCapHot[8].T,aquWel2.heaCapHot[9].T,aquWel2.heaCapHot[10].T,aquWel2.heaCapHot[11].T,aquWel2.heaCapHot[12].T,aquWel2.heaCapHot[13].T,aquWel2.heaCapHot[14].T,aquWel2.heaCapHot[15].T,aquWel2.heaCapHot[16].T,aquWel2.heaCapHot[17].T,aquWel2.heaCapHot[18].T,aquWel2.heaCapHot[19].T,aquWel2.heaCapHot[20].T,aquWel2.heaCapHot[21].T,aquWel2.heaCapHot[22].T,aquWel2.heaCapHot[23].T,aquWel2.heaCapHot[24].T,aquWel2.heaCapHot[25].T,aquWel2.heaCapHot[26].T,aquWel2.heaCapHot[27].T,aquWel2.heaCapHot[28].T,aquWel2.heaCapHot[29].T,aquWel2.heaCapHot[30].T,aquWel2.heaCapHot[31].T,aquWel2.heaCapHot[32].T,aquWel2.heaCapHot[33].T,aquWel2.heaCapHot[34].T,aquWel2.heaCapHot[35].T,aquWel2.heaCapHot[36].T,aquWel2.heaCapHot[37].T,aquWel2.heaCapHot[38].T,aquWel2.heaCapHot[39].T,aquWel2.heaCapHot[40].T,aquWel2.heaCapHot[41].T,aquWel2.heaCapHot[42].T,aquWel2.heaCapHot[43].T,aquWel2.heaCapHot[44].T,aquWel2.heaCapHot[45].T,aquWel2.heaCapHot[46].T,aquWel2.heaCapHot[47].T,aquWel2.heaCapHot[48].T,aquWel2.heaCapHot[49].T,aquWel2.heaCapHot[50].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 (2612):
 * Single equations (assignments): 2395
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 13
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 204
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 200 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%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (1,4,100.0%), (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): 4 systems
   {(1,10), (1,12), (1,13), (1,13)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.1862/14.6, allocations: 84.83 MB / 4.836 GB, free: 324 MB / 1.39 GB
Notification: Performance of simCode: created initialization part: time 0.2353/14.83, allocations: 145 MB / 4.978 GB, free: 193.1 MB / 1.39 GB
Notification: Performance of simCode: created event and clocks part: time 5.248e-05/14.83, allocations: 44.73 kB / 4.978 GB, free: 193 MB / 1.39 GB
Notification: Performance of simCode: created simulation system equations: time 0.06467/14.9, allocations: 47.59 MB / 5.024 GB, free: 143.4 MB / 1.39 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1821/15.08, allocations: 34.35 MB / 5.058 GB, free: 110.6 MB / 1.39 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 2.191/17.27, allocations: 0.641 GB / 5.699 GB, free: 50.03 MB / 1.468 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0999/17.37, allocations: 43.89 MB / 5.741 GB, free: 6.859 MB / 1.468 GB
Notification: Performance of simCode: alias equations: time 0.1384/17.51, allocations: 33.92 MB / 5.775 GB, free: 6.746 MB / 1.499 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.8607/18.37, allocations: 31.89 MB / 5.806 GB, free: 329.6 MB / 1.515 GB
Notification: Performance of SimCode: time 1.923e-06/18.37, allocations: 0 / 5.806 GB, free: 329.6 MB / 1.515 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 5.175/23.55, allocations: 1.795 GB / 7.601 GB, free: 436.3 MB / 1.562 GB
Notification: Performance of buildModelFMU: Generate platform static: time 448.9/472.5, allocations: 17.17 kB / 7.601 GB, free: 436.3 MB / 1.562 GB
"
[Timeout remaining time 186]
(rm -f BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.pipe ; mkfifo BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.pipe >> ../files/BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.sim & python3 -m fmpy simulate  --output-file BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells_res.csv --start-time 0 --stop-time 3.1536e+07 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 12614.4 BuildingSystems_BuildingSystems_Fluid_Geothermal_Aquifer_Validation_NumberWells.fmu > BuildingSystems_BuildingSystems.Fluid.Geothermal.Aquifer.Validation.NumberWells.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 478.35653930110857]
Failed to read output from testmodel.py, exit status != 0:
473.59577957587317 473.60378318 470.525971658
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa64d88afb0>