Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volume.medium.T|volume.medium.Xi.1.|volume.medium.p",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir")
translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|volume.medium.T|volume.medium.Xi.1.|volume.medium.p",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001006/0.001006, allocations: 102.6 kB / 16.02 MB, free: 76 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00102/0.00102, allocations: 182.5 kB / 16.92 MB, free: 6.688 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.503/1.503, allocations: 205.4 MB / 223.1 MB, free: 5.559 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.3621/0.3621, allocations: 43.57 MB / 315 MB, free: 8.789 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0005871/0.0005872, allocations: 12 kB / 384.6 MB, free: 7.625 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.131/0.1316, allocations: 58.86 MB / 443.5 MB, free: 12.66 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir): time 0.4866/0.6183, allocations: 193.2 MB / 0.6217 GB, free: 9.668 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.3121/0.9304, allocations: 10.46 MB / 0.632 GB, free: 1.676 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001511/0.932, allocations: 24.03 kB / 0.632 GB, free: 1.676 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001467/0.9335, allocations: 393.7 kB / 0.6324 GB, free: 1.676 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006879/0.9404, allocations: 2.278 MB / 0.6346 GB, free: 1.645 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005894/0.9463, allocations: 2.333 MB / 0.6369 GB, free: 1.625 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003372/0.9497, allocations: 3.205 MB / 0.64 GB, free: 1.625 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007417/0.9504, allocations: 0.5069 MB / 0.6405 GB, free: 1.582 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004443/0.9549, allocations: 1.762 MB / 0.6422 GB, free: 1.559 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001463/0.9564, allocations: 0.8961 MB / 0.6431 GB, free: 1.555 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001471/0.9565, allocations: 45.47 kB / 0.6431 GB, free: 1.555 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0119/0.9684, allocations: 5.926 MB / 0.6489 GB, free: 1.512 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001296/0.9698, allocations: 385.8 kB / 0.6493 GB, free: 1.512 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001392/0.9712, allocations: 0.5092 MB / 0.6498 GB, free: 17.48 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.01394/0.9851, allocations: 4.147 MB / 0.6538 GB, free: 17.27 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 6.262e-06/0.9852, allocations: 1.406 kB / 0.6538 GB, free: 17.27 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 4.067e-06/0.9852, allocations: 0 / 0.6538 GB, free: 17.27 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 2.351e-05/0.9852, allocations: 1 kB / 0.6538 GB, free: 17.27 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 186
 * Number of variables: 186
