Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.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.0009518/0.0009518, allocations: 80.88 kB / 21.55 MB, free: 2.945 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.0009037/0.0009037, allocations: 165.5 kB / 24.97 MB, free: 8.656 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.8993/0.8993, allocations: 177.1 MB / 205.4 MB, free: 5.496 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.886/1.886, allocations: 389.6 MB / 0.6362 GB, free: 12.32 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.MassExchangers.Examples.ConstantEffectiveness,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness")
translateModel(Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.213e-06/1.213e-06, allocations: 0 / 0.7759 GB, free: 5.414 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 9.307e-06/1.052e-05, allocations: 2.312 kB / 0.7759 GB, free: 5.41 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness): time 0.1256/0.1256, allocations: 153.4 MB / 0.9258 GB, free: 11.59 MB / 0.8855 GB
Notification: Performance of NFInst.instExpressions: time 0.005876/0.1315, allocations: 5.477 MB / 0.9311 GB, free: 6.105 MB / 0.8855 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004968/0.132, allocations: 59.5 kB / 0.9312 GB, free: 6.047 MB / 0.8855 GB
Notification: Performance of NFTyping.typeComponents: time 0.0006822/0.1327, allocations: 377.1 kB / 0.9316 GB, free: 5.676 MB / 0.8855 GB
Notification: Performance of NFTyping.typeBindings: time 0.002174/0.1349, allocations: 1.675 MB / 0.9332 GB, free: 3.992 MB / 0.8855 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001466/0.1363, allocations: 1.184 MB / 0.9343 GB, free: 2.805 MB / 0.8855 GB
Notification: Performance of NFFlatten.flatten: time 0.001778/0.1381, allocations: 2.781 MB / 0.9371 GB, free: 16 kB / 0.8855 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009581/0.1391, allocations: 0.8608 MB / 0.9379 GB, free: 15.14 MB / 0.9011 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001458/0.1405, allocations: 1.619 MB / 0.9395 GB, free: 13.56 MB / 0.9011 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006035/0.1411, allocations: 0.7399 MB / 0.9402 GB, free: 12.82 MB / 0.9011 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001088/0.1412, allocations: 112 kB / 0.9403 GB, free: 12.71 MB / 0.9011 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001295/0.1425, allocations: 1.305 MB / 0.9416 GB, free: 11.4 MB / 0.9011 GB
Notification: Performance of NFScalarize.scalarize: time 0.0002534/0.1428, allocations: 454.5 kB / 0.942 GB, free: 10.96 MB / 0.9011 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003286/0.1431, allocations: 474.9 kB / 0.9425 GB, free: 10.49 MB / 0.9011 GB
Notification: Performance of NFConvertDAE.convert: time 0.001361/0.1445, allocations: 1.721 MB / 0.9442 GB, free: 8.766 MB / 0.9011 GB
Notification: Performance of FrontEnd - DAE generated: time 2.695e-06/0.1445, allocations: 0 / 0.9442 GB, free: 8.766 MB / 0.9011 GB
Notification: Performance of FrontEnd: time 9.72e-07/0.1445, allocations: 0 / 0.9442 GB, free: 8.766 MB / 0.9011 GB
Notification: Performance of Transformations before backend: time 8.356e-06/0.1445, allocations: 0 / 0.9442 GB, free: 8.766 MB / 0.9011 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 209
 * Number of variables: 209
