Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Incompressible.Essotherm650.conf.json
Using package ModelicaTest with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo)
Using package ModelicaServices with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo)
Running command: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Incompressible.Essotherm650,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volume.medium.T|p_start|T_start|h_start|X_start.1.|volume.ports.1..m_flow|volume.ports.1..p|volume.ports.1..h_outflow|volume.ports.2..m_flow|volume.ports.2..p|volume.ports.2..h_outflow|volume.ports_H_flow.1.|volume.ports_H_flow.2.|volume.ports_E_flow.1.|volume.ports_E_flow.2.|volume.ports_penetration.1.|volume.ports_penetration.2.|volume.portsData_diameter.1.|volume.portsData_diameter.2.|volume.portsData_height.1.|volume.portsData_height.2.|volume.portsData_zeta_in.1.|volume.portsData_zeta_in.2.|volume.portsData_zeta_out.1.|volume.portsData_zeta_out.2.|fixedMassFlowRate.ports.1..m_flow|fixedMassFlowRate.ports.1..p|fixedMassFlowRate.ports.1..h_outflow|shortPipe.port_b.m_flow|shortPipe.port_b.p|shortPipe.port_b.h_outflow",fileNamePrefix="ModelicaTest_3.2.2_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Incompressible.Essotherm650")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.003763/0.003768, allocations: 369.7 kB / 15.96 MB, free: 6.199 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 4.072/4.072, allocations: 388.9 MB / 405.6 MB, free: 10.24 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.003483/0.003483, allocations: 237.8 kB / 451 MB, free: 145.1 MB / 346.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.5637/0.5637, allocations: 80.02 MB / 0.5627 GB, free: 19.84 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.000638/0.0006383, allocations: 15.75 kB / 0.6265 GB, free: 96.54 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09579/0.09646, allocations: 54.87 MB / 0.6801 GB, free: 41.66 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Incompressible.Essotherm650): time 0.03842/0.1349, allocations: 26.99 MB / 0.7065 GB, free: 14.59 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.008212/0.1432, allocations: 4.323 MB / 0.7107 GB, free: 10.25 MB / 346.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001061/0.1443, allocations: 31.75 kB / 0.7107 GB, free: 10.22 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009374/0.1453, allocations: 226.6 kB / 0.7109 GB, free: 9.996 MB / 346.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:826:36-827:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:824:36-825:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:772:36-773:54:writable] Warning: 'each' used when modifying non-array element m_flow.
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/Fluid/Sources.mo:770:36-771:54:writable] Warning: 'each' used when modifying non-array element m_flow.
Notification: Performance of NFTyping.typeBindings: time 0.005204/0.1505, allocations: 1.623 MB / 0.7125 GB, free: 8.375 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00425/0.1548, allocations: 1.241 MB / 0.7137 GB, free: 7.145 MB / 346.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002965/0.1578, allocations: 2.234 MB / 0.7159 GB, free: 4.906 MB / 346.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008427/0.1587, allocations: 397.2 kB / 0.7163 GB, free: 4.512 MB / 346.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.3228/0.4815, allocations: 3.477 MB / 0.7197 GB, free: 16.41 MB / 346.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003632/0.4852, allocations: 1.42 MB / 0.7211 GB, free: 16.41 MB / 346.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002608/0.4855, allocations: 35.91 kB / 0.7211 GB, free: 16.41 MB / 346.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004316/0.4898, allocations: 1.449 MB / 0.7225 GB, free: 16.41 MB / 346.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0008628/0.4907, allocations: 229.1 kB / 0.7227 GB, free: 16.41 MB / 346.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0005122/0.4913, allocations: 167.5 kB / 0.7229 GB, free: 16.38 MB / 346.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.003286/0.4946, allocations: 1.053 MB / 0.7239 GB, free: 16.36 MB / 346.7 MB
Notification: Performance of FrontEnd - DAE generated: time 8.916e-06/0.4947, allocations: 0 / 0.7239 GB, free: 16.36 MB / 346.7 MB
Notification: Performance of FrontEnd: time 3.606e-06/0.4947, allocations: 0 / 0.7239 GB, free: 16.36 MB / 346.7 MB
Notification: Performance of Transformations before backend: time 4.427e-05/0.4947, allocations: 4.219 kB / 0.7239 GB, free: 16.36 MB / 346.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 133
 * Number of variables: 133
Notification: Performance of Generate backend data structure: time 0.003507/0.4983, allocations: 1.01 MB / 0.7249 GB, free: 15.83 MB / 346.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.006e-05/0.4983, allocations: 12.48 kB / 0.7249 GB, free: 15.83 MB / 346.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001415/0.4998, allocations: 142.8 kB / 0.7251 GB, free: 15.79 MB / 346.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001456/0.5013, allocations: 0.4935 MB / 0.7256 GB, free: 15.54 MB / 346.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001392/0.5014, allocations: 107.2 kB / 0.7257 GB, free: 15.48 MB / 346.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001317/0.5016, allocations: 50.28 kB / 0.7257 GB, free: 15.48 MB / 346.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002181/0.5038, allocations: 0.8331 MB / 0.7265 GB, free: 15.24 MB / 346.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.425e-05/0.5038, allocations: 5.312 kB / 0.7265 GB, free: 15.24 MB / 346.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001235/0.504, allocations: 25.56 kB / 0.7265 GB, free: 15.24 MB / 346.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.685e-05/0.5041, allocations: 38.02 kB / 0.7266 GB, free: 15.24 MB / 346.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002061/0.5062, allocations: 0.6022 MB / 0.7272 GB, free: 15.24 MB / 346.7 MB
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.007628/0.5138, allocations: 4.888 MB / 0.7319 GB, free: 11.62 MB / 346.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001052/0.5149, allocations: 316.5 kB / 0.7322 GB, free: 11.59 MB / 346.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003426/0.5153, allocations: 134.3 kB / 0.7324 GB, free: 11.58 MB / 346.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005913/0.5159, allocations: 0.6103 MB / 0.733 GB, free: 10.92 MB / 346.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.323e-05/0.516, allocations: 37.22 kB / 0.733 GB, free: 10.88 MB / 346.7 MB
Notification: Performance of pre-optimization done (n=29): time 3.647e-06/0.516, allocations: 0 / 0.733 GB, free: 10.88 MB / 346.7 MB
Notification: Performance of matching and sorting (n=35): time 0.006303/0.5223, allocations: 1.499 MB / 0.7345 GB, free: 10.38 MB / 346.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.593e-05/0.5224, allocations: 131.9 kB / 0.7346 GB, free: 10.22 MB / 346.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001201/0.5236, allocations: 0.6353 MB / 0.7352 GB, free: 10.09 MB / 346.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001186/0.5237, allocations: 31.67 kB / 0.7352 GB, free: 10.05 MB / 346.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003238/0.5241, allocations: 408.8 kB / 0.7356 GB, free: 9.832 MB / 346.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001402/0.5242, allocations: 131.5 kB / 0.7358 GB, free: 9.754 MB / 346.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.998e-05/0.5243, allocations: 11.56 kB / 0.7358 GB, free: 9.754 MB / 346.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001452/0.5244, allocations: 380.3 kB / 0.7361 GB, free: 9.438 MB / 346.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005645/0.525, allocations: 203.7 kB / 0.7363 GB, free: 9.438 MB / 346.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007623/0.5258, allocations: 0.5292 MB / 0.7368 GB, free: 9.023 MB / 346.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0007653/0.5266, allocations: 300.6 kB / 0.7371 GB, free: 9.008 MB / 346.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.041e-05/0.5266, allocations: 4.188 kB / 0.7371 GB, free: 9.008 MB / 346.7 MB
Notification: Performance of matching and sorting (n=92) (initialization): time 0.002421/0.529, allocations: 0.8878 MB / 0.738 GB, free: 8.91 MB / 346.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.175e-05/0.5291, allocations: 9.75 kB / 0.738 GB, free: 8.91 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.435e-05/0.5291, allocations: 11.11 kB / 0.738 GB, free: 8.91 MB / 346.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002092/0.5312, allocations: 277.1 kB / 0.7383 GB, free: 8.887 MB / 346.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008648/0.5321, allocations: 135.7 kB / 0.7384 GB, free: 8.887 MB / 346.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001625/0.5338, allocations: 1.377 MB / 0.7398 GB, free: 7.84 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001109/0.5349, allocations: 32.89 kB / 0.7398 GB, free: 7.84 MB / 346.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001268/0.5351, allocations: 50.81 kB / 0.7398 GB, free: 7.84 MB / 346.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 48
 * Number of states: 0 ()
 * Number of discrete variables: 4 (volume.regularFlow[1],volume.regularFlow[2],volume.inFlow[2],volume.inFlow[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (75):
 * Single equations (assignments): 73
 * 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: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 2 {1 4,1 13}
Notification: Performance of prepare postOptimizeDAE: time 0.000558/0.5357, allocations: 257.4 kB / 0.7401 GB, free: 7.711 MB / 346.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002066/0.5359, allocations: 72.11 kB / 0.7402 GB, free: 7.676 MB / 346.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002306/0.5382, allocations: 0.6109 MB / 0.7408 GB, free: 7.586 MB / 346.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.083e-05/0.5382, allocations: 1.875 kB / 0.7408 GB, free: 7.586 MB / 346.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.136e-06/0.5382, allocations: 0 / 0.7408 GB, free: 7.586 MB / 346.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005491/0.5388, allocations: 199.1 kB / 0.7409 GB, free: 7.453 MB / 346.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001768/0.539, allocations: 7.984 kB / 0.741 GB, free: 7.449 MB / 346.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006041/0.5396, allocations: 22.17 kB / 0.741 GB, free: 7.449 MB / 346.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 9.837e-05/0.5397, allocations: 19.12 kB / 0.741 GB, free: 7.449 MB / 346.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.002216/0.542, allocations: 0.6021 MB / 0.7416 GB, free: 7.305 MB / 346.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.0017/0.5437, allocations: 0.7451 MB / 0.7423 GB, free: 7.043 MB / 346.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0004095/0.5441, allocations: 153.1 kB / 0.7425 GB, free: 7.043 MB / 346.7 MB
Notification: Performance of sorting global known variables: time 0.0008422/0.545, allocations: 439 kB / 0.7429 GB, free: 6.949 MB / 346.7 MB
Notification: Performance of Backend: time 5.71e-07/0.545, allocations: 0 / 0.7429 GB, free: 6.949 MB / 346.7 MB
Notification: Performance of simCode: created initialization part: time 0.003797/0.5488, allocations: 0.839 MB / 0.7437 GB, free: 6.801 MB / 346.7 MB
Notification: Performance of SimCode: time 0.004752/0.5536, allocations: 2.138 MB / 0.7458 GB, free: 5.617 MB / 346.7 MB
Notification: Performance of Templates: time 0.0451/0.5988, allocations: 15.72 MB / 0.7611 GB, free: 13.33 MB / 362.7 MB