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.000944/0.000944, allocations: 76.16 kB / 9.861 MB, free: 0.8281 MB / 10.45 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001275/0.002219, allocations: 443.8 kB / 10.29 MB, free: 404 kB / 10.45 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.001023/0.001023, allocations: 141.2 kB / 11.17 MB, free: 3 MB / 13.93 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001258/0.002282, allocations: 475.6 kB / 11.64 MB, free: 2.531 MB / 13.93 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.017/1.017, allocations: 177.2 MB / 189.5 MB, free: 5.324 MB / 186.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.0929/1.11, allocations: 53.66 MB / 243.2 MB, free: 15.59 MB / 250.7 MB
"
[Timeout remaining time 179]
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-545:108: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-545:116: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 0.9112/0.9112, allocations: 154.6 MB / 398.5 MB, free: 0.5312 MB / 378.7 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.
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.2944/1.206, allocations: 49.04 MB / 447.5 MB, free: 6.68 MB / 426.7 MB
"
[Timeout remaining time 179]
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: setCommandLineOptions("--fmiFlags=s:cvode")
Running command: "" <> buildModelFMU(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,fileNamePrefix="IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice",fmuType="me_cs",version="2.0+3.0",platforms={"static"})
"" <> buildModelFMU(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice,fileNamePrefix="IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice",fmuType="me_cs",version="2.0+3.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.323e-06/1.323e-06, allocations: 0 / 448.8 MB, free: 5.465 MB / 426.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.737e-05/1.87e-05, allocations: 4.781 kB / 448.8 MB, free: 5.461 MB / 426.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice): time 0.02059/0.02061, allocations: 29.18 MB / 478 MB, free: 8.25 MB / 458.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02109/0.0417, allocations: 15.78 MB / 493.7 MB, free: 8.578 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002526/0.04423, allocations: 186.6 kB / 493.9 MB, free: 8.395 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003238/0.04747, allocations: 2.098 MB / 496 MB, free: 6.293 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00575/0.05322, allocations: 4.315 MB / 0.4886 GB, free: 1.992 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004528/0.05775, allocations: 3.594 MB / 0.4921 GB, free: 14.42 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.5677/0.6254, allocations: 202.5 MB / 0.6899 GB, free: 33.12 MB / 0.6355 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.06736/0.6928, allocations: 56.06 MB / 0.7446 GB, free: 28.77 MB / 0.6355 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1497/0.8425, allocations: 87.72 MB / 0.8303 GB, free: 11.25 MB / 0.6512 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0731/0.9156, allocations: 46.46 MB / 0.8757 GB, free: 4.316 MB / 0.6824 GB
Notification: Performance of NFPackage.collectConstants: time 0.01971/0.9353, allocations: 6.402 MB / 0.8819 GB, free: 13.91 MB / 0.698 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04016/0.9755, allocations: 7.974 MB / 0.8897 GB, free: 6.027 MB / 0.698 GB
Notification: Performance of NFScalarize.scalarize: time 0.03041/1.006, allocations: 14.46 MB / 0.9038 GB, free: 7.512 MB / 0.7137 GB
Notification: Performance of NFVerifyModel.verify: time 0.06192/1.068, allocations: 20.55 MB / 0.9239 GB, free: 2.922 MB / 0.7293 GB
Notification: Performance of NFConvertDAE.convert: time 0.5398/1.608, allocations: 57.35 MB / 0.9799 GB, free: 127.5 MB / 0.745 GB
Notification: Performance of FrontEnd - DAE generated: time 1.629e-05/1.608, allocations: 0 / 0.9799 GB, free: 127.5 MB / 0.745 GB
Notification: Performance of FrontEnd: time 1.092e-06/1.608, allocations: 1.938 kB / 0.9799 GB, free: 127.5 MB / 0.745 GB
Notification: Performance of Transformations before backend: time 0.003052/1.611, allocations: 0 / 0.9799 GB, free: 127.5 MB / 0.745 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.1248/1.736, allocations: 72.82 MB / 1.051 GB, free: 104.5 MB / 0.745 GB
Notification: Performance of prepare preOptimizeDAE: time 5.456e-05/1.736, allocations: 11.28 kB / 1.051 GB, free: 104.5 MB / 0.745 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.01937/1.755, allocations: 5.425 MB / 1.056 GB, free: 104.2 MB / 0.745 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03838/1.793, allocations: 9.878 MB / 1.066 GB, free: 103.2 MB / 0.745 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.07871/1.872, allocations: 37.23 MB / 1.102 GB, free: 94.66 MB / 0.745 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002202/1.874, allocations: 2.993 MB / 1.105 GB, free: 94.59 MB / 0.745 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01493/1.889, allocations: 4.354 MB / 1.109 GB, free: 92.99 MB / 0.745 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.08917/1.978, allocations: 45.82 MB / 1.154 GB, free: 52.64 MB / 0.745 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.002339/1.981, allocations: 242.2 kB / 1.154 GB, free: 52.41 MB / 0.745 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.008806/1.989, allocations: 1.711 MB / 1.156 GB, free: 50.7 MB / 0.745 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001419/1.991, allocations: 1.819 MB / 1.158 GB, free: 48.88 MB / 0.745 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04606/2.037, allocations: 14.83 MB / 1.172 GB, free: 34.06 MB / 0.745 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.5424/2.579, allocations: 138.9 MB / 1.308 GB, free: 127.1 MB / 0.7606 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01935/2.599, allocations: 14.38 MB / 1.322 GB, free: 124 MB / 0.7606 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007881/2.607, allocations: 5.744 MB / 1.328 GB, free: 123.8 MB / 0.7606 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.08382/2.69, allocations: 25.49 MB / 1.353 GB, free: 121 MB / 0.7606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002977/2.691, allocations: 393.8 kB / 1.353 GB, free: 120.9 MB / 0.7606 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01582/2.707, allocations: 1.971 MB / 1.355 GB, free: 120.5 MB / 0.7606 GB
Notification: Performance of pre-optimization done (n=2331): time 5.565e-05/2.707, allocations: 0 / 1.355 GB, free: 120.5 MB / 0.7606 GB
Notification: Performance of matching and sorting (n=2666): time 0.1048/2.811, allocations: 54.8 MB / 1.408 GB, free: 75.61 MB / 0.7606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002109/2.812, allocations: 336.2 kB / 1.409 GB, free: 75.27 MB / 0.7606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04107/2.853, allocations: 31.65 MB / 1.44 GB, free: 47.75 MB / 0.7606 GB
Notification: Performance of collectPreVariables (initialization): time 0.005508/2.858, allocations: 0.5915 MB / 1.44 GB, free: 47.16 MB / 0.7606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01043/2.869, allocations: 16.84 MB / 1.457 GB, free: 30.19 MB / 0.7606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.009703/2.878, allocations: 9.861 MB / 1.466 GB, free: 20.24 MB / 0.7606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.01096/2.889, allocations: 7.808 MB / 1.474 GB, free: 12.41 MB / 0.7606 GB
Notification: Performance of setup shared object (initialization): time 2.929e-05/2.889, allocations: 306 kB / 1.474 GB, free: 12.11 MB / 0.7606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01157/2.901, allocations: 6.931 MB / 1.481 GB, free: 5.172 MB / 0.7606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01553/2.916, allocations: 14.6 MB / 1.495 GB, free: 3.414 MB / 0.7763 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.368/3.284, allocations: 23.53 MB / 1.518 GB, free: 210.8 MB / 0.7919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000279/3.285, allocations: 69.16 kB / 1.518 GB, free: 210.8 MB / 0.7919 GB
Notification: Performance of matching and sorting (n=5388) (initialization): time 0.06878/3.353, allocations: 28.17 MB / 1.546 GB, free: 208.5 MB / 0.7919 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001478/3.354, allocations: 103.2 kB / 1.546 GB, free: 208.5 MB / 0.7919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002706/3.354, allocations: 221.6 kB / 1.546 GB, free: 208.5 MB / 0.7919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01171/3.366, allocations: 6.027 MB / 1.552 GB, free: 207.2 MB / 0.7919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03096/3.396, allocations: 12.2 MB / 1.564 GB, free: 206.8 MB / 0.7919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08447/3.481, allocations: 233.9 MB / 1.792 GB, free: 47.91 MB / 0.8544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0329/3.514, allocations: 1.271 MB / 1.794 GB, free: 46.69 MB / 0.8544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006334/3.52, allocations: 1.363 MB / 1.795 GB, free: 45.32 MB / 0.8544 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.03569/3.556, allocations: 7.637 MB / 1.802 GB, free: 37.68 MB / 0.8544 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01752/3.573, allocations: 14.57 MB / 1.817 GB, free: 20.75 MB / 0.8544 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.4229/3.996, allocations: 23.28 MB / 1.839 GB, free: 210 MB / 0.8544 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003772/3.997, allocations: 64.19 kB / 1.839 GB, free: 210 MB / 0.8544 GB
Notification: Performance of matching and sorting (n=5388) (initialization_lambda0): time 0.06693/4.064, allocations: 28.2 MB / 1.867 GB, free: 208.1 MB / 0.8544 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001847/4.064, allocations: 100.7 kB / 1.867 GB, free: 208.1 MB / 0.8544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003876/4.064, allocations: 221.8 kB / 1.867 GB, free: 208.1 MB / 0.8544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01169/4.076, allocations: 5.913 MB / 1.873 GB, free: 206.7 MB / 0.8544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.03083/4.107, allocations: 12.17 MB / 1.885 GB, free: 206.7 MB / 0.8544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08578/4.192, allocations: 233.9 MB / 2.113 GB, free: 26.05 MB / 0.8856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03571/4.228, allocations: 1.278 MB / 2.114 GB, free: 25.26 MB / 0.8856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.008917/4.237, allocations: 1.341 MB / 2.116 GB, free: 24.82 MB / 0.8856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1019
 * 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 (4714):
 * Single equations (assignments): 4550
 * 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.01442/4.251, allocations: 2.461 MB / 2.118 GB, free: 22.98 MB / 0.8856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.01434/4.266, allocations: 3.334 MB / 2.121 GB, free: 21.42 MB / 0.8856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04507/4.311, allocations: 17.38 MB / 2.138 GB, free: 19.78 MB / 0.9013 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001398/4.311, allocations: 252 kB / 2.139 GB, free: 19.54 MB / 0.9013 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0003197/4.311, allocations: 100 kB / 2.139 GB, free: 19.44 MB / 0.9013 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02154/4.333, allocations: 11.56 MB / 2.15 GB, free: 7.332 MB / 0.9013 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.4961/4.829, allocations: 41.83 MB / 2.191 GB, free: 232.9 MB / 0.9169 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.66e-05/4.829, allocations: 3.156 kB / 2.191 GB, free: 232.9 MB / 0.9169 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.02076/4.85, allocations: 7.393 MB / 2.198 GB, free: 232.9 MB / 0.9169 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.02144/4.871, allocations: 5.214 MB / 2.203 GB, free: 232.8 MB / 0.9169 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01074/4.882, allocations: 5.242 MB / 2.208 GB, free: 231.4 MB / 0.9169 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003594/4.886, allocations: 0.6212 MB / 2.209 GB, free: 231.4 MB / 0.9169 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.09162/4.977, allocations: 235.6 MB / 2.439 GB, free: 39.24 MB / 0.9325 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.0001038/4.977, allocations: 2.562 kB / 2.439 GB, free: 39.24 MB / 0.9325 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04136/5.019, allocations: 21.15 MB / 2.46 GB, free: 35.91 MB / 0.9325 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.01066/5.029, allocations: 0.5338 MB / 2.46 GB, free: 35.88 MB / 0.9325 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02412/5.053, allocations: 1.127 MB / 2.461 GB, free: 35.58 MB / 0.9325 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.006674/5.06, allocations: 1.94 MB / 2.463 GB, free: 34.49 MB / 0.9325 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.004226/5.064, allocations: 0.8893 MB / 2.464 GB, free: 33.83 MB / 0.9325 GB
Notification: Performance of sorting global known variables: time 0.03212/5.096, allocations: 13.7 MB / 2.477 GB, free: 22.21 MB / 0.9325 GB
Notification: Performance of sort global known variables: time 7.01e-07/5.096, allocations: 0 / 2.477 GB, free: 22.21 MB / 0.9325 GB
Notification: Performance of remove unused functions: time 0.1155/5.212, allocations: 24.88 MB / 2.502 GB, free: 13.39 MB / 0.9481 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 (2031):
 * Single equations (assignments): 1862
 * 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,13), (1,17)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.04636/5.258, allocations: 23.17 MB / 2.524 GB, free: 6.82 MB / 0.9638 GB
