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.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.0009765/0.0009765, allocations: 79.73 kB / 21.97 MB, free: 2.598 MB / 18.57 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.00114/0.00114, allocations: 165.5 kB / 25.33 MB, free: 8.594 MB / 18.57 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 0.8968/0.8968, allocations: 158.6 MB / 187.2 MB, free: 2.93 MB / 170.7 MB " [Timeout remaining time 179] 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.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.Engine1a,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,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=5002,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.272e-06/1.272e-06, allocations: 0 / 251 MB, free: 7.512 MB / 234.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.7e-05/1.827e-05, allocations: 6.25 kB / 251.1 MB, free: 7.504 MB / 234.7 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a): time 0.01394/0.01396, allocations: 18.91 MB / 270 MB, free: 4.508 MB / 250.7 MB Notification: Performance of NFInst.instExpressions: time 0.009394/0.02336, allocations: 5.928 MB / 275.9 MB, free: 14.56 MB / 266.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.001593/0.02495, allocations: 194.9 kB / 276.1 MB, free: 14.37 MB / 266.7 MB Notification: Performance of NFTyping.typeComponents: time 0.005884/0.03083, allocations: 6.324 MB / 282.4 MB, free: 8.027 MB / 266.7 MB Notification: Performance of NFTyping.typeBindings: time 0.005415/0.03625, allocations: 3.592 MB / 286 MB, free: 4.418 MB / 266.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.002151/0.0384, allocations: 1.821 MB / 287.8 MB, free: 2.594 MB / 266.7 MB Notification: Performance of NFFlatten.flatten: time 0.01055/0.04895, allocations: 13.27 MB / 301.1 MB, free: 5.297 MB / 282.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.00781/0.05676, allocations: 7.755 MB / 308.9 MB, free: 13.45 MB / 298.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.007732/0.06449, allocations: 7.346 MB / 316.2 MB, free: 6.094 MB / 298.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005741/0.07023, allocations: 5.749 MB / 321.9 MB, free: 340 kB / 298.7 MB Notification: Performance of NFPackage.collectConstants: time 0.002729/0.07296, allocations: 0.754 MB / 322.7 MB, free: 15.57 MB / 314.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004463/0.07742, allocations: 1.682 MB / 324.4 MB, free: 13.89 MB / 314.7 MB Notification: Performance of NFScalarize.scalarize: time 0.005226/0.08265, allocations: 4.641 MB / 329 MB, free: 9.242 MB / 314.7 MB Notification: Performance of NFVerifyModel.verify: time 0.005565/0.08822, allocations: 2.844 MB / 331.9 MB, free: 6.391 MB / 314.7 MB Notification: Performance of NFConvertDAE.convert: time 0.01225/0.1005, allocations: 9.056 MB / 340.9 MB, free: 13.3 MB / 330.7 MB Notification: Performance of FrontEnd - DAE generated: time 7.554e-06/0.1005, allocations: 0 / 340.9 MB, free: 13.3 MB / 330.7 MB Notification: Performance of FrontEnd: time 1.553e-06/0.1005, allocations: 0 / 340.9 MB, free: 13.3 MB / 330.7 MB Notification: Performance of Transformations before backend: time 0.0003963/0.1009, allocations: 0 / 340.9 MB, free: 13.3 MB / 330.7 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.01932/0.1202, allocations: 10.76 MB / 351.7 MB, free: 2.449 MB / 330.7 MB Notification: Performance of prepare preOptimizeDAE: time 4.731e-05/0.1202, allocations: 11.98 kB / 351.7 MB, free: 2.438 MB / 330.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.2373/0.3575, allocations: 5.982 MB / 357.7 MB, free: 70.48 MB / 330.8 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.009929/0.3675, allocations: 6.268 MB / 363.9 MB, free: 69.98 MB / 330.8 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002866/0.3677, allocations: 431.7 kB / 364.4 MB, free: 69.97 MB / 330.8 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001232/0.369, allocations: 0.581 MB / 364.9 MB, free: 69.95 MB / 330.8 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.01623/0.3852, allocations: 10.34 MB / 375.3 MB, free: 65.43 MB / 330.8 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0002815/0.3855, allocations: 93.23 kB / 375.4 MB, free: 65.41 MB / 330.8 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005886/0.3861, allocations: 270 kB / 375.6 MB, free: 65.41 MB / 330.8 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002279/0.3883, allocations: 2.665 MB / 378.3 MB, free: 64.04 MB / 330.8 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01466/0.403, allocations: 5.257 MB / 383.6 MB, free: 60 MB / 330.8 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.05796/0.461, allocations: 70.78 MB / 454.3 MB, free: 4.762 MB / 346.8 MB Notification: Performance of preOpt comSubExp (simulation): time 0.009671/0.4706, allocations: 3.841 MB / 458.2 MB, free: 0.8789 MB / 346.8 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.002093/0.4727, allocations: 1.065 MB / 459.3 MB, free: 15.8 MB / 362.8 MB Notification: Performance of preOpt evalFunc (simulation): time 0.01315/0.4859, allocations: 7.482 MB / 466.7 MB, free: 8.543 MB / 362.8 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.556e-05/0.486, allocations: 70.77 kB / 466.8 MB, free: 8.469 MB / 362.8 MB Notification: Performance of pre-optimization done (n=248): time 5.851e-06/0.486, allocations: 0 / 466.8 MB, free: 8.469 MB / 362.8 MB Notification: Performance of matching and sorting (n=298): time 0.06257/0.5485, allocations: 24.77 MB / 491.6 MB, free: 244 kB / 378.8 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001649/0.5487, allocations: 392.5 kB / 492 MB, free: 15.77 MB / 394.8 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009661/0.5584, allocations: 6.304 MB / 498.3 MB, free: 9.492 MB / 394.8 MB Notification: Performance of collectPreVariables (initialization): time 0.0006108/0.559, allocations: 73.7 kB / 498.3 MB, free: 9.414 MB / 394.8 MB Notification: Performance of collectInitialEqns (initialization): time 0.003769/0.5627, allocations: 5.578 MB / 0.4921 GB, free: 3.883 MB / 394.8 MB Notification: Performance of collectInitialBindings (initialization): time 0.001507/0.5642, allocations: 1.204 MB / 0.4933 GB, free: 2.695 MB / 394.8 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002561/0.5668, allocations: 1.939 MB / 0.4952 GB, free: 0.7383 MB / 394.8 MB Notification: Performance of setup shared object (initialization): time 0.0001069/0.5669, allocations: 480.8 kB / 0.4956 GB, free: 272 kB / 394.8 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.1534/0.7203, allocations: 1.03 MB / 0.4966 GB, free: 137.5 MB / 394.8 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.00532/0.7256, allocations: 8.679 MB / 0.5051 GB, free: 127.7 MB / 394.8 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.008224/0.7338, allocations: 10.41 MB / 0.5153 GB, free: 117.9 MB / 394.8 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002361/0.7341, allocations: 51.47 kB / 0.5153 GB, free: 117.9 MB / 394.8 MB Notification: Performance of matching and sorting (n=1180) (initialization): time 0.01735/0.7514, allocations: 8.484 MB / 0.5236 GB, free: 116.4 MB / 394.8 MB Notification: Performance of prepare postOptimizeDAE: time 0.000142/0.7516, allocations: 82.75 kB / 0.5237 GB, free: 116.4 MB / 394.8 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002218/0.7518, allocations: 178.4 kB / 0.5239 GB, free: 116.4 MB / 394.8 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.003807/0.7556, allocations: 1.635 MB / 0.5255 GB, free: 116.3 MB / 394.8 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004307/0.7599, allocations: 2.069 MB / 0.5275 GB, free: 116.1 MB / 394.8 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007033/0.7669, allocations: 7.228 MB / 0.5345 GB, free: 110.8 MB / 394.8 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006156/0.7731, allocations: 0.6534 MB / 0.5352 GB, free: 110.5 MB / 394.8 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001223/0.7743, allocations: 0.4993 MB / 0.5357 GB, free: 110 MB / 394.8 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 792 * 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 (1106): * Single equations (assignments): 1099 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 2 * Torn equation systems: 2 * 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): 0 systems * 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,76.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,2)} Notification: Performance of prepare postOptimizeDAE: time 0.00306/0.7774, allocations: 0.885 MB / 0.5365 GB, free: 109.5 MB / 394.8 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001357/0.7787, allocations: 0.7061 MB / 0.5372 GB, free: 109.2 MB / 394.8 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004415/0.7832, allocations: 2.985 MB / 0.5401 GB, free: 107.5 MB / 394.8 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.867e-05/0.7832, allocations: 32 kB / 0.5402 GB, free: 107.5 MB / 394.8 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.552e-05/0.7832, allocations: 11 kB / 0.5402 GB, free: 107.5 MB / 394.8 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.883e-05/0.7832, allocations: 17.97 kB / 0.5402 GB, free: 107.5 MB / 394.8 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01801/0.8012, allocations: 11.41 MB / 0.5513 GB, free: 98.5 MB / 394.8 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.334e-05/0.8013, allocations: 20 kB / 0.5513 GB, free: 98.48 MB / 394.8 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001046/0.8023, allocations: 0.4966 MB / 0.5518 GB, free: 98.07 MB / 394.8 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.002871/0.8052, allocations: 1.37 MB / 0.5532 GB, free: 96.72 MB / 394.8 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001838/0.8054, allocations: 72 kB / 0.5532 GB, free: 96.65 MB / 394.8 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00521/0.8106, allocations: 6.227 MB / 0.5593 GB, free: 90.28 MB / 394.8 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.105e-05/0.8106, allocations: 35.81 kB / 0.5594 GB, free: 90.24 MB / 394.8 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00695/0.8175, allocations: 5.152 MB / 0.5644 GB, free: 84.91 MB / 394.8 MB Notification: Performance of postOpt removeConstants (simulation): time 0.002524/0.82, allocations: 1.041 MB / 0.5654 GB, free: 83.87 MB / 394.8 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001252/0.8213, allocations: 82.11 kB / 0.5655 GB, free: 83.79 MB / 394.8 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002136/0.8234, allocations: 307.5 kB / 0.5658 GB, free: 83.49 MB / 394.8 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004966/0.8239, allocations: 257.1 kB / 0.566 GB, free: 83.24 MB / 394.8 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000142/0.8241, allocations: 112 kB / 0.5661 GB, free: 83.13 MB / 394.8 MB Notification: Performance of sorting global known variables: time 0.004181/0.8283, allocations: 2.959 MB / 0.569 GB, free: 80.19 MB / 394.8 MB Notification: Performance of sort global known variables: time 9e-08/0.8283, allocations: 0 / 0.569 GB, free: 80.19 MB / 394.8 MB Notification: Performance of remove unused functions: time 0.007651/0.8359, allocations: 2.065 MB / 0.571 GB, free: 78.13 MB / 394.8 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 88 * 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 (245): * Single equations (assignments): 238 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 2 * Torn equation systems: 2 * 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): 0 systems * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(6,37,61.1%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,2)} Notification: Performance of Backend phase and start with SimCode phase: time 0.0007215/0.8366, allocations: 257.1 kB / 0.5713 GB, free: 77.89 MB / 394.8 MB Notification: Performance of simCode: created initialization part: time 0.007526/0.8442, allocations: 5.064 MB / 0.5762 GB, free: 72.79 MB / 394.8 MB Notification: Performance of simCode: created event and clocks part: time 1.674e-05/0.8442, allocations: 4 kB / 0.5762 GB, free: 72.79 MB / 394.8 MB Notification: Performance of simCode: created simulation system equations: time 0.002362/0.8465, allocations: 1.853 MB / 0.578 GB, free: 70.89 MB / 394.8 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004679/0.8512, allocations: 0.9709 MB / 0.579 GB, free: 69.94 MB / 394.8 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02048/0.8717, allocations: 14.44 MB / 0.5931 GB, free: 55.05 MB / 394.8 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002759/0.8745, allocations: 2.313 MB / 0.5953 GB, free: 52.71 MB / 394.8 MB Notification: Performance of simCode: alias equations: time 0.00439/0.8788, allocations: 2.218 MB / 0.5975 GB, free: 50.48 MB / 394.8 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001337/0.8802, allocations: 400.4 kB / 0.5979 GB, free: 50.09 MB / 394.8 MB Notification: Performance of SimCode: time 2.865e-06/0.8802, allocations: 0 / 0.5979 GB, free: 50.09 MB / 394.8 MB Notification: Performance of Templates: time 0.2267/1.107, allocations: 68.19 MB / 0.6645 GB, free: 110.2 MB / 394.8 MB " [Timeout remaining time 659] make -j1 -f Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.makefile [Timeout 660] (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=240 -emit_protected -lv LOG_STATS > Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe 2>&1) [Timeout 240] diffSimulationResults("Modelica_3.2.3_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1a/Engine1a.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.023203038144857]