Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.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.Engine1a,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a") translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001203/0.001202, allocations: 111.9 kB / 18.37 MB, free: 4.73 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.001109/0.001109, allocations: 180.5 kB / 19.29 MB, free: 3.812 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.344/1.344, allocations: 205.1 MB / 225.2 MB, free: 12.24 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.001256/0.001256, allocations: 52.31 kB / 329.2 MB, free: 3.324 MB / 270.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.0919/0.09316, allocations: 46.62 MB / 375.8 MB, free: 4.598 MB / 318.1 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a): time 0.1998/0.2931, allocations: 19.49 MB / 395.3 MB, free: 1.465 MB / 318.1 MB Notification: Performance of NFInst.instExpressions: time 0.01451/0.3076, allocations: 5.994 MB / 401.3 MB, free: 13.44 MB / 334.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.00527/0.3129, allocations: 157.8 kB / 401.4 MB, free: 13.41 MB / 334.1 MB Notification: Performance of NFTyping.typeComponents: time 0.01189/0.3249, allocations: 5.857 MB / 407.3 MB, free: 10.66 MB / 334.1 MB Notification: Performance of NFTyping.typeBindings: time 0.01037/0.3353, allocations: 3.359 MB / 410.6 MB, free: 8.27 MB / 334.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.004232/0.3396, allocations: 1.573 MB / 412.2 MB, free: 7.211 MB / 334.1 MB Notification: Performance of NFFlatten.flatten: time 0.01785/0.3574, allocations: 11.36 MB / 423.6 MB, free: 15.69 MB / 350.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.01024/0.3677, allocations: 7.673 MB / 431.2 MB, free: 7.965 MB / 350.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.007647/0.3754, allocations: 3.571 MB / 434.8 MB, free: 4.383 MB / 350.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.007535/0.383, allocations: 4.771 MB / 439.6 MB, free: 15.6 MB / 366.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002605/0.3857, allocations: 0.7148 MB / 440.3 MB, free: 14.88 MB / 366.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004958/0.3907, allocations: 1.674 MB / 442 MB, free: 13.21 MB / 366.1 MB Notification: Performance of NFScalarize.scalarize: time 0.005466/0.3962, allocations: 4.22 MB / 446.2 MB, free: 8.977 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.008324/0.4045, allocations: 4.483 MB / 450.7 MB, free: 4.469 MB / 366.1 MB Notification: Performance of NFConvertDAE.convert: time 0.0177/0.4223, allocations: 13.69 MB / 464.4 MB, free: 6.738 MB / 382.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.677e-06/0.4223, allocations: 0 / 464.4 MB, free: 6.738 MB / 382.1 MB Notification: Performance of FrontEnd: time 2.334e-06/0.4223, allocations: 0 / 464.4 MB, free: 6.738 MB / 382.1 MB Notification: Performance of Transformations before backend: time 0.0004993/0.4228, allocations: 0 / 464.4 MB, free: 6.738 MB / 382.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2386 * Number of variables: 2386 Notification: Performance of Generate backend data structure: time 0.02542/0.4483, allocations: 9.692 MB / 474.1 MB, free: 12.96 MB / 398.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.771e-05/0.4483, allocations: 8.031 kB / 474.1 MB, free: 12.95 MB / 398.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04504/0.4934, allocations: 5.236 MB / 479.3 MB, free: 7.695 MB / 398.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01902/0.5124, allocations: 8.374 MB / 487.7 MB, free: 15.25 MB / 414.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004111/0.5129, allocations: 431.6 kB / 488.1 MB, free: 14.83 MB / 414.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002392/0.5153, allocations: 0.5814 MB / 488.7 MB, free: 14.25 MB / 414.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.03193/0.5472, allocations: 16.55 MB / 0.4934 GB, free: 12.64 MB / 430.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003319/0.5476, allocations: 103.1 kB / 0.4935 GB, free: 12.54 MB / 430.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001331/0.549, allocations: 275.8 kB / 0.4938 GB, free: 12.27 MB / 430.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004308/0.5533, allocations: 2.756 MB / 0.4964 GB, free: 9.512 MB / 430.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02768/0.581, allocations: 13.63 MB / 0.5098 GB, free: 11.86 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.5149/1.096, allocations: 67.65 MB / 0.5758 GB, free: 105.3 MB / 478.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.02176/1.118, allocations: 7.893 MB / 0.5835 GB, free: 104.4 MB / 478.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.00612/1.124, allocations: 4.512 MB / 0.5879 GB, free: 102.5 MB / 478.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.02089/1.145, allocations: 12.43 MB / 0.6001 GB, free: 96.81 MB / 478.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.063e-05/1.145, allocations: 64.41 kB / 0.6001 GB, free: 96.75 MB / 478.1 MB Notification: Performance of pre-optimization done (n=280): time 5.03e-06/1.145, allocations: 7.578 kB / 0.6001 GB, free: 96.75 MB / 478.1 MB Notification: Performance of matching and sorting (n=340): time 0.1669/1.312, allocations: 49.27 MB / 0.6482 GB, free: 58.54 MB / 478.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001094/1.312, allocations: 398.1 kB / 0.6486 GB, free: 58.07 MB / 478.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01037/1.322, allocations: 7.183 MB / 0.6556 GB, free: 50.9 MB / 478.1 MB Notification: Performance of collectPreVariables (initialization): time 0.001041/1.324, allocations: 77.7 kB / 0.6557 GB, free: 50.82 MB / 478.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.003052/1.327, allocations: 5.031 MB / 0.6606 GB, free: 45.79 MB / 478.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.001777/1.328, allocations: 1.262 MB / 0.6619 GB, free: 44.54 MB / 478.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002863/1.331, allocations: 1.864 MB / 0.6637 GB, free: 42.66 MB / 478.1 MB Notification: Performance of setup shared object (initialization): time 4.718e-05/1.331, allocations: 301.1 kB / 0.664 GB, free: 42.36 MB / 478.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.005599/1.337, allocations: 4.554 MB / 0.6684 GB, free: 37.8 MB / 478.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.008569/1.346, allocations: 9.266 MB / 0.6775 GB, free: 26.32 MB / 478.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.01525/1.361, allocations: 14.55 MB / 0.6917 GB, free: 9.535 MB / 478.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001597/1.361, allocations: 48 kB / 0.6917 GB, free: 9.488 MB / 478.1 MB Notification: Performance of matching and sorting (n=1185) (initialization): time 0.04218/1.403, allocations: 19.76 MB / 0.711 GB, free: 5.676 MB / 494.1 MB Notification: Performance of prepare postOptimizeDAE: time 0.0001241/1.403, allocations: 75.94 kB / 0.7111 GB, free: 5.602 MB / 494.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001937/1.404, allocations: 160 kB / 0.7112 GB, free: 5.445 MB / 494.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.02165/1.425, allocations: 7.04 MB / 0.7181 GB, free: 14.38 MB / 0.4982 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006881/1.432, allocations: 1.93 MB / 0.72 GB, free: 12.45 MB / 0.4982 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.2754/1.708, allocations: 35.31 MB / 0.7545 GB, free: 166.2 MB / 0.5138 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008639/1.716, allocations: 0.5868 MB / 0.7551 GB, free: 166.2 MB / 0.5138 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001955/1.718, allocations: 497.5 kB / 0.7555 GB, free: 166.2 MB / 0.5138 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 733 * Number of states: 0 () * Number of discrete variables: 66 (cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],Cylinder.boxColor[3],Cylinder.boxColor[2],Cylinder.boxColor[1],Mid.color[3],Mid.color[2],Mid.color[1],B1.cylinderColor[3],B1.cylinderColor[2],B1.cylinderColor[1],Crank2.color[3],Crank2.color[2],Crank2.color[1],Crank2.body.sphereColor[3],Crank2.body.sphereColor[2],Crank2.body.sphereColor[1],Crank3.color[3],Crank3.color[2],Crank3.color[1],Crank3.body.sphereColor[3],Crank3.body.sphereColor[2],Crank3.body.sphereColor[1],Crank1.color[3],Crank1.color[2],Crank1.color[1],Crank1.body.sphereColor[3],Crank1.body.sphereColor[2],Crank1.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],B2.cylinderColor[3],B2.cylinderColor[2],B2.cylinderColor[1],Rod.color[3],Rod.color[2],Rod.color[1],Rod.body.sphereColor[3],Rod.body.sphereColor[2],Rod.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 (1049): * Single equations (assignments): 1043 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 3 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(8,93,51.6%), (3,5,88.9%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(2,4)} Notification: Performance of prepare postOptimizeDAE: time 0.007834/1.726, allocations: 3.489 MB / 0.7589 GB, free: 165.8 MB / 0.5138 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001707/1.728, allocations: 0.6351 MB / 0.7596 GB, free: 165.8 MB / 0.5138 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03068/1.759, allocations: 13.33 MB / 0.7726 GB, free: 161.7 MB / 0.5138 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.404e-05/1.759, allocations: 35.91 kB / 0.7726 GB, free: 161.7 MB / 0.5138 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 5.95e-05/1.759, allocations: 20.16 kB / 0.7726 GB, free: 161.7 MB / 0.5138 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.511e-05/1.759, allocations: 20.19 kB / 0.7727 GB, free: 161.7 MB / 0.5138 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04815/1.807, allocations: 26.59 MB / 0.7986 GB, free: 151.1 MB / 0.5138 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.47e-05/1.807, allocations: 8.844 kB / 0.7986 GB, free: 151.1 MB / 0.5138 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001915/1.809, allocations: 419.1 kB / 0.799 GB, free: 151 MB / 0.5138 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.01851/1.828, allocations: 6.652 MB / 0.8055 GB, free: 147.6 MB / 0.5138 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008244/1.829, allocations: 63.09 kB / 0.8056 GB, free: 147.6 MB / 0.5138 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03873/1.867, allocations: 34 MB / 0.8388 GB, free: 113.5 MB / 0.5138 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.277e-06/1.867, allocations: 11.94 kB / 0.8388 GB, free: 113.5 MB / 0.5138 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03213/1.899, allocations: 18.77 MB / 0.8571 GB, free: 94.94 MB / 0.5138 GB Notification: Performance of postOpt removeConstants (simulation): time 0.003851/1.903, allocations: 1.381 MB / 0.8585 GB, free: 93.61 MB / 0.5138 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001859/1.905, allocations: 63.98 kB / 0.8585 GB, free: 93.54 MB / 0.5138 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004279/1.91, allocations: 307.5 kB / 0.8588 GB, free: 93.24 MB / 0.5138 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009679/1.911, allocations: 246.6 kB / 0.8591 GB, free: 93 MB / 0.5138 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002927/1.911, allocations: 87.91 kB / 0.8592 GB, free: 92.92 MB / 0.5138 GB Notification: Performance of sorting global known variables: time 0.00725/1.918, allocations: 4.613 MB / 0.8637 GB, free: 88.34 MB / 0.5138 GB Notification: Performance of sort global known variables: time 4.61e-07/1.918, allocations: 0 / 0.8637 GB, free: 88.34 MB / 0.5138 GB Notification: Performance of remove unused functions: time 0.0105/1.929, allocations: 2.144 MB / 0.8658 GB, free: 86.2 MB / 0.5138 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 28 * 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 (215): * Single equations (assignments): 209 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 3 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(7,87,55.1%), (2,4,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(2,6)} Notification: Performance of Backend phase and start with SimCode phase: time 0.001206/1.93, allocations: 208 kB / 0.866 GB, free: 86 MB / 0.5138 GB Notification: Performance of simCode: created initialization part: time 0.01823/1.948, allocations: 11.62 MB / 0.8773 GB, free: 74.4 MB / 0.5138 GB Notification: Performance of simCode: created event and clocks part: time 1.611e-05/1.948, allocations: 7.938 kB / 0.8773 GB, free: 74.39 MB / 0.5138 GB Notification: Performance of simCode: created simulation system equations: time 0.008816/1.957, allocations: 5.012 MB / 0.8822 GB, free: 69.35 MB / 0.5138 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00797/1.965, allocations: 0.9972 MB / 0.8832 GB, free: 68.41 MB / 0.5138 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03857/2.004, allocations: 20.2 MB / 0.9029 GB, free: 48.16 MB / 0.5138 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008287/2.012, allocations: 5.473 MB / 0.9082 GB, free: 42.65 MB / 0.5138 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001799/2.014, allocations: 451.4 kB / 0.9087 GB, free: 42.21 MB / 0.5138 GB Notification: Performance of SimCode: time 8.42e-07/2.014, allocations: 0 / 0.9087 GB, free: 42.21 MB / 0.5138 GB Notification: Performance of Templates: time 0.5267/2.541, allocations: 207.1 MB / 1.111 GB, free: 9.379 MB / 0.5138 GB make -j1 -f Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.makefile (rm -f Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; mkfifo Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; head -c 1048576 < Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe >> ../files/Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.sim & ./Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe 2>&1) diffSimulationResults("Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1a/Engine1a.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) Reference file matches