Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
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/Modelica 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)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/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 Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolume,tolerance=1e-09,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume")
translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolume,tolerance=1e-09,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001035/0.001035, allocations: 92.95 kB / 16.02 MB, free: 84 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001153/0.001153, allocations: 185 kB / 16.92 MB, free: 6.934 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.397/1.397, allocations: 223.3 MB / 241 MB, free: 5.34 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7317/0.7317, allocations: 123 MB / 415.1 MB, free: 11.13 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003517/0.0003518, allocations: 9.219 kB / 0.4933 GB, free: 14.02 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4351/0.4355, allocations: 79.28 MB / 0.5707 GB, free: 9.906 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MixingVolume): time 0.2555/0.691, allocations: 237.2 MB / 0.8024 GB, free: 14.63 MB / 0.6387 GB
Notification: Performance of NFInst.instExpressions: time 0.4259/1.117, allocations: 15.9 MB / 0.8179 GB, free: 2.289 MB / 0.6387 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002991/1.12, allocations: 68.34 kB / 0.818 GB, free: 2.289 MB / 0.6387 GB
Notification: Performance of NFTyping.typeComponents: time 0.003179/1.123, allocations: 1.012 MB / 0.819 GB, free: 2.285 MB / 0.6387 GB
Notification: Performance of NFTyping.typeBindings: time 0.01151/1.135, allocations: 3.601 MB / 0.8225 GB, free: 2.199 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005435/1.14, allocations: 1.615 MB / 0.8241 GB, free: 2.16 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.01332/1.153, allocations: 7.424 MB / 0.8313 GB, free: 2.145 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0033/1.157, allocations: 1.383 MB / 0.8327 GB, free: 1.996 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007075/1.164, allocations: 2.3 MB / 0.8349 GB, free: 1.965 MB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004799/1.169, allocations: 1.734 MB / 0.8366 GB, free: 1.957 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.000415/1.169, allocations: 87.56 kB / 0.8367 GB, free: 1.953 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007306/1.177, allocations: 3.128 MB / 0.8397 GB, free: 1.926 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.00112/1.178, allocations: 0.6754 MB / 0.8404 GB, free: 1.926 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.002125/1.18, allocations: 1.062 MB / 0.8414 GB, free: 17.89 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.005502/1.185, allocations: 3.599 MB / 0.845 GB, free: 17.68 MB / 0.6544 GB
Notification: Performance of FrontEnd - DAE generated: time 4.268e-06/1.185, allocations: 0 / 0.845 GB, free: 17.68 MB / 0.6544 GB
Notification: Performance of FrontEnd: time 2.545e-06/1.185, allocations: 0 / 0.845 GB, free: 17.68 MB / 0.6544 GB
Notification: Performance of Transformations before backend: time 6.584e-05/1.185, allocations: 0 / 0.845 GB, free: 17.68 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: 363
 * Number of variables: 363
