Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.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.002435/0.002435, allocations: 83.69 kB / 20.23 MB, free: 4.188 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.002938/0.002938, allocations: 157.3 kB / 23.55 MB, free: 0.875 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 1.568/1.568, allocations: 177.1 MB / 203.9 MB, free: 5.605 MB / 186.7 MB
"
[Timeout remaining time 178]
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 2.87/2.87, allocations: 359 MB / 0.6048 GB, free: 10.94 MB / 0.573 GB
"
[Timeout remaining time 176]
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.Interfaces.Examples.FourPortHeatMassExchanger,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|fouPor.Q1_flow|fouPor.Q2_flow|fouPor.vol1.T|fouPor.vol2.T|sou2.T|sou1.T_in|sin.T",fileNamePrefix="Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger")
translateModel(Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|fouPor.Q1_flow|fouPor.Q2_flow|fouPor.vol1.T|fouPor.vol2.T|sou2.T|sou1.T_in|sin.T",fileNamePrefix="Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.894e-06/1.894e-06, allocations: 0 / 0.7864 GB, free: 12.8 MB / 0.7448 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.238e-05/3.427e-05, allocations: 7.125 kB / 0.7864 GB, free: 12.79 MB / 0.7448 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger): time 0.07733/0.07737, allocations: 43.02 MB / 0.8284 GB, free: 1.695 MB / 0.7761 GB
Notification: Performance of NFInst.instExpressions: time 0.02329/0.1007, allocations: 10.65 MB / 0.8388 GB, free: 7.02 MB / 0.7917 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001747/0.1024, allocations: 79.31 kB / 0.8389 GB, free: 6.941 MB / 0.7917 GB
Notification: Performance of NFTyping.typeComponents: time 0.001797/0.1042, allocations: 0.6558 MB / 0.8395 GB, free: 6.281 MB / 0.7917 GB
Notification: Performance of NFTyping.typeBindings: time 0.00539/0.1096, allocations: 2.055 MB / 0.8415 GB, free: 4.215 MB / 0.7917 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003154/0.1127, allocations: 1.247 MB / 0.8427 GB, free: 2.965 MB / 0.7917 GB
Notification: Performance of NFFlatten.flatten: time 0.005355/0.1181, allocations: 3.499 MB / 0.8462 GB, free: 15.45 MB / 0.8073 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001588/0.1197, allocations: 0.846 MB / 0.847 GB, free: 14.59 MB / 0.8073 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003953/0.1236, allocations: 1.73 MB / 0.8487 GB, free: 12.88 MB / 0.8073 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001571/0.1252, allocations: 0.8139 MB / 0.8495 GB, free: 12.06 MB / 0.8073 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002165/0.1254, allocations: 100 kB / 0.8496 GB, free: 11.96 MB / 0.8073 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001981/0.1274, allocations: 0.7285 MB / 0.8503 GB, free: 11.23 MB / 0.8073 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003861/0.1278, allocations: 302.7 kB / 0.8506 GB, free: 10.93 MB / 0.8073 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006985/0.1285, allocations: 442.9 kB / 0.851 GB, free: 10.5 MB / 0.8073 GB
Notification: Performance of NFConvertDAE.convert: time 0.003411/0.1319, allocations: 1.971 MB / 0.8529 GB, free: 8.523 MB / 0.8073 GB
Notification: Performance of FrontEnd - DAE generated: time 4.358e-06/0.1319, allocations: 0 / 0.8529 GB, free: 8.523 MB / 0.8073 GB
Notification: Performance of FrontEnd: time 1.563e-06/0.1319, allocations: 4 kB / 0.8529 GB, free: 8.52 MB / 0.8073 GB
Notification: Performance of Transformations before backend: time 2.042e-05/0.1319, allocations: 0 / 0.8529 GB, free: 8.52 MB / 0.8073 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 174
 * Number of variables: 174
