Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.Fluid.TestComponents.Fittings.TestMultiPortTraceSubstances.conf.json
Using package ModelicaTest with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo)
Using package ModelicaServices with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo)
Using package Modelica with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/package.mo)
Using package Complex with version 3.2.1 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo)
Running command: "" <> buildModelFMU(ModelicaTest.Fluid.TestComponents.Fittings.TestMultiPortTraceSubstances,fileNamePrefix="ModelicaTest_3_2_1_ModelicaTest_Fluid_TestComponents_Fittings_TestMultiPortTraceSubstances",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo): time 0.009538/0.009538, allocations: 165.7 kB / 15.11 MB, free: 6.051 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+maint.om/package.mo): time 1.711/1.711, allocations: 170.8 MB / 186.6 MB, free: 1.137 MB / 154.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo): time 0.001939/0.001939, allocations: 106.7 kB / 226.2 MB, free: 10.11 MB / 186.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo): time 0.4223/0.4223, allocations: 38.06 MB / 303.7 MB, free: 42.38 MB / 250.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004448/0.0004449, allocations: 8 kB / 363 MB, free: 5.52 MB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.101/0.1014, allocations: 48.71 MB / 411.7 MB, free: 4.723 MB / 314.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 11.24/11.35, allocations: 3.667 GB / 4.069 GB, free: 105.3 MB / 426.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002775/11.35, allocations: 85.78 kB / 4.069 GB, free: 105.3 MB / 426.7 MB
Notification: Performance of FrontEnd - DAE generated: time 9.81/21.16, allocations: 1.621 GB / 5.691 GB, free: 18.95 MB / 0.5261 GB
Notification: Performance of FrontEnd: time 2.685e-06/21.16, allocations: 4 kB / 5.691 GB, free: 18.95 MB / 0.5261 GB
Notification: Performance of Transformations before backend: time 0.0003635/21.16, allocations: 86.22 kB / 5.691 GB, free: 18.93 MB / 0.5261 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 752
 * Number of variables: 752
