Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.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.0009089/0.0009089, allocations: 80.81 kB / 21.55 MB, free: 2.941 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.0009871/0.0009871, allocations: 168.8 kB / 24.97 MB, free: 9.18 MB / 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.9461/0.9461, allocations: 177.1 MB / 205.4 MB, free: 5.488 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 1.929/1.929, allocations: 389.6 MB / 0.6362 GB, free: 12.3 MB / 0.6042 GB
"
[Timeout remaining time 178]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.HeatExchangers.Validation.HeaterCooler_u,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u")
translateModel(Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.162e-06/1.162e-06, allocations: 0 / 0.776 GB, free: 5.375 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.927e-05/2.043e-05, allocations: 6.312 kB / 0.776 GB, free: 5.367 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u): time 0.1438/0.1438, allocations: 159.3 MB / 0.9315 GB, free: 5.711 MB / 0.8855 GB
Notification: Performance of NFInst.instExpressions: time 0.01008/0.1539, allocations: 6.317 MB / 0.9377 GB, free: 15.38 MB / 0.9011 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001795/0.1557, allocations: 107.1 kB / 0.9378 GB, free: 15.27 MB / 0.9011 GB
Notification: Performance of NFTyping.typeComponents: time 0.001054/0.1568, allocations: 492.1 kB / 0.9382 GB, free: 14.79 MB / 0.9011 GB
Notification: Performance of NFTyping.typeBindings: time 0.00268/0.1595, allocations: 1.616 MB / 0.9398 GB, free: 13.17 MB / 0.9011 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002661/0.1621, allocations: 1.619 MB / 0.9414 GB, free: 11.55 MB / 0.9011 GB
Notification: Performance of NFFlatten.flatten: time 0.004223/0.1663, allocations: 4.103 MB / 0.9454 GB, free: 7.43 MB / 0.9011 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002056/0.1684, allocations: 1.263 MB / 0.9466 GB, free: 6.141 MB / 0.9011 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00314/0.1715, allocations: 2.17 MB / 0.9488 GB, free: 4 MB / 0.9011 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001675/0.1732, allocations: 1.044 MB / 0.9498 GB, free: 2.953 MB / 0.9011 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003158/0.1735, allocations: 172 kB / 0.9499 GB, free: 2.785 MB / 0.9011 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001781/0.1753, allocations: 1.123 MB / 0.951 GB, free: 1.66 MB / 0.9011 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004872/0.1758, allocations: 0.6347 MB / 0.9517 GB, free: 1.023 MB / 0.9011 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008709/0.1767, allocations: 0.6858 MB / 0.9523 GB, free: 344 kB / 0.9011 GB
Notification: Performance of NFConvertDAE.convert: time 0.002758/0.1794, allocations: 2.106 MB / 0.9544 GB, free: 14.22 MB / 0.9167 GB
Notification: Performance of FrontEnd - DAE generated: time 4.258e-06/0.1794, allocations: 4 kB / 0.9544 GB, free: 14.22 MB / 0.9167 GB
Notification: Performance of FrontEnd: time 1.363e-06/0.1794, allocations: 0 / 0.9544 GB, free: 14.22 MB / 0.9167 GB
Notification: Performance of Transformations before backend: time 4.164e-05/0.1795, allocations: 0 / 0.9544 GB, free: 14.22 MB / 0.9167 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 294
 * Number of variables: 294
