Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.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) 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(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic") translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001391/0.001391, allocations: 107.4 kB / 17.15 MB, free: 6.328 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001773/0.001773, allocations: 198 kB / 18.07 MB, free: 5.434 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.435/1.435, allocations: 205.1 MB / 223.9 MB, free: 12.48 MB / 190.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.001181/0.001181, allocations: 47.94 kB / 280 MB, free: 3.598 MB / 222.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.0964/0.09759, allocations: 46.63 MB / 326.6 MB, free: 4.898 MB / 270.1 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic): time 0.3043/0.4019, allocations: 114.3 MB / 440.9 MB, free: 3.957 MB / 334.1 MB Notification: Performance of NFInst.instExpressions: time 0.05521/0.4572, allocations: 22.4 MB / 463.3 MB, free: 13.49 MB / 366.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.02327/0.4805, allocations: 0.5104 MB / 463.8 MB, free: 12.98 MB / 366.1 MB Notification: Performance of NFTyping.typeComponents: time 0.01398/0.4945, allocations: 7.99 MB / 471.8 MB, free: 4.969 MB / 366.1 MB Notification: Performance of NFTyping.typeBindings: time 0.01025/0.5048, allocations: 3.418 MB / 475.2 MB, free: 1.543 MB / 366.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.005055/0.5099, allocations: 1.853 MB / 477.1 MB, free: 15.69 MB / 382.1 MB Notification: Performance of NFFlatten.flatten: time 0.02406/0.534, allocations: 17.37 MB / 494.4 MB, free: 14.29 MB / 398.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.01355/0.5476, allocations: 10.16 MB / 0.4928 GB, free: 4.055 MB / 398.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0098/0.5575, allocations: 3.75 MB / 0.4964 GB, free: 300 kB / 398.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01053/0.568, allocations: 6.018 MB / 0.5023 GB, free: 10.26 MB / 414.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002966/0.571, allocations: 0.6289 MB / 0.5029 GB, free: 9.629 MB / 414.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.006027/0.5771, allocations: 1.881 MB / 0.5048 GB, free: 7.746 MB / 414.1 MB Notification: Performance of NFScalarize.scalarize: time 0.007352/0.5845, allocations: 4.723 MB / 0.5094 GB, free: 3.012 MB / 414.1 MB Notification: Performance of NFVerifyModel.verify: time 0.012/0.5965, allocations: 5.039 MB / 0.5143 GB, free: 13.95 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.02095/0.6175, allocations: 16.66 MB / 0.5306 GB, free: 13.23 MB / 446.1 MB Notification: Performance of FrontEnd - DAE generated: time 9.598e-06/0.6175, allocations: 0 / 0.5306 GB, free: 13.23 MB / 446.1 MB Notification: Performance of FrontEnd: time 2.084e-06/0.6176, allocations: 0 / 0.5306 GB, free: 13.23 MB / 446.1 MB Notification: Performance of Transformations before backend: time 0.0006305/0.6182, allocations: 0 / 0.5306 GB, free: 13.23 MB / 446.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2753 * Number of variables: 2753 Notification: Performance of Generate backend data structure: time 0.03446/0.6527, allocations: 11.23 MB / 0.5415 GB, free: 1.902 MB / 446.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.959e-05/0.6527, allocations: 12.03 kB / 0.5416 GB, free: 1.891 MB / 446.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.5194/1.172, allocations: 5.414 MB / 0.5468 GB, free: 62.6 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.02269/1.195, allocations: 9.161 MB / 0.5558 GB, free: 61.67 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004536/1.195, allocations: 0.5215 MB / 0.5563 GB, free: 61.66 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.003197/1.199, allocations: 0.7225 MB / 0.557 GB, free: 61.64 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.04221/1.241, allocations: 19.51 MB / 0.5761 GB, free: 58.76 MB / 446.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0005245/1.241, allocations: 91.05 kB / 0.5761 GB, free: 58.75 MB / 446.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001976/1.243, allocations: 329 kB / 0.5765 GB, free: 58.75 MB / 446.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004064/1.247, allocations: 2.82 MB / 0.5792 GB, free: 57.88 MB / 446.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03267/1.28, allocations: 16.25 MB / 0.5951 GB, free: 41.95 MB / 446.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.1005/1.381, allocations: 74.58 MB / 0.6679 GB, free: 14.32 MB / 494.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.02301/1.404, allocations: 8.468 MB / 0.6762 GB, free: 5.727 MB / 494.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.007008/1.411, allocations: 4.767 MB / 0.6808 GB, free: 0.9375 MB / 494.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.02898/1.44, allocations: 15.41 MB / 0.6959 GB, free: 1.117 MB / 0.4982 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.916e-05/1.44, allocations: 74.81 kB / 0.6959 GB, free: 1.035 MB / 0.4982 GB Notification: Performance of pre-optimization done (n=319): time 8.175e-06/1.44, allocations: 5.406 kB / 0.696 GB, free: 1.031 MB / 0.4982 GB Notification: Performance of matching and sorting (n=419): time 0.4106/1.851, allocations: 50.43 MB / 0.7452 GB, free: 254.3 MB / 0.545 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001577/1.851, allocations: 407.6 kB / 0.7456 GB, free: 253.9 MB / 0.545 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01695/1.868, allocations: 7.648 MB / 0.7531 GB, free: 252.1 MB / 0.545 GB Notification: Performance of collectPreVariables (initialization): time 0.001287/1.869, allocations: 91.03 kB / 0.7531 GB, free: 252.1 MB / 0.545 GB Notification: Performance of collectInitialEqns (initialization): time 0.004272/1.873, allocations: 5.621 MB / 0.7586 GB, free: 248.7 MB / 0.545 GB Notification: Performance of collectInitialBindings (initialization): time 0.002223/1.876, allocations: 1.533 MB / 0.7601 GB, free: 247.6 MB / 0.545 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.003622/1.879, allocations: 2.082 MB / 0.7622 GB, free: 246.6 MB / 0.545 GB Notification: Performance of setup shared object (initialization): time 5.826e-05/1.879, allocations: 306.3 kB / 0.7625 GB, free: 246.3 MB / 0.545 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.00679/1.886, allocations: 5.12 MB / 0.7675 GB, free: 244.4 MB / 0.545 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01002/1.896, allocations: 10.2 MB / 0.7774 GB, free: 235.8 MB / 0.545 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01811/1.914, allocations: 16.13 MB / 0.7932 GB, free: 224.2 MB / 0.545 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002032/1.915, allocations: 50.59 kB / 0.7932 GB, free: 224.2 MB / 0.545 GB Notification: Performance of matching and sorting (n=1332) (initialization): time 0.04392/1.958, allocations: 20.86 MB / 0.8136 GB, free: 211.5 MB / 0.545 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001011/1.959, allocations: 80.19 kB / 0.8137 GB, free: 211.4 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001779/1.959, allocations: 176 kB / 0.8138 GB, free: 211.3 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.01938/1.978, allocations: 6.451 MB / 0.8201 GB, free: 204.8 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007067/1.985, allocations: 2.148 MB / 0.8222 GB, free: 202.7 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03993/2.025, allocations: 31.43 MB / 0.8529 GB, free: 171.1 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009416/2.035, allocations: 0.6162 MB / 0.8535 GB, free: 170.5 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001853/2.037, allocations: 0.5312 MB / 0.8541 GB, free: 170 MB / 0.545 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 784 * Number of states: 0 () * Number of discrete variables: 73 (jointRRP.jointUSP.prismatic.positiveBranch,jointRRP.jointUSP.rod1Color[3],jointRRP.jointUSP.rod1Color[2],jointRRP.jointUSP.rod1Color[1],jointRRP.jointUSP.cylinderColor[3],jointRRP.jointUSP.cylinderColor[2],jointRRP.jointUSP.cylinderColor[1],jointRRP.jointUSP.sphereColor[3],jointRRP.jointUSP.sphereColor[2],jointRRP.jointUSP.sphereColor[1],jointRRP.rodColor[3],jointRRP.rodColor[2],jointRRP.rodColor[1],jointRRP.cylinderColor[3],jointRRP.cylinderColor[2],jointRRP.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],Mid.color[3],Mid.color[2],Mid.color[1],Crank2.color[3],Crank2.color[2],Crank2.color[1],Crank2.body.sphereColor[3],Crank2.body.sphereColor[2],Crank2.body.sphereColor[1],Crank1.color[3],Crank1.color[2],Crank1.color[1],Crank1.body.sphereColor[3],Crank1.body.sphereColor[2],Crank1.body.sphereColor[1],Crank3.color[3],Crank3.color[2],Crank3.color[1],Crank3.body.sphereColor[3],Crank3.body.sphereColor[2],Crank3.body.sphereColor[1],Crank4.color[3],Crank4.color[2],Crank4.color[1],Crank4.body.sphereColor[3],Crank4.body.sphereColor[2],Crank4.body.sphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],Bearing.cylinderColor[3],Bearing.cylinderColor[2],Bearing.cylinderColor[1],Rod2.color[3],Rod2.color[2],Rod2.color[1],Rod2.body.sphereColor[3],Rod2.body.sphereColor[2],Rod2.body.sphereColor[1],Piston.color[3],Piston.color[2],Piston.color[1],Piston.body.sphereColor[3],Piston.body.sphereColor[2],Piston.body.sphereColor[1]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (1180): * Single equations (assignments): 1175 * Array equations: 4 * 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): 1 system {(3,124,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.006727/2.043, allocations: 4.101 MB / 0.8581 GB, free: 165.8 MB / 0.545 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001892/2.045, allocations: 0.6903 MB / 0.8587 GB, free: 165.1 MB / 0.545 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02519/2.07, allocations: 13.85 MB / 0.8723 GB, free: 151.2 MB / 0.545 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.097e-05/2.071, allocations: 40 kB / 0.8723 GB, free: 151.2 MB / 0.545 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 3.521e-05/2.071, allocations: 24 kB / 0.8723 GB, free: 151.2 MB / 0.545 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.257e-05/2.071, allocations: 19.92 kB / 0.8723 GB, free: 151.2 MB / 0.545 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04848/2.119, allocations: 27.2 MB / 0.8989 GB, free: 123.8 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.912e-05/2.119, allocations: 8 kB / 0.8989 GB, free: 123.8 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002665/2.122, allocations: 0.5421 MB / 0.8994 GB, free: 123.3 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0171/2.139, allocations: 5.957 MB / 0.9053 GB, free: 117.3 MB / 0.545 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001214/2.14, allocations: 78.61 kB / 0.9053 GB, free: 117.2 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03789/2.178, allocations: 28.2 MB / 0.9329 GB, free: 88.88 MB / 0.545 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.354e-06/2.178, allocations: 11.94 kB / 0.9329 GB, free: 88.86 MB / 0.545 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03454/2.213, allocations: 19.99 MB / 0.9524 GB, free: 68.69 MB / 0.545 GB Notification: Performance of postOpt removeConstants (simulation): time 0.004996/2.218, allocations: 1.538 MB / 0.9539 GB, free: 67.12 MB / 0.545 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002644/2.221, allocations: 79.98 kB / 0.954 GB, free: 67.05 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004982/2.226, allocations: 315.5 kB / 0.9543 GB, free: 66.74 MB / 0.545 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001582/2.227, allocations: 296.5 kB / 0.9546 GB, free: 66.45 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0007343/2.228, allocations: 112 kB / 0.9547 GB, free: 66.34 MB / 0.545 GB Notification: Performance of sorting global known variables: time 0.008428/2.236, allocations: 4.898 MB / 0.9595 GB, free: 61.48 MB / 0.545 GB Notification: Performance of sort global known variables: time 2.61e-07/2.236, allocations: 0 / 0.9595 GB, free: 61.48 MB / 0.545 GB Notification: Performance of remove unused functions: time 0.01054/2.247, allocations: 2.406 MB / 0.9618 GB, free: 59.09 MB / 0.545 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 34 * Number of states: 2 (Inertia.phi,Inertia.w) * 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 (292): * Single equations (assignments): 288 * Array equations: 3 * 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): 1 system {(3,111,77.8%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.001155/2.248, allocations: 280.3 kB / 0.9621 GB, free: 58.81 MB / 0.545 GB Notification: Performance of simCode: created initialization part: time 0.01488/2.263, allocations: 12.4 MB / 0.9742 GB, free: 46.42 MB / 0.545 GB Notification: Performance of simCode: created event and clocks part: time 2.876e-05/2.263, allocations: 2.625 kB / 0.9742 GB, free: 46.42 MB / 0.545 GB Notification: Performance of simCode: created simulation system equations: time 0.006084/2.269, allocations: 5.097 MB / 0.9792 GB, free: 41.3 MB / 0.545 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005824/2.275, allocations: 1.014 MB / 0.9802 GB, free: 40.3 MB / 0.545 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.225/2.5, allocations: 26.86 MB / 1.006 GB, free: 232.1 MB / 0.545 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01261/2.513, allocations: 6.395 MB / 1.013 GB, free: 231.2 MB / 0.545 GB Notification: Performance of simCode: alias equations: time 0.008603/2.521, allocations: 2.954 MB / 1.016 GB, free: 231.1 MB / 0.545 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001573/2.523, allocations: 435.3 kB / 1.016 GB, free: 231.1 MB / 0.545 GB Notification: Performance of SimCode: time 1.102e-06/2.523, allocations: 0 / 1.016 GB, free: 231.1 MB / 0.545 GB Notification: Performance of Templates: time 0.1609/2.684, allocations: 106.9 MB / 1.12 GB, free: 143.5 MB / 0.545 GB make -j1 -f Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.makefile (rm -f Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe ; mkfifo Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe ; head -c 1048576 < Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe >> ../files/Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.sim & ./Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.pipe 2>&1) diffSimulationResults("Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1b_analytic/Engine1b_analytic.csv","../files/Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) Reference file matches