Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_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.001589/0.001589, allocations: 99.64 kB / 18.94 MB, free: 2.551 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.001632/0.001632, allocations: 212.6 kB / 22.25 MB, free: 4.859 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.718/1.718, allocations: 230.6 MB / 256 MB, free: 7.809 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.502/3.502, allocations: 0.5107 GB / 0.8158 GB, free: 17.82 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: 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.1..TBouCon.port.T|borHol.borHol.2..TBouCon.port.T|borHol.borHol.3..TBouCon.port.T|borHol.borHol.4..TBouCon.port.T|borHol.borHol.5..TBouCon.port.T|borHol.borHol.6..TBouCon.port.T|borHol.borHol.7..TBouCon.port.T|borHol.borHol.8..TBouCon.port.T|borHol.borHol.9..TBouCon.port.T|borHol.borHol.10..TBouCon.port.T|borHol.borHol.10..soi.T.5.",fileNamePrefix="Buildings_latest_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.1..TBouCon.port.T|borHol.borHol.2..TBouCon.port.T|borHol.borHol.3..TBouCon.port.T|borHol.borHol.4..TBouCon.port.T|borHol.borHol.5..TBouCon.port.T|borHol.borHol.6..TBouCon.port.T|borHol.borHol.7..TBouCon.port.T|borHol.borHol.8..TBouCon.port.T|borHol.borHol.9..TBouCon.port.T|borHol.borHol.10..TBouCon.port.T|borHol.borHol.10..soi.T.5.",fileNamePrefix="Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube") [Timeout 300]
"Notification: Buildings requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002146/0.002146, allocations: 64.7 kB / 1.186 GB, free: 13.47 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4517/0.4539, allocations: 181.9 MB / 1.364 GB, free: 13.62 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Geothermal.Boreholes.Examples.UTube): time 0.05769/0.5116, allocations: 49.07 MB / 1.412 GB, free: 12.36 MB / 1.107 GB
Notification: Performance of NFInst.instExpressions: time 0.02334/0.5349, allocations: 13.55 MB / 1.425 GB, free: 14.79 MB / 1.123 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00346/0.5384, allocations: 99.19 kB / 1.425 GB, free: 14.69 MB / 1.123 GB
Notification: Performance of NFTyping.typeComponents: time 0.003027/0.5414, allocations: 0.9824 MB / 1.426 GB, free: 13.7 MB / 1.123 GB
Notification: Performance of NFTyping.typeBindings: time 0.008978/0.5504, allocations: 2.969 MB / 1.429 GB, free: 10.72 MB / 1.123 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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.005085/0.5554, allocations: 1.835 MB / 1.431 GB, free: 8.898 MB / 1.123 GB
Notification: Performance of NFFlatten.flatten: time 0.07517/0.6306, allocations: 56.93 MB / 1.486 GB, free: 15.84 MB / 1.186 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02918/0.6598, allocations: 16.68 MB / 1.503 GB, free: 15.02 MB / 1.201 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.04016/0.7, allocations: 18.51 MB / 1.521 GB, free: 12.53 MB / 1.217 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.04229/0.7422, allocations: 18.79 MB / 1.539 GB, free: 9.684 MB / 1.232 GB
Notification: Performance of NFPackage.collectConstants: time 0.01515/0.7574, allocations: 3.324 MB / 1.542 GB, free: 6.359 MB / 1.232 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02874/0.7861, allocations: 4.939 MB / 1.547 GB, free: 1.418 MB / 1.232 GB
Notification: Performance of NFScalarize.scalarize: time 0.01918/0.8053, allocations: 7.89 MB / 1.555 GB, free: 9.508 MB / 1.248 GB
Notification: Performance of NFVerifyModel.verify: time 0.0379/0.8432, allocations: 11.13 MB / 1.566 GB, free: 14.33 MB / 1.264 GB
Notification: Performance of NFConvertDAE.convert: time 1.8/2.644, allocations: 46.74 MB / 1.611 GB, free: 94.53 MB / 1.295 GB
Notification: Performance of FrontEnd - DAE generated: time 9.838e-06/2.644, allocations: 0.6875 kB / 1.611 GB, free: 94.53 MB / 1.295 GB
Notification: Performance of FrontEnd: time 2.786e-06/2.644, allocations: 0.5156 kB / 1.611 GB, free: 94.53 MB / 1.295 GB
Notification: Performance of Transformations before backend: time 0.001443/2.645, allocations: 0 / 1.611 GB, free: 94.53 MB / 1.295 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.1156/2.761, allocations: 26.82 MB / 1.638 GB, free: 86.61 MB / 1.295 GB
Notification: Performance of prepare preOptimizeDAE: time 5.899e-05/2.761, allocations: 9.375 kB / 1.638 GB, free: 86.61 MB / 1.295 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04811/2.809, allocations: 6.745 MB / 1.644 GB, free: 85.39 MB / 1.295 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06441/2.873, allocations: 23.82 MB / 1.667 GB, free: 82.75 MB / 1.295 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001604/2.875, allocations: 0.9563 MB / 1.668 GB, free: 82.73 MB / 1.295 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.01028/2.885, allocations: 1.371 MB / 1.67 GB, free: 82.7 MB / 1.295 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04729/2.932, allocations: 19.36 MB / 1.689 GB, free: 79.04 MB / 1.295 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0006826/2.933, allocations: 39.45 kB / 1.689 GB, free: 79.04 MB / 1.295 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003752/2.937, allocations: 449.9 kB / 1.689 GB, free: 78.7 MB / 1.295 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002589/2.937, allocations: 307.1 kB / 1.689 GB, free: 78.69 MB / 1.295 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03484/2.972, allocations: 16 MB / 1.705 GB, free: 73.21 MB / 1.295 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.1801/3.152, allocations: 71.38 MB / 1.775 GB, free: 14.82 MB / 1.295 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03799/3.19, allocations: 17.67 MB / 1.792 GB, free: 13.19 MB / 1.311 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01594/3.206, allocations: 6.877 MB / 1.799 GB, free: 6.293 MB / 1.311 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001182/3.207, allocations: 181.9 kB / 1.799 GB, free: 6.113 MB / 1.311 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01794/3.225, allocations: 8.4 MB / 1.807 GB, free: 13.29 MB / 1.326 GB
Notification: Performance of pre-optimization done (n=784): time 1.567e-05/3.225, allocations: 0 / 1.807 GB, free: 13.29 MB / 1.326 GB
Notification: Performance of matching and sorting (n=784): time 0.05951/3.285, allocations: 20.35 MB / 1.827 GB, free: 8.867 MB / 1.342 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002861/3.285, allocations: 238.1 kB / 1.827 GB, free: 8.613 MB / 1.342 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04698/3.332, allocations: 16.7 MB / 1.843 GB, free: 7.961 MB / 1.358 GB
Notification: Performance of collectPreVariables (initialization): time 0.005494/3.337, allocations: 313.7 kB / 1.844 GB, free: 7.648 MB / 1.358 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01491/3.352, allocations: 6.971 MB / 1.851 GB, free: 0.7031 MB / 1.358 GB
Notification: Performance of collectInitialBindings (initialization): time 0.008236/3.361, allocations: 2.981 MB / 1.853 GB, free: 13.76 MB / 1.373 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0192/3.38, allocations: 3.183 MB / 1.857 GB, free: 10.57 MB / 1.373 GB
Notification: Performance of setup shared object (initialization): time 0.0001738/3.38, allocations: 309.1 kB / 1.857 GB, free: 10.27 MB / 1.373 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.02834/3.408, allocations: 11.22 MB / 1.868 GB, free: 15.03 MB / 1.389 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0334/3.442, allocations: 15.73 MB / 1.883 GB, free: 13.94 MB / 1.404 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04706/3.489, allocations: 20.18 MB / 1.903 GB, free: 9.156 MB / 1.42 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001472/3.489, allocations: 16 kB / 1.903 GB, free: 9.141 MB / 1.42 GB
Notification: Performance of matching and sorting (n=1795) (initialization): time 0.08646/3.575, allocations: 28.88 MB / 1.931 GB, free: 12.53 MB / 1.451 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001739/3.576, allocations: 57.7 kB / 1.931 GB, free: 12.46 MB / 1.451 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002983/3.576, allocations: 78.39 kB / 1.931 GB, free: 12.39 MB / 1.451 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004673/3.581, allocations: 1.829 MB / 1.933 GB, free: 10.48 MB / 1.451 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02225/3.603, allocations: 3.676 MB / 1.937 GB, free: 6.973 MB / 1.451 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05725/3.66, allocations: 54.44 MB / 1.99 GB, free: 13.91 MB / 1.514 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03807/3.698, allocations: 4.008 MB / 1.994 GB, free: 9.887 MB / 1.514 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005778/3.704, allocations: 0.5427 MB / 1.994 GB, free: 9.344 MB / 1.514 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.05391/3.758, allocations: 17.71 MB / 2.011 GB, free: 7.605 MB / 1.529 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03524/3.793, allocations: 15.73 MB / 2.027 GB, free: 6.52 MB / 1.545 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0463/3.839, allocations: 20.17 MB / 2.047 GB, free: 1.754 MB / 1.561 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001356/3.839, allocations: 16 kB / 2.047 GB, free: 1.738 MB / 1.561 GB
Notification: Performance of matching and sorting (n=1795) (initialization_lambda0): time 0.08994/3.929, allocations: 28.88 MB / 2.075 GB, free: 5.129 MB / 1.592 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001864/3.93, allocations: 53.7 kB / 2.075 GB, free: 5.07 MB / 1.592 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003113/3.93, allocations: 75.98 kB / 2.075 GB, free: 4.996 MB / 1.592 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004704/3.935, allocations: 1.834 MB / 2.077 GB, free: 3.082 MB / 1.592 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02186/3.956, allocations: 3.677 MB / 2.08 GB, free: 15.57 MB / 1.608 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0567/4.013, allocations: 54.42 MB / 2.133 GB, free: 6.473 MB / 1.654 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03907/4.052, allocations: 3.823 MB / 2.137 GB, free: 2.641 MB / 1.654 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005873/4.058, allocations: 0.5466 MB / 2.138 GB, free: 2.094 MB / 1.654 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 261
 * 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 (1654):
 * Single equations (assignments): 1613
 * 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.008394/4.067, allocations: 0.9732 MB / 2.139 GB, free: 1.281 MB / 1.654 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.007875/4.074, allocations: 1.331 MB / 2.14 GB, free: 15.94 MB / 1.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04028/4.115, allocations: 14.63 MB / 2.154 GB, free: 1.23 MB / 1.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.05e-05/4.115, allocations: 80 kB / 2.154 GB, free: 1.152 MB / 1.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001025/4.115, allocations: 22.16 kB / 2.154 GB, free: 1.133 MB / 1.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.03083/4.146, allocations: 12.44 MB / 2.166 GB, free: 4.602 MB / 1.686 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.08955/4.235, allocations: 31.04 MB / 2.197 GB, free: 5.617 MB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.51e-05/4.235, allocations: 0 / 2.197 GB, free: 5.617 MB / 1.717 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.008764/4.244, allocations: 1.474 MB / 2.198 GB, free: 4.199 MB / 1.717 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004471/4.249, allocations: 1.729 MB / 2.2 GB, free: 2.387 MB / 1.717 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.002669/4.251, allocations: 351.7 kB / 2.2 GB, free: 2.043 MB / 1.717 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.05627/4.307, allocations: 54.47 MB / 2.253 GB, free: 8.828 MB / 1.779 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.859e-06/4.307, allocations: 4 kB / 2.253 GB, free: 8.824 MB / 1.779 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05005/4.358, allocations: 21.86 MB / 2.275 GB, free: 2.828 MB / 1.795 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.9414/5.299, allocations: 3.807 MB / 2.278 GB, free: 0.6298 GB / 1.795 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00731/5.306, allocations: 359 kB / 2.279 GB, free: 0.6298 GB / 1.795 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01948/5.326, allocations: 1.363 MB / 2.28 GB, free: 0.6298 GB / 1.795 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002889/5.329, allocations: 0.5319 MB / 2.281 GB, free: 0.6297 GB / 1.795 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001688/5.33, allocations: 294.2 kB / 2.281 GB, free: 0.6297 GB / 1.795 GB
Notification: Performance of sorting global known variables: time 0.03379/5.364, allocations: 11.46 MB / 2.292 GB, free: 0.6294 GB / 1.795 GB
Notification: Performance of sort global known variables: time 3.1e-07/5.364, allocations: 0 / 2.292 GB, free: 0.6294 GB / 1.795 GB
Notification: Performance of remove unused functions: time 0.06568/5.43, allocations: 12.83 MB / 2.305 GB, free: 0.6294 GB / 1.795 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.004782/5.435, allocations: 0.9826 MB / 2.306 GB, free: 0.6293 GB / 1.795 GB
Notification: Performance of simCode: created initialization part: time 0.08408/5.519, allocations: 40.48 MB / 2.345 GB, free: 0.6141 GB / 1.795 GB
Notification: Performance of simCode: created event and clocks part: time 1.53e-05/5.519, allocations: 4.25 kB / 2.345 GB, free: 0.6141 GB / 1.795 GB
Notification: Performance of simCode: created simulation system equations: time 0.01705/5.536, allocations: 9.752 MB / 2.355 GB, free: 0.6086 GB / 1.795 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.04771/5.583, allocations: 7.286 MB / 2.362 GB, free: 0.6043 GB / 1.795 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.09556/5.679, allocations: 45.9 MB / 2.407 GB, free: 0.5642 GB / 1.795 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.02495/5.704, allocations: 11.4 MB / 2.418 GB, free: 0.5546 GB / 1.795 GB
Notification: Performance of simCode: alias equations: time 0.03659/5.741, allocations: 13.07 MB / 2.431 GB, free: 0.5418 GB / 1.795 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.009522/5.75, allocations: 1.081 MB / 2.432 GB, free: 0.5408 GB / 1.795 GB
Notification: Performance of SimCode: time 1.653e-06/5.75, allocations: 0 / 2.432 GB, free: 0.5408 GB / 1.795 GB
Notification: Performance of Templates: time 0.5035/6.253, allocations: 404.8 MB / 2.827 GB, free: 151.3 MB / 1.795 GB
"
[Timeout remaining time 294]
make -j1 -f Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe ; mkfifo Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe >> ../files/Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.sim & ./Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.pipe 2>&1) [Timeout 800]
diffSimulationResults("Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Fluid_Geothermal_Boreholes_Examples_UTube.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Fluid.Geothermal.Boreholes.Examples.UTube.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 30.014468125998974]