Notification: Performance of Generate backend data structure: time 0.002516/0.182, allocations: 2.251 MB / 0.9566 GB, free: 11.91 MB / 0.9167 GB
Notification: Performance of prepare preOptimizeDAE: time 4.893e-05/0.182, allocations: 9.344 kB / 0.9566 GB, free: 11.9 MB / 0.9167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001663/0.1837, allocations: 0.6665 MB / 0.9572 GB, free: 11.23 MB / 0.9167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001562/0.1853, allocations: 1.099 MB / 0.9583 GB, free: 10.08 MB / 0.9167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.707e-05/0.1853, allocations: 90.28 kB / 0.9584 GB, free: 9.988 MB / 0.9167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001195/0.1854, allocations: 127.9 kB / 0.9585 GB, free: 9.863 MB / 0.9167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001043/0.1865, allocations: 1.1 MB / 0.9596 GB, free: 8.645 MB / 0.9167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.248e-05/0.1865, allocations: 0 / 0.9596 GB, free: 8.645 MB / 0.9167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7e-05/0.1866, allocations: 51.94 kB / 0.9596 GB, free: 8.594 MB / 0.9167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.921e-05/0.1866, allocations: 46.2 kB / 0.9597 GB, free: 8.547 MB / 0.9167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0006475/0.1873, allocations: 385.1 kB / 0.9601 GB, free: 8.145 MB / 0.9167 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.003351/0.1906, allocations: 3.053 MB / 0.963 GB, free: 5.012 MB / 0.9167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008178/0.1914, allocations: 488.1 kB / 0.9635 GB, free: 4.523 MB / 0.9167 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002147/0.1916, allocations: 215.6 kB / 0.9637 GB, free: 4.305 MB / 0.9167 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002044/0.1918, allocations: 68.53 kB / 0.9638 GB, free: 4.234 MB / 0.9167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0003779/0.1922, allocations: 472.3 kB / 0.9642 GB, free: 3.723 MB / 0.9167 GB
Notification: Performance of pre-optimization done (n=85): time 2.906e-06/0.1922, allocations: 4 kB / 0.9642 GB, free: 3.719 MB / 0.9167 GB
Notification: Performance of matching and sorting (n=92): time 0.002744/0.195, allocations: 1.672 MB / 0.9659 GB, free: 1.984 MB / 0.9167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.277e-05/0.195, allocations: 90.11 kB / 0.966 GB, free: 1.879 MB / 0.9167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0006774/0.1957, allocations: 0.9725 MB / 0.9669 GB, free: 0.8906 MB / 0.9167 GB
Notification: Performance of collectPreVariables (initialization): time 5.185e-05/0.1957, allocations: 49.7 kB / 0.9669 GB, free: 0.8359 MB / 0.9167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000275/0.196, allocations: 0.5286 MB / 0.9675 GB, free: 308 kB / 0.9167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001962/0.1962, allocations: 333.3 kB / 0.9678 GB, free: 15.97 MB / 0.9323 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001962/0.1964, allocations: 182.5 kB / 0.968 GB, free: 15.79 MB / 0.9323 GB
Notification: Performance of setup shared object (initialization): time 8.612e-05/0.1965, allocations: 345.9 kB / 0.9683 GB, free: 15.44 MB / 0.9323 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002774/0.1968, allocations: 181.3 kB / 0.9685 GB, free: 15.25 MB / 0.9323 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004205/0.1972, allocations: 0.6066 MB / 0.9691 GB, free: 14.5 MB / 0.9323 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009833/0.1982, allocations: 0.8313 MB / 0.9699 GB, free: 13.6 MB / 0.9323 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.588e-06/0.1982, allocations: 0 / 0.9699 GB, free: 13.6 MB / 0.9323 GB
Notification: Performance of matching and sorting (n=124) (initialization): time 0.001546/0.1997, allocations: 0.7552 MB / 0.9706 GB, free: 12.82 MB / 0.9323 GB
Notification: Performance of prepare postOptimizeDAE: time 4.374e-05/0.1998, allocations: 45.62 kB / 0.9706 GB, free: 12.77 MB / 0.9323 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.147e-05/0.1998, allocations: 4 kB / 0.9706 GB, free: 12.76 MB / 0.9323 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004471/0.2002, allocations: 172 kB / 0.9708 GB, free: 12.58 MB / 0.9323 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000464/0.2007, allocations: 191.7 kB / 0.971 GB, free: 12.39 MB / 0.9323 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002145/0.2028, allocations: 3.506 MB / 0.9744 GB, free: 8.672 MB / 0.9323 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005889/0.2034, allocations: 43.98 kB / 0.9745 GB, free: 8.629 MB / 0.9323 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.404e-05/0.2035, allocations: 31.98 kB / 0.9745 GB, free: 8.598 MB / 0.9323 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization system:
         $DER.heaDyn.vol.dynBal.medium.p = 0.0.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0005115/0.204, allocations: 285.4 kB / 0.9748 GB, free: 8.305 MB / 0.9323 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0004128/0.2044, allocations: 0.6068 MB / 0.9754 GB, free: 7.562 MB / 0.9323 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0008979/0.2053, allocations: 0.8167 MB / 0.9762 GB, free: 6.668 MB / 0.9323 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.472e-06/0.2053, allocations: 0 / 0.9762 GB, free: 6.668 MB / 0.9323 GB
