Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001708/0.001708, allocations: 71.75 kB / 21.24 MB, free: 400 kB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.00214/0.00214, allocations: 169.2 kB / 24.5 MB, free: 3.453 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.518/1.518, allocations: 177.2 MB / 204.9 MB, free: 8.934 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo): time 0.2076/0.2076, allocations: 34.27 MB / 295.5 MB, free: 14.59 MB / 286.1 MB " [Timeout remaining time 180] Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo) Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Running command: translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|volume.medium.p|volume.medium.T",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition") translateModel(ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|volume.medium.p|volume.medium.T",fileNamePrefix="ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 0.002118/0.002118, allocations: 67.83 kB / 443.2 MB, free: 6.676 MB / 414.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3043/0.3064, allocations: 66 MB / 0.4972 GB, free: 29.86 MB / 446.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition): time 0.185/0.4914, allocations: 156.5 MB / 0.6501 GB, free: 8.746 MB / 0.545 GB Notification: Performance of NFInst.instExpressions: time 0.009378/0.5008, allocations: 5.977 MB / 0.6559 GB, free: 2.754 MB / 0.545 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0005505/0.5013, allocations: 27.75 kB / 0.656 GB, free: 2.727 MB / 0.545 GB Notification: Performance of NFTyping.typeComponents: time 0.001147/0.5025, allocations: 397.7 kB / 0.6563 GB, free: 2.336 MB / 0.545 GB Notification: Performance of NFTyping.typeBindings: time 0.004068/0.5065, allocations: 1.781 MB / 0.6581 GB, free: 0.5508 MB / 0.545 GB Notification: Performance of NFTyping.typeClassSections: time 0.00385/0.5104, allocations: 1.517 MB / 0.6596 GB, free: 15.04 MB / 0.5606 GB Notification: Performance of NFFlatten.flatten: time 0.002547/0.5129, allocations: 2.134 MB / 0.6616 GB, free: 12.9 MB / 0.5606 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0006956/0.5136, allocations: 485.3 kB / 0.6621 GB, free: 12.42 MB / 0.5606 GB Notification: Performance of NFEvalConstants.evaluate: time 0.003387/0.517, allocations: 1.799 MB / 0.6639 GB, free: 10.62 MB / 0.5606 GB Notification: Performance of NFSimplifyModel.simplify: time 0.002661/0.5197, allocations: 1.535 MB / 0.6654 GB, free: 9.078 MB / 0.5606 GB Notification: Performance of NFPackage.collectConstants: time 0.0001792/0.5199, allocations: 76 kB / 0.6654 GB, free: 9.004 MB / 0.5606 GB Notification: Performance of NFFlatten.collectFunctions: time 0.002387/0.5222, allocations: 1.15 MB / 0.6665 GB, free: 7.852 MB / 0.5606 GB Notification: Performance of NFScalarize.scalarize: time 0.0004501/0.5227, allocations: 446.8 kB / 0.667 GB, free: 7.414 MB / 0.5606 GB Notification: Performance of NFVerifyModel.verify: time 0.0005957/0.5233, allocations: 490 kB / 0.6674 GB, free: 6.934 MB / 0.5606 GB Notification: Performance of NFConvertDAE.convert: time 0.002622/0.5259, allocations: 2.068 MB / 0.6695 GB, free: 4.859 MB / 0.5606 GB Notification: Performance of FrontEnd - DAE generated: time 2.845e-06/0.5259, allocations: 0 / 0.6695 GB, free: 4.859 MB / 0.5606 GB Notification: Performance of FrontEnd: time 9.82e-07/0.5259, allocations: 0 / 0.6695 GB, free: 4.859 MB / 0.5606 GB Notification: Performance of Transformations before backend: time 3.326e-05/0.5259, allocations: 0 / 0.6695 GB, free: 4.859 MB / 0.5606 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 192 * Number of variables: 192 Notification: Performance of Generate backend data structure: time 0.002872/0.5288, allocations: 1.399 MB / 0.6708 GB, free: 3.426 MB / 0.5606 GB Notification: Performance of prepare preOptimizeDAE: time 3.432e-05/0.5289, allocations: 12.03 kB / 0.6708 GB, free: 3.414 MB / 0.5606 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001411/0.5303, allocations: 283.1 kB / 0.6711 GB, free: 3.137 MB / 0.5606 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001098/0.5314, allocations: 0.5906 MB / 0.6717 GB, free: 2.516 MB / 0.5606 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001526/0.5315, allocations: 168.1 kB / 0.6718 GB, free: 2.324 MB / 0.5606 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001224/0.5316, allocations: 64 kB / 0.6719 GB, free: 2.262 MB / 0.5606 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.002266/0.5339, allocations: 1.966 MB / 0.6738 GB, free: 60 kB / 0.5606 GB Notification: Performance of preOpt findStateOrder (simulation): time 2.592e-05/0.5339, allocations: 3.938 kB / 0.6738 GB, free: 56 kB / 0.5606 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001054/0.534, allocations: 35.98 kB / 0.6739 GB, free: 20 kB / 0.5606 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001142/0.5341, allocations: 81.27 kB / 0.6739 GB, free: 15.94 MB / 0.5763 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001686/0.5358, allocations: 1.055 MB / 0.675 GB, free: 14.88 MB / 0.5763 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.006061/0.5419, allocations: 3.868 MB / 0.6788 GB, free: 10.89 MB / 0.5763 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0005199/0.5424, allocations: 294.5 kB / 0.679 GB, free: 10.6 MB / 0.5763 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0002389/0.5427, allocations: 136.8 kB / 0.6792 GB, free: 10.46 MB / 0.5763 GB Notification: Performance of preOpt evalFunc (simulation): time 6.34e-05/0.5427, allocations: 19.92 kB / 0.6792 GB, free: 10.44 MB / 0.5763 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.939e-05/0.5427, allocations: 38.56 kB / 0.6792 GB, free: 10.39 MB / 0.5763 GB Notification: Performance of pre-optimization done (n=28): time 1.643e-06/0.5427, allocations: 0 / 0.6792 GB, free: 10.39 MB / 0.5763 GB Notification: Performance of matching and sorting (n=33): time 0.006353/0.5491, allocations: 1.753 MB / 0.6809 GB, free: 8.574 MB / 0.5763 GB Notification: Performance of inlineWhenForInitialization (initialization): time 8.372e-05/0.5492, allocations: 136.5 kB / 0.6811 GB, free: 8.41 MB / 0.5763 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0009713/0.5502, allocations: 0.84 MB / 0.6819 GB, free: 7.559 MB / 0.5763 GB Notification: Performance of collectPreVariables (initialization): time 4.311e-05/0.5502, allocations: 33.7 kB / 0.6819 GB, free: 7.52 MB / 0.5763 GB Notification: Performance of collectInitialEqns (initialization): time 0.0004412/0.5506, allocations: 0.5961 MB / 0.6825 GB, free: 6.914 MB / 0.5763 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001112/0.5508, allocations: 131 kB / 0.6826 GB, free: 6.785 MB / 0.5763 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002757/0.551, allocations: 218.8 kB / 0.6828 GB, free: 6.566 MB / 0.5763 GB Notification: Performance of setup shared object (initialization): time 0.000194/0.5512, allocations: 390.8 kB / 0.6832 GB, free: 6.176 MB / 0.5763 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004529/0.5517, allocations: 274.5 kB / 0.6835 GB, free: 5.91 MB / 0.5763 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009729/0.5526, allocations: 0.9392 MB / 0.6844 GB, free: 4.668 MB / 0.5763 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.001428/0.5541, allocations: 1.179 MB / 0.6855 GB, free: 3.211 MB / 0.5763 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.377e-06/0.5541, allocations: 11.94 kB / 0.6855 GB, free: 3.199 MB / 0.5763 GB Notification: Performance of matching and sorting (n=130) (initialization): time 0.001939/0.556, allocations: 1.224 MB / 0.6867 GB, free: 1.965 MB / 0.5763 GB Notification: Performance of prepare postOptimizeDAE: time 2.449e-05/0.5561, allocations: 12.94 kB / 0.6868 GB, free: 1.953 MB / 0.5763 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.271e-05/0.5561, allocations: 20 kB / 0.6868 GB, free: 1.934 MB / 0.5763 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.001225/0.5573, allocations: 298.1 kB / 0.6871 GB, free: 1.641 MB / 0.5763 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005811/0.5579, allocations: 203.6 kB / 0.6872 GB, free: 1.441 MB / 0.5763 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001093/0.559, allocations: 0.9174 MB / 0.6881 GB, free: 0.5 MB / 0.5763 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006171/0.5596, allocations: 40 kB / 0.6882 GB, free: 472 kB / 0.5763 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.833e-05/0.5597, allocations: 71.98 kB / 0.6883 GB, free: 400 kB / 0.5763 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 90 * 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 (112): * Single equations (assignments): 111 * 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 {(2,17)} Notification: Performance of prepare postOptimizeDAE: time 0.0004245/0.5601, allocations: 345.8 kB / 0.6886 GB, free: 44 kB / 0.5763 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.241/0.8012, allocations: 87.66 kB / 0.6887 GB, free: 166.9 MB / 0.5763 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001285/0.8024, allocations: 0.5012 MB / 0.6892 GB, free: 166.9 MB / 0.5763 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.353e-06/0.8024, allocations: 3.375 kB / 0.6892 GB, free: 166.9 MB / 0.5763 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 6.943e-06/0.8025, allocations: 4.609 kB / 0.6892 GB, free: 166.9 MB / 0.5763 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.833e-06/0.8025, allocations: 1.312 kB / 0.6892 GB, free: 166.9 MB / 0.5763 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001732/0.8042, allocations: 0.9671 MB / 0.6901 GB, free: 166.5 MB / 0.5763 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.329e-06/0.8042, allocations: 1.469 kB / 0.6901 GB, free: 166.5 MB / 0.5763 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001027/0.8052, allocations: 178.7 kB / 0.6903 GB, free: 166.5 MB / 0.5763 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0001008/0.8053, allocations: 31.69 kB / 0.6903 GB, free: 166.4 MB / 0.5763 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.101e-05/0.8054, allocations: 13.38 kB / 0.6903 GB, free: 166.4 MB / 0.5763 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001455/0.8068, allocations: 2.626 MB / 0.6929 GB, free: 163.9 MB / 0.5763 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.154e-06/0.8068, allocations: 1.922 kB / 0.6929 GB, free: 163.9 MB / 0.5763 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001016/0.8078, allocations: 0.6974 MB / 0.6936 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0002617/0.8081, allocations: 106.3 kB / 0.6937 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.125e-05/0.8082, allocations: 19.8 kB / 0.6937 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003871/0.8086, allocations: 14.3 kB / 0.6937 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 8.133e-05/0.8087, allocations: 25.97 kB / 0.6937 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.21e-05/0.8087, allocations: 15.72 kB / 0.6937 GB, free: 163.5 MB / 0.5763 GB Notification: Performance of sorting global known variables: time 0.0008828/0.8096, allocations: 0.5255 MB / 0.6943 GB, free: 163.3 MB / 0.5763 GB Notification: Performance of sort global known variables: time 1e-07/0.8096, allocations: 0.9062 kB / 0.6943 GB, free: 163.3 MB / 0.5763 GB Notification: Performance of remove unused functions: time 0.002435/0.812, allocations: 381.3 kB / 0.6946 GB, free: 163.3 MB / 0.5763 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 2 * Number of states: 2 (volume.medium.p,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 (33): * Single equations (assignments): 31 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * 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): 1 system {(1,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.001882/0.8139, allocations: 0.6357 MB / 0.6952 GB, free: 163.2 MB / 0.5763 GB Notification: Performance of simCode: created initialization part: time 0.001493/0.8154, allocations: 0.6468 MB / 0.6959 GB, free: 163 MB / 0.5763 GB Notification: Performance of simCode: created event and clocks part: time 4.539e-06/0.8154, allocations: 0.7188 kB / 0.6959 GB, free: 163 MB / 0.5763 GB Notification: Performance of simCode: created simulation system equations: time 0.0008718/0.8163, allocations: 363.2 kB / 0.6962 GB, free: 162.8 MB / 0.5763 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001475/0.8177, allocations: 347.1 kB / 0.6966 GB, free: 162.7 MB / 0.5763 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002077/0.8198, allocations: 1.604 MB / 0.6981 GB, free: 162 MB / 0.5763 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003773/0.8202, allocations: 447.2 kB / 0.6985 GB, free: 161.8 MB / 0.5763 GB Notification: Performance of simCode: alias equations: time 0.0007512/0.8209, allocations: 367 kB / 0.6989 GB, free: 161.8 MB / 0.5763 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001549/0.8211, allocations: 65.7 kB / 0.699 GB, free: 161.8 MB / 0.5763 GB Notification: Performance of SimCode: time 4.81e-07/0.8211, allocations: 7.156 kB / 0.699 GB, free: 161.8 MB / 0.5763 GB Notification: Performance of Templates: time 0.03263/0.8537, allocations: 19.42 MB / 0.7179 GB, free: 158.2 MB / 0.5763 GB " [Timeout remaining time 659] make -j1 -f ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.makefile [Timeout 660] (rm -f ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.pipe ; mkfifo ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.pipe ; head -c 1048576 < ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.pipe >> ../files/ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.sim & ./ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition -abortSlowSimulation -alarm=480 -s ida -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.pipe 2>&1) [Timeout 480] diffSimulationResults("ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/Media/TestsWithFluid/MediaTestModels/IdealGases/SimpleNaturalGasFixedComposition/SimpleNaturalGasFixedComposition.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0_ModelicaTest.Media.TestsWithFluid.MediaTestModels.IdealGases.SimpleNaturalGasFixedComposition.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: 6.511455024941824]