Notification: Performance of Generate backend data structure: time 0.043/21.2, allocations: 9.057 MB / 5.7 GB, free: 14.01 MB / 0.5261 GB
Notification: Performance of prepare preOptimizeDAE: time 5.165e-05/21.2, allocations: 7.406 kB / 5.7 GB, free: 14.01 MB / 0.5261 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002459/21.2, allocations: 434 kB / 5.7 GB, free: 13.95 MB / 0.5261 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002722/21.2, allocations: 403.3 kB / 5.7 GB, free: 13.77 MB / 0.5261 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008564/21.21, allocations: 2.138 MB / 5.703 GB, free: 13.22 MB / 0.5261 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006665/21.21, allocations: 0.4942 MB / 5.703 GB, free: 12.93 MB / 0.5261 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001803/21.22, allocations: 284.4 kB / 5.703 GB, free: 12.93 MB / 0.5261 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01522/21.23, allocations: 4.917 MB / 5.708 GB, free: 12.16 MB / 0.5261 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001355/21.23, allocations: 11.23 kB / 5.708 GB, free: 12.16 MB / 0.5261 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007733/21.23, allocations: 125.2 kB / 5.708 GB, free: 12.16 MB / 0.5261 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005165/21.23, allocations: 258.8 kB / 5.708 GB, free: 12.15 MB / 0.5261 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01595/21.25, allocations: 5.008 MB / 5.713 GB, free: 12.06 MB / 0.5261 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.051/21.3, allocations: 19.95 MB / 5.733 GB, free: 13.44 MB / 0.5417 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008762/21.31, allocations: 2.588 MB / 5.735 GB, free: 11.21 MB / 0.5417 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.004817/21.31, allocations: 3.39 MB / 5.739 GB, free: 7.277 MB / 0.5417 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00013/21.31, allocations: 68.52 kB / 5.739 GB, free: 7.203 MB / 0.5417 GB
Notification: Performance of pre-optimization done (n=235): time 6.132e-06/21.31, allocations: 0 / 5.739 GB, free: 7.203 MB / 0.5417 GB
Notification: Performance of matching and sorting (n=271): time 0.6382/21.95, allocations: 16.41 MB / 5.755 GB, free: 36.04 MB / 0.5417 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001282/21.95, allocations: 236.3 kB / 5.755 GB, free: 35.78 MB / 0.5417 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00564/21.96, allocations: 2.011 MB / 5.757 GB, free: 35.55 MB / 0.5417 GB
Notification: Performance of collectPreVariables (initialization): time 0.001147/21.96, allocations: 82.17 kB / 5.757 GB, free: 35.52 MB / 0.5417 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001814/21.96, allocations: 1.464 MB / 5.758 GB, free: 34.96 MB / 0.5417 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001999/21.96, allocations: 0.962 MB / 5.759 GB, free: 34.48 MB / 0.5417 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007337/21.96, allocations: 83.28 kB / 5.759 GB, free: 34.48 MB / 0.5417 GB
Notification: Performance of setup shared object (initialization): time 0.0003479/21.96, allocations: 468.2 kB / 5.76 GB, free: 34.18 MB / 0.5417 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007602/21.97, allocations: 1.941 MB / 5.762 GB, free: 34.17 MB / 0.5417 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008981/21.98, allocations: 3.205 MB / 5.765 GB, free: 32.7 MB / 0.5417 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008814/21.99, allocations: 2.493 MB / 5.767 GB, free: 32.54 MB / 0.5417 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.081e-05/21.99, allocations: 13.72 kB / 5.767 GB, free: 32.54 MB / 0.5417 GB
Notification: Performance of matching and sorting (n=508) (initialization): time 0.02486/22.01, allocations: 6.101 MB / 5.773 GB, free: 32.12 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0042/22.02, allocations: 16.1 MB / 5.789 GB, free: 15.27 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002322/22.02, allocations: 40.62 kB / 5.789 GB, free: 15.27 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008835/22.03, allocations: 0.9847 MB / 5.79 GB, free: 15.24 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00811/22.04, allocations: 0.9319 MB / 5.791 GB, free: 15.24 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007758/22.04, allocations: 2.797 MB / 5.794 GB, free: 13.68 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007803/22.05, allocations: 391 kB / 5.794 GB, free: 13.67 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001549/22.05, allocations: 284.3 kB / 5.794 GB, free: 13.67 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 1.668e-05/22.05, allocations: 4.375 kB / 5.794 GB, free: 13.67 MB / 0.5417 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02314/22.08, allocations: 5.743 MB / 5.8 GB, free: 13.43 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001824/22.08, allocations: 42.34 kB / 5.8 GB, free: 13.43 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008437/22.08, allocations: 0.9865 MB / 5.801 GB, free: 13.41 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00791/22.09, allocations: 0.9327 MB / 5.802 GB, free: 13.41 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007476/22.1, allocations: 2.796 MB / 5.805 GB, free: 11.85 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007235/22.11, allocations: 376.3 kB / 5.805 GB, free: 11.84 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00151/22.11, allocations: 280.8 kB / 5.805 GB, free: 11.84 MB / 0.5417 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 177
 * Number of states: 0 ()
 * Number of discrete variables: 22 (traceSubstance1.ind,traceSubstance2.ind,traceSubstance3.ind,traceSubstance.ind,volume1.regularFlow[1],volume1.regularFlow[2],volume1.regularFlow[3],volume2.regularFlow[1],volume2.regularFlow[2],volume2.regularFlow[3],volume3.regularFlow[1],volume3.regularFlow[2],volume3.regularFlow[3],volume3.inFlow[3],volume3.inFlow[2],volume3.inFlow[1],volume2.inFlow[3],volume2.inFlow[2],volume2.inFlow[1],volume1.inFlow[3],volume1.inFlow[2],volume1.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (487):
 * Single equations (assignments): 480
 * Array equations: 0
 * Algorithm blocks: 4
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 3 {2 6,2 6,2 6}
Notification: Performance of prepare postOptimizeDAE: time 0.00393/22.11, allocations: 0.9762 MB / 5.806 GB, free: 11.66 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001173/22.11, allocations: 294.3 kB / 5.806 GB, free: 11.51 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02268/22.14, allocations: 6.067 MB / 5.812 GB, free: 11.23 MB / 0.5417 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.164e-05/22.14, allocations: 39.41 kB / 5.812 GB, free: 11.23 MB / 0.5417 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.397e-05/22.14, allocations: 0 / 5.812 GB, free: 11.23 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.288e-05/22.14, allocations: 14.39 kB / 5.812 GB, free: 11.23 MB / 0.5417 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03238/22.17, allocations: 8.826 MB / 5.821 GB, free: 10.69 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.271e-05/22.17, allocations: 3.891 kB / 5.821 GB, free: 10.69 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00561/22.18, allocations: 0.5737 MB / 5.822 GB, free: 10.69 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01032/22.19, allocations: 1.162 MB / 5.823 GB, free: 10.65 MB / 0.5417 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001117/22.19, allocations: 126.7 kB / 5.823 GB, free: 10.65 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006463/22.19, allocations: 2.676 MB / 5.826 GB, free: 9.094 MB / 0.5417 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.27e-06/22.19, allocations: 0.7188 kB / 5.826 GB, free: 9.094 MB / 0.5417 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02483/22.22, allocations: 7.337 MB / 5.833 GB, free: 8.477 MB / 0.5417 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003655/22.22, allocations: 0.8518 MB / 5.834 GB, free: 8.227 MB / 0.5417 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001208/22.22, allocations: 71.25 kB / 5.834 GB, free: 8.227 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005516/22.23, allocations: 283.1 kB / 5.834 GB, free: 8.219 MB / 0.5417 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008455/22.23, allocations: 218.1 kB / 5.834 GB, free: 8.211 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0006369/22.23, allocations: 186.1 kB / 5.834 GB, free: 8.211 MB / 0.5417 GB
Notification: Performance of sorting global known variables: time 0.00343/22.23, allocations: 1.473 MB / 5.836 GB, free: 8.066 MB / 0.5417 GB
Notification: Performance of sort global known variables: time 5.71e-07/22.23, allocations: 0 / 5.836 GB, free: 8.066 MB / 0.5417 GB
Notification: Performance of remove unused functions: time 0.02031/22.25, allocations: 4.235 MB / 5.84 GB, free: 8.066 MB / 0.5417 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 12 (volume1.medium.p,volume1.medium.Xi[1],volume1.medium.T,volume1.mC_scaled[1],volume2.medium.p,volume2.medium.Xi[1],volume2.medium.T,volume2.mC_scaled[1],volume3.medium.p,volume3.medium.Xi[1],volume3.medium.T,volume3.mC_scaled[1])
 * 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 (297):
 * Single equations (assignments): 285
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 9
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 3 {2 6,2 6,2 6}
Notification: Performance of Backend phase and start with SimCode phase: time 0.04615/22.3, allocations: 13.22 MB / 5.853 GB, free: 22.06 MB / 0.5573 GB
Notification: Performance of simCode: created initialization part: time 0.028/22.33, allocations: 8.219 MB / 5.861 GB, free: 21.37 MB / 0.5573 GB
Notification: Performance of simCode: created event and clocks part: time 1.236e-05/22.33, allocations: 5.875 kB / 5.861 GB, free: 21.37 MB / 0.5573 GB
Notification: Performance of simCode: created simulation system equations: time 0.008235/22.34, allocations: 2.347 MB / 5.863 GB, free: 21.28 MB / 0.5573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01105/22.35, allocations: 1.213 MB / 5.864 GB, free: 21.23 MB / 0.5573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.04868/22.4, allocations: 39.68 MB / 5.903 GB, free: 4.656 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003041/22.4, allocations: 2.289 MB / 5.905 GB, free: 3.855 MB / 0.573 GB
Notification: Performance of simCode: alias equations: time 0.0144/22.41, allocations: 2.225 MB / 5.907 GB, free: 3.562 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004747/22.42, allocations: 1.433 MB / 5.909 GB, free: 3.547 MB / 0.573 GB
Notification: Performance of SimCode: time 1.352e-06/22.42, allocations: 0 / 5.909 GB, free: 3.547 MB / 0.573 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.9093/23.33, allocations: 165 MB / 6.07 GB, free: 76.7 MB / 0.5886 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002398/23.33, allocations: 280 kB / 6.07 GB, free: 76.34 MB / 0.5886 GB
Notification: Performance of buildModelFMU: Generate platform static: time 10.94/34.27, allocations: 0 / 6.07 GB, free: 76.34 MB / 0.5886 GB
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.1_ModelicaTest.Fluid.TestComponents.Fittings.TestMultiPortTraceSubstances_res.mat does not exist to compare? outputFormat=mat