Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.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.001926/0.001926, allocations: 111.1 kB / 20.44 MB, free: 1.062 MB / 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.002356/0.002356, allocations: 214.3 kB / 23.73 MB, free: 4.16 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.471/1.471, allocations: 230.6 MB / 257.5 MB, free: 7.816 MB / 206.1 MB " [Timeout remaining time 178] 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(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5028,variableFilter="time|inertia.phi|inertia.w",fileNamePrefix="Modelica_4.1.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.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 0.001613/0.001613, allocations: 51.92 kB / 378.3 MB, free: 2.453 MB / 302.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.256/0.2576, allocations: 53.36 MB / 431.6 MB, free: 6.746 MB / 334.1 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b): time 0.02139/0.279, allocations: 17.63 MB / 449.2 MB, free: 5.043 MB / 350.1 MB Notification: Performance of NFInst.instExpressions: time 0.01376/0.2927, allocations: 5.997 MB / 455.2 MB, free: 15.03 MB / 366.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.004033/0.2968, allocations: 202.9 kB / 455.4 MB, free: 14.83 MB / 366.1 MB Notification: Performance of NFTyping.typeComponents: time 0.01382/0.3106, allocations: 6.164 MB / 461.6 MB, free: 8.645 MB / 366.1 MB Notification: Performance of NFTyping.typeBindings: time 0.01116/0.3217, allocations: 3.517 MB / 465.1 MB, free: 5.113 MB / 366.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.004594/0.3263, allocations: 1.666 MB / 466.8 MB, free: 3.445 MB / 366.1 MB Notification: Performance of NFFlatten.flatten: time 0.01966/0.346, allocations: 12.99 MB / 479.8 MB, free: 6.438 MB / 382.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.009618/0.3556, allocations: 6.263 MB / 486 MB, free: 88 kB / 382.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.007823/0.3634, allocations: 3.817 MB / 489.8 MB, free: 12.26 MB / 398.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.00923/0.3727, allocations: 5.047 MB / 494.9 MB, free: 7.195 MB / 398.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002873/0.3755, allocations: 0.7461 MB / 495.6 MB, free: 6.449 MB / 398.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.00497/0.3805, allocations: 1.596 MB / 497.2 MB, free: 4.852 MB / 398.1 MB Notification: Performance of NFScalarize.scalarize: time 0.006756/0.3873, allocations: 4.496 MB / 0.49 GB, free: 352 kB / 398.1 MB Notification: Performance of NFVerifyModel.verify: time 0.00803/0.3953, allocations: 3.543 MB / 0.4934 GB, free: 12.78 MB / 414.1 MB Notification: Performance of NFConvertDAE.convert: time 0.02015/0.4154, allocations: 14.25 MB / 0.5073 GB, free: 14.48 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 1.161e-05/0.4155, allocations: 3.984 kB / 0.5074 GB, free: 14.47 MB / 430.1 MB Notification: Performance of FrontEnd: time 2.936e-06/0.4155, allocations: 3.938 kB / 0.5074 GB, free: 14.47 MB / 430.1 MB Notification: Performance of Transformations before backend: time 0.0005931/0.4161, allocations: 4 kB / 0.5074 GB, free: 14.46 MB / 430.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2488 * Number of variables: 2488 Notification: Performance of Generate backend data structure: time 0.02923/0.4453, allocations: 10.19 MB / 0.5173 GB, free: 4.195 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.873e-05/0.4453, allocations: 12.03 kB / 0.5173 GB, free: 4.184 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04754/0.4929, allocations: 5.332 MB / 0.5225 GB, free: 14.84 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.02219/0.5151, allocations: 8.023 MB / 0.5304 GB, free: 6.727 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004559/0.5155, allocations: 444 kB / 0.5308 GB, free: 6.293 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002653/0.5182, allocations: 0.6167 MB / 0.5314 GB, free: 5.676 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.4038/0.922, allocations: 17.42 MB / 0.5484 GB, free: 88.44 MB / 446.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003392/0.9223, allocations: 106.5 kB / 0.5485 GB, free: 88.4 MB / 446.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001169/0.9235, allocations: 279.7 kB / 0.5488 GB, free: 88.26 MB / 446.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003495/0.927, allocations: 2.851 MB / 0.5516 GB, free: 87.73 MB / 446.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02582/0.9528, allocations: 13.99 MB / 0.5652 GB, free: 75.54 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.07749/1.03, allocations: 63.2 MB / 0.6269 GB, free: 11.93 MB / 446.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.0188/1.049, allocations: 7.509 MB / 0.6343 GB, free: 4.43 MB / 446.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.004997/1.054, allocations: 4.365 MB / 0.6385 GB, free: 56 kB / 446.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.02548/1.08, allocations: 11.03 MB / 0.6493 GB, free: 5.109 MB / 462.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.402e-05/1.08, allocations: 72.5 kB / 0.6494 GB, free: 5.031 MB / 462.1 MB Notification: Performance of pre-optimization done (n=293): time 6.071e-06/1.08, allocations: 0 / 0.6494 GB, free: 5.031 MB / 462.1 MB Notification: Performance of matching and sorting (n=359): time 0.2089/1.289, allocations: 52.78 MB / 0.7009 GB, free: 0.7891 MB / 0.4982 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002667/1.289, allocations: 402.2 kB / 0.7013 GB, free: 316 kB / 0.4982 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01384/1.303, allocations: 6.948 MB / 0.7081 GB, free: 9.566 MB / 0.5138 GB Notification: Performance of collectPreVariables (initialization): time 0.001146/1.304, allocations: 81.7 kB / 0.7082 GB, free: 9.48 MB / 0.5138 GB Notification: Performance of collectInitialEqns (initialization): time 0.005523/1.309, allocations: 5.422 MB / 0.7135 GB, free: 4.156 MB / 0.5138 GB Notification: Performance of collectInitialBindings (initialization): time 0.002306/1.312, allocations: 1.387 MB / 0.7148 GB, free: 2.758 MB / 0.5138 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.003379/1.315, allocations: 1.925 MB / 0.7167 GB, free: 0.8203 MB / 0.5138 GB Notification: Performance of setup shared object (initialization): time 0.0001324/1.315, allocations: 305.1 kB / 0.717 GB, free: 0.5195 MB / 0.5138 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.007582/1.323, allocations: 4.711 MB / 0.7216 GB, free: 11.8 MB / 0.5294 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01238/1.335, allocations: 9.76 MB / 0.7311 GB, free: 15.64 MB / 0.545 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.2721/1.607, allocations: 15 MB / 0.7458 GB, free: 178.9 MB / 0.545 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002216/1.607, allocations: 46.88 kB / 0.7458 GB, free: 178.9 MB / 0.545 GB Notification: Performance of matching and sorting (n=1220) (initialization): time 0.03954/1.647, allocations: 20.36 MB / 0.7657 GB, free: 173.9 MB / 0.545 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001034/1.647, allocations: 77 kB / 0.7658 GB, free: 173.9 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001769/1.647, allocations: 163.4 kB / 0.7659 GB, free: 173.9 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02009/1.667, allocations: 7.32 MB / 0.7731 GB, free: 172.6 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006106/1.673, allocations: 2 MB / 0.775 GB, free: 172.4 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04466/1.718, allocations: 35.83 MB / 0.81 GB, free: 138.9 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008763/1.727, allocations: 0.6068 MB / 0.8106 GB, free: 138.3 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002022/1.729, allocations: 0.4961 MB / 0.8111 GB, free: 137.8 MB / 0.545 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 749 * 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 (1081): * Single equations (assignments): 1075 * 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,96,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.008003/1.737, allocations: 3.6 MB / 0.8146 GB, free: 134.3 MB / 0.545 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003161/1.74, allocations: 0.6385 MB / 0.8152 GB, free: 134 MB / 0.545 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03028/1.77, allocations: 13.8 MB / 0.8287 GB, free: 120.2 MB / 0.545 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.174e-05/1.77, allocations: 41.73 kB / 0.8288 GB, free: 120.1 MB / 0.545 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 7.297e-05/1.77, allocations: 23.94 kB / 0.8288 GB, free: 120.1 MB / 0.545 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.486e-05/1.771, allocations: 19.94 kB / 0.8288 GB, free: 120.1 MB / 0.545 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05532/1.826, allocations: 25.21 MB / 0.8534 GB, free: 94.61 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.606e-05/1.826, allocations: 4 kB / 0.8534 GB, free: 94.61 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002391/1.828, allocations: 468 kB / 0.8539 GB, free: 94.15 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.02034/1.849, allocations: 6.96 MB / 0.8607 GB, free: 87.17 MB / 0.545 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001126/1.85, allocations: 64 kB / 0.8607 GB, free: 87.11 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04412/1.894, allocations: 34.62 MB / 0.8945 GB, free: 52.42 MB / 0.545 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.162e-06/1.894, allocations: 4 kB / 0.8945 GB, free: 52.41 MB / 0.545 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03627/1.93, allocations: 19.6 MB / 0.9137 GB, free: 32.84 MB / 0.545 GB Notification: Performance of postOpt removeConstants (simulation): time 0.002077/1.932, allocations: 0.6131 MB / 0.9143 GB, free: 32.23 MB / 0.545 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00231/1.935, allocations: 67.98 kB / 0.9144 GB, free: 32.16 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005351/1.94, allocations: 315.5 kB / 0.9147 GB, free: 31.85 MB / 0.545 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001672/1.942, allocations: 270.2 kB / 0.9149 GB, free: 31.59 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008452/1.942, allocations: 95.98 kB / 0.915 GB, free: 31.5 MB / 0.545 GB Notification: Performance of sorting global known variables: time 0.007764/1.95, allocations: 4.22 MB / 0.9191 GB, free: 27.29 MB / 0.545 GB Notification: Performance of sort global known variables: time 2.11e-07/1.95, allocations: 3.719 kB / 0.9191 GB, free: 27.29 MB / 0.545 GB Notification: Performance of remove unused functions: time 0.01119/1.961, allocations: 2.198 MB / 0.9213 GB, free: 25.1 MB / 0.545 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 26 * 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 (231): * Single equations (assignments): 225 * 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,90,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.001282/1.963, allocations: 211.5 kB / 0.9215 GB, free: 24.9 MB / 0.545 GB Notification: Performance of simCode: created initialization part: time 0.01923/1.982, allocations: 11.51 MB / 0.9327 GB, free: 13.35 MB / 0.545 GB Notification: Performance of simCode: created event and clocks part: time 1.673e-05/1.982, allocations: 0 / 0.9327 GB, free: 13.35 MB / 0.545 GB Notification: Performance of simCode: created simulation system equations: time 0.009335/1.991, allocations: 4.92 MB / 0.9375 GB, free: 8.387 MB / 0.545 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0078/1.999, allocations: 0.9235 MB / 0.9384 GB, free: 7.469 MB / 0.545 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2691/2.268, allocations: 20.69 MB / 0.9586 GB, free: 188.1 MB / 0.5607 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007401/2.276, allocations: 5.269 MB / 0.9638 GB, free: 187.6 MB / 0.5607 GB Notification: Performance of simCode: alias equations: time 0.005594/2.281, allocations: 2.637 MB / 0.9663 GB, free: 187.6 MB / 0.5607 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001136/2.282, allocations: 375.5 kB / 0.9667 GB, free: 187.6 MB / 0.5607 GB Notification: Performance of SimCode: time 1.162e-06/2.282, allocations: 0 / 0.9667 GB, free: 187.6 MB / 0.5607 GB Notification: Performance of Templates: time 0.14/2.422, allocations: 104.3 MB / 1.069 GB, free: 96.06 MB / 0.5607 GB " [Timeout remaining time 658] make -j1 -f Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.makefile [Timeout 660] (rm -f Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; mkfifo Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; head -c 1048576 < Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe >> ../files/Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.sim & ./Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe 2>&1) [Timeout 480] diffSimulationResults("Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1b/Engine1b.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.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: 9.48866829648614]