Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001297/0.001297, allocations: 112.6 kB / 18.49 MB, free: 4.562 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00125/0.00125, allocations: 193 kB / 21.43 MB, free: 1.633 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.343/1.343, allocations: 225.6 MB / 249.8 MB, free: 3.613 MB / 190.1 MB
"
[Timeout remaining time 178]
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(Modelica.Media.Examples.WaterIF97,fileNamePrefix="Modelica_4_0_0_Modelica_Media_Examples_WaterIF97",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Media.Examples.WaterIF97,fileNamePrefix="Modelica_4_0_0_Modelica_Media_Examples_WaterIF97",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001469/0.001469, allocations: 51.91 kB / 366.3 MB, free: 0.8633 MB / 286.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.0901/0.09157, allocations: 52.15 MB / 418.5 MB, free: 12.62 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Media.Examples.WaterIF97): time 0.01562/0.1072, allocations: 12.96 MB / 431.4 MB, free: 15.61 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01317/0.1204, allocations: 11.58 MB / 443 MB, free: 3.98 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 7.972e-05/0.1205, allocations: 0 / 443 MB, free: 3.98 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003033/0.1208, allocations: 75.55 kB / 443.1 MB, free: 3.906 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0002621/0.121, allocations: 91.42 kB / 443.2 MB, free: 3.816 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.1899/0.311, allocations: 5.055 MB / 448.2 MB, free: 9.039 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0002954/0.3113, allocations: 231 kB / 448.5 MB, free: 9.039 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 5.299e-05/0.3113, allocations: 10.73 kB / 448.5 MB, free: 9.035 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003576/0.3117, allocations: 200.3 kB / 448.7 MB, free: 9.035 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001067/0.3118, allocations: 70.03 kB / 448.7 MB, free: 9.035 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 1.899e-05/0.3118, allocations: 11.22 kB / 448.7 MB, free: 9.035 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0114/0.3232, allocations: 6.995 MB / 455.7 MB, free: 9.031 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 7.462e-05/0.3233, allocations: 25.95 kB / 455.8 MB, free: 9.031 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001222/0.3234, allocations: 70.03 kB / 455.8 MB, free: 9.031 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008026/0.3314, allocations: 3.877 MB / 459.7 MB, free: 8.926 MB / 366.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.65e-06/0.3314, allocations: 3.531 kB / 459.7 MB, free: 8.926 MB / 366.1 MB
Notification: Performance of FrontEnd: time 1.763e-06/0.3314, allocations: 1.156 kB / 459.7 MB, free: 8.926 MB / 366.1 MB
Notification: Performance of Transformations before backend: time 5.541e-06/0.3314, allocations: 0 / 459.7 MB, free: 8.926 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 34
 * Number of variables: 34
