Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001422/0.001422, allocations: 82.23 kB / 21.25 MB, free: 0.5391 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001501/0.001501, allocations: 160 kB / 24.5 MB, free: 3.719 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.417/1.417, allocations: 177.1 MB / 204.7 MB, free: 8.965 MB / 190.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/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_trunk_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_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001975/0.001975, allocations: 64.98 kB / 325.5 MB, free: 208 kB / 286.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08902/0.09099, allocations: 53.38 MB / 378.9 MB, free: 15.48 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a): time 0.01995/0.1109, allocations: 16.74 MB / 395.7 MB, free: 14.66 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01252/0.1235, allocations: 5.79 MB / 401.5 MB, free: 8.852 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00317/0.1266, allocations: 194.9 kB / 401.6 MB, free: 8.66 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01137/0.138, allocations: 5.674 MB / 407.3 MB, free: 2.969 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.008609/0.1466, allocations: 3.42 MB / 410.7 MB, free: 15.54 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003319/0.1499, allocations: 1.568 MB / 412.3 MB, free: 13.96 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01823/0.1682, allocations: 12.72 MB / 425 MB, free: 1.223 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.009027/0.1772, allocations: 5.904 MB / 430.9 MB, free: 11.23 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006139/0.1833, allocations: 3.634 MB / 434.6 MB, free: 7.586 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.007676/0.191, allocations: 4.837 MB / 439.4 MB, free: 2.734 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001987/0.193, allocations: 0.7148 MB / 440.1 MB, free: 2.02 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00391/0.1969, allocations: 1.573 MB / 441.7 MB, free: 456 kB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00544/0.2023, allocations: 4.297 MB / 446 MB, free: 12.13 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.006056/0.2084, allocations: 3.403 MB / 449.4 MB, free: 8.711 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0162/0.2246, allocations: 13.73 MB / 463.1 MB, free: 10.93 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.661e-06/0.2246, allocations: 0 / 463.1 MB, free: 10.93 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.673e-06/0.2246, allocations: 0 / 463.1 MB, free: 10.93 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0002927/0.2249, allocations: 3.938 kB / 463.1 MB, free: 10.92 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: 2386
 * Number of variables: 2386
