Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
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/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 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)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.InitializationConversion.Bodies,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.frame_a.r_0.1.|body.frame_a.r_0.2.|body.frame_a.r_0.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.|body.w_a.1.|body.w_a.2.|body.w_a.3.|body1.body.Q.1.|body1.body.Q.2.|body1.body.Q.3.|body1.body.Q.4.|body1.body.frame_a.r_0.1.|body1.body.frame_a.r_0.2.|body1.body.frame_a.r_0.3.|body1.body.w_a.1.|body1.body.w_a.2.|body1.body.w_a.3.|body1.v_0.1.|body1.v_0.2.|body1.v_0.3.|body2.body.Q.1.|body2.body.Q.2.|body2.body.Q.3.|body2.body.Q.4.|body2.body.frame_a.r_0.1.|body2.body.frame_a.r_0.2.|body2.body.frame_a.r_0.3.|body2.body.w_a.1.|body2.body.w_a.2.|body2.body.w_a.3.|body2.v_0.1.|body2.v_0.2.|body2.v_0.3.|body3.body.Q.1.|body3.body.Q.2.|body3.body.Q.3.|body3.body.Q.4.|body3.body.frame_a.r_0.1.|body3.body.frame_a.r_0.2.|body3.body.frame_a.r_0.3.|body3.body.w_a.1.|body3.body.w_a.2.|body3.body.w_a.3.|body3.v_0.1.|body3.v_0.2.|body3.v_0.3.|pointMass.r_0.1.|pointMass.r_0.2.|pointMass.r_0.3.|pointMass.v_0.1.|pointMass.v_0.2.|pointMass.v_0.3.",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies")
translateModel(ModelicaTest.MultiBody.InitializationConversion.Bodies,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.frame_a.r_0.1.|body.frame_a.r_0.2.|body.frame_a.r_0.3.|body.v_0.1.|body.v_0.2.|body.v_0.3.|body.w_a.1.|body.w_a.2.|body.w_a.3.|body1.body.Q.1.|body1.body.Q.2.|body1.body.Q.3.|body1.body.Q.4.|body1.body.frame_a.r_0.1.|body1.body.frame_a.r_0.2.|body1.body.frame_a.r_0.3.|body1.body.w_a.1.|body1.body.w_a.2.|body1.body.w_a.3.|body1.v_0.1.|body1.v_0.2.|body1.v_0.3.|body2.body.Q.1.|body2.body.Q.2.|body2.body.Q.3.|body2.body.Q.4.|body2.body.frame_a.r_0.1.|body2.body.frame_a.r_0.2.|body2.body.frame_a.r_0.3.|body2.body.w_a.1.|body2.body.w_a.2.|body2.body.w_a.3.|body2.v_0.1.|body2.v_0.2.|body2.v_0.3.|body3.body.Q.1.|body3.body.Q.2.|body3.body.Q.3.|body3.body.Q.4.|body3.body.frame_a.r_0.1.|body3.body.frame_a.r_0.2.|body3.body.frame_a.r_0.3.|body3.body.w_a.1.|body3.body.w_a.2.|body3.body.w_a.3.|body3.v_0.1.|body3.v_0.2.|body3.v_0.3.|pointMass.r_0.1.|pointMass.r_0.2.|pointMass.r_0.3.|pointMass.v_0.1.|pointMass.v_0.2.|pointMass.v_0.3.",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001239/0.001239, allocations: 115.6 kB / 18.46 MB, free: 4.613 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001308/0.001308, allocations: 212 kB / 19.41 MB, free: 3.668 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.318/1.318, allocations: 227.4 MB / 247.6 MB, free: 10.48 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2014/0.2014, allocations: 46.1 MB / 345 MB, free: 11.5 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001354/0.001354, allocations: 79.88 kB / 482 MB, free: 4.078 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2952/0.2965, allocations: 62.44 MB / 0.5317 GB, free: 13.59 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.InitializationConversion.Bodies): time 0.01222/0.3088, allocations: 12.29 MB / 0.5437 GB, free: 3.906 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008948/0.3177, allocations: 4.248 MB / 0.5478 GB, free: 0.582 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00219/0.32, allocations: 91.09 kB / 0.5479 GB, free: 0.5586 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004912/0.3249, allocations: 2.618 MB / 0.5504 GB, free: 14.4 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005226/0.3302, allocations: 2.202 MB / 0.5526 GB, free: 12.19 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00173/0.3319, allocations: 0.8853 MB / 0.5535 GB, free: 11.3 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008206/0.3401, allocations: 7.051 MB / 0.5603 GB, free: 4.246 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003825/0.344, allocations: 3.09 MB / 0.5634 GB, free: 1.105 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0029/0.3469, allocations: 1.873 MB / 0.5652 GB, free: 15.23 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003632/0.3506, allocations: 2.64 MB / 0.5678 GB, free: 12.58 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001013/0.3516, allocations: 352 kB / 0.5681 GB, free: 12.23 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003055/0.3546, allocations: 1.51 MB / 0.5696 GB, free: 10.72 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002432/0.3571, allocations: 2.353 MB / 0.5719 GB, free: 8.363 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003542/0.3606, allocations: 2.513 MB / 0.5743 GB, free: 5.836 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008322/0.369, allocations: 8.075 MB / 0.5822 GB, free: 13.73 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.484e-06/0.369, allocations: 3.984 kB / 0.5822 GB, free: 13.73 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.563e-06/0.369, allocations: 4 kB / 0.5822 GB, free: 13.73 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0001195/0.3691, allocations: 0 / 0.5822 GB, free: 13.73 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1498
 * Number of variables: 1498
Notification: Performance of Generate backend data structure: time 0.01192/0.3811, allocations: 6.027 MB / 0.5881 GB, free: 7.621 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.206e-05/0.3811, allocations: 12.03 kB / 0.5881 GB, free: 7.609 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02065/0.4018, allocations: 3.473 MB / 0.5915 GB, free: 4.125 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009068/0.4109, allocations: 4.731 MB / 0.5961 GB, free: 15.31 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002043/0.4111, allocations: 230.1 kB / 0.5964 GB, free: 15.08 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002142/0.4133, allocations: 0.6515 MB / 0.597 GB, free: 14.43 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01665/0.4299, allocations: 10.66 MB / 0.6074 GB, free: 2.977 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001728/0.4301, allocations: 79.33 kB / 0.6075 GB, free: 2.898 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006837/0.4308, allocations: 143.9 kB / 0.6076 GB, free: 2.758 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002799/0.4336, allocations: 1.939 MB / 0.6095 GB, free: 0.8203 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01648/0.4501, allocations: 9.852 MB / 0.6191 GB, free: 6.953 MB / 478.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.05274/0.5029, allocations: 44.55 MB / 0.6626 GB, free: 9.117 MB / 0.5138 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009017/0.5119, allocations: 4.048 MB / 0.6666 GB, free: 4.887 MB / 0.5138 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00286/0.5148, allocations: 2.199 MB / 0.6687 GB, free: 2.664 MB / 0.5138 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001662/0.515, allocations: 24.22 kB / 0.6688 GB, free: 2.641 MB / 0.5138 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.548e-05/0.5151, allocations: 66.66 kB / 0.6688 GB, free: 2.566 MB / 0.5138 GB
Notification: Performance of pre-optimization done (n=190): time 5.29e-06/0.5151, allocations: 0 / 0.6688 GB, free: 2.566 MB / 0.5138 GB
Notification: Performance of matching and sorting (n=194): time 0.05276/0.5679, allocations: 22.53 MB / 0.6908 GB, free: 11.43 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001452/0.568, allocations: 332.4 kB / 0.6911 GB, free: 11.04 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00829/0.5763, allocations: 5.168 MB / 0.6962 GB, free: 5.863 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005492/0.5769, allocations: 57.64 kB / 0.6962 GB, free: 5.801 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002376/0.5793, allocations: 3.405 MB / 0.6996 GB, free: 2.348 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001125/0.5804, allocations: 0.845 MB / 0.7004 GB, free: 1.492 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001924/0.5824, allocations: 1.484 MB / 0.7018 GB, free: 0 / 0.545 GB
Notification: Performance of setup shared object (initialization): time 9.509e-05/0.5825, allocations: 301.1 kB / 0.7021 GB, free: 15.7 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004113/0.5866, allocations: 3.04 MB / 0.7051 GB, free: 12.66 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006491/0.5931, allocations: 7.334 MB / 0.7123 GB, free: 3.207 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01234/0.6054, allocations: 10.96 MB / 0.723 GB, free: 6.129 MB / 0.5763 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000128/0.6056, allocations: 48 kB / 0.723 GB, free: 6.082 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=964) (initialization): time 0.3049/0.9106, allocations: 17.4 MB / 0.74 GB, free: 168.7 MB / 0.5763 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001695/0.9108, allocations: 72.94 kB / 0.7401 GB, free: 168.7 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.03459/0.9454, allocations: 10.61 MB / 0.7504 GB, free: 164.4 MB / 0.5763 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0379/0.9833, allocations: 8.976 MB / 0.7592 GB, free: 159.2 MB / 0.5763 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004641/0.988, allocations: 1.664 MB / 0.7608 GB, free: 157.7 MB / 0.5763 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02462/1.013, allocations: 22.11 MB / 0.7824 GB, free: 134.9 MB / 0.5763 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006215/1.019, allocations: 0.5612 MB / 0.783 GB, free: 134.4 MB / 0.5763 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001272/1.02, allocations: 432 kB / 0.7834 GB, free: 134 MB / 0.5763 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 686
 * Number of states: 0 ()
 * Number of discrete variables: 39 (body3.color[3],body3.color[2],body3.color[1],body3.body.sphereColor[3],body3.body.sphereColor[2],body3.body.sphereColor[1],body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[1],body1.color[3],body1.color[2],body1.color[1],body1.body.sphereColor[3],body1.body.sphereColor[2],body1.body.sphereColor[1],body1.frameTranslation.color[3],body1.frameTranslation.color[2],body1.frameTranslation.color[1],pointMass.sphereColor[3],pointMass.sphereColor[2],pointMass.sphereColor[1],body.sphereColor[3],body.sphereColor[2],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 (900):
 * Single equations (assignments): 881
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 14
 * 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): 10 systems
   {(4,100.0%), (3,100.0%), (4,100.0%), (3,100.0%), (4,100.0%), (3,100.0%), (3,100.0%), (4,100.0%), (3,100.0%), (3,100.0%)}
 * Non-linear Jacobian (size): 4 systems
   {4, 4, 4, 4}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(2,1,100.0%), (2,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003932/1.024, allocations: 2.318 MB / 0.7857 GB, free: 131.8 MB / 0.5763 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001029/1.025, allocations: 433.3 kB / 0.7861 GB, free: 131.6 MB / 0.5763 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001448/1.027, allocations: 0.6089 MB / 0.7867 GB, free: 130.9 MB / 0.5763 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.711e-05/1.027, allocations: 15.95 kB / 0.7867 GB, free: 130.9 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.671e-06/1.027, allocations: 0.9375 kB / 0.7867 GB, free: 130.9 MB / 0.5763 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008724/1.028, allocations: 496.8 kB / 0.7871 GB, free: 130.7 MB / 0.5763 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009916/1.029, allocations: 87.95 kB / 0.7872 GB, free: 130.6 MB / 0.5763 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002544/1.031, allocations: 231.6 kB / 0.7875 GB, free: 130.3 MB / 0.5763 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004741/1.032, allocations: 155.1 kB / 0.7876 GB, free: 130.2 MB / 0.5763 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009686/1.041, allocations: 6.612 MB / 0.7941 GB, free: 123.7 MB / 0.5763 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.006077/1.047, allocations: 6.719 MB / 0.8006 GB, free: 117.2 MB / 0.5763 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001677/1.049, allocations: 1.846 MB / 0.8024 GB, free: 115.4 MB / 0.5763 GB
Notification: Performance of sorting global known variables: time 0.003606/1.053, allocations: 3.287 MB / 0.8056 GB, free: 112.1 MB / 0.5763 GB
Notification: Performance of Backend: time 2.71e-07/1.053, allocations: 0 / 0.8056 GB, free: 112.1 MB / 0.5763 GB
Notification: Performance of simCode: created initialization part: time 0.01379/1.066, allocations: 8.516 MB / 0.814 GB, free: 103.4 MB / 0.5763 GB
Notification: Performance of SimCode: time 0.01464/1.081, allocations: 13.07 MB / 0.8267 GB, free: 90.35 MB / 0.5763 GB
Notification: Performance of Templates: time 0.1025/1.184, allocations: 80.46 MB / 0.9053 GB, free: 10.25 MB / 0.5763 GB
make -j1 -f ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.sim & ./ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.pipe 2>&1)
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/ModelicaTest/MultiBody/InitializationConversion/Bodies/Bodies.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_trunk_ModelicaTest.MultiBody.InitializationConversion.Bodies.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches