Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr AixLib_AixLib.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.001767/0.001767, allocations: 83.11 kB / 19.18 MB, free: 2.516 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.002228/0.002228, allocations: 161.9 kB / 22.46 MB, free: 5.375 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.413/1.413, allocations: 177.1 MB / 202.8 MB, free: 9.156 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo): time 0.05867/0.05867, allocations: 9.146 MB / 268.3 MB, free: 7.902 MB / 254.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo): time 0.0007451/0.0007451, allocations: 36.62 kB / 327.8 MB, free: 42.33 MB / 286.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo): time 2.395/2.395, allocations: 275 MB / 0.6467 GB, free: 6.195 MB / 0.4982 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 177]
Using package AixLib with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.0/package.mo)
Using package SDF with version 0.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF master/package.mo)
Using package Modelica_DeviceDrivers with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.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(AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice")
translateModel(AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.824e-06/1.824e-06, allocations: 0 / 0.9285 GB, free: 22.52 MB / 0.7325 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.681e-05/2.863e-05, allocations: 5.797 kB / 0.9285 GB, free: 22.51 MB / 0.7325 GB
Notification: Performance of NFInst.instantiate(AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice): time 0.1151/0.1152, allocations: 99.55 MB / 1.026 GB, free: 15.39 MB / 0.795 GB
Notification: Performance of NFInst.instExpressions: time 0.05834/0.1735, allocations: 32.36 MB / 1.057 GB, free: 14.94 MB / 0.8263 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.006853/0.1803, allocations: 190.5 kB / 1.057 GB, free: 14.75 MB / 0.8263 GB
Notification: Performance of NFTyping.typeComponents: time 0.009906/0.1903, allocations: 2.81 MB / 1.06 GB, free: 11.93 MB / 0.8263 GB
Notification: Performance of NFTyping.typeBindings: time 0.01847/0.2087, allocations: 6.024 MB / 1.066 GB, free: 5.879 MB / 0.8263 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01141/0.2201, allocations: 4.122 MB / 1.07 GB, free: 1.785 MB / 0.8263 GB
Notification: Performance of NFFlatten.flatten: time 0.2231/0.4433, allocations: 205.7 MB / 1.271 GB, free: 3.586 MB / 1.029 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.6856/1.129, allocations: 51.86 MB / 1.322 GB, free: 41.74 MB / 1.029 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1816/1.31, allocations: 82.5 MB / 1.402 GB, free: 40.23 MB / 1.029 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.09582/1.406, allocations: 50.06 MB / 1.451 GB, free: 30.35 MB / 1.029 GB
Notification: Performance of NFPackage.collectConstants: time 0.03323/1.44, allocations: 6.809 MB / 1.458 GB, free: 30.34 MB / 1.029 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.06023/1.5, allocations: 9.454 MB / 1.467 GB, free: 29.59 MB / 1.029 GB
Notification: Performance of NFScalarize.scalarize: time 0.0403/1.54, allocations: 19.31 MB / 1.486 GB, free: 19.17 MB / 1.029 GB
Notification: Performance of NFVerifyModel.verify: time 0.084/1.624, allocations: 25.78 MB / 1.511 GB, free: 14.57 MB / 1.045 GB
Notification: Performance of NFConvertDAE.convert: time 0.1728/1.797, allocations: 107.6 MB / 1.616 GB, free: 6.953 MB / 1.139 GB
Notification: Performance of FrontEnd - DAE generated: time 5.991e-06/1.797, allocations: 0 / 1.616 GB, free: 6.953 MB / 1.139 GB
Notification: Performance of FrontEnd: time 1.433e-06/1.797, allocations: 0 / 1.616 GB, free: 6.953 MB / 1.139 GB
Notification: Performance of Transformations before backend: time 0.004502/1.801, allocations: 0 / 1.616 GB, free: 6.953 MB / 1.139 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.2214/2.023, allocations: 67.31 MB / 1.682 GB, free: 4.34 MB / 1.201 GB
Notification: Performance of prepare preOptimizeDAE: time 5.368e-05/2.023, allocations: 12.03 kB / 1.682 GB, free: 4.328 MB / 1.201 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.05346/2.076, allocations: 8.344 MB / 1.69 GB, free: 11.95 MB / 1.217 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 1.057/3.133, allocations: 66.41 MB / 1.755 GB, free: 414.9 MB / 1.264 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.003849/3.137, allocations: 3 MB / 1.758 GB, free: 414.8 MB / 1.264 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01549/3.152, allocations: 4.151 MB / 1.762 GB, free: 414.8 MB / 1.264 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.1629/3.315, allocations: 74.87 MB / 1.835 GB, free: 374.6 MB / 1.264 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.002973/3.318, allocations: 266 kB / 1.835 GB, free: 374.5 MB / 1.264 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.01471/3.333, allocations: 1.713 MB / 1.837 GB, free: 373.3 MB / 1.264 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002243/3.335, allocations: 1.82 MB / 1.839 GB, free: 373.1 MB / 1.264 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.1218/3.457, allocations: 50.66 MB / 1.888 GB, free: 350 MB / 1.264 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.3751/3.832, allocations: 198.7 MB / 2.082 GB, free: 158.2 MB / 1.264 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.04662/3.879, allocations: 28.49 MB / 2.11 GB, free: 129.6 MB / 1.264 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0352/3.914, allocations: 17.78 MB / 2.127 GB, free: 111.8 MB / 1.264 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1597/4.074, allocations: 51.7 MB / 2.178 GB, free: 60.53 MB / 1.264 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0004621/4.074, allocations: 392.7 kB / 2.178 GB, free: 60.13 MB / 1.264 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02467/4.099, allocations: 1.945 MB / 2.18 GB, free: 58.17 MB / 1.264 GB
Notification: Performance of pre-optimization done (n=2331): time 0.0001169/4.099, allocations: 4 kB / 2.18 GB, free: 58.17 MB / 1.264 GB
Notification: Performance of matching and sorting (n=2666): time 0.8185/4.917, allocations: 116.9 MB / 2.294 GB, free: 363.8 MB / 1.264 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004279/4.918, allocations: 323.3 kB / 2.295 GB, free: 363.7 MB / 1.264 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.09357/5.011, allocations: 45.04 MB / 2.339 GB, free: 349.4 MB / 1.264 GB
Notification: Performance of collectPreVariables (initialization): time 0.007071/5.018, allocations: 0.5979 MB / 2.339 GB, free: 349 MB / 1.264 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0289/5.047, allocations: 24.59 MB / 2.363 GB, free: 330.2 MB / 1.264 GB
Notification: Performance of collectInitialBindings (initialization): time 0.01489/5.062, allocations: 9.82 MB / 2.373 GB, free: 322.5 MB / 1.264 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.03082/5.093, allocations: 8.356 MB / 2.381 GB, free: 318.7 MB / 1.264 GB
Notification: Performance of setup shared object (initialization): time 4.892e-05/5.093, allocations: 301.1 kB / 2.381 GB, free: 318.4 MB / 1.264 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.04384/5.137, allocations: 20.91 MB / 2.402 GB, free: 311 MB / 1.264 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.04439/5.181, allocations: 29.86 MB / 2.431 GB, free: 289.9 MB / 1.264 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.08055/5.262, allocations: 48.63 MB / 2.478 GB, free: 242.5 MB / 1.264 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004249/5.262, allocations: 64 kB / 2.478 GB, free: 242.4 MB / 1.264 GB
Notification: Performance of matching and sorting (n=5384) (initialization): time 0.1473/5.409, allocations: 66.54 MB / 2.543 GB, free: 175.6 MB / 1.264 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003994/5.41, allocations: 104 kB / 2.543 GB, free: 175.5 MB / 1.264 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004437/5.41, allocations: 227.6 kB / 2.544 GB, free: 175.2 MB / 1.264 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02381/5.434, allocations: 10.73 MB / 2.554 GB, free: 164.1 MB / 1.264 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04741/5.482, allocations: 11.07 MB / 2.565 GB, free: 153 MB / 1.264 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8067/6.288, allocations: 287.7 MB / 2.846 GB, free: 206.6 MB / 1.264 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.05323/6.341, allocations: 2.646 MB / 2.848 GB, free: 205.9 MB / 1.264 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01212/6.354, allocations: 1.769 MB / 2.85 GB, free: 205.6 MB / 1.264 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0781/6.432, allocations: 39.71 MB / 2.889 GB, free: 191.4 MB / 1.264 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.04828/6.48, allocations: 29.75 MB / 2.918 GB, free: 174.2 MB / 1.264 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.08792/6.568, allocations: 48.44 MB / 2.965 GB, free: 148.7 MB / 1.264 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003767/6.568, allocations: 62.28 kB / 2.965 GB, free: 148.7 MB / 1.264 GB
Notification: Performance of matching and sorting (n=5384) (initialization_lambda0): time 0.157/6.725, allocations: 66.21 MB / 3.03 GB, free: 105.5 MB / 1.264 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001655/6.725, allocations: 101.4 kB / 3.03 GB, free: 105.4 MB / 1.264 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002838/6.726, allocations: 223.9 kB / 3.03 GB, free: 105.2 MB / 1.264 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02266/6.748, allocations: 10.69 MB / 3.041 GB, free: 94.02 MB / 1.264 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.04668/6.795, allocations: 11.03 MB / 3.052 GB, free: 82.98 MB / 1.264 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8022/7.597, allocations: 288 MB / 3.333 GB, free: 221.8 MB / 1.342 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04625/7.644, allocations: 2.664 MB / 3.335 GB, free: 221.8 MB / 1.342 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.01304/7.657, allocations: 1.734 MB / 3.337 GB, free: 221.8 MB / 1.342 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.02026/7.677, allocations: 2.456 MB / 3.339 GB, free: 221.6 MB / 1.342 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.02723/7.704, allocations: 4.281 MB / 3.344 GB, free: 221.6 MB / 1.342 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1513/7.855, allocations: 43.99 MB / 3.387 GB, free: 207.7 MB / 1.342 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0004619/7.856, allocations: 260.2 kB / 3.387 GB, free: 207.7 MB / 1.342 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0009138/7.857, allocations: 104.6 kB / 3.387 GB, free: 207.7 MB / 1.342 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.1273/7.984, allocations: 37.32 MB / 3.423 GB, free: 192.9 MB / 1.342 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.2275/8.211, allocations: 82.44 MB / 3.504 GB, free: 161.5 MB / 1.342 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0003093/8.212, allocations: 0 / 3.504 GB, free: 161.5 MB / 1.342 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.03212/8.244, allocations: 6.507 MB / 3.51 GB, free: 155.8 MB / 1.342 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02219/8.266, allocations: 9.434 MB / 3.519 GB, free: 145.9 MB / 1.342 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.007225/8.273, allocations: 0.6871 MB / 3.52 GB, free: 145.2 MB / 1.342 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.8942/9.168, allocations: 284.8 MB / 3.798 GB, free: 289.2 MB / 1.42 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.286e-06/9.168, allocations: 2.562 kB / 3.798 GB, free: 289.2 MB / 1.42 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1419/9.309, allocations: 63 MB / 3.86 GB, free: 283.5 MB / 1.42 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.03831/9.348, allocations: 9.567 MB / 3.869 GB, free: 280.5 MB / 1.42 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01923/9.367, allocations: 0.5448 MB / 3.87 GB, free: 280.2 MB / 1.42 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.04178/9.409, allocations: 2.04 MB / 3.872 GB, free: 279.7 MB / 1.42 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.008395/9.417, allocations: 1.997 MB / 3.874 GB, free: 279.4 MB / 1.42 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.006427/9.424, allocations: 1.305 MB / 3.875 GB, free: 279.2 MB / 1.42 GB
Notification: Performance of sorting global known variables: time 0.07085/9.494, allocations: 31.04 MB / 3.905 GB, free: 269.1 MB / 1.42 GB
Notification: Performance of sort global known variables: time 2.2e-07/9.494, allocations: 0 / 3.905 GB, free: 269.1 MB / 1.42 GB
Notification: Performance of remove unused functions: time 0.1384/9.633, allocations: 25.71 MB / 3.93 GB, free: 267.2 MB / 1.42 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.009016/9.642, allocations: 1.924 MB / 3.932 GB, free: 266.6 MB / 1.42 GB
Notification: Performance of simCode: created initialization part: time 0.1759/9.818, allocations: 111.4 MB / 4.041 GB, free: 178.5 MB / 1.42 GB
Notification: Performance of simCode: created event and clocks part: time 4.686e-05/9.818, allocations: 39.8 kB / 4.041 GB, free: 178.4 MB / 1.42 GB
Notification: Performance of simCode: created simulation system equations: time 0.04763/9.865, allocations: 37.47 MB / 4.078 GB, free: 142.9 MB / 1.42 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1502/10.02, allocations: 26.97 MB / 4.104 GB, free: 121.3 MB / 1.42 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2384/10.25, allocations: 122.6 MB / 4.224 GB, free: 15.38 MB / 1.436 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.09009/10.34, allocations: 34.37 MB / 4.257 GB, free: 12.94 MB / 1.467 GB
Notification: Performance of simCode: alias equations: time 0.7724/11.12, allocations: 27.17 MB / 4.284 GB, free: 300.9 MB / 1.483 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.02031/11.14, allocations: 3.382 MB / 4.287 GB, free: 300.9 MB / 1.483 GB
Notification: Performance of SimCode: time 1.573e-06/11.14, allocations: 0 / 4.287 GB, free: 300.9 MB / 1.483 GB
Notification: Performance of Templates: time 2.149/13.29, allocations: 1.048 GB / 5.335 GB, free: 124.1 MB / 1.592 GB
"
[Timeout remaining time 647]
make -j1 -f AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.makefile [Timeout 660]
(rm -f AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; mkfifo AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe ; head -c 1048576 < AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe >> ../files/AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.sim & ./AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice  -abortSlowSimulation -alarm=480 -lv LOG_STATS > AixLib_AixLib.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 56.20736552774906]
Failed to read output from testmodel.py, exit status != 0:
13.29535511881113 13.304916863 11.505407857
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa3e08ed1e0>