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.001781/0.001782, allocations: 115.2 kB / 18.46 MB, free: 4.605 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002045/0.002045, allocations: 202.7 kB / 19.42 MB, free: 3.66 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.775/1.775, allocations: 227.4 MB / 247.6 MB, free: 10.51 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.3992/0.3992, allocations: 46.11 MB / 345 MB, free: 11.5 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001526/0.001526, allocations: 75.88 kB / 482 MB, free: 4.078 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3414/0.3429, allocations: 62.44 MB / 0.5317 GB, free: 13.62 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.InitializationConversion.Bodies): time 0.01482/0.3578, allocations: 12.28 MB / 0.5436 GB, free: 3.945 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01092/0.3688, allocations: 4.252 MB / 0.5478 GB, free: 0.6172 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003169/0.372, allocations: 87.25 kB / 0.5479 GB, free: 0.5977 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005777/0.3778, allocations: 2.62 MB / 0.5504 GB, free: 14.44 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006427/0.3843, allocations: 2.264 MB / 0.5526 GB, free: 12.17 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002077/0.3864, allocations: 0.8853 MB / 0.5535 GB, free: 11.28 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01026/0.3967, allocations: 7.054 MB / 0.5604 GB, free: 4.215 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004454/0.4011, allocations: 3.092 MB / 0.5634 GB, free: 1.074 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003489/0.4047, allocations: 1.873 MB / 0.5652 GB, free: 15.2 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004317/0.409, allocations: 2.633 MB / 0.5678 GB, free: 12.55 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001413/0.4104, allocations: 356 kB / 0.5682 GB, free: 12.21 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004177/0.4146, allocations: 1.51 MB / 0.5696 GB, free: 10.7 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003768/0.4184, allocations: 2.365 MB / 0.5719 GB, free: 8.324 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.005601/0.4241, allocations: 2.505 MB / 0.5744 GB, free: 5.805 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01381/0.4379, allocations: 8.101 MB / 0.5823 GB, free: 13.67 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.235e-06/0.438, allocations: 0 / 0.5823 GB, free: 13.67 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.415e-06/0.438, allocations: 3.938 kB / 0.5823 GB, free: 13.67 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0003605/0.4383, allocations: 0 / 0.5823 GB, free: 13.67 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.01979/0.4582, allocations: 6.195 MB / 0.5884 GB, free: 7.387 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 6.09e-05/0.4582, allocations: 12.02 kB / 0.5884 GB, free: 7.375 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02562/0.4839, allocations: 3.5 MB / 0.5918 GB, free: 3.867 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0104/0.4943, allocations: 4.744 MB / 0.5964 GB, free: 15.04 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002194/0.4945, allocations: 230.1 kB / 0.5966 GB, free: 14.81 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002367/0.4969, allocations: 0.6555 MB / 0.5973 GB, free: 14.16 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02215/0.5191, allocations: 10.67 MB / 0.6077 GB, free: 2.695 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002675/0.5194, allocations: 67.52 kB / 0.6078 GB, free: 2.629 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001255/0.5207, allocations: 151.8 kB / 0.6079 GB, free: 2.48 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003328/0.524, allocations: 1.935 MB / 0.6098 GB, free: 0.5469 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02611/0.5501, allocations: 9.86 MB / 0.6194 GB, free: 6.672 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.0679/0.6181, allocations: 44.61 MB / 0.663 GB, free: 8.785 MB / 0.5138 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00929/0.6274, allocations: 4.051 MB / 0.6669 GB, free: 4.551 MB / 0.5138 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003191/0.6306, allocations: 2.199 MB / 0.6691 GB, free: 2.328 MB / 0.5138 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000197/0.6308, allocations: 24.22 kB / 0.6691 GB, free: 2.305 MB / 0.5138 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.247e-05/0.6309, allocations: 70.64 kB / 0.6692 GB, free: 2.227 MB / 0.5138 GB
Notification: Performance of pre-optimization done (n=190): time 4.909e-06/0.6309, allocations: 0 / 0.6692 GB, free: 2.227 MB / 0.5138 GB
Notification: Performance of matching and sorting (n=194): time 0.05508/0.686, allocations: 22.99 MB / 0.6916 GB, free: 10.69 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000174/0.6862, allocations: 332.7 kB / 0.692 GB, free: 10.29 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008203/0.6944, allocations: 5.185 MB / 0.697 GB, free: 5.102 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005935/0.695, allocations: 57.7 kB / 0.6971 GB, free: 5.039 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002942/0.698, allocations: 3.544 MB / 0.7005 GB, free: 1.461 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001319/0.6993, allocations: 0.8878 MB / 0.7014 GB, free: 0.5664 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003479/0.7028, allocations: 1.489 MB / 0.7029 GB, free: 15.07 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 0.0001286/0.703, allocations: 305.1 kB / 0.7031 GB, free: 14.77 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005577/0.7086, allocations: 3.028 MB / 0.7061 GB, free: 11.73 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008814/0.7174, allocations: 7.338 MB / 0.7133 GB, free: 2.281 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2923/1.01, allocations: 10.97 MB / 0.724 GB, free: 149 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.536e-05/1.01, allocations: 43.09 kB / 0.724 GB, free: 149 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=964) (initialization): time 0.04669/1.057, allocations: 17.43 MB / 0.741 GB, free: 144.2 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 8.309e-05/1.057, allocations: 73 kB / 0.7411 GB, free: 144.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.03364/1.09, allocations: 10.6 MB / 0.7515 GB, free: 136 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04072/1.131, allocations: 8.98 MB / 0.7602 GB, free: 127 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006358/1.137, allocations: 1.669 MB / 0.7619 GB, free: 125.3 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03079/1.168, allocations: 22.14 MB / 0.7835 GB, free: 102.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007534/1.176, allocations: 0.5547 MB / 0.784 GB, free: 101.9 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001023/1.177, allocations: 429 kB / 0.7844 GB, free: 101.5 MB / 0.5606 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.003107/1.18, allocations: 2.321 MB / 0.7867 GB, free: 99.26 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000778/1.181, allocations: 457.3 kB / 0.7871 GB, free: 99.07 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001035/1.182, allocations: 0.605 MB / 0.7877 GB, free: 98.45 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.372e-05/1.182, allocations: 15.97 kB / 0.7877 GB, free: 98.44 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.623e-06/1.182, allocations: 0 / 0.7877 GB, free: 98.44 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006632/1.183, allocations: 0.5114 MB / 0.7882 GB, free: 98.16 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005397/1.183, allocations: 91.78 kB / 0.7883 GB, free: 98.08 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0022/1.185, allocations: 239.6 kB / 0.7886 GB, free: 97.84 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004442/1.186, allocations: 148.9 kB / 0.7887 GB, free: 97.7 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009383/1.195, allocations: 6.601 MB / 0.7951 GB, free: 91.16 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.006398/1.202, allocations: 6.74 MB / 0.8017 GB, free: 84.46 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001712/1.203, allocations: 1.853 MB / 0.8035 GB, free: 82.61 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.004087/1.207, allocations: 3.284 MB / 0.8067 GB, free: 79.34 MB / 0.5606 GB
Notification: Performance of Backend: time 3.8e-07/1.207, allocations: 0 / 0.8067 GB, free: 79.34 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01566/1.223, allocations: 8.53 MB / 0.8151 GB, free: 70.59 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.01731/1.24, allocations: 13.25 MB / 0.828 GB, free: 57.38 MB / 0.5606 GB
Notification: Performance of Templates: time 0.1149/1.355, allocations: 79.25 MB / 0.9054 GB, free: 10.4 MB / 0.5919 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