Notification: Performance of Generate backend data structure: time 0.02071/0.2456, allocations: 9.835 MB / 473 MB, free: 1.004 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.891e-05/0.2456, allocations: 15.98 kB / 473 MB, free: 0.9883 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.3691/0.6147, allocations: 5.507 MB / 478.5 MB, free: 79.42 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01804/0.6327, allocations: 7.848 MB / 486.3 MB, free: 77.03 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003339/0.6331, allocations: 426.5 kB / 486.7 MB, free: 76.88 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001597/0.6347, allocations: 0.5836 MB / 487.3 MB, free: 76.71 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02102/0.6557, allocations: 16.82 MB / 0.4923 GB, free: 60.82 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001957/0.6559, allocations: 105.6 kB / 0.4924 GB, free: 60.72 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005637/0.6565, allocations: 265.2 kB / 0.4927 GB, free: 60.51 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003344/0.6598, allocations: 2.765 MB / 0.4954 GB, free: 57.75 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02131/0.6811, allocations: 13.62 MB / 0.5087 GB, free: 44.14 MB / 430.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.06887/0.75, allocations: 61.61 MB / 0.5689 GB, free: 13.49 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02114/0.7711, allocations: 7.456 MB / 0.5761 GB, free: 6.004 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005913/0.777, allocations: 4.259 MB / 0.5803 GB, free: 1.742 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0196/0.7966, allocations: 10.76 MB / 0.5908 GB, free: 7.074 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.402e-05/0.7967, allocations: 72.48 kB / 0.5909 GB, free: 6.996 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=280): time 6.643e-06/0.7967, allocations: 0 / 0.5909 GB, free: 6.996 MB / 478.1 MB
Notification: Performance of matching and sorting (n=339): time 0.1906/0.9873, allocations: 52.25 MB / 0.6419 GB, free: 3.062 MB / 0.5138 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002513/0.9876, allocations: 406 kB / 0.6423 GB, free: 2.578 MB / 0.5138 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009778/0.9974, allocations: 6.766 MB / 0.6489 GB, free: 11.82 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.000585/0.9979, allocations: 77.7 kB / 0.649 GB, free: 11.73 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004183/1.002, allocations: 5.227 MB / 0.6541 GB, free: 6.465 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001546/1.004, allocations: 1.31 MB / 0.6554 GB, free: 5.145 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00283/1.006, allocations: 1.877 MB / 0.6572 GB, free: 3.254 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001338/1.007, allocations: 305.1 kB / 0.6575 GB, free: 2.953 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00716/1.014, allocations: 4.543 MB / 0.6619 GB, free: 14.4 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01229/1.026, allocations: 9.493 MB / 0.6712 GB, free: 2.562 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.22/1.246, allocations: 14.53 MB / 0.6854 GB, free: 172.2 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001508/1.246, allocations: 45.69 kB / 0.6854 GB, free: 172.2 MB / 0.545 GB
Notification: Performance of matching and sorting (n=1184) (initialization): time 0.03817/1.284, allocations: 19.67 MB / 0.7046 GB, free: 166.1 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 8.866e-05/1.285, allocations: 75.88 kB / 0.7047 GB, free: 166.1 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001181/1.285, allocations: 158.6 kB / 0.7049 GB, free: 166.1 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0187/1.303, allocations: 7.05 MB / 0.7117 GB, free: 164.9 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005384/1.309, allocations: 1.93 MB / 0.7136 GB, free: 164 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03928/1.348, allocations: 34.57 MB / 0.7474 GB, free: 129.4 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007397/1.355, allocations: 0.591 MB / 0.748 GB, free: 128.8 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001002/1.356, allocations: 499 kB / 0.7484 GB, free: 128.3 MB / 0.545 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 (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 (1048):
 * Single equations (assignments): 1042
 * 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.004123/1.361, allocations: 3.472 MB / 0.7518 GB, free: 124.9 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009191/1.361, allocations: 0.617 MB / 0.7524 GB, free: 124.7 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02438/1.386, allocations: 13.19 MB / 0.7653 GB, free: 111.4 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.097e-05/1.386, allocations: 39.94 kB / 0.7653 GB, free: 111.4 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.976e-05/1.386, allocations: 20 kB / 0.7654 GB, free: 111.3 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.449e-05/1.386, allocations: 15.94 kB / 0.7654 GB, free: 111.3 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03767/1.424, allocations: 24.15 MB / 0.789 GB, free: 86.93 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.796e-05/1.424, allocations: 4 kB / 0.789 GB, free: 86.92 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001691/1.425, allocations: 419.3 kB / 0.7894 GB, free: 86.51 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01789/1.443, allocations: 6.635 MB / 0.7958 GB, free: 79.86 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006954/1.444, allocations: 67.98 kB / 0.7959 GB, free: 79.8 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03619/1.48, allocations: 33.28 MB / 0.8284 GB, free: 46.45 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.941e-06/1.48, allocations: 11.94 kB / 0.8284 GB, free: 46.44 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0294/1.509, allocations: 18.67 MB / 0.8466 GB, free: 27.66 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001639/1.511, allocations: 0.5923 MB / 0.8472 GB, free: 27.07 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001207/1.512, allocations: 60 kB / 0.8473 GB, free: 27.01 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003305/1.516, allocations: 303.5 kB / 0.8476 GB, free: 26.71 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008083/1.516, allocations: 258.4 kB / 0.8478 GB, free: 26.46 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002684/1.517, allocations: 95.81 kB / 0.8479 GB, free: 26.37 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.004652/1.521, allocations: 4.11 MB / 0.8519 GB, free: 22.27 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.521, allocations: 0 / 0.8519 GB, free: 22.27 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.006528/1.528, allocations: 2.113 MB / 0.854 GB, free: 20.16 MB / 0.545 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 (214):
 * Single equations (assignments): 208
 * 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.0004867/1.528, allocations: 214 kB / 0.8542 GB, free: 19.96 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01417/1.543, allocations: 11.09 MB / 0.865 GB, free: 8.828 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 7.755e-06/1.543, allocations: 4.188 kB / 0.865 GB, free: 8.824 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.006742/1.549, allocations: 4.676 MB / 0.8696 GB, free: 4.102 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004192/1.553, allocations: 0.8907 MB / 0.8705 GB, free: 3.223 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2449/1.798, allocations: 19.88 MB / 0.8899 GB, free: 188.2 MB / 0.5607 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.006176/1.805, allocations: 5.056 MB / 0.8948 GB, free: 187.7 MB / 0.5607 GB
Notification: Performance of simCode: alias equations: time 0.005054/1.81, allocations: 2.543 MB / 0.8973 GB, free: 187.7 MB / 0.5607 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000894/1.81, allocations: 370.7 kB / 0.8977 GB, free: 187.7 MB / 0.5607 GB
Notification: Performance of SimCode: time 1.042e-06/1.81, allocations: 0 / 0.8977 GB, free: 187.7 MB / 0.5607 GB
Notification: Performance of Templates: time 0.121/1.932, allocations: 90.5 MB / 0.986 GB, free: 109.7 MB / 0.5607 GB
"
[Timeout remaining time 658]
make -j1 -f Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.makefile [Timeout 660]
(rm -f Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; mkfifo Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; head -c 1048576 < Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe >> ../files/Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.sim & ./Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a  -abortSlowSimulation -alarm=480 -s ida -emit_protected -lv LOG_STATS > Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe 2>&1) [Timeout 480]
diffSimulationResults("Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/Modelica/Mechanics/MultiBody/Examples/Loops/Engine1a/Engine1a.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.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: 8.664878398994915]