Notification: Performance of Generate backend data structure: time 0.00412/1.19, allocations: 2.116 MB / 0.847 GB, free: 16.55 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 4.181e-05/1.19, allocations: 9.406 kB / 0.847 GB, free: 16.55 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002941/1.193, allocations: 0.5017 MB / 0.8475 GB, free: 16.43 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002138/1.195, allocations: 1.278 MB / 0.8488 GB, free: 15.98 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001755/1.195, allocations: 180 kB / 0.8489 GB, free: 15.91 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002264/1.195, allocations: 147.2 kB / 0.8491 GB, free: 15.91 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0031/1.198, allocations: 1.759 MB / 0.8508 GB, free: 15.68 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.95e-05/1.198, allocations: 6.906 kB / 0.8508 GB, free: 15.68 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001291/1.198, allocations: 58.84 kB / 0.8509 GB, free: 15.68 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.409e-05/1.199, allocations: 66.3 kB / 0.8509 GB, free: 15.67 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002283/1.201, allocations: 1.394 MB / 0.8523 GB, free: 15.65 MB / 0.6544 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.009585/1.21, allocations: 8.213 MB / 0.8603 GB, free: 10.81 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001486/1.212, allocations: 0.9142 MB / 0.8612 GB, free: 10.78 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007849/1.213, allocations: 0.5462 MB / 0.8617 GB, free: 10.77 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006565/1.213, allocations: 341.8 kB / 0.8621 GB, free: 10.48 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.008e-05/1.213, allocations: 44.03 kB / 0.8621 GB, free: 10.44 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=96): time 2.846e-06/1.213, allocations: 0 / 0.8621 GB, free: 10.44 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=102): time 0.007321/1.221, allocations: 3.177 MB / 0.8652 GB, free: 9.801 MB / 0.6544 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.413e-05/1.221, allocations: 61.88 kB / 0.8653 GB, free: 9.738 MB / 0.6544 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00149/1.222, allocations: 1.26 MB / 0.8665 GB, free: 9.57 MB / 0.6544 GB
Notification: Performance of collectPreVariables (initialization): time 9.859e-05/1.222, allocations: 54.11 kB / 0.8665 GB, free: 9.535 MB / 0.6544 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005411/1.223, allocations: 0.8147 MB / 0.8673 GB, free: 9.215 MB / 0.6544 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002948/1.223, allocations: 363.1 kB / 0.8677 GB, free: 8.984 MB / 0.6544 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001286/1.223, allocations: 101.7 kB / 0.8678 GB, free: 8.969 MB / 0.6544 GB
Notification: Performance of setup shared object (initialization): time 0.0001441/1.224, allocations: 359.4 kB / 0.8681 GB, free: 8.66 MB / 0.6544 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007604/1.224, allocations: 447.3 kB / 0.8685 GB, free: 8.652 MB / 0.6544 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001062/1.225, allocations: 0.8349 MB / 0.8694 GB, free: 8.207 MB / 0.6544 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001863/1.227, allocations: 1.27 MB / 0.8706 GB, free: 7.855 MB / 0.6544 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.462e-06/1.227, allocations: 2.625 kB / 0.8706 GB, free: 7.855 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=150) (initialization): time 0.002447/1.23, allocations: 1.424 MB / 0.872 GB, free: 7.727 MB / 0.6544 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002367/1.23, allocations: 371.1 kB / 0.8723 GB, free: 7.301 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.451e-05/1.23, allocations: 9.094 kB / 0.8724 GB, free: 7.301 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.295e-05/1.23, allocations: 14.89 kB / 0.8724 GB, free: 7.301 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001074/1.231, allocations: 279.2 kB / 0.8726 GB, free: 7.301 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.094e-05/1.231, allocations: 27.5 kB / 0.8727 GB, free: 7.297 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001762/1.233, allocations: 182.3 kB / 0.8728 GB, free: 7.266 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.419e-05/1.233, allocations: 51.69 kB / 0.8729 GB, free: 7.266 MB / 0.6544 GB
Notification: Performance of prepare postOptimizeDAE: time 6.342e-06/1.233, allocations: 2.625 kB / 0.8729 GB, free: 7.266 MB / 0.6544 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001918/1.235, allocations: 1.242 MB / 0.8741 GB, free: 7.242 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.988e-06/1.235, allocations: 14.5 kB / 0.8741 GB, free: 7.238 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.213e-05/1.235, allocations: 15.16 kB / 0.8741 GB, free: 7.238 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001018/1.236, allocations: 275.6 kB / 0.8744 GB, free: 7.238 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.702e-05/1.236, allocations: 20.34 kB / 0.8744 GB, free: 7.238 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001756/1.238, allocations: 174.9 kB / 0.8746 GB, free: 7.215 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.109e-05/1.238, allocations: 57.86 kB / 0.8746 GB, free: 7.215 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 41
 * Number of states: 0 ()
 * Number of discrete variables: 4 (vol.regularFlow[1],vol.regularFlow[2],vol.inFlow[2],vol.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (150):
 * Single equations (assignments): 150
 * 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.0009773/1.239, allocations: 0.5099 MB / 0.8751 GB, free: 7.016 MB / 0.6544 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002751/1.239, allocations: 166.3 kB / 0.8753 GB, free: 6.93 MB / 0.6544 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002221/1.241, allocations: 1.174 MB / 0.8764 GB, free: 6.836 MB / 0.6544 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.145e-06/1.241, allocations: 10.03 kB / 0.8764 GB, free: 6.836 MB / 0.6544 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.002e-06/1.241, allocations: 5.125 kB / 0.8765 GB, free: 6.836 MB / 0.6544 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001579/1.243, allocations: 1.101 MB / 0.8775 GB, free: 6.562 MB / 0.6544 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004371/1.247, allocations: 2.802 MB / 0.8803 GB, free: 5.684 MB / 0.6544 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.17e-06/1.247, allocations: 0 / 0.8803 GB, free: 5.684 MB / 0.6544 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001167/1.249, allocations: 253.8 kB / 0.8805 GB, free: 5.645 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.377e-05/1.249, allocations: 13.8 kB / 0.8805 GB, free: 5.637 MB / 0.6544 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.044e-05/1.249, allocations: 25.64 kB / 0.8805 GB, free: 5.637 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.742e-06/1.249, allocations: 7.062 kB / 0.8806 GB, free: 5.637 MB / 0.6544 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.044e-06/1.249, allocations: 0 / 0.8806 GB, free: 5.637 MB / 0.6544 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002763/1.252, allocations: 1.789 MB / 0.8823 GB, free: 5.141 MB / 0.6544 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006774/1.252, allocations: 338.7 kB / 0.8826 GB, free: 4.922 MB / 0.6544 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001872/1.252, allocations: 32.38 kB / 0.8827 GB, free: 4.918 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001297/1.254, allocations: 147.9 kB / 0.8828 GB, free: 4.863 MB / 0.6544 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001312/1.254, allocations: 62.98 kB / 0.8829 GB, free: 4.84 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.961e-05/1.254, allocations: 46.22 kB / 0.8829 GB, free: 4.828 MB / 0.6544 GB
Notification: Performance of sorting global known variables: time 0.0009976/1.255, allocations: 0.8377 MB / 0.8837 GB, free: 4.582 MB / 0.6544 GB
Notification: Performance of sort global known variables: time 1.5e-07/1.255, allocations: 0 / 0.8837 GB, free: 4.582 MB / 0.6544 GB
Notification: Performance of remove unused functions: time 0.001894/1.257, allocations: 0.7715 MB / 0.8845 GB, free: 4.57 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 8
 * Number of states: 9 (vol1.dynBal.medium.Xi[1],vol1.dynBal.U,vol1.dynBal.m,vol.medium.Xi[1],vol.medium.T,vol.m,vol2.dynBal.medium.Xi[1],vol2.dynBal.U,vol2.dynBal.m)
 * 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 (102):
 * Single equations (assignments): 102
 * 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 Backend phase and start with SimCode phase: time 0.0005694/1.257, allocations: 283.1 kB / 0.8847 GB, free: 4.43 MB / 0.6544 GB
Notification: Performance of simCode: created initialization part: time 0.003085/1.261, allocations: 1.76 MB / 0.8865 GB, free: 4.184 MB / 0.6544 GB
Notification: Performance of simCode: created event and clocks part: time 5.49e-06/1.261, allocations: 0 / 0.8865 GB, free: 4.184 MB / 0.6544 GB
Notification: Performance of simCode: created simulation system equations: time 0.0008289/1.261, allocations: 0.5281 MB / 0.887 GB, free: 4.117 MB / 0.6544 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005271/1.267, allocations: 0.967 MB / 0.8879 GB, free: 3.781 MB / 0.6544 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00577/1.272, allocations: 4.361 MB / 0.8922 GB, free: 1.109 MB / 0.6544 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009517/1.273, allocations: 1.44 MB / 0.8936 GB, free: 116 kB / 0.6544 GB
Notification: Performance of simCode: alias equations: time 0.002181/1.276, allocations: 0.5644 MB / 0.8941 GB, free: 15.87 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003226/1.276, allocations: 130.6 kB / 0.8943 GB, free: 15.85 MB / 0.67 GB
Notification: Performance of SimCode: time 6.31e-07/1.276, allocations: 0 / 0.8943 GB, free: 15.85 MB / 0.67 GB
Notification: Performance of Templates: time 0.05188/1.328, allocations: 30.24 MB / 0.9238 GB, free: 4.961 MB / 0.6856 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.sim & ./IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe 2>&1)