Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.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.001509/0.001509, allocations: 118 kB / 17.84 MB, free: 5.234 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.001925/0.001925, allocations: 203.9 kB / 20.79 MB, free: 2.297 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.608/1.608, allocations: 230.6 MB / 254.2 MB, free: 15.09 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 1.032/1.032, allocations: 150 MB / 460.2 MB, free: 5.293 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 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: "" <> buildModelFMU(IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger,fileNamePrefix="IBPSA_latest_IBPSA_Fluid_Interfaces_Examples_EightPortHeatMassExchanger",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger,fileNamePrefix="IBPSA_latest_IBPSA_Fluid_Interfaces_Examples_EightPortHeatMassExchanger",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: IBPSA 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.00208/0.00208, allocations: 70.34 kB / 0.6368 GB, free: 30.47 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.198/0.2001, allocations: 89.13 MB / 0.7238 GB, free: 4.004 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger): time 0.08876/0.2888, allocations: 78.15 MB / 0.8002 GB, free: 5.566 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.0324/0.3212, allocations: 18.92 MB / 0.8186 GB, free: 2.598 MB / 0.6231 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004658/0.3259, allocations: 158.6 kB / 0.8188 GB, free: 2.441 MB / 0.6231 GB
Notification: Performance of NFTyping.typeComponents: time 0.004689/0.3306, allocations: 1.312 MB / 0.8201 GB, free: 1.121 MB / 0.6231 GB
Notification: Performance of NFTyping.typeBindings: time 0.01292/0.3435, allocations: 3.82 MB / 0.8238 GB, free: 13.29 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00689/0.3504, allocations: 2.105 MB / 0.8259 GB, free: 11.2 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.01217/0.3626, allocations: 6.693 MB / 0.8324 GB, free: 4.496 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003844/0.3664, allocations: 1.708 MB / 0.8341 GB, free: 2.738 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007892/0.3743, allocations: 2.708 MB / 0.8367 GB, free: 36 kB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00419/0.3785, allocations: 1.841 MB / 0.8385 GB, free: 14.19 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008736/0.3794, allocations: 196 kB / 0.8387 GB, free: 14 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005263/0.3846, allocations: 1.723 MB / 0.8404 GB, free: 12.27 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.001212/0.3858, allocations: 0.564 MB / 0.8409 GB, free: 11.7 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.003329/0.3892, allocations: 1.252 MB / 0.8422 GB, free: 10.45 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.01052/0.3997, allocations: 3.858 MB / 0.8459 GB, free: 6.574 MB / 0.6544 GB
Notification: Performance of FrontEnd - DAE generated: time 1.063e-05/0.3997, allocations: 4 kB / 0.8459 GB, free: 6.57 MB / 0.6544 GB
Notification: Performance of FrontEnd: time 2.344e-06/0.3997, allocations: 0 / 0.8459 GB, free: 6.57 MB / 0.6544 GB
Notification: Performance of Transformations before backend: time 0.0001648/0.3999, allocations: 0 / 0.8459 GB, free: 6.57 MB / 0.6544 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 344
 * Number of variables: 344
Notification: Performance of Generate backend data structure: time 0.008148/0.408, allocations: 2.794 MB / 0.8487 GB, free: 3.707 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 5.092e-05/0.4081, allocations: 8.031 kB / 0.8487 GB, free: 3.699 MB / 0.6544 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 7.538e-05/0.4081, allocations: 34.89 kB / 0.8487 GB, free: 3.664 MB / 0.6544 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0005504/0.4087, allocations: 210.8 kB / 0.8489 GB, free: 3.449 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001248/0.4099, allocations: 286.3 kB / 0.8492 GB, free: 3.168 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004829/0.4148, allocations: 2.048 MB / 0.8512 GB, free: 1.07 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001176/0.4149, allocations: 106.7 kB / 0.8513 GB, free: 0.9648 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003623/0.4153, allocations: 140 kB / 0.8514 GB, free: 0.8281 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003654/0.4189, allocations: 1.52 MB / 0.8529 GB, free: 15.23 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.654e-05/0.419, allocations: 7.938 kB / 0.8529 GB, free: 15.22 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001575/0.4191, allocations: 59.94 kB / 0.853 GB, free: 15.16 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.565e-05/0.4192, allocations: 36 kB / 0.853 GB, free: 15.12 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002666/0.4218, allocations: 1.13 MB / 0.8541 GB, free: 13.99 MB / 0.67 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.01103/0.4329, allocations: 7.937 MB / 0.8618 GB, free: 5.406 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001089/0.434, allocations: 444.5 kB / 0.8623 GB, free: 4.961 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006977/0.4347, allocations: 310.3 kB / 0.8626 GB, free: 4.656 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001044/0.4357, allocations: 1.164 MB / 0.8637 GB, free: 3.234 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.291e-05/0.4358, allocations: 39.12 kB / 0.8637 GB, free: 3.188 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=59): time 2.254e-06/0.4358, allocations: 0 / 0.8637 GB, free: 3.188 MB / 0.67 GB
Notification: Performance of matching and sorting (n=59): time 0.002882/0.4386, allocations: 0.974 MB / 0.8647 GB, free: 2.211 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.389e-05/0.4387, allocations: 55.97 kB / 0.8647 GB, free: 2.145 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002863/0.4416, allocations: 1.803 MB / 0.8665 GB, free: 312 kB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001417/0.4417, allocations: 45.7 kB / 0.8665 GB, free: 260 kB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.3801/0.8218, allocations: 0.9482 MB / 0.8675 GB, free: 138.1 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003932/0.8222, allocations: 229.4 kB / 0.8677 GB, free: 138.1 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005137/0.8227, allocations: 227.3 kB / 0.8679 GB, free: 138 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001099/0.8228, allocations: 347 kB / 0.8682 GB, free: 137.7 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009114/0.8237, allocations: 331.8 kB / 0.8686 GB, free: 137.7 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001151/0.8248, allocations: 0.692 MB / 0.8692 GB, free: 137.3 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001831/0.8267, allocations: 1.011 MB / 0.8702 GB, free: 136.7 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.158e-05/0.8267, allocations: 2.812 kB / 0.8702 GB, free: 136.7 MB / 0.67 GB
Notification: Performance of matching and sorting (n=135) (initialization): time 0.003057/0.8297, allocations: 1.226 MB / 0.8714 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 3.809e-05/0.8298, allocations: 10.47 kB / 0.8714 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.345e-05/0.8298, allocations: 9.719 kB / 0.8714 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.421e-05/0.8299, allocations: 16.19 kB / 0.8714 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001063/0.8309, allocations: 289.9 kB / 0.8717 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.8e-05/0.8309, allocations: 32.12 kB / 0.8718 GB, free: 136.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001449/0.8324, allocations: 165 kB / 0.8719 GB, free: 136.1 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.664e-05/0.8325, allocations: 50.75 kB / 0.872 GB, free: 136.1 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 45
 * 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 (135):
 * Single equations (assignments): 135
 * 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.0007494/0.8332, allocations: 441.8 kB / 0.8724 GB, free: 135.8 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003619/0.8336, allocations: 202.1 kB / 0.8726 GB, free: 135.7 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001828/0.8354, allocations: 0.8612 MB / 0.8734 GB, free: 135.4 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.114e-05/0.8354, allocations: 10.45 kB / 0.8734 GB, free: 135.4 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.246e-06/0.8354, allocations: 6.875 kB / 0.8734 GB, free: 135.4 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001294/0.8367, allocations: 0.7903 MB / 0.8742 GB, free: 135 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004368/0.8411, allocations: 2.45 MB / 0.8766 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.556e-06/0.8411, allocations: 2.469 kB / 0.8766 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0008891/0.842, allocations: 181.7 kB / 0.8768 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.168e-05/0.842, allocations: 8.219 kB / 0.8768 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.7e-05/0.8421, allocations: 18.05 kB / 0.8768 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.818e-06/0.8421, allocations: 4.812 kB / 0.8768 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.499e-06/0.8421, allocations: 3.062 kB / 0.8768 GB, free: 133.8 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002799/0.8449, allocations: 1.353 MB / 0.8781 GB, free: 133.2 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006782/0.8455, allocations: 375.1 kB / 0.8785 GB, free: 132.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002306/0.8458, allocations: 15.25 kB / 0.8785 GB, free: 132.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001036/0.8468, allocations: 69.52 kB / 0.8786 GB, free: 132.9 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001283/0.8469, allocations: 56.91 kB / 0.8786 GB, free: 132.9 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.136e-05/0.847, allocations: 37.86 kB / 0.8787 GB, free: 132.9 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.002308/0.8493, allocations: 1.244 MB / 0.8799 GB, free: 132.4 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1e-07/0.8493, allocations: 4.469 kB / 0.8799 GB, free: 132.4 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.002673/0.852, allocations: 0.6648 MB / 0.8805 GB, free: 132.3 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 9
 * Number of states: 4 (eigPor.vol1.dynBal.U,eigPor.vol2.dynBal.U,eigPor.vol3.dynBal.U,eigPor.vol4.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 (64):
 * Single equations (assignments): 59
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 5
 * 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.004242/0.8562, allocations: 2.382 MB / 0.8829 GB, free: 131.1 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.001901/0.8581, allocations: 0.7765 MB / 0.8836 GB, free: 130.9 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 6.432e-06/0.8582, allocations: 5.109 kB / 0.8836 GB, free: 130.9 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.000706/0.8589, allocations: 333.7 kB / 0.8839 GB, free: 130.8 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005569/0.8644, allocations: 1.201 MB / 0.8851 GB, free: 130.5 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01789/0.8823, allocations: 12.67 MB / 0.8975 GB, free: 123.2 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006656/0.883, allocations: 1.416 MB / 0.8989 GB, free: 121.8 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.001641/0.8846, allocations: 382 kB / 0.8992 GB, free: 121.4 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008783/0.8855, allocations: 0.7687 MB / 0.9 GB, free: 120.6 MB / 0.67 GB
Notification: Performance of SimCode: time 1.583e-06/0.8855, allocations: 0 / 0.9 GB, free: 120.6 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.08809/0.9736, allocations: 57.87 MB / 0.9565 GB, free: 63.32 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 12.69/13.67, allocations: 4.062 kB / 0.9565 GB, free: 63.32 MB / 0.67 GB
"
[Timeout remaining time 646]
(rm -f IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_IBPSA_latest_IBPSA_Fluid_Interfaces_Examples_EightPortHeatMassExchanger_fmu --startTime=0 --stopTime=5 --stepSize=0.002 --timeout=50 --tolerance=1e-06 IBPSA_latest_IBPSA_Fluid_Interfaces_Examples_EightPortHeatMassExchanger.fmu > IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.EightPortHeatMassExchanger.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 18.0683306089486]
Failed to read output from testmodel.py, exit status != 0:
13.835642142046709 13.84552251 13.443106325
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fe13de773a0>