Notification: Performance of Generate backend data structure: time 0.003073/0.135, allocations: 1.678 MB / 0.8546 GB, free: 6.801 MB / 0.8073 GB
Notification: Performance of prepare preOptimizeDAE: time 4.767e-05/0.135, allocations: 12.03 kB / 0.8546 GB, free: 6.789 MB / 0.8073 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.000544/0.1356, allocations: 208 kB / 0.8548 GB, free: 6.586 MB / 0.8073 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002168/0.1378, allocations: 1.081 MB / 0.8558 GB, free: 5.465 MB / 0.8073 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.963e-05/0.1378, allocations: 54.38 kB / 0.8559 GB, free: 5.414 MB / 0.8073 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001329/0.138, allocations: 83.94 kB / 0.8559 GB, free: 5.332 MB / 0.8073 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001726/0.1397, allocations: 0.9699 MB / 0.8569 GB, free: 4.277 MB / 0.8073 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.62e-05/0.1397, allocations: 4 kB / 0.8569 GB, free: 4.273 MB / 0.8073 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.48e-05/0.1398, allocations: 28 kB / 0.8569 GB, free: 4.246 MB / 0.8073 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.872e-05/0.1398, allocations: 16 kB / 0.8569 GB, free: 4.23 MB / 0.8073 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001106/0.1409, allocations: 0.5008 MB / 0.8574 GB, free: 3.727 MB / 0.8073 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.005296/0.1462, allocations: 3.636 MB / 0.861 GB, free: 15.97 MB / 0.823 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005817/0.1468, allocations: 238.8 kB / 0.8612 GB, free: 15.73 MB / 0.823 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002997/0.1471, allocations: 128.6 kB / 0.8613 GB, free: 15.6 MB / 0.823 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002586/0.1474, allocations: 63.89 kB / 0.8614 GB, free: 15.54 MB / 0.823 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.315e-05/0.1474, allocations: 41.39 kB / 0.8614 GB, free: 15.48 MB / 0.823 GB
Notification: Performance of pre-optimization done (n=31): time 1.833e-06/0.1474, allocations: 0 / 0.8614 GB, free: 15.48 MB / 0.823 GB
Notification: Performance of matching and sorting (n=31): time 0.001102/0.1485, allocations: 430.2 kB / 0.8618 GB, free: 15.06 MB / 0.823 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.042e-05/0.1486, allocations: 59.39 kB / 0.8619 GB, free: 14.99 MB / 0.823 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001431/0.15, allocations: 1.038 MB / 0.8629 GB, free: 13.93 MB / 0.823 GB
Notification: Performance of collectPreVariables (initialization): time 4.651e-05/0.15, allocations: 33.7 kB / 0.8629 GB, free: 13.89 MB / 0.823 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006206/0.1507, allocations: 0.6237 MB / 0.8636 GB, free: 13.27 MB / 0.823 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001462/0.1508, allocations: 123 kB / 0.8637 GB, free: 13.15 MB / 0.823 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002334/0.151, allocations: 143.9 kB / 0.8638 GB, free: 13 MB / 0.823 GB
Notification: Performance of setup shared object (initialization): time 0.0002984/0.1513, allocations: 0.516 MB / 0.8643 GB, free: 12.48 MB / 0.823 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003056/0.1516, allocations: 125.2 kB / 0.8644 GB, free: 12.36 MB / 0.823 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006865/0.1523, allocations: 0.5437 MB / 0.865 GB, free: 11.67 MB / 0.823 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008593/0.1532, allocations: 0.5121 MB / 0.8655 GB, free: 11.06 MB / 0.823 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.422e-06/0.1532, allocations: 4 kB / 0.8655 GB, free: 11.05 MB / 0.823 GB
Notification: Performance of matching and sorting (n=65) (initialization): time 0.001114/0.1543, allocations: 0.5475 MB / 0.866 GB, free: 10.5 MB / 0.823 GB
Notification: Performance of prepare postOptimizeDAE: time 1.678e-05/0.1543, allocations: 8 kB / 0.866 GB, free: 10.49 MB / 0.823 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.242e-05/0.1543, allocations: 8 kB / 0.866 GB, free: 10.48 MB / 0.823 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.389e-05/0.1544, allocations: 8 kB / 0.866 GB, free: 10.48 MB / 0.823 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004329/0.1548, allocations: 111.8 kB / 0.8661 GB, free: 10.37 MB / 0.823 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.826e-05/0.1548, allocations: 15.92 kB / 0.8661 GB, free: 10.35 MB / 0.823 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004802/0.1553, allocations: 59.94 kB / 0.8662 GB, free: 10.29 MB / 0.823 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.517e-05/0.1553, allocations: 31.98 kB / 0.8662 GB, free: 10.26 MB / 0.823 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (65):
 * Single equations (assignments): 65
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0004133/0.1558, allocations: 304.4 kB / 0.8665 GB, free: 9.953 MB / 0.823 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001994/0.156, allocations: 107.3 kB / 0.8666 GB, free: 9.848 MB / 0.823 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008875/0.1568, allocations: 384.4 kB / 0.867 GB, free: 9.461 MB / 0.823 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.046e-05/0.1569, allocations: 4 kB / 0.867 GB, free: 9.457 MB / 0.823 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.456e-06/0.1569, allocations: 0 / 0.867 GB, free: 9.457 MB / 0.823 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0006566/0.1575, allocations: 377.9 kB / 0.8674 GB, free: 9.059 MB / 0.823 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002386/0.1599, allocations: 1.353 MB / 0.8687 GB, free: 7.645 MB / 0.823 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.081e-06/0.1599, allocations: 3.984 kB / 0.8687 GB, free: 7.641 MB / 0.823 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000321/0.1602, allocations: 67.97 kB / 0.8687 GB, free: 7.574 MB / 0.823 GB
Notification: Performance of postOpt tearingSystem (simulation): time 4.248e-06/0.1602, allocations: 3.938 kB / 0.8688 GB, free: 7.57 MB / 0.823 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.933e-05/0.1603, allocations: 16.14 kB / 0.8688 GB, free: 7.555 MB / 0.823 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.805e-06/0.1603, allocations: 4 kB / 0.8688 GB, free: 7.551 MB / 0.823 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.498e-06/0.1603, allocations: 7.938 kB / 0.8688 GB, free: 7.543 MB / 0.823 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001229/0.1615, allocations: 0.7048 MB / 0.8695 GB, free: 6.801 MB / 0.823 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003233/0.1618, allocations: 135.2 kB / 0.8696 GB, free: 6.668 MB / 0.823 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001037/0.1619, allocations: 8 kB / 0.8696 GB, free: 6.66 MB / 0.823 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004093/0.1623, allocations: 23.97 kB / 0.8696 GB, free: 6.637 MB / 0.823 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.092e-05/0.1624, allocations: 31.92 kB / 0.8697 GB, free: 6.605 MB / 0.823 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.532e-05/0.1625, allocations: 19.98 kB / 0.8697 GB, free: 6.586 MB / 0.823 GB
Notification: Performance of sorting global known variables: time 0.0008683/0.1633, allocations: 0.6239 MB / 0.8703 GB, free: 5.969 MB / 0.823 GB
Notification: Performance of sort global known variables: time 9e-08/0.1633, allocations: 0 / 0.8703 GB, free: 5.969 MB / 0.823 GB
Notification: Performance of remove unused functions: time 0.001015/0.1643, allocations: 359 kB / 0.8706 GB, free: 5.617 MB / 0.823 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 2 (fouPor.vol1.dynBal.U,fouPor.vol2.dynBal.U)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (34):
 * Single equations (assignments): 31
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0006881/0.165, allocations: 386.7 kB / 0.871 GB, free: 5.234 MB / 0.823 GB
Notification: Performance of simCode: created initialization part: time 0.0008162/0.1659, allocations: 369.8 kB / 0.8713 GB, free: 4.871 MB / 0.823 GB
Notification: Performance of simCode: created event and clocks part: time 2.334e-06/0.1659, allocations: 4.125 kB / 0.8714 GB, free: 4.867 MB / 0.823 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003402/0.1662, allocations: 163.2 kB / 0.8715 GB, free: 4.703 MB / 0.823 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002273/0.1685, allocations: 0.4942 MB / 0.872 GB, free: 4.219 MB / 0.823 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002655/0.1711, allocations: 1.543 MB / 0.8735 GB, free: 2.609 MB / 0.823 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005198/0.1716, allocations: 437 kB / 0.8739 GB, free: 2.152 MB / 0.823 GB
Notification: Performance of simCode: alias equations: time 0.0005333/0.1722, allocations: 165.9 kB / 0.8741 GB, free: 1.988 MB / 0.823 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000169/0.1723, allocations: 76.47 kB / 0.8741 GB, free: 1.91 MB / 0.823 GB
Notification: Performance of SimCode: time 1.142e-06/0.1723, allocations: 0 / 0.8741 GB, free: 1.91 MB / 0.823 GB
Notification: Performance of Templates: time 0.03605/0.2084, allocations: 18.82 MB / 0.8925 GB, free: 15.52 MB / 0.8542 GB
"
[Timeout remaining time 300]
make -j1 -f Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.makefile [Timeout 300]
(rm -f Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe ; mkfifo Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe ; head -c 1048576 < Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe >> ../files/Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.sim & ./Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger -abortSlowSimulation -alarm=240  -emit_protected -lv LOG_STATS > Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe 2>&1) [Timeout 240]
diffSimulationResults("Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_11.x/Buildings_Fluid_Interfaces_Examples_FourPortHeatMassExchanger.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_11_Buildings.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.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: 8.71077216800768]