Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
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)
Running command: translateModel(Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|body.body.frame_a.r_0.1.|body.body.frame_a.r_0.2.|body.body.frame_a.r_0.3.|body.body.w_a.1.|body.body.w_a.2.|body.body.w_a.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.",fileNamePrefix="Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody")
translateModel(Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|body.body.frame_a.r_0.1.|body.body.frame_a.r_0.2.|body.body.frame_a.r_0.3.|body.body.w_a.1.|body.body.w_a.2.|body.body.w_a.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.",fileNamePrefix="Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001977/0.001977, allocations: 202.8 kB / 8.768 MB, free: 320 kB / 5.871 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.476/1.476, allocations: 225.6 MB / 234.8 MB, free: 3.176 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001014/0.001014, allocations: 98.48 kB / 286.7 MB, free: 5.305 MB / 234.7 MB
Notification: Performance of FrontEnd - loaded program: time 2.348e-05/2.356e-05, allocations: 4 kB / 343.7 MB, free: 8.23 MB / 282.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1112/0.1113, allocations: 51.48 MB / 395.2 MB, free: 4.707 MB / 330.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.4254/0.5368, allocations: 95.46 MB / 490.6 MB, free: 13.87 MB / 362.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002602/0.5371, allocations: 86.64 kB / 490.7 MB, free: 13.82 MB / 362.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.1857/0.7228, allocations: 73.71 MB / 0.5512 GB, free: 8.777 MB / 426.7 MB
Notification: Performance of FrontEnd: time 1.502e-06/0.7229, allocations: 4 kB / 0.5512 GB, free: 8.773 MB / 426.7 MB
Notification: Performance of Transformations before backend: time 0.0001703/0.723, allocations: 72 kB / 0.5513 GB, free: 8.703 MB / 426.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1330
 * Number of variables: 1330
Notification: Performance of Generate backend data structure: time 0.01618/0.7392, allocations: 3.947 MB / 0.5551 GB, free: 4.699 MB / 426.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.54e-05/0.7393, allocations: 12.02 kB / 0.5551 GB, free: 4.688 MB / 426.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01042/0.7497, allocations: 2.203 MB / 0.5573 GB, free: 2.477 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01031/0.7601, allocations: 3.819 MB / 0.561 GB, free: 14.6 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001705/0.7603, allocations: 120 kB / 0.5611 GB, free: 14.48 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001249/0.7615, allocations: 215.9 kB / 0.5613 GB, free: 14.27 MB / 442.7 MB
Notification: Performance of preOpt removeEqualFunctionCalls (simulation): time 0.01023/0.7718, allocations: 5.344 MB / 0.5666 GB, free: 8.922 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2574/1.029, allocations: 8.288 MB / 0.5747 GB, free: 80.18 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001957/1.029, allocations: 53.52 kB / 0.5747 GB, free: 80.18 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008425/1.03, allocations: 29.52 kB / 0.5747 GB, free: 80.18 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001286/1.032, allocations: 0.9091 MB / 0.5756 GB, free: 80.17 MB / 442.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0384/1.07, allocations: 42.81 MB / 0.6174 GB, free: 53.09 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00307/1.073, allocations: 2.39 MB / 0.6198 GB, free: 52.43 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001509/1.075, allocations: 1.327 MB / 0.6211 GB, free: 52.4 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005643/1.075, allocations: 0.6756 MB / 0.6217 GB, free: 51.83 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.819e-05/1.075, allocations: 52.12 kB / 0.6218 GB, free: 51.79 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=138): time 3.567e-06/1.075, allocations: 2.062 kB / 0.6218 GB, free: 51.79 MB / 442.7 MB
Notification: Performance of matching and sorting (n=145): time 0.01706/1.092, allocations: 8.771 MB / 0.6303 GB, free: 49.43 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.635e-05/1.092, allocations: 148.1 kB / 0.6305 GB, free: 49.27 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004052/1.097, allocations: 2.767 MB / 0.6332 GB, free: 48.96 MB / 442.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002264/1.097, allocations: 27.17 kB / 0.6332 GB, free: 48.93 MB / 442.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001411/1.098, allocations: 2.846 MB / 0.636 GB, free: 46.98 MB / 442.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0005908/1.099, allocations: 0.5779 MB / 0.6365 GB, free: 46.57 MB / 442.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001797/1.099, allocations: 1.031 kB / 0.6365 GB, free: 46.57 MB / 442.7 MB
Notification: Performance of setup shared object (initialization): time 0.0003091/1.099, allocations: 0.5513 MB / 0.6371 GB, free: 46.16 MB / 442.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002424/1.102, allocations: 1.804 MB / 0.6388 GB, free: 45.52 MB / 442.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003719/1.105, allocations: 5.357 MB / 0.6441 GB, free: 40.01 MB / 442.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003984/1.109, allocations: 3.075 MB / 0.6471 GB, free: 38.85 MB / 442.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.805e-05/1.11, allocations: 38.91 kB / 0.6471 GB, free: 38.84 MB / 442.7 MB
Notification: Performance of matching and sorting (n=742) (initialization): time 0.0208/1.13, allocations: 7.764 MB / 0.6547 GB, free: 36.44 MB / 442.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001284/1.131, allocations: 64.2 kB / 0.6548 GB, free: 36.43 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.009875/1.14, allocations: 3.05 MB / 0.6577 GB, free: 35.7 MB / 442.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009253/1.15, allocations: 1.902 MB / 0.6596 GB, free: 35.48 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004578/1.154, allocations: 1.299 MB / 0.6609 GB, free: 35.32 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006958/1.161, allocations: 5.514 MB / 0.6663 GB, free: 31.87 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005068/1.166, allocations: 277.4 kB / 0.6665 GB, free: 31.84 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001145/1.168, allocations: 358.8 kB / 0.6669 GB, free: 31.8 MB / 442.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 583
 * Number of states: 0 ()
 * Number of discrete variables: 36 (spring2.color[3],spring2.color[2],spring2.color[1],spring2.massColor[3],spring2.massColor[2],spring2.massColor[1],body.color[3],body.color[2],body.color[1],body.body.sphereColor[3],body.body.sphereColor[2],body.body.sphereColor[1],body.frameTranslation.color[3],body.frameTranslation.color[2],body.frameTranslation.color[1],spring1.color[3],spring1.color[2],spring1.color[1],spring1.massColor[3],spring1.massColor[2],spring1.massColor[1],bar2.color[3],bar2.color[2],bar2.color[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 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (704):
 * Single equations (assignments): 695
 * Array equations: 5
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 3
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 2 {(4,100.0%),(3,100.0%)}
 * Non-linear Jacobian: 1 {4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 1}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0009681/1.169, allocations: 417.1 kB / 0.6673 GB, free: 31.46 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006008/1.169, allocations: 290.1 kB / 0.6675 GB, free: 31.3 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004782/1.174, allocations: 2.649 MB / 0.6701 GB, free: 30.41 MB / 442.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.003698/1.178, allocations: 2.264 MB / 0.6723 GB, free: 29.7 MB / 442.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.45e-05/1.178, allocations: 7.281 kB / 0.6723 GB, free: 29.7 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.329e-05/1.178, allocations: 14.59 kB / 0.6723 GB, free: 29.69 MB / 442.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01259/1.19, allocations: 7.53 MB / 0.6797 GB, free: 26.82 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.189e-05/1.19, allocations: 4.031 kB / 0.6797 GB, free: 26.82 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001028/1.191, allocations: 236.7 kB / 0.6799 GB, free: 26.8 MB / 442.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002684/1.194, allocations: 1.025 MB / 0.6809 GB, free: 26.57 MB / 442.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001449/1.194, allocations: 21.73 kB / 0.681 GB, free: 26.57 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004367/1.199, allocations: 4.858 MB / 0.6857 GB, free: 23.36 MB / 442.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.0007985/1.199, allocations: 1.411 MB / 0.6871 GB, free: 21.98 MB / 442.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006521/1.206, allocations: 5.034 MB / 0.692 GB, free: 19.81 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008128/1.207, allocations: 344.6 kB / 0.6923 GB, free: 19.54 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002939/1.207, allocations: 10.88 kB / 0.6923 GB, free: 19.54 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001236/1.208, allocations: 109.9 kB / 0.6924 GB, free: 19.54 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000189/1.209, allocations: 92.56 kB / 0.6925 GB, free: 19.52 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000102/1.209, allocations: 44.89 kB / 0.6926 GB, free: 19.51 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.002455/1.211, allocations: 1.825 MB / 0.6943 GB, free: 18.83 MB / 442.7 MB
Notification: Performance of sort global known variables: time 1.81e-07/1.211, allocations: 0 / 0.6943 GB, free: 18.83 MB / 442.7 MB
Notification: Performance of remove unused functions: time 0.004458/1.216, allocations: 0.8831 MB / 0.6952 GB, free: 18.82 MB / 442.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * Number of states: 12 ($STATESET11.x[3],$STATESET11.x[2],$STATESET11.x[1],body.v_0[1],body.v_0[2],body.v_0[3],body.body.frame_a.r_0[1],body.body.frame_a.r_0[2],body.body.frame_a.r_0[3],body.body.w_a[1],body.body.w_a[2],body.body.w_a[3])
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (111):
 * Single equations (assignments): 104
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 3
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 2 {(3,100.0%),(4,100.0%)}
 * Non-linear Jacobian: 1 {4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 1}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004347/1.216, allocations: 155.9 kB / 0.6954 GB, free: 18.71 MB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.007497/1.224, allocations: 4.912 MB / 0.7002 GB, free: 16.84 MB / 442.7 MB
Notification: Performance of simCode: created event and clocks part: time 6.312e-06/1.224, allocations: 4.188 kB / 0.7002 GB, free: 16.83 MB / 442.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.003203/1.227, allocations: 1.988 MB / 0.7021 GB, free: 15.95 MB / 442.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001872/1.229, allocations: 301.3 kB / 0.7024 GB, free: 15.82 MB / 442.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01197/1.241, allocations: 6.976 MB / 0.7092 GB, free: 13.04 MB / 442.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002611/1.243, allocations: 2.983 MB / 0.7121 GB, free: 11.17 MB / 442.7 MB
Notification: Performance of simCode: alias equations: time 0.003921/1.247, allocations: 1.787 MB / 0.7139 GB, free: 10.06 MB / 442.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000499/1.248, allocations: 198.3 kB / 0.7141 GB, free: 9.879 MB / 442.7 MB
Notification: Performance of SimCode: time 1.553e-06/1.248, allocations: 0 / 0.7141 GB, free: 9.879 MB / 442.7 MB
Notification: Performance of Templates: time 0.05275/1.301, allocations: 29.89 MB / 0.7432 GB, free: 2.062 MB / 458.7 MB
make -j1 -f Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.makefile
(rm -f Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe ; mkfifo Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe ; head -c 1048576 < Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe >> ../files/Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.sim & ./Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe 2>&1)
diffSimulationResults("Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/Modelica/Mechanics/MultiBody/Examples/Elementary/FreeBody/FreeBody.csv","../files/Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches