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.000941/0.0009411, allocations: 91.38 kB / 15.06 MB, free: 1.098 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.001312/0.001313, allocations: 172.7 kB / 15.95 MB, free: 208 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.522/1.522, allocations: 223.3 MB / 240 MB, free: 5.32 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.5.0-master/package.mo): time 1.097/1.097, allocations: 143.4 MB / 434.4 MB, free: 13.66 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003641/0.0003643, allocations: 8 kB / 0.5161 GB, free: 11.17 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2045/0.2049, allocations: 83.41 MB / 0.5975 GB, free: 7.695 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MixingVolume): time 0.7453/0.9503, allocations: 221.5 MB / 0.8138 GB, free: 14.44 MB / 0.6387 GB
Notification: Performance of NFInst.instExpressions: time 0.02766/0.978, allocations: 14.94 MB / 0.8284 GB, free: 15.46 MB / 0.6544 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004354/0.9824, allocations: 71.5 kB / 0.8285 GB, free: 15.39 MB / 0.6544 GB
Notification: Performance of NFTyping.typeComponents: time 0.004563/0.9871, allocations: 0.7919 MB / 0.8293 GB, free: 14.59 MB / 0.6544 GB
Notification: Performance of NFTyping.typeBindings: time 0.01324/1, allocations: 2.849 MB / 0.832 GB, free: 11.74 MB / 0.6544 GB
Notification: Performance of NFTyping.typeClassSections: time 0.008518/1.009, allocations: 1.369 MB / 0.8334 GB, free: 10.38 MB / 0.6544 GB
Notification: Performance of NFFlatten.flatten: time 0.01319/1.022, allocations: 6.238 MB / 0.8395 GB, free: 4.129 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004528/1.027, allocations: 1.271 MB / 0.8407 GB, free: 2.836 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00948/1.036, allocations: 2.167 MB / 0.8428 GB, free: 0.6602 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005889/1.042, allocations: 1.646 MB / 0.8444 GB, free: 15.01 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.001194/1.043, allocations: 84 kB / 0.8445 GB, free: 14.93 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007256/1.051, allocations: 1.52 MB / 0.846 GB, free: 13.4 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.002286/1.053, allocations: 0.6659 MB / 0.8467 GB, free: 12.73 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.002693/1.056, allocations: 444.6 kB / 0.8471 GB, free: 12.29 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.007798/1.064, allocations: 2.537 MB / 0.8496 GB, free: 9.75 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 1.332e-05/1.064, allocations: 4 kB / 0.8496 GB, free: 9.746 MB / 0.67 GB
Notification: Performance of FrontEnd: time 4.489e-06/1.064, allocations: 0 / 0.8496 GB, free: 9.746 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 0.0002239/1.064, allocations: 0 / 0.8496 GB, free: 9.746 MB / 0.67 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.008956/1.073, allocations: 2.143 MB / 0.8517 GB, free: 7.551 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 8.182e-05/1.073, allocations: 8.031 kB / 0.8517 GB, free: 7.543 MB / 0.67 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001047/1.074, allocations: 219.1 kB / 0.8519 GB, free: 7.32 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004057/1.078, allocations: 0.5048 MB / 0.8524 GB, free: 6.812 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004329/1.083, allocations: 1.219 MB / 0.8536 GB, free: 5.539 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003237/1.083, allocations: 174.2 kB / 0.8537 GB, free: 5.355 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007123/1.084, allocations: 151.9 kB / 0.8539 GB, free: 5.207 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005806/1.089, allocations: 1.737 MB / 0.8556 GB, free: 3.395 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.86e-05/1.09, allocations: 11.84 kB / 0.8556 GB, free: 3.383 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003691/1.09, allocations: 60 kB / 0.8556 GB, free: 3.324 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001116/1.09, allocations: 58.69 kB / 0.8557 GB, free: 3.266 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003617/1.094, allocations: 1.396 MB / 0.857 GB, free: 1.863 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.4906/1.584, allocations: 8.202 MB / 0.8651 GB, free: 112 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001632/1.586, allocations: 0.9039 MB / 0.8659 GB, free: 111.9 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0008191/1.587, allocations: 0.5364 MB / 0.8665 GB, free: 111.9 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005733/1.587, allocations: 335.2 kB / 0.8668 GB, free: 111.7 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.085e-05/1.587, allocations: 48.45 kB / 0.8668 GB, free: 111.6 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=96): time 3.417e-06/1.587, allocations: 0 / 0.8668 GB, free: 111.6 MB / 0.67 GB
Notification: Performance of matching and sorting (n=102): time 0.007118/1.595, allocations: 3.162 MB / 0.8699 GB, free: 111 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.038e-05/1.595, allocations: 62.91 kB / 0.87 GB, free: 110.9 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001672/1.596, allocations: 1.254 MB / 0.8712 GB, free: 110.8 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001054/1.596, allocations: 48.55 kB / 0.8713 GB, free: 110.7 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004997/1.597, allocations: 0.8088 MB / 0.872 GB, free: 110.4 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002785/1.597, allocations: 368.6 kB / 0.8724 GB, free: 110.2 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.279e-05/1.597, allocations: 27.05 kB / 0.8724 GB, free: 110.2 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 8.633e-05/1.597, allocations: 352.3 kB / 0.8728 GB, free: 109.9 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006988/1.598, allocations: 441.5 kB / 0.8732 GB, free: 109.9 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009182/1.599, allocations: 0.8191 MB / 0.874 GB, free: 109.5 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008723/1.6, allocations: 0.5589 MB / 0.8745 GB, free: 109.5 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.778e-06/1.6, allocations: 6.578 kB / 0.8745 GB, free: 109.5 MB / 0.67 GB
Notification: Performance of matching and sorting (n=150) (initialization): time 0.002258/1.602, allocations: 1.402 MB / 0.8759 GB, free: 109.3 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001829/1.602, allocations: 366 kB / 0.8762 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.501e-05/1.602, allocations: 7.812 kB / 0.8763 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.082e-05/1.602, allocations: 20.78 kB / 0.8763 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001058/1.604, allocations: 280.7 kB / 0.8765 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.571e-05/1.604, allocations: 20.09 kB / 0.8766 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001587/1.605, allocations: 183.9 kB / 0.8767 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.416e-05/1.605, allocations: 50.45 kB / 0.8768 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.026e-05/1.605, allocations: 6.438 kB / 0.8768 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001877/1.607, allocations: 1.229 MB / 0.878 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.318e-05/1.607, allocations: 16.17 kB / 0.878 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.85e-05/1.607, allocations: 15.31 kB / 0.878 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001006/1.608, allocations: 280.5 kB / 0.8783 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.952e-05/1.608, allocations: 21.77 kB / 0.8783 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001561/1.61, allocations: 176.1 kB / 0.8785 GB, free: 108.9 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.013e-05/1.61, allocations: 50.16 kB / 0.8785 GB, free: 108.9 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.0008415/1.611, allocations: 0.5151 MB / 0.879 GB, free: 108.7 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002542/1.611, allocations: 162.7 kB / 0.8792 GB, free: 108.6 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00192/1.613, allocations: 1.178 MB / 0.8803 GB, free: 108.5 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.019e-05/1.613, allocations: 11.39 kB / 0.8803 GB, free: 108.5 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.504e-06/1.613, allocations: 2.344 kB / 0.8803 GB, free: 108.5 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001461/1.614, allocations: 1.092 MB / 0.8814 GB, free: 108.2 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004816/1.619, allocations: 2.802 MB / 0.8841 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.995e-06/1.619, allocations: 5.844 kB / 0.8842 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001244/1.621, allocations: 250.2 kB / 0.8844 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.479e-05/1.621, allocations: 5 kB / 0.8844 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.64e-05/1.621, allocations: 33.5 kB / 0.8844 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.281e-05/1.621, allocations: 6.719 kB / 0.8844 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.697e-06/1.621, allocations: 1.688 kB / 0.8844 GB, free: 107.8 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00325/1.624, allocations: 1.794 MB / 0.8862 GB, free: 107.5 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009644/1.625, allocations: 339 kB / 0.8865 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000212/1.625, allocations: 27.94 kB / 0.8865 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001241/1.626, allocations: 142.8 kB / 0.8867 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001681/1.627, allocations: 71.41 kB / 0.8867 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.817e-05/1.627, allocations: 43.44 kB / 0.8868 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001435/1.628, allocations: 0.8279 MB / 0.8876 GB, free: 107.2 MB / 0.67 GB
Notification: Performance of sort global known variables: time 2.11e-07/1.628, allocations: 0 / 0.8876 GB, free: 107.2 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.002752/1.631, allocations: 0.7655 MB / 0.8883 GB, free: 107.2 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.003882/1.635, allocations: 2.515 MB / 0.8908 GB, free: 106.3 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.003657/1.638, allocations: 1.744 MB / 0.8925 GB, free: 106.3 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 7.665e-06/1.638, allocations: 1.75 kB / 0.8925 GB, free: 106.3 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.0009385/1.639, allocations: 0.524 MB / 0.893 GB, free: 106.3 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004598/1.644, allocations: 0.9717 MB / 0.894 GB, free: 106.2 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01714/1.661, allocations: 10.38 MB / 0.9041 GB, free: 102 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001134/1.662, allocations: 1.444 MB / 0.9055 GB, free: 101.2 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.002515/1.665, allocations: 0.5565 MB / 0.906 GB, free: 101.1 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008431/1.666, allocations: 0.6938 MB / 0.9067 GB, free: 101.1 MB / 0.67 GB
Notification: Performance of SimCode: time 1.323e-06/1.666, allocations: 0 / 0.9067 GB, free: 101.1 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07013/1.736, allocations: 44.57 MB / 0.9503 GB, free: 65.01 MB / 0.67 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001871/1.736, allocations: 239.3 kB / 0.9505 GB, free: 64.77 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 3.366/5.103, allocations: 0 / 0.9505 GB, free: 64.77 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: