Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.Fluid.TestComponents.Valves.TestValveVaporizing.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.Fluid.TestComponents.Valves.TestValveVaporizing,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|SourceP1.ports.1..m_flow|SourceP1.ports.1..p|SourceP1.ports.1..h_outflow|V1.port_b.m_flow|V1.port_b.p|V1.port_b.h_outflow|V1.minLimiter.y|SinkP1.ports.1..m_flow|SinkP1.ports.1..p|SinkP1.ports.1..h_outflow|Opening.y|downstreamPressure.y",fileNamePrefix="ModelicaTest_3.2.2_ModelicaTest.Fluid.TestComponents.Valves.TestValveVaporizing")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.004135/0.004135, allocations: 366.6 kB / 15.96 MB, free: 6.203 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 3.986/3.986, allocations: 388.9 MB / 405.6 MB, free: 9.555 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.003553/0.003553, allocations: 224.7 kB / 451 MB, free: 145 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.4988/0.4988, allocations: 80.02 MB / 0.5627 GB, free: 19.74 MB / 346.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004562/0.0004565, allocations: 20 kB / 0.6265 GB, free: 96.5 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09029/0.09078, allocations: 54.87 MB / 0.6801 GB, free: 41.62 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Fluid.TestComponents.Valves.TestValveVaporizing): time 0.0345/0.1253, allocations: 26.51 MB / 0.706 GB, free: 15.03 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.362/0.4874, allocations: 15.84 MB / 0.7215 GB, free: 13.62 MB / 346.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009162/0.4884, allocations: 15.22 kB / 0.7215 GB, free: 13.62 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001574/0.49, allocations: 237.6 kB / 0.7217 GB, free: 13.62 MB / 346.7 MB
[/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.
[/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.01914/0.5092, allocations: 2.979 MB / 0.7246 GB, free: 13.6 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01937/0.5286, allocations: 3.1 MB / 0.7276 GB, free: 13.53 MB / 346.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002782/0.5314, allocations: 1.08 MB / 0.7287 GB, free: 13.53 MB / 346.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009585/0.5324, allocations: 233.5 kB / 0.7289 GB, free: 13.52 MB / 346.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001933/0.5344, allocations: 400 kB / 0.7293 GB, free: 13.52 MB / 346.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001125/0.5356, allocations: 290.1 kB / 0.7296 GB, free: 13.51 MB / 346.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001757/0.5358, allocations: 17.66 kB / 0.7296 GB, free: 13.51 MB / 346.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0299/0.5657, allocations: 6.971 MB / 0.7364 GB, free: 13.3 MB / 346.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003303/0.5661, allocations: 88.64 kB / 0.7365 GB, free: 13.26 MB / 346.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003711/0.5665, allocations: 113.3 kB / 0.7366 GB, free: 13.22 MB / 346.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0105/0.577, allocations: 3.076 MB / 0.7396 GB, free: 11.88 MB / 346.7 MB
Notification: Performance of FrontEnd - DAE generated: time 1.246e-05/0.5771, allocations: 5.406 kB / 0.7396 GB, free: 11.88 MB / 346.7 MB
Notification: Performance of FrontEnd: time 2.755e-06/0.5771, allocations: 4 kB / 0.7396 GB, free: 11.88 MB / 346.7 MB
Notification: Performance of Transformations before backend: time 1.338e-05/0.5772, allocations: 0 / 0.7396 GB, free: 11.88 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: 84
 * Number of variables: 84
Notification: Performance of Generate backend data structure: time 0.006689/0.5839, allocations: 1.259 MB / 0.7408 GB, free: 11.03 MB / 346.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.58e-05/0.584, allocations: 10.78 kB / 0.7408 GB, free: 11.03 MB / 346.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004301/0.5883, allocations: 243 kB / 0.7411 GB, free: 10.95 MB / 346.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001083/0.5894, allocations: 400.1 kB / 0.7415 GB, free: 10.66 MB / 346.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.452e-05/0.5895, allocations: 27.09 kB / 0.7415 GB, free: 10.66 MB / 346.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001106/0.5897, allocations: 31.56 kB / 0.7415 GB, free: 10.66 MB / 346.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004164/0.5938, allocations: 0.6694 MB / 0.7422 GB, free: 10.38 MB / 346.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.849e-05/0.5939, allocations: 5.078 kB / 0.7422 GB, free: 10.38 MB / 346.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.936e-05/0.594, allocations: 25.48 kB / 0.7422 GB, free: 10.37 MB / 346.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.358e-05/0.5941, allocations: 13.34 kB / 0.7422 GB, free: 10.36 MB / 346.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004909/0.599, allocations: 0.6474 MB / 0.7428 GB, free: 10.21 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.006056/0.6051, allocations: 2.779 MB / 0.7456 GB, free: 7.883 MB / 346.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004259/0.6094, allocations: 0.5071 MB / 0.7461 GB, free: 7.742 MB / 346.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001398/0.6109, allocations: 200.8 kB / 0.7462 GB, free: 7.699 MB / 346.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.2831/0.894, allocations: 52.15 MB / 0.7972 GB, free: 3.398 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.685e-05/0.8942, allocations: 37.25 kB / 0.7972 GB, free: 3.355 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=27): time 5.461e-06/0.8942, allocations: 0 / 0.7972 GB, free: 3.355 MB / 394.7 MB
Notification: Performance of matching and sorting (n=27): time 0.007864/0.9021, allocations: 0.8665 MB / 0.7981 GB, free: 2.629 MB / 394.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.853e-05/0.9022, allocations: 109.9 kB / 0.7982 GB, free: 2.5 MB / 394.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009044/0.9031, allocations: 346.5 kB / 0.7985 GB, free: 2.219 MB / 394.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001089/0.9033, allocations: 40.89 kB / 0.7985 GB, free: 2.176 MB / 394.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002927/0.9036, allocations: 243.3 kB / 0.7988 GB, free: 1.992 MB / 394.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001667/0.9038, allocations: 89.73 kB / 0.7988 GB, free: 1.918 MB / 394.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.092e-05/0.9038, allocations: 6.375 kB / 0.7989 GB, free: 1.918 MB / 394.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002319/0.9041, allocations: 346 kB / 0.7992 GB, free: 1.586 MB / 394.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001428/0.9055, allocations: 173.7 kB / 0.7993 GB, free: 1.453 MB / 394.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00168/0.9072, allocations: 310.7 kB / 0.7996 GB, free: 1.141 MB / 394.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00155/0.9088, allocations: 202.3 kB / 0.7998 GB, free: 0.9922 MB / 394.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.375e-05/0.9089, allocations: 4 kB / 0.7998 GB, free: 0.9883 MB / 394.7 MB
Notification: Performance of matching and sorting (n=44) (initialization): time 0.003698/0.9126, allocations: 0.5336 MB / 0.8004 GB, free: 0.5469 MB / 394.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0002202/0.9129, allocations: 205 kB / 0.8006 GB, free: 312 kB / 394.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.397e-05/0.9129, allocations: 0 / 0.8006 GB, free: 312 kB / 394.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 3.349e-05/0.913, allocations: 8 kB / 0.8006 GB, free: 304 kB / 394.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001428/0.9144, allocations: 122.3 kB / 0.8007 GB, free: 188 kB / 394.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.529e-05/0.9145, allocations: 8 kB / 0.8007 GB, free: 180 kB / 394.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00128/0.9158, allocations: 85.3 kB / 0.8008 GB, free: 100 kB / 394.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001009/0.9159, allocations: 23.23 kB / 0.8008 GB, free: 80 kB / 394.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.175e-05/0.916, allocations: 7.281 kB / 0.8008 GB, free: 76 kB / 394.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003493/0.9195, allocations: 482.5 kB / 0.8013 GB, free: 15.71 MB / 410.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.452e-05/0.9196, allocations: 4 kB / 0.8013 GB, free: 15.7 MB / 410.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 2.063e-05/0.9196, allocations: 8 kB / 0.8013 GB, free: 15.7 MB / 410.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001335/0.9209, allocations: 119.4 kB / 0.8014 GB, free: 15.58 MB / 410.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.061e-05/0.921, allocations: 12.17 kB / 0.8014 GB, free: 15.57 MB / 410.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001199/0.9222, allocations: 81.78 kB / 0.8015 GB, free: 15.5 MB / 410.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000103/0.9224, allocations: 25.92 kB / 0.8015 GB, free: 15.48 MB / 410.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 4 (SourceP1.medium.phase,SinkP1.medium.phase,V1.state_b.phase,V1.state_a.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (44):
 * Single equations (assignments): 44
 * 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.0005375/0.9229, allocations: 245.6 kB / 0.8017 GB, free: 15.25 MB / 410.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002047/0.9231, allocations: 35.53 kB / 0.8018 GB, free: 15.22 MB / 410.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006771/0.9299, allocations: 1.98 MB / 0.8037 GB, free: 13.88 MB / 410.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.007e-05/0.93, allocations: 3.938 kB / 0.8037 GB, free: 13.88 MB / 410.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.045e-05/0.93, allocations: 0 / 0.8037 GB, free: 13.88 MB / 410.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007001/0.9308, allocations: 253 kB / 0.8039 GB, free: 13.64 MB / 410.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001894/0.931, allocations: 4.5 kB / 0.8039 GB, free: 13.64 MB / 410.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001243/0.9322, allocations: 82.92 kB / 0.804 GB, free: 13.57 MB / 410.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001835/0.9325, allocations: 34.77 kB / 0.8041 GB, free: 13.54 MB / 410.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004955/0.9374, allocations: 1.163 MB / 0.8052 GB, free: 12.68 MB / 410.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004483/0.942, allocations: 1.218 MB / 0.8064 GB, free: 11.74 MB / 410.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00203/0.9441, allocations: 0.5265 MB / 0.8069 GB, free: 11.45 MB / 410.7 MB
Notification: Performance of sorting global known variables: time 0.0008758/0.945, allocations: 309 kB / 0.8072 GB, free: 11.21 MB / 410.7 MB
Notification: Performance of Backend: time 1.193e-06/0.9451, allocations: 0 / 0.8072 GB, free: 11.21 MB / 410.7 MB
Notification: Performance of simCode: created initialization part: time 0.01788/0.963, allocations: 2.003 MB / 0.8092 GB, free: 9.48 MB / 410.7 MB
Notification: Performance of SimCode: time 0.005123/0.9681, allocations: 1.863 MB / 0.811 GB, free: 7.668 MB / 410.7 MB
Notification: Performance of Templates: time 0.1526/1.121, allocations: 45.71 MB / 0.8556 GB, free: 10.12 MB / 458.7 MB
"Warning: Resultfile and Reference have different end time points!
Reffile[5007]=1.010000
File[5947]=1.200082
"

Variables in the reference:time,SourceP1.ports[1].m_flow,SourceP1.ports[1].p,SourceP1.ports[1].h_outflow,V1.port_b.m_flow,V1.port_b.p,V1.port_b.h_outflow,V1.minLimiter.y,SinkP1.ports[1].m_flow,SinkP1.ports[1].p,SinkP1.ports[1].h_outflow,Opening.y,downstreamPressure.y

Variables in the result:Opening.k,Opening.y,SinkP1.T,SinkP1.X[1],SinkP1.flowDirection,SinkP1.medium.preferredMediumStates,SinkP1.medium.standardOrderComponents,SinkP1.nPorts,SinkP1.p,SinkP1.ports[1].h_outflow,SinkP1.ports[1].m_flow,SinkP1.ports[1].p,SourceP1.T,SourceP1.X[1],SourceP1.flowDirection,SourceP1.medium.preferredMediumStates,SourceP1.medium.standardOrderComponents,SourceP1.nPorts,SourceP1.p,SourceP1.p_in_internal,SourceP1.ports[1].h_outflow,SourceP1.ports[1].m_flow,SourceP1.ports[1].p,V1.Av,V1.Cv,V1.CvData,V1.Fl_nominal,V1.Kv,V1.allowFlowReversal,V1.checkValve,V1.dp_nominal,V1.dp_small,V1.dp_start,V1.dp_turbulent,V1.filteredOpening,V1.leakageOpening,V1.m_flow,V1.m_flow_nominal,V1.m_flow_small,V1.m_flow_start,V1.minLimiter.u,V1.minLimiter.uMin,V1.minLimiter.y,V1.opening,V1.opening_actual,V1.opening_nominal,V1.p_out,V1.port_a_exposesState,V1.port_b.h_outflow,V1.port_b.m_flow,V1.port_b.p,V1.port_b_exposesState,V1.rho_nominal,V1.riseTime,V1.showDesignFlowDirection,V1.show_T,V1.show_V_flow,V1.use_Re,downstreamPressure.duration,downstreamPressure.height,downstreamPressure.offset,downstreamPressure.startTime,downstreamPressure.y,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