Notification: Performance of matching and sorting (n=124) (initialization_lambda0): time 0.001231/0.2065, allocations: 0.7399 MB / 0.9769 GB, free: 5.898 MB / 0.9323 GB
Notification: Performance of prepare postOptimizeDAE: time 3.023e-05/0.2066, allocations: 41.69 kB / 0.9769 GB, free: 5.852 MB / 0.9323 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.164e-06/0.2066, allocations: 4 kB / 0.9769 GB, free: 5.848 MB / 0.9323 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002558/0.2068, allocations: 128.6 kB / 0.977 GB, free: 5.711 MB / 0.9323 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004292/0.2073, allocations: 195.7 kB / 0.9772 GB, free: 5.52 MB / 0.9323 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002343/0.2096, allocations: 4.369 MB / 0.9815 GB, free: 0.875 MB / 0.9323 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005909/0.2102, allocations: 43.97 kB / 0.9815 GB, free: 0.832 MB / 0.9323 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.135e-05/0.2102, allocations: 31.98 kB / 0.9816 GB, free: 0.8008 MB / 0.9323 GB
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         $DER.heaDyn.vol.dynBal.medium.p = 0.0.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * Number of states: 0 ()
 * Number of discrete variables: 9 (con1.I.trigger_internal,con2.I.trigger_internal,TSet.nextEventScaled,TSet.nextEvent,$PRE.TSet.nextEvent,TSet.last,TSet.b,TSet.a,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (103):
 * Single equations (assignments): 97
 * Array equations: 2
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,8), (1,2), (1,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.0002429/0.2105, allocations: 277.8 kB / 0.9818 GB, free: 0.5156 MB / 0.9323 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001606/0.2106, allocations: 123.8 kB / 0.982 GB, free: 404 kB / 0.9323 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008339/0.2115, allocations: 0.6244 MB / 0.9826 GB, free: 15.74 MB / 0.948 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0004726/0.212, allocations: 0.5063 MB / 0.9831 GB, free: 15.19 MB / 0.948 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.595e-06/0.212, allocations: 4 kB / 0.9831 GB, free: 15.18 MB / 0.948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003243/0.2123, allocations: 178.9 kB / 0.9832 GB, free: 15.01 MB / 0.948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000104/0.2124, allocations: 20 kB / 0.9832 GB, free: 14.99 MB / 0.948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003392/0.2127, allocations: 44.03 kB / 0.9833 GB, free: 14.95 MB / 0.948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.979e-05/0.2128, allocations: 56.83 kB / 0.9833 GB, free: 14.89 MB / 0.948 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00106/0.2139, allocations: 0.6324 MB / 0.984 GB, free: 14.23 MB / 0.948 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0008182/0.2147, allocations: 0.9206 MB / 0.9849 GB, free: 13.27 MB / 0.948 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0002254/0.2149, allocations: 228.3 kB / 0.9851 GB, free: 13.04 MB / 0.948 GB
Notification: Performance of sorting global known variables: time 0.0004599/0.2154, allocations: 0.4962 MB / 0.9856 GB, free: 12.54 MB / 0.948 GB
Notification: Performance of Backend: time 3.51e-07/0.2154, allocations: 0 / 0.9856 GB, free: 12.54 MB / 0.948 GB
Notification: Performance of simCode: created initialization part: time 0.002459/0.2178, allocations: 1.857 MB / 0.9874 GB, free: 10.57 MB / 0.948 GB
Notification: Performance of SimCode: time 0.005033/0.2229, allocations: 3.872 MB / 0.9912 GB, free: 6.625 MB / 0.948 GB
Notification: Performance of Templates: time 0.04375/0.2666, allocations: 37.57 MB / 1.028 GB, free: 1.68 MB / 0.9792 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.pipe ; mkfifo Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.pipe ; head -c 1048576 < Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.pipe >> ../files/Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.sim & ./Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Fluid.HeatExchangers.Validation.HeaterCooler_u.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 5.711404946632683]