Notification: Performance of Generate backend data structure: time 0.001917/0.1464, allocations: 1.671 MB / 0.9458 GB, free: 7.055 MB / 0.9011 GB
Notification: Performance of prepare preOptimizeDAE: time 3.342e-05/0.1464, allocations: 9.344 kB / 0.9458 GB, free: 7.047 MB / 0.9011 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001186/0.1476, allocations: 0.5147 MB / 0.9463 GB, free: 6.531 MB / 0.9011 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007434/0.1484, allocations: 0.6919 MB / 0.947 GB, free: 5.812 MB / 0.9011 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.15e-05/0.1484, allocations: 70 kB / 0.947 GB, free: 5.742 MB / 0.9011 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 8.528e-05/0.1485, allocations: 83.98 kB / 0.9471 GB, free: 5.66 MB / 0.9011 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0008384/0.1493, allocations: 0.788 MB / 0.9479 GB, free: 4.828 MB / 0.9011 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.506e-05/0.1493, allocations: 0 / 0.9479 GB, free: 4.828 MB / 0.9011 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.694e-05/0.1494, allocations: 32 kB / 0.9479 GB, free: 4.797 MB / 0.9011 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.8e-05/0.1494, allocations: 49.92 kB / 0.948 GB, free: 4.75 MB / 0.9011 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0005259/0.15, allocations: 377.8 kB / 0.9483 GB, free: 4.379 MB / 0.9011 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.002307/0.1523, allocations: 1.787 MB / 0.9501 GB, free: 2.566 MB / 0.9011 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005035/0.1528, allocations: 384.7 kB / 0.9504 GB, free: 2.18 MB / 0.9011 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.000217/0.153, allocations: 190.1 kB / 0.9506 GB, free: 1.984 MB / 0.9011 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002727/0.1533, allocations: 108.5 kB / 0.9507 GB, free: 1.879 MB / 0.9011 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.267e-05/0.1533, allocations: 39.22 kB / 0.9508 GB, free: 1.832 MB / 0.9011 GB
Notification: Performance of pre-optimization done (n=76): time 1.983e-06/0.1533, allocations: 4 kB / 0.9508 GB, free: 1.828 MB / 0.9011 GB
Notification: Performance of matching and sorting (n=76): time 0.0008024/0.1541, allocations: 0.5251 MB / 0.9513 GB, free: 1.297 MB / 0.9011 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.033e-05/0.1541, allocations: 60.06 kB / 0.9513 GB, free: 1.227 MB / 0.9011 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0003529/0.1545, allocations: 0.5896 MB / 0.9519 GB, free: 0.6289 MB / 0.9011 GB
Notification: Performance of collectPreVariables (initialization): time 5.322e-05/0.1545, allocations: 45.7 kB / 0.952 GB, free: 0.5781 MB / 0.9011 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0001406/0.1547, allocations: 350.9 kB / 0.9523 GB, free: 232 kB / 0.9011 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001367/0.1548, allocations: 265.7 kB / 0.9525 GB, free: 15.96 MB / 0.9167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002256/0.155, allocations: 146.5 kB / 0.9527 GB, free: 15.82 MB / 0.9167 GB
Notification: Performance of setup shared object (initialization): time 7.471e-05/0.1551, allocations: 341.9 kB / 0.953 GB, free: 15.48 MB / 0.9167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001988/0.1553, allocations: 149.3 kB / 0.9531 GB, free: 15.32 MB / 0.9167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002798/0.1556, allocations: 478.3 kB / 0.9536 GB, free: 14.77 MB / 0.9167 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0004028/0.156, allocations: 383.6 kB / 0.954 GB, free: 14.38 MB / 0.9167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.444e-06/0.156, allocations: 0 / 0.954 GB, free: 14.38 MB / 0.9167 GB
Notification: Performance of matching and sorting (n=84) (initialization): time 0.0005249/0.1565, allocations: 491.3 kB / 0.9544 GB, free: 13.88 MB / 0.9167 GB
Notification: Performance of prepare postOptimizeDAE: time 2.496e-05/0.1565, allocations: 33.7 kB / 0.9545 GB, free: 13.84 MB / 0.9167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.498e-06/0.1565, allocations: 8.031 kB / 0.9545 GB, free: 13.83 MB / 0.9167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.424e-05/0.1566, allocations: 4 kB / 0.9545 GB, free: 13.82 MB / 0.9167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006361/0.1572, allocations: 231.7 kB / 0.9547 GB, free: 13.6 MB / 0.9167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0003608/0.1575, allocations: 1.168 MB / 0.9558 GB, free: 12.38 MB / 0.9167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003046/0.1579, allocations: 91.7 kB / 0.9559 GB, free: 12.29 MB / 0.9167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.103e-05/0.1579, allocations: 27.98 kB / 0.956 GB, free: 12.27 MB / 0.9167 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0002922/0.1582, allocations: 241.4 kB / 0.9562 GB, free: 12.02 MB / 0.9167 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0002718/0.1585, allocations: 470.4 kB / 0.9566 GB, free: 11.48 MB / 0.9167 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0003931/0.1589, allocations: 380.7 kB / 0.957 GB, free: 11.08 MB / 0.9167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.563e-06/0.1589, allocations: 0 / 0.957 GB, free: 11.08 MB / 0.9167 GB
Notification: Performance of matching and sorting (n=84) (initialization_lambda0): time 0.0005097/0.1594, allocations: 491.4 kB / 0.9575 GB, free: 10.58 MB / 0.9167 GB
Notification: Performance of prepare postOptimizeDAE: time 1.925e-05/0.1594, allocations: 37.69 kB / 0.9575 GB, free: 10.54 MB / 0.9167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.164e-06/0.1594, allocations: 0 / 0.9575 GB, free: 10.54 MB / 0.9167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.22e-06/0.1594, allocations: 4 kB / 0.9575 GB, free: 10.54 MB / 0.9167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005412/0.16, allocations: 227.6 kB / 0.9577 GB, free: 10.31 MB / 0.9167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.087e-06/0.16, allocations: 4 kB / 0.9577 GB, free: 10.31 MB / 0.9167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002914/0.1602, allocations: 91.58 kB / 0.9578 GB, free: 10.22 MB / 0.9167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.726e-05/0.1603, allocations: 27.97 kB / 0.9578 GB, free: 10.19 MB / 0.9167 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 4
 * Number of states: 0 ()
 * Number of discrete variables: 2 (hex.i1_w,hex.i2_w)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (83):
 * Single equations (assignments): 80
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 2 systems
   {1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0001382/0.1604, allocations: 216.1 kB / 0.958 GB, free: 9.973 MB / 0.9167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001017/0.1605, allocations: 83.92 kB / 0.9581 GB, free: 9.891 MB / 0.9167 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006258/0.1612, allocations: 0.4955 MB / 0.9586 GB, free: 9.383 MB / 0.9167 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.082e-06/0.1612, allocations: 3.984 kB / 0.9586 GB, free: 9.379 MB / 0.9167 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.473e-06/0.1612, allocations: 0 / 0.9586 GB, free: 9.379 MB / 0.9167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002219/0.1614, allocations: 147.2 kB / 0.9588 GB, free: 9.234 MB / 0.9167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.064e-05/0.1614, allocations: 16 kB / 0.9588 GB, free: 9.219 MB / 0.9167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003227/0.1618, allocations: 59.89 kB / 0.9588 GB, free: 9.16 MB / 0.9167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.762e-05/0.1619, allocations: 52.55 kB / 0.9589 GB, free: 9.109 MB / 0.9167 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0007273/0.1626, allocations: 0.526 MB / 0.9594 GB, free: 8.574 MB / 0.9167 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0008224/0.1634, allocations: 0.7606 MB / 0.9601 GB, free: 7.793 MB / 0.9167 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0002099/0.1636, allocations: 183.2 kB / 0.9603 GB, free: 7.609 MB / 0.9167 GB
Notification: Performance of sorting global known variables: time 0.0001949/0.1638, allocations: 288.5 kB / 0.9606 GB, free: 7.328 MB / 0.9167 GB
Notification: Performance of Backend: time 1.6e-07/0.1638, allocations: 0 / 0.9606 GB, free: 7.328 MB / 0.9167 GB
Notification: Performance of simCode: created initialization part: time 0.001596/0.1654, allocations: 1.1 MB / 0.9617 GB, free: 6.199 MB / 0.9167 GB
Notification: Performance of SimCode: time 0.002234/0.1676, allocations: 2.332 MB / 0.9639 GB, free: 3.801 MB / 0.9167 GB
Notification: Performance of Templates: time 0.02713/0.1948, allocations: 28.67 MB / 0.9919 GB, free: 7.469 MB / 0.948 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; mkfifo Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe ; head -c 1048576 < Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe >> ../files/Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.sim & ./Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 5.282493521459401]