Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger")
translateModel(IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001508/0.001508, allocations: 182.7 kB / 16.63 MB, free: 5.117 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.458/0.458, allocations: 87.5 MB / 104.9 MB, free: 13.01 MB / 90.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.337/1.337, allocations: 222.9 MB / 349.1 MB, free: 2.777 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001031/0.001031, allocations: 103.5 kB / 420 MB, free: 8.512 MB / 314.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0009864/0.0009864, allocations: 59.92 kB / 0.4891 GB, free: 7.715 MB / 394.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3325/0.3335, allocations: 70.06 MB / 0.5575 GB, free: 44.62 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger): time 0.029/0.3626, allocations: 41.34 MB / 0.5979 GB, free: 3.746 MB / 410.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01366/0.3763, allocations: 10.43 MB / 0.608 GB, free: 9.293 MB / 426.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001956/0.3783, allocations: 55.62 kB / 0.6081 GB, free: 9.238 MB / 426.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001613/0.3799, allocations: 0.54 MB / 0.6086 GB, free: 8.695 MB / 426.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.004936/0.3849, allocations: 1.732 MB / 0.6103 GB, free: 6.957 MB / 426.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00298/0.3879, allocations: 1.077 MB / 0.6114 GB, free: 5.891 MB / 426.7 MB
Notification: Performance of NFFlatten.flatten: time 0.005218/0.3931, allocations: 4.535 MB / 0.6158 GB, free: 1.348 MB / 426.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001302/0.3945, allocations: 0.8811 MB / 0.6167 GB, free: 464 kB / 426.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002026/0.3965, allocations: 1.058 MB / 0.6177 GB, free: 15.39 MB / 442.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001296/0.3978, allocations: 0.8904 MB / 0.6186 GB, free: 14.5 MB / 442.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001277/0.398, allocations: 48 kB / 0.6186 GB, free: 14.45 MB / 442.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001825/0.3998, allocations: 0.7911 MB / 0.6194 GB, free: 13.66 MB / 442.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002396/0.4001, allocations: 227 kB / 0.6196 GB, free: 13.43 MB / 442.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.00065/0.4007, allocations: 0.5752 MB / 0.6202 GB, free: 12.86 MB / 442.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002307/0.403, allocations: 1.877 MB / 0.622 GB, free: 10.97 MB / 442.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.229e-06/0.403, allocations: 0 / 0.622 GB, free: 10.97 MB / 442.7 MB
Notification: Performance of FrontEnd: time 1.082e-06/0.403, allocations: 0 / 0.622 GB, free: 10.97 MB / 442.7 MB
Notification: Performance of Transformations before backend: time 1.295e-05/0.4031, allocations: 0 / 0.622 GB, free: 10.97 MB / 442.7 MB
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.002361/0.4054, allocations: 1.511 MB / 0.6235 GB, free: 9.406 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.916e-05/0.4055, allocations: 15.95 kB / 0.6235 GB, free: 9.391 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0003229/0.4058, allocations: 139.4 kB / 0.6236 GB, free: 9.254 MB / 442.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001619/0.4074, allocations: 1.076 MB / 0.6247 GB, free: 8.137 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.265e-05/0.4075, allocations: 58 kB / 0.6247 GB, free: 8.078 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001091/0.4076, allocations: 72 kB / 0.6248 GB, free: 8.008 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001247/0.4089, allocations: 0.7944 MB / 0.6256 GB, free: 7.18 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.922e-05/0.4089, allocations: 0 / 0.6256 GB, free: 7.18 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.998e-05/0.409, allocations: 28 kB / 0.6256 GB, free: 7.152 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.534e-05/0.409, allocations: 19.98 kB / 0.6256 GB, free: 7.133 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001104/0.4101, allocations: 0.5711 MB / 0.6262 GB, free: 6.562 MB / 442.7 MB
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.004254/0.4144, allocations: 3.547 MB / 0.6296 GB, free: 2.785 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006012/0.415, allocations: 262.4 kB / 0.6299 GB, free: 2.52 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003423/0.4154, allocations: 172.8 kB / 0.63 GB, free: 2.348 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000635/0.416, allocations: 0.6571 MB / 0.6307 GB, free: 1.543 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.574e-05/0.4161, allocations: 39.09 kB / 0.6307 GB, free: 1.496 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=31): time 3.366e-06/0.4161, allocations: 0 / 0.6307 GB, free: 1.496 MB / 442.7 MB
Notification: Performance of matching and sorting (n=31): time 0.001321/0.4174, allocations: 0.5119 MB / 0.6312 GB, free: 0.9766 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.272e-05/0.4175, allocations: 55.41 kB / 0.6313 GB, free: 0.9102 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001154/0.4186, allocations: 0.9377 MB / 0.6322 GB, free: 15.95 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 3.883e-05/0.4187, allocations: 33.7 kB / 0.6322 GB, free: 15.91 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004024/0.4191, allocations: 499.6 kB / 0.6327 GB, free: 15.42 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001077/0.4192, allocations: 118.4 kB / 0.6328 GB, free: 15.3 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001595/0.4194, allocations: 114.2 kB / 0.6329 GB, free: 15.18 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001155/0.4195, allocations: 329.9 kB / 0.6332 GB, free: 14.85 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003393/0.4198, allocations: 161 kB / 0.6334 GB, free: 14.7 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004634/0.4203, allocations: 362.9 kB / 0.6337 GB, free: 14.27 MB / 458.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008059/0.4211, allocations: 0.5109 MB / 0.6342 GB, free: 13.69 MB / 458.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.208e-06/0.4211, allocations: 0 / 0.6342 GB, free: 13.69 MB / 458.7 MB
Notification: Performance of matching and sorting (n=65) (initialization): time 0.001109/0.4223, allocations: 0.6175 MB / 0.6348 GB, free: 13.06 MB / 458.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.014e-05/0.4223, allocations: 4 kB / 0.6348 GB, free: 13.06 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.095e-05/0.4223, allocations: 12.03 kB / 0.6349 GB, free: 13.05 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 2.063e-05/0.4223, allocations: 8 kB / 0.6349 GB, free: 13.04 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004276/0.4228, allocations: 119.8 kB / 0.635 GB, free: 12.92 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.703e-05/0.4228, allocations: 11.98 kB / 0.635 GB, free: 12.91 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007537/0.4235, allocations: 115.1 kB / 0.6351 GB, free: 12.8 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.326e-05/0.4236, allocations: 28 kB / 0.6351 GB, free: 12.77 MB / 458.7 MB
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.0004595/0.4241, allocations: 273.6 kB / 0.6354 GB, free: 12.49 MB / 458.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001694/0.4243, allocations: 102.8 kB / 0.6355 GB, free: 12.39 MB / 458.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001042/0.4253, allocations: 484.5 kB / 0.6359 GB, free: 11.91 MB / 458.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.621e-05/0.4253, allocations: 19.86 kB / 0.636 GB, free: 11.89 MB / 458.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.903e-06/0.4253, allocations: 0 / 0.636 GB, free: 11.89 MB / 458.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0007479/0.4261, allocations: 450.1 kB / 0.6364 GB, free: 11.42 MB / 458.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002238/0.4283, allocations: 1.359 MB / 0.6377 GB, free: 9.988 MB / 458.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.41e-06/0.4284, allocations: 4 kB / 0.6377 GB, free: 9.984 MB / 458.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003158/0.4287, allocations: 75.89 kB / 0.6378 GB, free: 9.91 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 9.137e-06/0.4287, allocations: 7.938 kB / 0.6378 GB, free: 9.902 MB / 458.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.508e-05/0.4287, allocations: 11.98 kB / 0.6378 GB, free: 9.891 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.196e-06/0.4287, allocations: 0 / 0.6378 GB, free: 9.891 MB / 458.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.592e-06/0.4288, allocations: 7.984 kB / 0.6378 GB, free: 9.883 MB / 458.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005476/0.4342, allocations: 3.592 MB / 0.6413 GB, free: 6.141 MB / 458.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003115/0.4346, allocations: 227.8 kB / 0.6416 GB, free: 5.898 MB / 458.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.082e-05/0.4347, allocations: 8 kB / 0.6416 GB, free: 5.891 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005346/0.4352, allocations: 63.78 kB / 0.6416 GB, free: 5.828 MB / 458.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.841e-05/0.4353, allocations: 27.84 kB / 0.6416 GB, free: 5.801 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.791e-05/0.4353, allocations: 19.98 kB / 0.6417 GB, free: 5.781 MB / 458.7 MB
Notification: Performance of sorting global known variables: time 0.0007844/0.4361, allocations: 0.6471 MB / 0.6423 GB, free: 5.125 MB / 458.7 MB
Notification: Performance of sort global known variables: time 1.443e-06/0.4361, allocations: 0 / 0.6423 GB, free: 5.125 MB / 458.7 MB
Notification: Performance of remove unused functions: time 0.001268/0.4374, allocations: 471.4 kB / 0.6427 GB, free: 4.664 MB / 458.7 MB
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.0006582/0.4381, allocations: 367.8 kB / 0.6431 GB, free: 4.305 MB / 458.7 MB
Notification: Performance of simCode: created initialization part: time 0.0008285/0.4389, allocations: 419.4 kB / 0.6435 GB, free: 3.895 MB / 458.7 MB
Notification: Performance of simCode: created event and clocks part: time 2.685e-06/0.4389, allocations: 1.375 kB / 0.6435 GB, free: 3.895 MB / 458.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.000364/0.4393, allocations: 192 kB / 0.6437 GB, free: 3.707 MB / 458.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002699/0.442, allocations: 0.5915 MB / 0.6443 GB, free: 3.117 MB / 458.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002896/0.4449, allocations: 2.199 MB / 0.6464 GB, free: 0.8594 MB / 458.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007689/0.4457, allocations: 1.052 MB / 0.6474 GB, free: 15.78 MB / 474.7 MB
Notification: Performance of simCode: alias equations: time 0.0008309/0.4466, allocations: 203.1 kB / 0.6476 GB, free: 15.58 MB / 474.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001957/0.4468, allocations: 83.42 kB / 0.6477 GB, free: 15.5 MB / 474.7 MB
Notification: Performance of SimCode: time 2.1e-07/0.4468, allocations: 0 / 0.6477 GB, free: 15.5 MB / 474.7 MB
Notification: Performance of Templates: time 0.03576/0.4825, allocations: 21.01 MB / 0.6682 GB, free: 10.71 MB / 490.7 MB
make -j1 -f IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.sim & ./IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.FourPortHeatMassExchanger.pipe 2>&1)