Notification: Performance of Generate backend data structure: time 0.006337/0.9916, allocations: 1.596 MB / 0.6554 GB, free: 16.37 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 7.845e-05/0.9917, allocations: 13.06 kB / 0.6554 GB, free: 16.37 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005362/0.9922, allocations: 114.1 kB / 0.6555 GB, free: 16.32 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00119/0.9934, allocations: 0.5903 MB / 0.6561 GB, free: 16.05 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000142/0.9936, allocations: 116.9 kB / 0.6562 GB, free: 15.98 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001647/0.9937, allocations: 73.03 kB / 0.6563 GB, free: 15.98 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002208/0.996, allocations: 1.171 MB / 0.6574 GB, free: 15.8 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.784e-05/0.996, allocations: 4.938 kB / 0.6574 GB, free: 15.8 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001132/0.9961, allocations: 31.33 kB / 0.6574 GB, free: 15.8 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.767e-05/0.9962, allocations: 60.14 kB / 0.6575 GB, free: 15.8 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002176/0.9984, allocations: 1.175 MB / 0.6586 GB, free: 15.79 MB / 0.4981 GB
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.007816/1.006, allocations: 4.998 MB / 0.6635 GB, free: 13.15 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001921/1.008, allocations: 0.8464 MB / 0.6644 GB, free: 13.11 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006824/1.009, allocations: 425.9 kB / 0.6648 GB, free: 13.1 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001813/1.011, allocations: 1.889 MB / 0.6666 GB, free: 11.13 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.3e-05/1.011, allocations: 43.88 kB / 0.6666 GB, free: 11.09 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=70): time 4.549e-06/1.011, allocations: 4 kB / 0.6667 GB, free: 11.09 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=82): time 0.01523/1.026, allocations: 4.153 MB / 0.6707 GB, free: 10.27 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.856e-05/1.026, allocations: 114.5 kB / 0.6708 GB, free: 10.14 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001044/1.027, allocations: 0.6658 MB / 0.6715 GB, free: 9.898 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001378/1.027, allocations: 43.17 kB / 0.6715 GB, free: 9.867 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002941/1.028, allocations: 380.7 kB / 0.6719 GB, free: 9.641 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002713/1.028, allocations: 290.1 kB / 0.6721 GB, free: 9.434 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001321/1.028, allocations: 89.47 kB / 0.6722 GB, free: 9.402 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0001825/1.028, allocations: 391.4 kB / 0.6726 GB, free: 9.066 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0009637/1.029, allocations: 0.4919 MB / 0.6731 GB, free: 8.984 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001141/1.03, allocations: 0.7545 MB / 0.6738 GB, free: 8.59 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002261/1.033, allocations: 1.3 MB / 0.6751 GB, free: 8.172 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.085e-06/1.033, allocations: 1.719 kB / 0.6751 GB, free: 8.172 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=124) (initialization): time 0.003529/1.036, allocations: 1.709 MB / 0.6768 GB, free: 7.855 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 2.296e-05/1.036, allocations: 10.22 kB / 0.6768 GB, free: 7.855 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.281e-05/1.036, allocations: 9.25 kB / 0.6768 GB, free: 7.855 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007974/1.044, allocations: 1.395 MB / 0.6781 GB, free: 7.426 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007763/1.045, allocations: 155.5 kB / 0.6783 GB, free: 7.406 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003026/1.048, allocations: 1.55 MB / 0.6798 GB, free: 6.707 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001624/1.05, allocations: 90.06 kB / 0.6799 GB, free: 6.68 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001443/1.05, allocations: 60.7 kB / 0.68 GB, free: 6.676 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 35
 * 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 (89):
 * Single equations (assignments): 88
 * 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
   {(3,33)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007683/1.051, allocations: 302.6 kB / 0.6802 GB, free: 6.527 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002565/1.051, allocations: 94.3 kB / 0.6803 GB, free: 6.48 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003652/1.055, allocations: 1.641 MB / 0.6819 GB, free: 6.043 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.205e-05/1.055, allocations: 12.11 kB / 0.6819 GB, free: 6.039 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.739e-05/1.055, allocations: 0 / 0.6819 GB, free: 6.039 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.851e-05/1.055, allocations: 7.141 kB / 0.682 GB, free: 6.035 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005034/1.06, allocations: 2.569 MB / 0.6845 GB, free: 5.172 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.036e-05/1.06, allocations: 6.125 kB / 0.6845 GB, free: 5.168 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001284/1.061, allocations: 265.8 kB / 0.6847 GB, free: 5.113 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001988/1.063, allocations: 401.6 kB / 0.6851 GB, free: 4.977 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001085/1.063, allocations: 33.7 kB / 0.6851 GB, free: 4.973 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002538/1.066, allocations: 2.273 MB / 0.6874 GB, free: 3.016 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.575e-06/1.066, allocations: 0 / 0.6874 GB, free: 3.016 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003783/1.07, allocations: 2.041 MB / 0.6893 GB, free: 2.5 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007157/1.07, allocations: 304.9 kB / 0.6896 GB, free: 2.312 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001576/1.07, allocations: 26.47 kB / 0.6897 GB, free: 2.312 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009271/1.071, allocations: 75.53 kB / 0.6897 GB, free: 2.289 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001668/1.072, allocations: 63.64 kB / 0.6898 GB, free: 2.262 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001261/1.072, allocations: 48.97 kB / 0.6898 GB, free: 2.254 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0007295/1.072, allocations: 0.4987 MB / 0.6903 GB, free: 2.09 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.71e-07/1.072, allocations: 2.531 kB / 0.6903 GB, free: 2.09 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.002978/1.075, allocations: 0.707 MB / 0.691 GB, free: 1.961 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 3 (volume.medium.p,volume.medium.Xi[1],volume.medium.T)
 * 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 (82):
 * Single equations (assignments): 78
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
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
   {(2,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.002744/1.078, allocations: 0.9131 MB / 0.6919 GB, free: 1.594 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.003022/1.081, allocations: 1.248 MB / 0.6931 GB, free: 1.391 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 1.04e-05/1.081, allocations: 3.953 kB / 0.6931 GB, free: 1.387 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.001795/1.083, allocations: 0.7195 MB / 0.6938 GB, free: 1.012 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003282/1.086, allocations: 0.4931 MB / 0.6943 GB, free: 0.6055 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005305/1.092, allocations: 2.59 MB / 0.6968 GB, free: 14.25 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006692/1.092, allocations: 1.042 MB / 0.6979 GB, free: 13.21 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.001506/1.094, allocations: 324.2 kB / 0.6982 GB, free: 12.89 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003505/1.094, allocations: 105.7 kB / 0.6983 GB, free: 12.8 MB / 0.5137 GB
Notification: Performance of SimCode: time 2.334e-06/1.094, allocations: 0 / 0.6983 GB, free: 12.8 MB / 0.5137 GB
Notification: Performance of Templates: time 0.0591/1.153, allocations: 34.79 MB / 0.7322 GB, free: 10.22 MB / 0.545 GB
make -j1 -f ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.makefile
(rm -f ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.sim & ./ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.pipe 2>&1)
diffSimulationResults("ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/ModelicaTest/Media/TestsWithFluid/MediaTestModels/Air/MoistAir/MoistAir.csv","../files/ModelicaTest_3.2.3_ModelicaTest.Media.TestsWithFluid.MediaTestModels.Air.MoistAir.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches