Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.ConstantPropertyLiquidWater.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: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.ConstantPropertyLiquidWater,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.1_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.ConstantPropertyLiquidWater")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.1+maint.om/package.mo): time 0.002663/0.002663, allocations: 352.2 kB / 15.95 MB, free: 6.223 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 3.68/3.68, allocations: 341.5 MB / 358.2 MB, free: 28.6 MB / 170.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.1+maint.om/package.mo): time 0.005347/0.005347, allocations: 222.1 kB / 397.9 MB, free: 6.012 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.5139/0.5139, allocations: 76.09 MB / 0.5014 GB, free: 0.8047 MB / 250.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004741/0.0004743, allocations: 12 kB / 0.5592 GB, free: 60.24 MB / 282.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08699/0.08749, allocations: 48.71 MB / 0.6068 GB, free: 11.51 MB / 282.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Water.ConstantPropertyLiquidWater): time 0.02161/0.1092, allocations: 15.39 MB / 0.6218 GB, free: 12.07 MB / 298.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01116/0.1204, allocations: 5.185 MB / 0.6268 GB, free: 6.875 MB / 298.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001162/0.1216, allocations: 23.81 kB / 0.6269 GB, free: 6.852 MB / 298.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001271/0.1229, allocations: 250.5 kB / 0.6271 GB, free: 6.605 MB / 298.7 MB
[/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.1+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.1+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.1+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.1+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.003625/0.1266, allocations: 0.8305 MB / 0.6279 GB, free: 5.773 MB / 298.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003773/0.1304, allocations: 0.7817 MB / 0.6287 GB, free: 5 MB / 298.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003983/0.1344, allocations: 2.175 MB / 0.6308 GB, free: 2.82 MB / 298.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00125/0.1357, allocations: 369.2 kB / 0.6312 GB, free: 2.453 MB / 298.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002346/0.1381, allocations: 0.6187 MB / 0.6318 GB, free: 1.832 MB / 298.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001805/0.14, allocations: 0.5994 MB / 0.6323 GB, free: 1.23 MB / 298.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002364/0.1403, allocations: 32 kB / 0.6324 GB, free: 1.199 MB / 298.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001269/0.1416, allocations: 207.7 kB / 0.6326 GB, free: 0.9961 MB / 298.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004388/0.142, allocations: 227.3 kB / 0.6328 GB, free: 0.7734 MB / 298.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.000342/0.1424, allocations: 173.3 kB / 0.633 GB, free: 0.5977 MB / 298.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001599/0.144, allocations: 0.7871 MB / 0.6337 GB, free: 15.81 MB / 314.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.172e-06/0.144, allocations: 0 / 0.6337 GB, free: 15.81 MB / 314.7 MB
Notification: Performance of FrontEnd: time 2.235e-06/0.1441, allocations: 0 / 0.6337 GB, free: 15.81 MB / 314.7 MB
Notification: Performance of Transformations before backend: time 1.698e-05/0.1441, allocations: 0 / 0.6337 GB, free: 15.81 MB / 314.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 130
 * Number of variables: 130
Notification: Performance of Generate backend data structure: time 0.002721/0.1468, allocations: 0.9086 MB / 0.6346 GB, free: 14.85 MB / 314.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.136e-05/0.1469, allocations: 12.03 kB / 0.6346 GB, free: 14.84 MB / 314.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005077/0.1474, allocations: 87.11 kB / 0.6347 GB, free: 14.75 MB / 314.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001239/0.1487, allocations: 0.4927 MB / 0.6352 GB, free: 14.22 MB / 314.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001587/0.1488, allocations: 104.4 kB / 0.6353 GB, free: 14.11 MB / 314.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001614/0.149, allocations: 48 kB / 0.6353 GB, free: 14.06 MB / 314.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002053/0.1511, allocations: 0.8269 MB / 0.6361 GB, free: 13.15 MB / 314.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.198e-05/0.1511, allocations: 7.938 kB / 0.6362 GB, free: 13.14 MB / 314.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001157/0.1513, allocations: 27.98 kB / 0.6362 GB, free: 13.12 MB / 314.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.914e-05/0.1514, allocations: 40.97 kB / 0.6362 GB, free: 13.08 MB / 314.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001842/0.1532, allocations: 0.6399 MB / 0.6368 GB, free: 12.44 MB / 314.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.007538/0.1608, allocations: 4.321 MB / 0.6411 GB, free: 7.809 MB / 314.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001079/0.1619, allocations: 340.7 kB / 0.6414 GB, free: 7.469 MB / 314.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004786/0.1624, allocations: 173.3 kB / 0.6416 GB, free: 7.297 MB / 314.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.003446/0.1659, allocations: 1.776 MB / 0.6433 GB, free: 5.273 MB / 314.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.69e-05/0.166, allocations: 42.52 kB / 0.6433 GB, free: 5.223 MB / 314.7 MB
Notification: Performance of pre-optimization done (n=23): time 3.927e-06/0.166, allocations: 0 / 0.6433 GB, free: 5.223 MB / 314.7 MB
Notification: Performance of matching and sorting (n=33): time 0.005086/0.1711, allocations: 1.225 MB / 0.6445 GB, free: 3.941 MB / 314.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.241e-05/0.1712, allocations: 132.5 kB / 0.6447 GB, free: 3.781 MB / 314.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001164/0.1724, allocations: 0.621 MB / 0.6453 GB, free: 3.152 MB / 314.7 MB
Notification: Performance of collectPreVariables (initialization): time 8.535e-05/0.1725, allocations: 37.7 kB / 0.6453 GB, free: 3.109 MB / 314.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0004545/0.173, allocations: 413.9 kB / 0.6457 GB, free: 2.699 MB / 314.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001528/0.1732, allocations: 118.4 kB / 0.6458 GB, free: 2.582 MB / 314.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.793e-05/0.1732, allocations: 20 kB / 0.6458 GB, free: 2.562 MB / 314.7 MB
Notification: Performance of setup shared object (initialization): time 0.000218/0.1734, allocations: 378.7 kB / 0.6462 GB, free: 2.184 MB / 314.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004827/0.1739, allocations: 173.9 kB / 0.6463 GB, free: 2.016 MB / 314.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.000915/0.1749, allocations: 0.5771 MB / 0.6469 GB, free: 1.242 MB / 314.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001272/0.1762, allocations: 291.7 kB / 0.6472 GB, free: 0.957 MB / 314.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.627e-05/0.1762, allocations: 4 kB / 0.6472 GB, free: 0.9531 MB / 314.7 MB
Notification: Performance of matching and sorting (n=95) (initialization): time 0.003607/0.1799, allocations: 0.7872 MB / 0.648 GB, free: 164 kB / 314.7 MB
Notification: Performance of prepare postOptimizeDAE: time 6.666e-05/0.18, allocations: 12 kB / 0.648 GB, free: 152 kB / 314.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.501e-05/0.1801, allocations: 12 kB / 0.648 GB, free: 140 kB / 314.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 7.187e-05/0.1801, allocations: 20 kB / 0.648 GB, free: 120 kB / 314.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.2851/0.4653, allocations: 208.6 kB / 0.6482 GB, free: 48.37 MB / 314.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.462e-05/0.4654, allocations: 27.33 kB / 0.6482 GB, free: 48.37 MB / 314.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009693/0.4664, allocations: 48.06 kB / 0.6483 GB, free: 48.37 MB / 314.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.794e-05/0.4665, allocations: 42.28 kB / 0.6483 GB, free: 48.37 MB / 314.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 61
 * 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 (95):
 * Single equations (assignments): 95
 * 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.0006139/0.4671, allocations: 264.7 kB / 0.6486 GB, free: 48.26 MB / 314.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001766/0.4673, allocations: 67.09 kB / 0.6486 GB, free: 48.26 MB / 314.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001246/0.4686, allocations: 435.7 kB / 0.649 GB, free: 48.19 MB / 314.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.698e-06/0.4686, allocations: 1.828 kB / 0.649 GB, free: 48.19 MB / 314.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.049e-06/0.4686, allocations: 5.75 kB / 0.6491 GB, free: 48.19 MB / 314.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004126/0.469, allocations: 177.8 kB / 0.6492 GB, free: 48.09 MB / 314.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001657/0.4692, allocations: 8.953 kB / 0.6492 GB, free: 48.09 MB / 314.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005859/0.4698, allocations: 23.98 kB / 0.6493 GB, free: 48.09 MB / 314.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.432e-05/0.4699, allocations: 19.09 kB / 0.6493 GB, free: 48.09 MB / 314.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001251/0.4712, allocations: 437.1 kB / 0.6497 GB, free: 47.97 MB / 314.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.001347/0.4725, allocations: 0.6611 MB / 0.6503 GB, free: 47.75 MB / 314.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003084/0.4729, allocations: 124.5 kB / 0.6505 GB, free: 47.75 MB / 314.7 MB
Notification: Performance of sorting global known variables: time 0.0008722/0.4738, allocations: 425.5 kB / 0.6509 GB, free: 47.57 MB / 314.7 MB
Notification: Performance of Backend: time 5.81e-07/0.4738, allocations: 0 / 0.6509 GB, free: 47.57 MB / 314.7 MB
Notification: Performance of simCode: created initialization part: time 0.002284/0.4761, allocations: 0.5976 MB / 0.6514 GB, free: 47.39 MB / 314.7 MB
Notification: Performance of SimCode: time 0.004599/0.4807, allocations: 2.039 MB / 0.6534 GB, free: 46.18 MB / 314.7 MB
Notification: Performance of Templates: time 0.03442/0.5152, allocations: 11.02 MB / 0.6642 GB, free: 42.03 MB / 314.7 MB
""

Variables in the reference: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

Variables in the result:T_start,X_start[1],ambient.T,ambient.X[1],ambient.d,ambient.flowDirection,ambient.h,ambient.medium.preferredMediumStates,ambient.medium.standardOrderComponents,ambient.nPorts,ambient.p,ambient.state.T,ambient.state.p,ambient.use_T,ambient.use_p,fixedMassFlowRate.T,fixedMassFlowRate.X[1],fixedMassFlowRate.flowDirection,fixedMassFlowRate.m_flow,fixedMassFlowRate.m_flow_in_internal,fixedMassFlowRate.medium.preferredMediumStates,fixedMassFlowRate.medium.standardOrderComponents,fixedMassFlowRate.nPorts,fixedMassFlowRate.ports[1].h_outflow,fixedMassFlowRate.ports[1].m_flow,fixedMassFlowRate.ports[1].p,h_start,p_start,shortPipe.V,shortPipe.allowFlowReversal,shortPipe.crossArea,shortPipe.diameter,shortPipe.flowModel.Re_turbulent,shortPipe.flowModel.allowFlowReversal,shortPipe.flowModel.crossAreas[1],shortPipe.flowModel.crossAreas[2],shortPipe.flowModel.dheights[1],shortPipe.flowModel.dp_nominal,shortPipe.flowModel.g,shortPipe.flowModel.m,shortPipe.flowModel.m_flow_nominal,shortPipe.flowModel.m_flow_start,shortPipe.flowModel.momentumDynamics,shortPipe.flowModel.mu_nominal,shortPipe.flowModel.mus[1],shortPipe.flowModel.mus[2],shortPipe.flowModel.mus_act[1],shortPipe.flowModel.n,shortPipe.flowModel.nParallel,shortPipe.flowModel.p_a_start,shortPipe.flowModel.p_b_start,shortPipe.flowModel.pathLengths[1],shortPipe.flowModel.rho_nominal,shortPipe.flowModel.roughnesses[1],shortPipe.flowModel.roughnesses[2],shortPipe.flowModel.show_Res,shortPipe.flowModel.states[2].p,shortPipe.flowModel.useUpstreamScheme,shortPipe.flowModel.use_Ib_flows,shortPipe.flowModel.use_mu_nominal,shortPipe.flowModel.use_rho_nominal,shortPipe.height_ab,shortPipe.isCircular,shortPipe.length,shortPipe.m_flow_start,shortPipe.nParallel,shortPipe.p_a_start,shortPipe.p_b_start,shortPipe.perimeter,shortPipe.port_a_exposesState,shortPipe.port_b.h_outflow,shortPipe.port_b.m_flow,shortPipe.port_b.p,shortPipe.port_b_exposesState,shortPipe.roughness,shortPipe.showDesignFlowDirection,system.T_ambient,system.T_start,system.allowFlowReversal,system.dp_small,system.energyDynamics,system.eps_m_flow,system.g,system.m_flow_nominal,system.m_flow_small,system.m_flow_start,system.massDynamics,system.momentumDynamics,system.p_ambient,system.p_start,system.substanceDynamics,system.traceDynamics,system.use_eps_Re,time,volume.T_start,volume.V,volume.X_start[1],volume.energyDynamics,volume.fluidLevel_max,volume.fluidVolume,volume.h_start,volume.heatTransfer.T_ambient,volume.heatTransfer.k,volume.heatTransfer.n,volume.heatTransfer.use_k,volume.initialize_p,volume.m_flow_nominal,volume.m_flow_small,volume.massDynamics,volume.medium.T,volume.medium.p,volume.medium.preferredMediumStates,volume.medium.standardOrderComponents,volume.nPorts,volume.p_start,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],volume.ports[1].h_outflow,volume.ports[1].m_flow,volume.ports[1].p,volume.ports[2].h_outflow,volume.ports[2].m_flow,volume.ports[2].p,volume.ports_E_flow[1],volume.ports_E_flow[2],volume.ports_H_flow[1],volume.ports_H_flow[2],volume.ports_penetration[1],volume.ports_penetration[2],volume.substanceDynamics,volume.traceDynamics,volume.use_HeatTransfer,volume.use_Re,volume.use_T_start,volume.use_portsData,volume.vesselArea