Notification: Performance of Generate backend data structure: time 0.004873/0.3363, allocations: 1.163 MB / 460.9 MB, free: 8.312 MB / 366.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.152e-05/0.3363, allocations: 11.41 kB / 460.9 MB, free: 8.312 MB / 366.1 MB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 2.428e-05/0.3364, allocations: 8.531 kB / 460.9 MB, free: 8.312 MB / 366.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 4.492e-05/0.3364, allocations: 47 kB / 460.9 MB, free: 8.273 MB / 366.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004579/0.3369, allocations: 53.17 kB / 461 MB, free: 8.266 MB / 366.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0002421/0.3371, allocations: 219.4 kB / 461.2 MB, free: 8.066 MB / 366.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.67e-05/0.3371, allocations: 14.78 kB / 461.2 MB, free: 8.066 MB / 366.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 4.758e-05/0.3372, allocations: 23.03 kB / 461.2 MB, free: 8.059 MB / 366.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001172/0.3384, allocations: 395.8 kB / 461.6 MB, free: 7.953 MB / 366.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.398e-05/0.3384, allocations: 0 / 461.6 MB, free: 7.953 MB / 366.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 3.211e-05/0.3384, allocations: 5.562 kB / 461.6 MB, free: 7.953 MB / 366.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.422e-06/0.3384, allocations: 4.125 kB / 461.6 MB, free: 7.953 MB / 366.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00135/0.3398, allocations: 355.2 kB / 462 MB, free: 7.945 MB / 366.1 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.001106/0.3409, allocations: 1.195 MB / 463.2 MB, free: 6.949 MB / 366.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0007927/0.3417, allocations: 273.2 kB / 463.4 MB, free: 6.875 MB / 366.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002548/0.3419, allocations: 108.1 kB / 463.6 MB, free: 6.852 MB / 366.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.09581/0.4377, allocations: 47.67 MB / 0.4992 GB, free: 12.9 MB / 414.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.516e-05/0.4378, allocations: 42.06 kB / 0.4993 GB, free: 12.86 MB / 414.1 MB
Notification: Performance of pre-optimization done (n=23): time 1.824e-06/0.4378, allocations: 0 / 0.4993 GB, free: 12.86 MB / 414.1 MB
Notification: Performance of matching and sorting (n=33): time 0.006821/0.4446, allocations: 1.576 MB / 0.5008 GB, free: 11.52 MB / 414.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.527e-05/0.4447, allocations: 110.9 kB / 0.5009 GB, free: 11.39 MB / 414.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0001063/0.4448, allocations: 119.5 kB / 0.501 GB, free: 11.27 MB / 414.1 MB
Notification: Performance of collectPreVariables (initialization): time 3.546e-05/0.4448, allocations: 29.7 kB / 0.5011 GB, free: 11.23 MB / 414.1 MB
Notification: Performance of collectInitialEqns (initialization): time 7.18e-05/0.4449, allocations: 113.3 kB / 0.5012 GB, free: 11.12 MB / 414.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001156/0.445, allocations: 126.2 kB / 0.5013 GB, free: 11 MB / 414.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001224/0.4451, allocations: 71 kB / 0.5014 GB, free: 10.93 MB / 414.1 MB
Notification: Performance of setup shared object (initialization): time 0.000137/0.4452, allocations: 326.8 kB / 0.5017 GB, free: 10.6 MB / 414.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004022/0.4457, allocations: 142.4 kB / 0.5018 GB, free: 10.46 MB / 414.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004465/0.4461, allocations: 198 kB / 0.502 GB, free: 10.25 MB / 414.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008378/0.4469, allocations: 341.3 kB / 0.5023 GB, free: 9.895 MB / 414.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.024e-06/0.4469, allocations: 1.125 kB / 0.5023 GB, free: 9.895 MB / 414.1 MB
Notification: Performance of matching and sorting (n=41) (initialization): time 0.001102/0.448, allocations: 432.2 kB / 0.5027 GB, free: 9.473 MB / 414.1 MB
Notification: Performance of prepare postOptimizeDAE: time 1.776e-05/0.4481, allocations: 8 kB / 0.5027 GB, free: 9.465 MB / 414.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.49e-06/0.4481, allocations: 0 / 0.5027 GB, free: 9.465 MB / 414.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002318/0.4483, allocations: 41.05 kB / 0.5028 GB, free: 9.422 MB / 414.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004556/0.4488, allocations: 90.88 kB / 0.5029 GB, free: 9.34 MB / 414.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0004159/0.4492, allocations: 0.5445 MB / 0.5034 GB, free: 8.77 MB / 414.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002759/0.4494, allocations: 28.08 kB / 0.5034 GB, free: 8.742 MB / 414.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.226e-05/0.4495, allocations: 15.98 kB / 0.5034 GB, free: 8.727 MB / 414.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 8
 * Number of states: 0 ()
 * Number of discrete variables: 3 (medium.phase,state.phase,state2.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (40):
 * Single equations (assignments): 39
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,1)}
Notification: Performance of prepare postOptimizeDAE: time 0.000142/0.4496, allocations: 139.5 kB / 0.5036 GB, free: 8.59 MB / 414.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001005/0.4497, allocations: 15.91 kB / 0.5036 GB, free: 8.574 MB / 414.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002261/0.452, allocations: 1.45 MB / 0.505 GB, free: 7.129 MB / 414.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.289e-05/0.452, allocations: 19.97 kB / 0.505 GB, free: 7.109 MB / 414.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.27e-06/0.452, allocations: 0 / 0.505 GB, free: 7.109 MB / 414.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.152e-06/0.452, allocations: 0 / 0.505 GB, free: 7.109 MB / 414.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003067/0.4551, allocations: 1.985 MB / 0.507 GB, free: 5.07 MB / 414.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.843e-06/0.4551, allocations: 3.984 kB / 0.507 GB, free: 5.066 MB / 414.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004356/0.4555, allocations: 83.62 kB / 0.5071 GB, free: 4.988 MB / 414.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0003019/0.4558, allocations: 78.59 kB / 0.5071 GB, free: 4.91 MB / 414.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.158e-05/0.4559, allocations: 8.5 kB / 0.5071 GB, free: 4.902 MB / 414.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0004198/0.4563, allocations: 0.5981 MB / 0.5077 GB, free: 4.285 MB / 414.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.883e-06/0.4563, allocations: 0 / 0.5077 GB, free: 4.285 MB / 414.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002362/0.4586, allocations: 1.699 MB / 0.5094 GB, free: 2.562 MB / 414.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 9.507e-05/0.4587, allocations: 125.2 kB / 0.5095 GB, free: 2.422 MB / 414.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 4.727e-05/0.4588, allocations: 12.03 kB / 0.5095 GB, free: 2.41 MB / 414.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003548/0.4591, allocations: 23.92 kB / 0.5095 GB, free: 2.387 MB / 414.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.651e-05/0.4592, allocations: 27.95 kB / 0.5096 GB, free: 2.359 MB / 414.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.243e-05/0.4592, allocations: 19.19 kB / 0.5096 GB, free: 2.344 MB / 414.1 MB
Notification: Performance of sorting global known variables: time 7.052e-05/0.4593, allocations: 98.27 kB / 0.5097 GB, free: 2.25 MB / 414.1 MB
Notification: Performance of sort global known variables: time 8e-08/0.4593, allocations: 0 / 0.5097 GB, free: 2.25 MB / 414.1 MB
Notification: Performance of remove unused functions: time 0.00375/0.4631, allocations: 0.9708 MB / 0.5106 GB, free: 1.277 MB / 414.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * Number of states: 3 (medium.p,medium.h,V)
 * Number of discrete variables: 11 ($cse2.phase,$cse2.region,$cse3.phase,$cse3.region,$cse4.phase,$cse4.region,$cse5.region3boundary,$cse6.region3boundary,medium.phase,$cse1.phase,$cse1.region)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (38):
 * Single equations (assignments): 31
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 6
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,1)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.008506/0.4716, allocations: 3.284 MB / 0.5138 GB, free: 14.08 MB / 430.1 MB
Notification: Performance of simCode: created initialization part: time 0.001507/0.4731, allocations: 0.5942 MB / 0.5144 GB, free: 13.5 MB / 430.1 MB
Notification: Performance of simCode: created event and clocks part: time 3.517e-06/0.4731, allocations: 0 / 0.5144 GB, free: 13.5 MB / 430.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0008112/0.4739, allocations: 450.5 kB / 0.5148 GB, free: 13.07 MB / 430.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0007839/0.4747, allocations: 81.67 kB / 0.5149 GB, free: 13 MB / 430.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008589/0.4833, allocations: 3.915 MB / 0.5187 GB, free: 8.969 MB / 430.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004669/0.4837, allocations: 0.8178 MB / 0.5195 GB, free: 8.121 MB / 430.1 MB
Notification: Performance of simCode: alias equations: time 0.0003575/0.4841, allocations: 135.7 kB / 0.5197 GB, free: 7.996 MB / 430.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002412/0.4843, allocations: 132.5 kB / 0.5198 GB, free: 7.867 MB / 430.1 MB
Notification: Performance of SimCode: time 8.51e-07/0.4843, allocations: 4 kB / 0.5198 GB, free: 7.863 MB / 430.1 MB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07932/0.5637, allocations: 40.62 MB / 0.5595 GB, free: 15.38 MB / 478.1 MB
Notification: Performance of buildModelFMU: Generate platform static: time 13.81/14.38, allocations: 10.25 kB / 0.5595 GB, free: 15.37 MB / 478.1 MB
"
[Timeout remaining time 645]
(rm -f Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.pipe ; mkfifo Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.pipe >> ../files/Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Modelica_4.0.0_Modelica.Media.Examples.WaterIF97_res.mat --tempDir=temp_Modelica_4_0_0_Modelica_Media_Examples_WaterIF97_fmu --startTime=0 --stopTime=1 --stepSize=0.00019992 --timeout=50 --tolerance=1e-06 Modelica_4_0_0_Modelica_Media_Examples_WaterIF97.fmu > Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.pipe 2>&1) [Timeout 52.5]
diffSimulationResults("Modelica_4.0.0_Modelica.Media.Examples.WaterIF97_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/Modelica/Media/Examples/WaterIF97/WaterIF97.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_4.0.0_Modelica.Media.Examples.WaterIF97.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 17.27519607101567]