Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.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.001433/0.001433, allocations: 83.89 kB / 21.18 MB, free: 3.387 MB / 18.57 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.001591/0.001591, allocations: 145.1 kB / 24.54 MB, free: 32 kB / 18.57 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 0.9102/0.9102, allocations: 173.3 MB / 201.1 MB, free: 9.355 MB / 186.7 MB " [Timeout remaining time 179] 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: translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5028,variableFilter="time|inertia.phi|inertia.w",fileNamePrefix="Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b") translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5028,variableFilter="time|inertia.phi|inertia.w",fileNamePrefix="Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.273e-06/1.273e-06, allocations: 0 / 268 MB, free: 6.414 MB / 250.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 2.125e-05/2.252e-05, allocations: 2.312 kB / 268 MB, free: 6.41 MB / 250.7 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b): time 0.0153/0.01533, allocations: 20.04 MB / 288.1 MB, free: 2.281 MB / 266.7 MB Notification: Performance of NFInst.instExpressions: time 0.1193/0.1346, allocations: 6.105 MB / 294.2 MB, free: 14.25 MB / 266.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003135/0.1378, allocations: 203.3 kB / 294.4 MB, free: 14.25 MB / 266.7 MB Notification: Performance of NFTyping.typeComponents: time 0.008192/0.146, allocations: 6.872 MB / 301.2 MB, free: 13.38 MB / 266.7 MB Notification: Performance of NFTyping.typeBindings: time 0.005984/0.1519, allocations: 3.681 MB / 304.9 MB, free: 11.62 MB / 266.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.00263/0.1546, allocations: 1.909 MB / 306.8 MB, free: 10.43 MB / 266.7 MB Notification: Performance of NFFlatten.flatten: time 0.01084/0.1654, allocations: 14.58 MB / 321.4 MB, free: 1.789 MB / 266.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.008276/0.1737, allocations: 8.095 MB / 329.5 MB, free: 10.06 MB / 282.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.01045/0.1841, allocations: 8.637 MB / 338.1 MB, free: 2.145 MB / 282.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.006621/0.1908, allocations: 5.928 MB / 344.1 MB, free: 12.64 MB / 298.7 MB Notification: Performance of NFPackage.collectConstants: time 0.001464/0.1922, allocations: 0.7852 MB / 344.9 MB, free: 11.86 MB / 298.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002487/0.1947, allocations: 1.704 MB / 346.6 MB, free: 10.19 MB / 298.7 MB Notification: Performance of NFScalarize.scalarize: time 0.003674/0.1984, allocations: 4.83 MB / 351.4 MB, free: 5.504 MB / 298.7 MB Notification: Performance of NFVerifyModel.verify: time 0.003543/0.2019, allocations: 2.952 MB / 354.3 MB, free: 2.68 MB / 298.7 MB Notification: Performance of NFConvertDAE.convert: time 0.008648/0.2106, allocations: 9.377 MB / 363.7 MB, free: 9.488 MB / 314.7 MB Notification: Performance of FrontEnd - DAE generated: time 3.818e-06/0.2106, allocations: 0 / 363.7 MB, free: 9.488 MB / 314.7 MB Notification: Performance of FrontEnd: time 1.283e-06/0.2106, allocations: 4 kB / 363.7 MB, free: 9.484 MB / 314.7 MB Notification: Performance of Transformations before backend: time 0.0003054/0.2109, allocations: 0 / 363.7 MB, free: 9.484 MB / 314.7 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2489 * Number of variables: 2489 Notification: Performance of Generate backend data structure: time 0.0134/0.2243, allocations: 11.13 MB / 374.9 MB, free: 14.27 MB / 330.7 MB Notification: Performance of prepare preOptimizeDAE: time 5.648e-05/0.2243, allocations: 8.922 kB / 374.9 MB, free: 14.26 MB / 330.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02168/0.246, allocations: 6.043 MB / 380.9 MB, free: 8.203 MB / 330.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.009562/0.2556, allocations: 6.316 MB / 387.2 MB, free: 1.812 MB / 330.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002387/0.2558, allocations: 448 kB / 387.7 MB, free: 1.375 MB / 330.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008626/0.2567, allocations: 0.6132 MB / 388.3 MB, free: 0.7617 MB / 330.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.01339/0.2701, allocations: 10.74 MB / 399 MB, free: 4.516 MB / 346.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001509/0.2702, allocations: 83.36 kB / 399.1 MB, free: 4.434 MB / 346.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004369/0.2707, allocations: 279.8 kB / 399.4 MB, free: 4.16 MB / 346.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002453/0.2731, allocations: 2.768 MB / 402.1 MB, free: 1.395 MB / 346.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01048/0.2836, allocations: 5.435 MB / 407.6 MB, free: 11.95 MB / 362.7 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.2702/0.5538, allocations: 72.33 MB / 479.9 MB, free: 60.91 MB / 378.8 MB Notification: Performance of preOpt comSubExp (simulation): time 0.008416/0.5622, allocations: 4.1 MB / 484 MB, free: 60.72 MB / 378.8 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.001563/0.5638, allocations: 1.129 MB / 485.1 MB, free: 60.66 MB / 378.8 MB Notification: Performance of preOpt evalFunc (simulation): time 0.009215/0.573, allocations: 7.64 MB / 492.8 MB, free: 58.96 MB / 378.8 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.615e-05/0.573, allocations: 68.98 kB / 492.8 MB, free: 58.9 MB / 378.8 MB Notification: Performance of pre-optimization done (n=261): time 4.157e-06/0.573, allocations: 0 / 492.8 MB, free: 58.9 MB / 378.8 MB Notification: Performance of matching and sorting (n=317): time 0.05008/0.6231, allocations: 25.75 MB / 0.5064 GB, free: 42.33 MB / 378.8 MB Notification: Performance of inlineWhenForInitialization (initialization): time 6.496e-05/0.6232, allocations: 401.1 kB / 0.5068 GB, free: 41.85 MB / 378.8 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004657/0.6278, allocations: 6.362 MB / 0.513 GB, free: 35.73 MB / 378.8 MB Notification: Performance of collectPreVariables (initialization): time 0.0001608/0.628, allocations: 73.7 kB / 0.5131 GB, free: 35.65 MB / 378.8 MB Notification: Performance of collectInitialEqns (initialization): time 0.001865/0.6299, allocations: 5.761 MB / 0.5187 GB, free: 30.49 MB / 378.8 MB Notification: Performance of collectInitialBindings (initialization): time 0.0005705/0.6304, allocations: 1.274 MB / 0.52 GB, free: 29.23 MB / 378.8 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001359/0.6318, allocations: 1.975 MB / 0.5219 GB, free: 27.24 MB / 378.8 MB Notification: Performance of setup shared object (initialization): time 3.526e-05/0.6318, allocations: 480.8 kB / 0.5224 GB, free: 26.77 MB / 378.8 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.001387/0.6332, allocations: 1.08 MB / 0.5234 GB, free: 25.68 MB / 378.8 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.003024/0.6362, allocations: 8.814 MB / 0.532 GB, free: 13.47 MB / 378.8 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.00565/0.6419, allocations: 10.6 MB / 0.5424 GB, free: 15.8 MB / 394.8 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.418e-05/0.642, allocations: 48 kB / 0.5424 GB, free: 15.75 MB / 394.8 MB Notification: Performance of matching and sorting (n=1209) (initialization): time 0.01168/0.6536, allocations: 8.703 MB / 0.5509 GB, free: 7 MB / 394.8 MB Notification: Performance of prepare postOptimizeDAE: time 7.376e-05/0.6537, allocations: 84 kB / 0.551 GB, free: 6.918 MB / 394.8 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003298/0.654, allocations: 172 kB / 0.5512 GB, free: 6.75 MB / 394.8 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.003372/0.6574, allocations: 1.702 MB / 0.5528 GB, free: 5.039 MB / 394.8 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003454/0.6609, allocations: 2.117 MB / 0.5549 GB, free: 2.918 MB / 394.8 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007389/0.6683, allocations: 7.461 MB / 0.5622 GB, free: 11.21 MB / 410.8 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004271/0.6725, allocations: 0.6164 MB / 0.5628 GB, free: 10.6 MB / 410.8 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005098/0.673, allocations: 0.5117 MB / 0.5633 GB, free: 10.09 MB / 410.8 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 802 * Number of states: 0 () * Number of discrete variables: 72 (rod3.color[3],rod3.color[2],rod3.color[1],rod1.color[3],rod1.color[2],rod1.color[1],cylinder.boxColor[3],cylinder.boxColor[2],cylinder.boxColor[1],b2.cylinderColor[3],b2.cylinderColor[2],b2.cylinderColor[1],b1.cylinderColor[3],b1.cylinderColor[2],b1.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],mid.color[3],mid.color[2],mid.color[1],crank4.color[3],crank4.color[2],crank4.color[1],crank4.body.sphereColor[3],crank4.body.sphereColor[2],crank4.body.sphereColor[1],crank3.color[3],crank3.color[2],crank3.color[1],crank3.body.sphereColor[3],crank3.body.sphereColor[2],crank3.body.sphereColor[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],bearing.cylinderColor[3],bearing.cylinderColor[2],bearing.cylinderColor[1],connectingRod.color[3],connectingRod.color[2],connectingRod.color[1],connectingRod.body.sphereColor[3],connectingRod.body.sphereColor[2],connectingRod.body.sphereColor[1],piston.color[3],piston.color[2],piston.color[1],piston.body.sphereColor[3],piston.body.sphereColor[2],piston.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]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (1133): * Single equations (assignments): 1126 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 3 * 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): 2 systems {(2,100.0%), (2,100.0%)} * 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 {(5,46,68.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.001967/0.675, allocations: 0.8983 MB / 0.5642 GB, free: 9.172 MB / 410.8 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008885/0.6759, allocations: 0.7301 MB / 0.5649 GB, free: 8.438 MB / 410.8 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004923/0.6808, allocations: 3.138 MB / 0.5679 GB, free: 5.227 MB / 410.8 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.573e-05/0.6808, allocations: 32 kB / 0.568 GB, free: 5.195 MB / 410.8 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.384e-05/0.6809, allocations: 15.94 kB / 0.568 GB, free: 5.18 MB / 410.8 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.863e-05/0.6809, allocations: 19.94 kB / 0.568 GB, free: 5.16 MB / 410.8 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.1424/0.8233, allocations: 11.81 MB / 0.5795 GB, free: 125.1 MB / 410.8 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.625e-05/0.8233, allocations: 18.78 kB / 0.5796 GB, free: 125.1 MB / 410.8 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00131/0.8246, allocations: 0.5352 MB / 0.5801 GB, free: 125.1 MB / 410.8 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.003249/0.8278, allocations: 1.437 MB / 0.5815 GB, free: 125 MB / 410.8 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002076/0.8281, allocations: 80.17 kB / 0.5816 GB, free: 125 MB / 410.8 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005773/0.8338, allocations: 6.913 MB / 0.5883 GB, free: 121.2 MB / 410.8 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.038e-05/0.8338, allocations: 32.22 kB / 0.5883 GB, free: 121.2 MB / 410.8 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006312/0.8402, allocations: 5.375 MB / 0.5936 GB, free: 119.1 MB / 410.8 MB Notification: Performance of postOpt removeConstants (simulation): time 0.001675/0.8418, allocations: 1.034 MB / 0.5946 GB, free: 119.1 MB / 410.8 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000564/0.8424, allocations: 77.75 kB / 0.5947 GB, free: 119.1 MB / 410.8 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001505/0.8439, allocations: 285.7 kB / 0.5949 GB, free: 119.1 MB / 410.8 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003782/0.8443, allocations: 283.9 kB / 0.5952 GB, free: 119.1 MB / 410.8 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001343/0.8444, allocations: 107.3 kB / 0.5953 GB, free: 119.1 MB / 410.8 MB Notification: Performance of sorting global known variables: time 0.003114/0.8475, allocations: 2.954 MB / 0.5982 GB, free: 118.8 MB / 410.8 MB Notification: Performance of sort global known variables: time 1.6e-07/0.8475, allocations: 0 / 0.5982 GB, free: 118.8 MB / 410.8 MB Notification: Performance of remove unused functions: time 0.004952/0.8525, allocations: 2.109 MB / 0.6003 GB, free: 118.8 MB / 410.8 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 90 * 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 (261): * Single equations (assignments): 254 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 3 * 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): 2 systems {(2,100.0%), (2,100.0%)} * 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 {(5,42,68.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0004331/0.8529, allocations: 272.2 kB / 0.6005 GB, free: 118.7 MB / 410.8 MB Notification: Performance of simCode: created initialization part: time 0.005674/0.8586, allocations: 5.081 MB / 0.6055 GB, free: 117.9 MB / 410.8 MB Notification: Performance of simCode: created event and clocks part: time 8.215e-06/0.8586, allocations: 3.75 kB / 0.6055 GB, free: 117.9 MB / 410.8 MB Notification: Performance of simCode: created simulation system equations: time 0.001849/0.8604, allocations: 1.763 MB / 0.6072 GB, free: 117.3 MB / 410.8 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002694/0.8631, allocations: 0.9967 MB / 0.6082 GB, free: 116.6 MB / 410.8 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01341/0.8765, allocations: 15.04 MB / 0.6229 GB, free: 102.4 MB / 410.8 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001533/0.8781, allocations: 2.387 MB / 0.6252 GB, free: 100.7 MB / 410.8 MB Notification: Performance of simCode: alias equations: time 0.002883/0.881, allocations: 2.285 MB / 0.6274 GB, free: 98.93 MB / 410.8 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000662/0.8816, allocations: 420.4 kB / 0.6278 GB, free: 98.52 MB / 410.8 MB Notification: Performance of SimCode: time 2.033e-06/0.8816, allocations: 0 / 0.6278 GB, free: 98.52 MB / 410.8 MB Notification: Performance of Templates: time 0.07716/0.9588, allocations: 69.95 MB / 0.6961 GB, free: 29.61 MB / 410.8 MB " [Timeout remaining time 659] make -j1 -f Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.makefile [Timeout 660] (rm -f Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; mkfifo Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe >> ../files/Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.sim & ./Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b -abortSlowSimulation -alarm=240 -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe 2>&1) [Timeout 240] diffSimulationResults("Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1b/Engine1b.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660] "" [Timeout remaining time 660] Reference file matches [Calling sys.exit(0), Time elapsed: 5.013985306955874]