Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.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.001258/0.001258, allocations: 71.75 kB / 20.97 MB, free: 3.582 MB / 18.57 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.001403/0.001403, allocations: 169 kB / 24.28 MB, free: 280 kB / 18.57 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 0.9532/0.9532, allocations: 177.1 MB / 204.6 MB, free: 5.605 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo): time 1.79/1.79, allocations: 359 MB / 0.6055 GB, free: 11 MB / 0.573 GB
"
[Timeout remaining time 178]
Using package Buildings with version 11.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/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(Buildings.Fluid.Geothermal.Boreholes.Examples.UTube,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|borHol.port_a.m_flow|borHol.sta_a.T|borHol.sta_b.T|borHol.borHol.[0-9,]+..TBouCon.port.T|borHol.borHol.[0-9,]+..soi.T.[0-9,]+.",fileNamePrefix="Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube")
translateModel(Buildings.Fluid.Geothermal.Boreholes.Examples.UTube,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|borHol.port_a.m_flow|borHol.sta_a.T|borHol.sta_b.T|borHol.borHol.[0-9,]+..TBouCon.port.T|borHol.borHol.[0-9,]+..soi.T.[0-9,]+.",fileNamePrefix="Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.634e-06/1.634e-06, allocations: 0 / 0.7871 GB, free: 12.81 MB / 0.7448 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.838e-05/3.002e-05, allocations: 9.688 kB / 0.7871 GB, free: 12.8 MB / 0.7448 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Geothermal.Boreholes.Examples.UTube): time 0.04505/0.04508, allocations: 55.53 MB / 0.8413 GB, free: 5.148 MB / 0.7917 GB
Notification: Performance of NFInst.instExpressions: time 0.01333/0.05841, allocations: 15.04 MB / 0.856 GB, free: 6.07 MB / 0.8073 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009428/0.05935, allocations: 99.19 kB / 0.8561 GB, free: 5.973 MB / 0.8073 GB
Notification: Performance of NFTyping.typeComponents: time 0.001409/0.06076, allocations: 0.9859 MB / 0.8571 GB, free: 4.98 MB / 0.8073 GB
Notification: Performance of NFTyping.typeBindings: time 0.003424/0.06419, allocations: 3.062 MB / 0.86 GB, free: 1.902 MB / 0.8073 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/Fluid/Geothermal/Boreholes/BaseClasses/temperatureDrop.mo:2:1-109:20:writable] Warning: Pure function 'Buildings.Fluid.Geothermal.Boreholes.BaseClasses.temperatureDrop' contains a call to impure function 'Buildings.Fluid.Geothermal.Boreholes.BaseClasses.exchangeValues'.
Notification: Performance of NFTyping.typeClassSections: time 0.002135/0.06632, allocations: 1.851 MB / 0.8619 GB, free: 48 kB / 0.8073 GB
Notification: Performance of NFFlatten.flatten: time 0.03227/0.09859, allocations: 57.49 MB / 0.918 GB, free: 6.418 MB / 0.8698 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01743/0.116, allocations: 17.48 MB / 0.9351 GB, free: 4.801 MB / 0.8855 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0331/0.1491, allocations: 26.33 MB / 0.9608 GB, free: 10.59 MB / 0.9167 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.02133/0.1704, allocations: 17.88 MB / 0.9782 GB, free: 8.672 MB / 0.9323 GB
Notification: Performance of NFPackage.collectConstants: time 0.005673/0.1761, allocations: 3.324 MB / 0.9815 GB, free: 5.348 MB / 0.9323 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009271/0.1854, allocations: 4.946 MB / 0.9863 GB, free: 408 kB / 0.9323 GB
Notification: Performance of NFScalarize.scalarize: time 0.009833/0.1952, allocations: 8.119 MB / 0.9942 GB, free: 8.258 MB / 0.948 GB
Notification: Performance of NFVerifyModel.verify: time 0.02059/0.2158, allocations: 9.916 MB / 1.004 GB, free: 14.32 MB / 0.9636 GB
Notification: Performance of NFConvertDAE.convert: time 0.03249/0.2483, allocations: 25.32 MB / 1.029 GB, free: 4.945 MB / 0.9792 GB
Notification: Performance of FrontEnd - DAE generated: time 6.433e-06/0.2483, allocations: 4 kB / 1.029 GB, free: 4.941 MB / 0.9792 GB
Notification: Performance of FrontEnd: time 1.783e-06/0.2483, allocations: 4 kB / 1.029 GB, free: 4.938 MB / 0.9792 GB
Notification: Performance of Transformations before backend: time 0.0008769/0.2492, allocations: 0 / 1.029 GB, free: 4.938 MB / 0.9792 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2751
 * Number of variables: 2751
Notification: Performance of Generate backend data structure: time 0.6055/0.8547, allocations: 27.86 MB / 1.056 GB, free: 272.6 MB / 0.9949 GB
Notification: Performance of prepare preOptimizeDAE: time 3.953e-05/0.8547, allocations: 10.78 kB / 1.056 GB, free: 272.6 MB / 0.9949 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02038/0.8751, allocations: 7.809 MB / 1.064 GB, free: 271.1 MB / 0.9949 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02379/0.8989, allocations: 21.23 MB / 1.084 GB, free: 261.9 MB / 0.9949 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005763/0.8995, allocations: 0.9572 MB / 1.085 GB, free: 261.5 MB / 0.9949 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002259/0.9017, allocations: 1.384 MB / 1.087 GB, free: 261 MB / 0.9949 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01524/0.917, allocations: 10.69 MB / 1.097 GB, free: 255.6 MB / 0.9949 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001976/0.9172, allocations: 34.42 kB / 1.097 GB, free: 255.5 MB / 0.9949 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007461/0.9179, allocations: 454.7 kB / 1.097 GB, free: 255.2 MB / 0.9949 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001996/0.9181, allocations: 315.4 kB / 1.098 GB, free: 255.2 MB / 0.9949 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01535/0.9335, allocations: 4.249 MB / 1.102 GB, free: 254.5 MB / 0.9949 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.09908/1.033, allocations: 67.5 MB / 1.168 GB, free: 192.4 MB / 0.9949 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01565/1.048, allocations: 9.851 MB / 1.177 GB, free: 182.6 MB / 0.9949 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004756/1.053, allocations: 2.452 MB / 1.18 GB, free: 180.1 MB / 0.9949 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004984/1.053, allocations: 233.9 kB / 1.18 GB, free: 179.9 MB / 0.9949 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004691/1.058, allocations: 4.769 MB / 1.185 GB, free: 174.7 MB / 0.9949 GB
Notification: Performance of pre-optimization done (n=784): time 1.046e-05/1.058, allocations: 4 kB / 1.185 GB, free: 174.7 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=784): time 0.01952/1.078, allocations: 9.233 MB / 1.194 GB, free: 165.2 MB / 0.9949 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001126/1.078, allocations: 240.8 kB / 1.194 GB, free: 165 MB / 0.9949 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02234/1.1, allocations: 13.01 MB / 1.207 GB, free: 152.1 MB / 0.9949 GB
Notification: Performance of collectPreVariables (initialization): time 0.004032/1.104, allocations: 313.7 kB / 1.207 GB, free: 151.8 MB / 0.9949 GB
Notification: Performance of collectInitialEqns (initialization): time 0.009979/1.114, allocations: 7.838 MB / 1.215 GB, free: 143.9 MB / 0.9949 GB
Notification: Performance of collectInitialBindings (initialization): time 0.005611/1.12, allocations: 3.15 MB / 1.218 GB, free: 140.8 MB / 0.9949 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00998/1.13, allocations: 3.64 MB / 1.221 GB, free: 137.1 MB / 0.9949 GB
Notification: Performance of setup shared object (initialization): time 3.246e-05/1.13, allocations: 476.8 kB / 1.222 GB, free: 136.6 MB / 0.9949 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006433/1.136, allocations: 2.259 MB / 1.224 GB, free: 134.3 MB / 0.9949 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00865/1.145, allocations: 8.114 MB / 1.232 GB, free: 124.4 MB / 0.9949 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01413/1.159, allocations: 8.297 MB / 1.24 GB, free: 114.9 MB / 0.9949 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.189e-05/1.159, allocations: 16 kB / 1.24 GB, free: 114.9 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=1775) (initialization): time 0.03527/1.194, allocations: 11.18 MB / 1.251 GB, free: 103.5 MB / 0.9949 GB
Notification: Performance of prepare postOptimizeDAE: time 8.273e-05/1.194, allocations: 61.69 kB / 1.251 GB, free: 103.5 MB / 0.9949 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000136/1.195, allocations: 76 kB / 1.251 GB, free: 103.4 MB / 0.9949 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002169/1.197, allocations: 1.225 MB / 1.252 GB, free: 102 MB / 0.9949 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01129/1.208, allocations: 3.713 MB / 1.256 GB, free: 98.31 MB / 0.9949 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01683/1.225, allocations: 63.87 MB / 1.318 GB, free: 30.84 MB / 0.9949 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01918/1.244, allocations: 3.943 MB / 1.322 GB, free: 26.88 MB / 0.9949 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003134/1.247, allocations: 0.5427 MB / 1.323 GB, free: 26.34 MB / 0.9949 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0126/1.26, allocations: 2.577 MB / 1.325 GB, free: 23.74 MB / 0.9949 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009899/1.27, allocations: 8.118 MB / 1.333 GB, free: 13.79 MB / 0.9949 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01398/1.284, allocations: 8.297 MB / 1.341 GB, free: 4.273 MB / 0.9949 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.733e-05/1.284, allocations: 20 kB / 1.341 GB, free: 4.254 MB / 0.9949 GB
Notification: Performance of matching and sorting (n=1775) (initialization_lambda0): time 0.03267/1.316, allocations: 11.19 MB / 1.352 GB, free: 8.895 MB / 1.011 GB
Notification: Performance of prepare postOptimizeDAE: time 8.064e-05/1.316, allocations: 61.7 kB / 1.352 GB, free: 8.828 MB / 1.011 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001435/1.317, allocations: 84.83 kB / 1.352 GB, free: 8.746 MB / 1.011 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002212/1.319, allocations: 1.209 MB / 1.353 GB, free: 7.41 MB / 1.011 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0118/1.331, allocations: 3.713 MB / 1.357 GB, free: 3.691 MB / 1.011 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02719/1.358, allocations: 63.88 MB / 1.419 GB, free: 216 kB / 1.073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02026/1.378, allocations: 3.712 MB / 1.423 GB, free: 12.49 MB / 1.089 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003385/1.381, allocations: 0.5466 MB / 1.424 GB, free: 11.94 MB / 1.089 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 281
 * Number of states: 0 ()
 * Number of discrete variables: 95 (borHol.borHol[1].TBouCon.iSam,$PRE.borHol.borHol[1].TBouCon.iSam,borHol.borHol[1].TBouCon.UOld,$PRE.borHol.borHol[1].TBouCon.UOld,borHol.borHol[1].TBouCon.QAve_flow,$PRE.borHol.borHol[1].TBouCon.QAve_flow,borHol.borHol[2].TBouCon.iSam,$PRE.borHol.borHol[2].TBouCon.iSam,borHol.borHol[2].TBouCon.UOld,$PRE.borHol.borHol[2].TBouCon.UOld,borHol.borHol[2].TBouCon.QAve_flow,$PRE.borHol.borHol[2].TBouCon.QAve_flow,borHol.borHol[3].TBouCon.iSam,$PRE.borHol.borHol[3].TBouCon.iSam,borHol.borHol[3].TBouCon.UOld,$PRE.borHol.borHol[3].TBouCon.UOld,borHol.borHol[3].TBouCon.QAve_flow,$PRE.borHol.borHol[3].TBouCon.QAve_flow,borHol.borHol[4].TBouCon.iSam,$PRE.borHol.borHol[4].TBouCon.iSam,borHol.borHol[4].TBouCon.UOld,$PRE.borHol.borHol[4].TBouCon.UOld,borHol.borHol[4].TBouCon.QAve_flow,$PRE.borHol.borHol[4].TBouCon.QAve_flow,borHol.borHol[5].TBouCon.iSam,$PRE.borHol.borHol[5].TBouCon.iSam,borHol.borHol[5].TBouCon.UOld,$PRE.borHol.borHol[5].TBouCon.UOld,borHol.borHol[5].TBouCon.QAve_flow,$PRE.borHol.borHol[5].TBouCon.QAve_flow,borHol.borHol[6].TBouCon.iSam,$PRE.borHol.borHol[6].TBouCon.iSam,borHol.borHol[6].TBouCon.UOld,$PRE.borHol.borHol[6].TBouCon.UOld,borHol.borHol[6].TBouCon.QAve_flow,$PRE.borHol.borHol[6].TBouCon.QAve_flow,borHol.borHol[7].TBouCon.iSam,$PRE.borHol.borHol[7].TBouCon.iSam,borHol.borHol[7].TBouCon.UOld,$PRE.borHol.borHol[7].TBouCon.UOld,borHol.borHol[7].TBouCon.QAve_flow,$PRE.borHol.borHol[7].TBouCon.QAve_flow,borHol.borHol[8].TBouCon.iSam,$PRE.borHol.borHol[8].TBouCon.iSam,borHol.borHol[8].TBouCon.UOld,$PRE.borHol.borHol[8].TBouCon.UOld,borHol.borHol[8].TBouCon.QAve_flow,$PRE.borHol.borHol[8].TBouCon.QAve_flow,borHol.borHol[9].TBouCon.iSam,$PRE.borHol.borHol[9].TBouCon.iSam,borHol.borHol[9].TBouCon.UOld,$PRE.borHol.borHol[9].TBouCon.UOld,borHol.borHol[9].TBouCon.QAve_flow,$PRE.borHol.borHol[9].TBouCon.QAve_flow,borHol.borHol[10].TBouCon.iSam,$PRE.borHol.borHol[10].TBouCon.iSam,borHol.borHol[10].TBouCon.UOld,$PRE.borHol.borHol[10].TBouCon.UOld,borHol.borHol[10].TBouCon.QAve_flow,$PRE.borHol.borHol[10].TBouCon.QAve_flow,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,borHol.borHol[10].TBouCon.port.T,$PRE.borHol.borHol[10].TBouCon.port.T,borHol.borHol[9].TBouCon.port.T,$PRE.borHol.borHol[9].TBouCon.port.T,borHol.borHol[8].TBouCon.port.T,$PRE.borHol.borHol[8].TBouCon.port.T,borHol.borHol[7].TBouCon.port.T,$PRE.borHol.borHol[7].TBouCon.port.T,borHol.borHol[6].TBouCon.port.T,$PRE.borHol.borHol[6].TBouCon.port.T,borHol.borHol[5].TBouCon.port.T,$PRE.borHol.borHol[5].TBouCon.port.T,borHol.borHol[4].TBouCon.port.T,$PRE.borHol.borHol[4].TBouCon.port.T,borHol.borHol[3].TBouCon.port.T,$PRE.borHol.borHol[3].TBouCon.port.T,borHol.borHol[2].TBouCon.port.T,$PRE.borHol.borHol[2].TBouCon.port.T,borHol.borHol[1].TBouCon.port.T,$PRE.borHol.borHol[1].TBouCon.port.T,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1634):
 * Single equations (assignments): 1593
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 10
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 30
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 30 systems
   {(1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.004357/1.386, allocations: 1.061 MB / 1.425 GB, free: 10.87 MB / 1.089 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004978/1.391, allocations: 1.585 MB / 1.426 GB, free: 9.273 MB / 1.089 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01224/1.403, allocations: 6.6 MB / 1.433 GB, free: 2.508 MB / 1.089 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.475e-05/1.403, allocations: 72 kB / 1.433 GB, free: 2.438 MB / 1.089 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.406e-05/1.403, allocations: 23.98 kB / 1.433 GB, free: 2.414 MB / 1.089 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.008547/1.412, allocations: 4.633 MB / 1.437 GB, free: 13.6 MB / 1.104 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 postOpt removeSimpleEquations (simulation): time 0.042/1.454, allocations: 23.28 MB / 1.46 GB, free: 6.074 MB / 1.12 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.478e-05/1.454, allocations: 0 / 1.46 GB, free: 6.074 MB / 1.12 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004117/1.458, allocations: 1.471 MB / 1.461 GB, free: 4.602 MB / 1.12 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002246/1.46, allocations: 1.107 MB / 1.462 GB, free: 3.367 MB / 1.12 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001336/1.461, allocations: 335.7 kB / 1.463 GB, free: 3.039 MB / 1.12 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.6336/2.095, allocations: 63.87 MB / 1.525 GB, free: 275.5 MB / 1.12 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.605e-06/2.095, allocations: 7.328 kB / 1.525 GB, free: 275.5 MB / 1.12 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01936/2.114, allocations: 8.761 MB / 1.534 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.007642/2.122, allocations: 3.357 MB / 1.537 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003185/2.125, allocations: 339.8 kB / 1.537 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009843/2.135, allocations: 1.334 MB / 1.539 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001448/2.136, allocations: 0.5255 MB / 1.539 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000692/2.137, allocations: 297.1 kB / 1.539 GB, free: 274.4 MB / 1.12 GB
Notification: Performance of sorting global known variables: time 0.01368/2.151, allocations: 6.393 MB / 1.546 GB, free: 274 MB / 1.12 GB
Notification: Performance of sort global known variables: time 2.2e-07/2.151, allocations: 0 / 1.546 GB, free: 274 MB / 1.12 GB
Notification: Performance of remove unused functions: time 0.03712/2.188, allocations: 12.79 MB / 1.558 GB, free: 273.7 MB / 1.12 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 4
 * Number of states: 150 (borHol.borHol[1].pipFil.vol1.dynBal.U,borHol.borHol[1].pipFil.vol2.dynBal.U,borHol.borHol[1].pipFil.capFil1.T,borHol.borHol[1].pipFil.capFil2.T,borHol.borHol[1].soi.T[1],borHol.borHol[1].soi.T[2],borHol.borHol[1].soi.T[3],borHol.borHol[1].soi.T[4],borHol.borHol[1].soi.T[5],borHol.borHol[1].soi.T[6],borHol.borHol[1].soi.T[7],borHol.borHol[1].soi.T[8],borHol.borHol[1].soi.T[9],borHol.borHol[1].soi.T[10],borHol.borHol[1].TBouCon.U,borHol.borHol[2].pipFil.vol1.dynBal.U,borHol.borHol[2].pipFil.vol2.dynBal.U,borHol.borHol[2].pipFil.capFil1.T,borHol.borHol[2].pipFil.capFil2.T,borHol.borHol[2].soi.T[1],borHol.borHol[2].soi.T[2],borHol.borHol[2].soi.T[3],borHol.borHol[2].soi.T[4],borHol.borHol[2].soi.T[5],borHol.borHol[2].soi.T[6],borHol.borHol[2].soi.T[7],borHol.borHol[2].soi.T[8],borHol.borHol[2].soi.T[9],borHol.borHol[2].soi.T[10],borHol.borHol[2].TBouCon.U,borHol.borHol[3].pipFil.vol1.dynBal.U,borHol.borHol[3].pipFil.vol2.dynBal.U,borHol.borHol[3].pipFil.capFil1.T,borHol.borHol[3].pipFil.capFil2.T,borHol.borHol[3].soi.T[1],borHol.borHol[3].soi.T[2],borHol.borHol[3].soi.T[3],borHol.borHol[3].soi.T[4],borHol.borHol[3].soi.T[5],borHol.borHol[3].soi.T[6],borHol.borHol[3].soi.T[7],borHol.borHol[3].soi.T[8],borHol.borHol[3].soi.T[9],borHol.borHol[3].soi.T[10],borHol.borHol[3].TBouCon.U,borHol.borHol[4].pipFil.vol1.dynBal.U,borHol.borHol[4].pipFil.vol2.dynBal.U,borHol.borHol[4].pipFil.capFil1.T,borHol.borHol[4].pipFil.capFil2.T,borHol.borHol[4].soi.T[1],borHol.borHol[4].soi.T[2],borHol.borHol[4].soi.T[3],borHol.borHol[4].soi.T[4],borHol.borHol[4].soi.T[5],borHol.borHol[4].soi.T[6],borHol.borHol[4].soi.T[7],borHol.borHol[4].soi.T[8],borHol.borHol[4].soi.T[9],borHol.borHol[4].soi.T[10],borHol.borHol[4].TBouCon.U,borHol.borHol[5].pipFil.vol1.dynBal.U,borHol.borHol[5].pipFil.vol2.dynBal.U,borHol.borHol[5].pipFil.capFil1.T,borHol.borHol[5].pipFil.capFil2.T,borHol.borHol[5].soi.T[1],borHol.borHol[5].soi.T[2],borHol.borHol[5].soi.T[3],borHol.borHol[5].soi.T[4],borHol.borHol[5].soi.T[5],borHol.borHol[5].soi.T[6],borHol.borHol[5].soi.T[7],borHol.borHol[5].soi.T[8],borHol.borHol[5].soi.T[9],borHol.borHol[5].soi.T[10],borHol.borHol[5].TBouCon.U,borHol.borHol[6].pipFil.vol1.dynBal.U,borHol.borHol[6].pipFil.vol2.dynBal.U,borHol.borHol[6].pipFil.capFil1.T,borHol.borHol[6].pipFil.capFil2.T,borHol.borHol[6].soi.T[1],borHol.borHol[6].soi.T[2],borHol.borHol[6].soi.T[3],borHol.borHol[6].soi.T[4],borHol.borHol[6].soi.T[5],borHol.borHol[6].soi.T[6],borHol.borHol[6].soi.T[7],borHol.borHol[6].soi.T[8],borHol.borHol[6].soi.T[9],borHol.borHol[6].soi.T[10],borHol.borHol[6].TBouCon.U,borHol.borHol[7].pipFil.vol1.dynBal.U,borHol.borHol[7].pipFil.vol2.dynBal.U,borHol.borHol[7].pipFil.capFil1.T,borHol.borHol[7].pipFil.capFil2.T,borHol.borHol[7].soi.T[1],borHol.borHol[7].soi.T[2],borHol.borHol[7].soi.T[3],borHol.borHol[7].soi.T[4],borHol.borHol[7].soi.T[5],borHol.borHol[7].soi.T[6],borHol.borHol[7].soi.T[7],borHol.borHol[7].soi.T[8],borHol.borHol[7].soi.T[9],borHol.borHol[7].soi.T[10],borHol.borHol[7].TBouCon.U,borHol.borHol[8].pipFil.vol1.dynBal.U,borHol.borHol[8].pipFil.vol2.dynBal.U,borHol.borHol[8].pipFil.capFil1.T,borHol.borHol[8].pipFil.capFil2.T,borHol.borHol[8].soi.T[1],borHol.borHol[8].soi.T[2],borHol.borHol[8].soi.T[3],borHol.borHol[8].soi.T[4],borHol.borHol[8].soi.T[5],borHol.borHol[8].soi.T[6],borHol.borHol[8].soi.T[7],borHol.borHol[8].soi.T[8],borHol.borHol[8].soi.T[9],borHol.borHol[8].soi.T[10],borHol.borHol[8].TBouCon.U,borHol.borHol[9].pipFil.vol1.dynBal.U,borHol.borHol[9].pipFil.vol2.dynBal.U,borHol.borHol[9].pipFil.capFil1.T,borHol.borHol[9].pipFil.capFil2.T,borHol.borHol[9].soi.T[1],borHol.borHol[9].soi.T[2],borHol.borHol[9].soi.T[3],borHol.borHol[9].soi.T[4],borHol.borHol[9].soi.T[5],borHol.borHol[9].soi.T[6],borHol.borHol[9].soi.T[7],borHol.borHol[9].soi.T[8],borHol.borHol[9].soi.T[9],borHol.borHol[9].soi.T[10],borHol.borHol[9].TBouCon.U,borHol.borHol[10].pipFil.vol1.dynBal.U,borHol.borHol[10].pipFil.vol2.dynBal.U,borHol.borHol[10].pipFil.capFil1.T,borHol.borHol[10].pipFil.capFil2.T,borHol.borHol[10].soi.T[1],borHol.borHol[10].soi.T[2],borHol.borHol[10].soi.T[3],borHol.borHol[10].soi.T[4],borHol.borHol[10].soi.T[5],borHol.borHol[10].soi.T[6],borHol.borHol[10].soi.T[7],borHol.borHol[10].soi.T[8],borHol.borHol[10].soi.T[9],borHol.borHol[10].soi.T[10],borHol.borHol[10].TBouCon.U)
 * Number of discrete variables: 53 ($whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,borHol.borHol[1].TBouCon.QAve_flow,borHol.borHol[1].TBouCon.port.T,borHol.borHol[1].TBouCon.UOld,borHol.borHol[1].TBouCon.iSam,borHol.borHol[2].TBouCon.QAve_flow,borHol.borHol[2].TBouCon.port.T,borHol.borHol[2].TBouCon.UOld,borHol.borHol[2].TBouCon.iSam,borHol.borHol[3].TBouCon.QAve_flow,borHol.borHol[3].TBouCon.port.T,borHol.borHol[3].TBouCon.UOld,borHol.borHol[3].TBouCon.iSam,borHol.borHol[4].TBouCon.QAve_flow,borHol.borHol[4].TBouCon.port.T,borHol.borHol[4].TBouCon.UOld,borHol.borHol[4].TBouCon.iSam,borHol.borHol[5].TBouCon.QAve_flow,borHol.borHol[5].TBouCon.port.T,borHol.borHol[5].TBouCon.UOld,borHol.borHol[5].TBouCon.iSam,borHol.borHol[6].TBouCon.QAve_flow,borHol.borHol[6].TBouCon.port.T,borHol.borHol[6].TBouCon.UOld,borHol.borHol[6].TBouCon.iSam,borHol.borHol[7].TBouCon.QAve_flow,borHol.borHol[7].TBouCon.port.T,borHol.borHol[7].TBouCon.UOld,borHol.borHol[7].TBouCon.iSam,borHol.borHol[8].TBouCon.QAve_flow,borHol.borHol[8].TBouCon.port.T,borHol.borHol[8].TBouCon.UOld,borHol.borHol[8].TBouCon.iSam,borHol.borHol[9].TBouCon.QAve_flow,borHol.borHol[9].TBouCon.port.T,borHol.borHol[9].TBouCon.UOld,borHol.borHol[9].TBouCon.iSam,borHol.borHol[10].TBouCon.QAve_flow,borHol.borHol[10].TBouCon.port.T,borHol.borHol[10].TBouCon.UOld,borHol.borHol[10].TBouCon.iSam,pulse.T_start,pulse.count)
 * Number of discrete states: 21 (pulse.count,borHol.borHol[1].TBouCon.iSam,borHol.borHol[1].TBouCon.UOld,borHol.borHol[2].TBouCon.iSam,borHol.borHol[2].TBouCon.UOld,borHol.borHol[3].TBouCon.iSam,borHol.borHol[3].TBouCon.UOld,borHol.borHol[4].TBouCon.iSam,borHol.borHol[4].TBouCon.UOld,borHol.borHol[5].TBouCon.iSam,borHol.borHol[5].TBouCon.UOld,borHol.borHol[6].TBouCon.iSam,borHol.borHol[6].TBouCon.UOld,borHol.borHol[7].TBouCon.iSam,borHol.borHol[7].TBouCon.UOld,borHol.borHol[8].TBouCon.iSam,borHol.borHol[8].TBouCon.UOld,borHol.borHol[9].TBouCon.iSam,borHol.borHol[9].TBouCon.UOld,borHol.borHol[10].TBouCon.iSam,borHol.borHol[10].TBouCon.UOld)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (675):
 * Single equations (assignments): 602
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * When equations: 42
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 30
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 30 systems
   {(1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.002597/2.19, allocations: 1.009 MB / 1.559 GB, free: 273.6 MB / 1.12 GB
Notification: Performance of simCode: created initialization part: time 0.03269/2.223, allocations: 24.55 MB / 1.583 GB, free: 260.5 MB / 1.12 GB
Notification: Performance of simCode: created event and clocks part: time 9.478e-06/2.223, allocations: 4.938 kB / 1.583 GB, free: 260.5 MB / 1.12 GB
Notification: Performance of simCode: created simulation system equations: time 0.006576/2.23, allocations: 8.2 MB / 1.591 GB, free: 254.3 MB / 1.12 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02247/2.252, allocations: 7.452 MB / 1.598 GB, free: 252.5 MB / 1.12 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03973/2.292, allocations: 32.88 MB / 1.63 GB, free: 222.7 MB / 1.12 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00423/2.296, allocations: 4.938 MB / 1.635 GB, free: 219.1 MB / 1.12 GB
Notification: Performance of simCode: alias equations: time 0.02086/2.317, allocations: 11.14 MB / 1.646 GB, free: 212.7 MB / 1.12 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00677/2.324, allocations: 1.079 MB / 1.647 GB, free: 211.7 MB / 1.12 GB
Notification: Performance of SimCode: time 4.278e-06/2.324, allocations: 0 / 1.647 GB, free: 211.7 MB / 1.12 GB
Notification: Performance of Templates: time 0.6663/2.99, allocations: 376.9 MB / 2.015 GB, free: 302.6 MB / 1.167 GB
"
[Timeout remaining time 297]
make -j1 -f Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.makefile [Timeout 300]
(rm -f Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe ; mkfifo Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe ; head -c 1048576 < Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe >> ../files/Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.sim & ./Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube -abortSlowSimulation -alarm=240  -emit_protected -lv LOG_STATS > Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe 2>&1) [Timeout 240]
diffSimulationResults("Buildings_11_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_11.x/Buildings_Fluid_Geothermal_Boreholes_Examples_UTube.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 15.971618206007406]