Notification: Performance of simCode: created initialization part: time 0.4534/5.712, allocations: 74.18 MB / 2.597 GB, free: 216.5 MB / 0.9638 GB
Notification: Performance of simCode: created event and clocks part: time 0.001036/5.713, allocations: 0.5004 MB / 2.597 GB, free: 216.5 MB / 0.9638 GB
Notification: Performance of simCode: created simulation system equations: time 0.02425/5.737, allocations: 27.35 MB / 2.624 GB, free: 198.4 MB / 0.9638 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.09655/5.834, allocations: 32.16 MB / 2.655 GB, free: 194.7 MB / 0.9638 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.4088/6.242, allocations: 210.2 MB / 2.861 GB, free: 12 MB / 0.9638 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0277/6.27, allocations: 11.99 MB / 2.872 GB, free: 15.96 MB / 0.9794 GB
Notification: Performance of simCode: alias equations: time 0.04728/6.317, allocations: 9.732 MB / 2.882 GB, free: 6.305 MB / 0.9794 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.5116/6.829, allocations: 38.47 MB / 2.919 GB, free: 243.4 MB / 0.995 GB
Notification: Performance of SimCode: time 1.593e-06/6.829, allocations: 0 / 2.919 GB, free: 243.4 MB / 0.995 GB
Notification: Performance of FMU documentation: time 3.051/9.879, allocations: 1.775 GB / 4.695 GB, free: 143.6 MB / 1.089 GB
Notification: FMU-only work: backend 0.03831, simCode 0.2089, templates 0
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.05836/9.938, allocations: 1.336 MB / 4.696 GB, free: 142.2 MB / 1.089 GB
Notification: Building FMU for platform 'static' (1/1).
Notification: Finished FMU for platform 'static' (1/1).
Notification: Performance of buildModelFMU: Generate platform static: time 27.91/37.85, allocations: 9.625 kB / 4.696 GB, free: 142.2 MB / 1.089 GB
"
[Timeout remaining time 622]
(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 & (cd run_OMSimulator && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --stripRoot --tempDir=temp_IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_fmu --startTime=0 --stopTime=7.776e+06 --stepSize=3110.4 --mode=me --timeout=240 --tolerance=1e-06 ../IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice.fmu) > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice.pipe 2>&1) [Timeout 252.0]
(rm -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-cvode.pipe ; mkfifo IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-cvode.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-cvode.sim & (cd run_OMSimulator-cs-cvode && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --stripRoot --tempDir=temp_IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_OMSimulator-cs-cvode_fmu --startTime=0 --stopTime=7.776e+06 --stepSize=3110.4 --mode=cs --timeout=240 --tolerance=1e-06 ../IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_OMSimulator-cs-cvode.fmu) > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-cvode.pipe 2>&1) [Timeout 252.0]
(rm -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-euler.pipe ; mkfifo IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-euler.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-euler.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-euler.sim & (cd run_OMSimulator-cs-euler && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --stripRoot --tempDir=temp_IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_OMSimulator-cs-euler_fmu --startTime=0 --stopTime=7.776e+06 --stepSize=3110.4 --mode=cs --timeout=240 --tolerance=1e-06 ../IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_OMSimulator-cs-euler.fmu) > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_OMSimulator-cs-euler.pipe 2>&1) [Timeout 252.0]
OMSimulator-cs-euler timed out simulating the FMU
(rm -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy.pipe ; mkfifo IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy.sim & (cd run_fmpy && python3 -m fmpy simulate --output-file ../IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy_res.csv --start-time 0 --stop-time 7.776e+06 --timeout 240 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 3110.4 ../IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice.fmu) > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy.pipe 2>&1) [Timeout 252.0]
(rm -f IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode.pipe ; mkfifo IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode.sim & (cd run_fmpy-fmi3-cs-cvode && python3 -m fmpy simulate --output-file ../IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode_res.csv --start-time 0 --stop-time 7.776e+06 --timeout 240 --relative-tolerance 1e-06 --interface-type CoSimulation --output-interval 3110.4 ../IDEAS_IDEAS_Fluid_Geothermal_Aquifer_Examples_CoolingOffice_fmpy-fmi3-cs-cvode.fmu) > IDEAS_IDEAS.Fluid.Geothermal.Aquifer.Examples.CoolingOffice_fmpy-fmi3-cs-cvode.pipe 2>&1) [Timeout 252.0]
[Calling sys.exit(0), Time elapsed: 44.978308585472405]