Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr BuildSysPro_BuildSysPro.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/BuildSysPro 3.5.0-master/package.mo", uses=false)
Using package BuildSysPro with version 3.5.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.5.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: "" <> buildModelFMU(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Fluid_MixingVolumes_Validation_MixingVolume",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Fluid_MixingVolumes_Validation_MixingVolume",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001235/0.001235, allocations: 99.86 kB / 15.05 MB, free: 1.105 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001262/0.001263, allocations: 177.3 kB / 15.94 MB, free: 228 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.405/1.405, allocations: 223.2 MB / 239.9 MB, free: 5.25 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.5.0-master/package.mo): time 1.089/1.089, allocations: 143.3 MB / 434.3 MB, free: 13.57 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003151/0.0003151, allocations: 12 kB / 0.5159 GB, free: 11.21 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1796/0.1799, allocations: 83.41 MB / 0.5973 GB, free: 7.738 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume): time 0.5926/0.7725, allocations: 198.9 MB / 0.7916 GB, free: 4.98 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.02482/0.7974, allocations: 14.79 MB / 0.8061 GB, free: 6.164 MB / 0.6231 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002709/0.8002, allocations: 59.56 kB / 0.8061 GB, free: 6.105 MB / 0.6231 GB
Notification: Performance of NFTyping.typeComponents: time 0.00342/0.8036, allocations: 1.065 MB / 0.8072 GB, free: 5.035 MB / 0.6231 GB
Notification: Performance of NFTyping.typeBindings: time 0.0114/0.815, allocations: 3.527 MB / 0.8106 GB, free: 1.5 MB / 0.6231 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005529/0.8206, allocations: 1.737 MB / 0.8123 GB, free: 15.79 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.006438/0.827, allocations: 3.081 MB / 0.8153 GB, free: 12.71 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002316/0.8294, allocations: 1.297 MB / 0.8166 GB, free: 11.39 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004593/0.834, allocations: 1.807 MB / 0.8184 GB, free: 9.578 MB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002051/0.836, allocations: 1.101 MB / 0.8194 GB, free: 8.473 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002056/0.8363, allocations: 95.88 kB / 0.8195 GB, free: 8.379 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003543/0.8398, allocations: 1.248 MB / 0.8207 GB, free: 7.129 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.0006859/0.8405, allocations: 0.6116 MB / 0.8213 GB, free: 6.516 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005838/0.8411, allocations: 440.6 kB / 0.8218 GB, free: 6.078 MB / 0.6387 GB
Notification: Performance of NFConvertDAE.convert: time 0.002695/0.8438, allocations: 2.388 MB / 0.8241 GB, free: 3.688 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 3.276e-06/0.8438, allocations: 0 / 0.8241 GB, free: 3.688 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.873e-06/0.8438, allocations: 0 / 0.8241 GB, free: 3.688 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 2.663e-05/0.8439, allocations: 0 / 0.8241 GB, free: 3.688 MB / 0.6387 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.003735/0.8476, allocations: 2.104 MB / 0.8261 GB, free: 1.531 MB / 0.6387 GB
Notification: Performance of prepare preOptimizeDAE: time 4.048e-05/0.8476, allocations: 8.031 kB / 0.8261 GB, free: 1.523 MB / 0.6387 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002707/0.8479, allocations: 215.6 kB / 0.8264 GB, free: 1.305 MB / 0.6387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002779/0.8507, allocations: 0.5048 MB / 0.8268 GB, free: 0.7969 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002255/0.853, allocations: 1.215 MB / 0.828 GB, free: 15.53 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001657/0.8532, allocations: 178.8 kB / 0.8282 GB, free: 15.34 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002097/0.8534, allocations: 159.9 kB / 0.8284 GB, free: 15.18 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002789/0.8562, allocations: 1.726 MB / 0.83 GB, free: 13.38 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.567e-05/0.8562, allocations: 7.844 kB / 0.8301 GB, free: 13.38 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001507/0.8564, allocations: 63.98 kB / 0.8301 GB, free: 13.31 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.062e-05/0.8565, allocations: 62.69 kB / 0.8302 GB, free: 13.25 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002382/0.8588, allocations: 1.381 MB / 0.8315 GB, free: 11.86 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.009682/0.8685, allocations: 8.202 MB / 0.8395 GB, free: 2.965 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001572/0.8701, allocations: 0.9161 MB / 0.8404 GB, free: 2.043 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004086/0.8705, allocations: 154.2 kB / 0.8406 GB, free: 1.875 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.772e-05/0.8706, allocations: 55.48 kB / 0.8406 GB, free: 1.812 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=96): time 2.265e-06/0.8706, allocations: 0 / 0.8406 GB, free: 1.812 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=102): time 0.00685/0.8775, allocations: 3.156 MB / 0.8437 GB, free: 14.6 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.349e-05/0.8775, allocations: 64.41 kB / 0.8438 GB, free: 14.53 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001519/0.8791, allocations: 1.254 MB / 0.845 GB, free: 13.27 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 8.426e-05/0.8791, allocations: 49.7 kB / 0.845 GB, free: 13.21 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006203/0.8798, allocations: 0.8055 MB / 0.8458 GB, free: 12.39 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003211/0.8801, allocations: 370.7 kB / 0.8462 GB, free: 12.03 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.135e-05/0.8802, allocations: 20 kB / 0.8462 GB, free: 12.01 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001538/0.8803, allocations: 357.9 kB / 0.8465 GB, free: 11.65 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007987/0.8811, allocations: 442.3 kB / 0.847 GB, free: 11.22 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001084/0.8822, allocations: 0.8195 MB / 0.8478 GB, free: 10.24 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0009481/0.8832, allocations: 0.5651 MB / 0.8483 GB, free: 9.676 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.46e-06/0.8832, allocations: 4 kB / 0.8483 GB, free: 9.672 MB / 0.67 GB
Notification: Performance of matching and sorting (n=150) (initialization): time 0.002335/0.8855, allocations: 1.401 MB / 0.8497 GB, free: 8.258 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001447/0.887, allocations: 3.662 MB / 0.8533 GB, free: 4.242 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.471e-05/0.887, allocations: 8 kB / 0.8533 GB, free: 4.234 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.205e-05/0.8871, allocations: 19.98 kB / 0.8533 GB, free: 4.215 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001144/0.8882, allocations: 287.5 kB / 0.8536 GB, free: 3.934 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.299e-05/0.8882, allocations: 19.94 kB / 0.8536 GB, free: 3.914 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001555/0.8898, allocations: 179.5 kB / 0.8538 GB, free: 3.738 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.911e-05/0.8899, allocations: 59.98 kB / 0.8538 GB, free: 3.68 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 8.446e-06/0.8899, allocations: 4.781 kB / 0.8538 GB, free: 3.676 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002138/0.8921, allocations: 1.222 MB / 0.855 GB, free: 2.453 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.113e-05/0.8921, allocations: 12 kB / 0.855 GB, free: 2.441 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.14e-05/0.8921, allocations: 20.8 kB / 0.855 GB, free: 2.422 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001098/0.8932, allocations: 279.6 kB / 0.8553 GB, free: 2.148 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.299e-05/0.8933, allocations: 23.92 kB / 0.8553 GB, free: 2.125 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001703/0.895, allocations: 179.6 kB / 0.8555 GB, free: 1.949 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.357e-05/0.8951, allocations: 44.62 kB / 0.8555 GB, free: 1.906 MB / 0.67 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 (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0009861/0.8961, allocations: 0.5084 MB / 0.856 GB, free: 1.387 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002817/0.8963, allocations: 162 kB / 0.8562 GB, free: 1.227 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002275/0.8986, allocations: 1.171 MB / 0.8573 GB, free: 36 kB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.362e-05/0.8987, allocations: 15.95 kB / 0.8574 GB, free: 20 kB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.346e-06/0.8987, allocations: 0 / 0.8574 GB, free: 20 kB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.3941/1.293, allocations: 1.097 MB / 0.8584 GB, free: 206.6 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004843/1.298, allocations: 2.803 MB / 0.8612 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.602e-06/1.298, allocations: 0 / 0.8612 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001137/1.299, allocations: 250.5 kB / 0.8614 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.183e-05/1.299, allocations: 5.359 kB / 0.8614 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.867e-05/1.299, allocations: 30.94 kB / 0.8614 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.147e-06/1.299, allocations: 10.31 kB / 0.8614 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.266e-06/1.299, allocations: 1.438 kB / 0.8614 GB, free: 206.2 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002691/1.302, allocations: 1.897 MB / 0.8633 GB, free: 205.8 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007996/1.302, allocations: 338.5 kB / 0.8636 GB, free: 205.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001915/1.303, allocations: 25.09 kB / 0.8636 GB, free: 205.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001235/1.304, allocations: 144.1 kB / 0.8638 GB, free: 205.7 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001645/1.304, allocations: 65.3 kB / 0.8638 GB, free: 205.7 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.948e-05/1.304, allocations: 39.3 kB / 0.8639 GB, free: 205.7 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001181/1.305, allocations: 0.8329 MB / 0.8647 GB, free: 205.6 MB / 0.67 GB
Notification: Performance of sort global known variables: time 5.71e-07/1.305, allocations: 0 / 0.8647 GB, free: 205.6 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.002039/1.307, allocations: 0.7638 MB / 0.8654 GB, free: 205.6 MB / 0.67 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 (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.003459/1.311, allocations: 3.083 MB / 0.8685 GB, free: 204.3 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.002881/1.314, allocations: 1.745 MB / 0.8702 GB, free: 204 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 5.33e-06/1.314, allocations: 0 / 0.8702 GB, free: 204 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.0007811/1.315, allocations: 0.522 MB / 0.8707 GB, free: 203.8 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004464/1.319, allocations: 0.9565 MB / 0.8716 GB, free: 203.6 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01626/1.335, allocations: 16.32 MB / 0.8875 GB, free: 192 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009103/1.336, allocations: 1.479 MB / 0.889 GB, free: 191.2 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.002231/1.339, allocations: 0.5648 MB / 0.8895 GB, free: 191.1 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008002/1.339, allocations: 0.6814 MB / 0.8902 GB, free: 190.6 MB / 0.67 GB
Notification: Performance of SimCode: time 1.413e-06/1.339, allocations: 0 / 0.8902 GB, free: 190.6 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09571/1.435, allocations: 44.53 MB / 0.9337 GB, free: 177.4 MB / 0.67 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002238/1.435, allocations: 225.9 kB / 0.9339 GB, free: 177.2 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 3.219/4.654, allocations: 0 / 0.9339 GB, free: 177.2 MB / 0.67 GB
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_BuildSysPro_BuildSysPro_IBPSA_Fluid_MixingVolumes_Validation_MixingVolume_fmu --startTime=0 --stopTime=2 --timeout=50 --tolerance=1e-09 BuildSysPro_BuildSysPro_IBPSA_Fluid_MixingVolumes_Validation_MixingVolume.fmu > BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume.pipe 2>&1)
Environment - environmentSimulation: