Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.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 4.1.0-beta.om/package.mo", uses=false)
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/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: "" <> buildModelFMU(ModelicaTest.MultiBody.Parts.Bodies,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Parts.Bodies,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001603/0.001603, allocations: 103.9 kB / 17.63 MB, free: 5.523 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001813/0.001813, allocations: 210.4 kB / 18.58 MB, free: 4.578 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.537/1.537, allocations: 227.4 MB / 246.8 MB, free: 10.59 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2317/0.2317, allocations: 46.11 MB / 344.2 MB, free: 11.59 MB / 286.1 MB
Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.002235/0.002235, allocations: 76.83 kB / 417.8 MB, free: 19.66 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1221/0.1243, allocations: 62.46 MB / 480.2 MB, free: 5.18 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.Bodies): time 0.01653/0.1409, allocations: 14.64 MB / 494.8 MB, free: 6.473 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01025/0.1512, allocations: 4.664 MB / 499.5 MB, free: 1.793 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00254/0.1538, allocations: 99.69 kB / 499.6 MB, free: 1.695 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.006054/0.1599, allocations: 3.107 MB / 0.4909 GB, free: 14.58 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004965/0.1649, allocations: 2.016 MB / 0.4929 GB, free: 12.55 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00204/0.167, allocations: 1.014 MB / 0.4939 GB, free: 11.54 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009566/0.1765, allocations: 6.949 MB / 0.5007 GB, free: 4.574 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004875/0.1815, allocations: 3.641 MB / 0.5042 GB, free: 0.8633 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.2195/0.4009, allocations: 1.806 MB / 0.506 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003689/0.4047, allocations: 2.616 MB / 0.5086 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0009269/0.4056, allocations: 358.5 kB / 0.5089 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001891/0.4075, allocations: 0.8913 MB / 0.5098 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002107/0.4096, allocations: 2.258 MB / 0.512 GB, free: 16.92 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002664/0.4123, allocations: 2.385 MB / 0.5143 GB, free: 16.18 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00697/0.4193, allocations: 6.827 MB / 0.521 GB, free: 15.14 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.769e-06/0.4193, allocations: 1.188 kB / 0.521 GB, free: 15.14 MB / 382.1 MB
Notification: Performance of FrontEnd: time 1.823e-06/0.4193, allocations: 2.391 kB / 0.521 GB, free: 15.14 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 0.0001864/0.4195, allocations: 0 / 0.521 GB, free: 15.14 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1021
 * Number of variables: 1021
Notification: Performance of Generate backend data structure: time 0.0156/0.4351, allocations: 5.426 MB / 0.5263 GB, free: 12.43 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.102e-05/0.4352, allocations: 8.625 kB / 0.5263 GB, free: 12.43 MB / 382.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001306/0.4365, allocations: 0.5148 MB / 0.5268 GB, free: 12.18 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1021/0.5386, allocations: 8.203 MB / 0.5348 GB, free: 9.102 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01044/0.5491, allocations: 5.089 MB / 0.5398 GB, free: 6.273 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001264/0.5493, allocations: 208 kB / 0.54 GB, free: 6.195 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007106/0.55, allocations: 291.3 kB / 0.5402 GB, free: 6.105 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01086/0.5609, allocations: 6.912 MB / 0.547 GB, free: 14.86 MB / 398.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001467/0.561, allocations: 61.14 kB / 0.547 GB, free: 14.82 MB / 398.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003512/0.5614, allocations: 125.6 kB / 0.5472 GB, free: 14.71 MB / 398.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00161/0.563, allocations: 0.9978 MB / 0.5481 GB, free: 13.73 MB / 398.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01131/0.5743, allocations: 4.931 MB / 0.553 GB, free: 8.941 MB / 398.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.04148/0.6158, allocations: 27.29 MB / 0.5796 GB, free: 13.26 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002936/0.6188, allocations: 0.7289 MB / 0.5803 GB, free: 12.49 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004242/0.6193, allocations: 224.6 kB / 0.5805 GB, free: 12.26 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 3.769e-05/0.6194, allocations: 7.281 kB / 0.5805 GB, free: 12.25 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.614e-05/0.6194, allocations: 44.09 kB / 0.5806 GB, free: 12.21 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=37): time 2.355e-06/0.6194, allocations: 3.938 kB / 0.5806 GB, free: 12.2 MB / 430.1 MB
Notification: Performance of matching and sorting (n=37): time 0.007577/0.627, allocations: 1.958 MB / 0.5825 GB, free: 10.25 MB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001711/0.6272, allocations: 243 kB / 0.5827 GB, free: 9.961 MB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01147/0.6387, allocations: 4.749 MB / 0.5874 GB, free: 5.211 MB / 430.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001202/0.6389, allocations: 37.69 kB / 0.5874 GB, free: 5.168 MB / 430.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.00272/0.6416, allocations: 2.752 MB / 0.5901 GB, free: 2.418 MB / 430.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002776/0.6419, allocations: 195.5 kB / 0.5903 GB, free: 2.227 MB / 430.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009886/0.6429, allocations: 0.7217 MB / 0.591 GB, free: 1.496 MB / 430.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001258/0.643, allocations: 305.1 kB / 0.5913 GB, free: 1.195 MB / 430.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001297/0.6443, allocations: 0.7915 MB / 0.592 GB, free: 412 kB / 430.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00387/0.6482, allocations: 3.414 MB / 0.5954 GB, free: 11.71 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005627/0.6539, allocations: 4.549 MB / 0.5998 GB, free: 6.227 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.508e-05/0.654, allocations: 28.5 kB / 0.5998 GB, free: 6.199 MB / 446.1 MB
Notification: Performance of matching and sorting (n=449) (initialization): time 0.008852/0.6628, allocations: 4.049 MB / 0.6038 GB, free: 2.699 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 9.087e-05/0.663, allocations: 48.25 kB / 0.6038 GB, free: 2.652 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001713/0.6631, allocations: 96 kB / 0.6039 GB, free: 2.559 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001536/0.6633, allocations: 120 kB / 0.6041 GB, free: 2.441 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002616/0.6659, allocations: 0.8538 MB / 0.6049 GB, free: 1.668 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001575/0.6661, allocations: 179.9 kB / 0.6051 GB, free: 1.492 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004196/0.6703, allocations: 0.5414 MB / 0.6056 GB, free: 0.9453 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004247/0.6708, allocations: 232 kB / 0.6058 GB, free: 0.7188 MB / 446.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 430
 * Number of states: 0 ()
 * Number of discrete variables: 54 (world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],Fixed2.color[3],Fixed2.color[2],Fixed2.color[1],Fixed1.color[3],Fixed1.color[2],Fixed1.color[1],body1.sphereColor[3],body1.sphereColor[2],body1.sphereColor[1],Fixed5.color[3],Fixed5.color[2],Fixed5.color[1],Fixed4.color[3],Fixed4.color[2],Fixed4.color[1],Fixed3.color[3],Fixed3.color[2],Fixed3.color[1],bodyCylinder.color[3],bodyCylinder.color[2],bodyCylinder.color[1],bodyCylinder.body.sphereColor[3],bodyCylinder.body.sphereColor[2],bodyCylinder.body.sphereColor[1],BodyBox1.color[3],BodyBox1.color[2],BodyBox1.color[1],BodyBox1.body.sphereColor[3],BodyBox1.body.sphereColor[2],BodyBox1.body.sphereColor[1],bodyShape.color[3],bodyShape.color[2],bodyShape.color[1],bodyShape.body.sphereColor[3],bodyShape.body.sphereColor[2],bodyShape.body.sphereColor[1],bodyShape.frameTranslation.color[3],bodyShape.frameTranslation.color[2],bodyShape.frameTranslation.color[1],body2.sphereColor[3],body2.sphereColor[2],body2.sphereColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (449):
 * Single equations (assignments): 449
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.003271/0.674, allocations: 1.627 MB / 0.6074 GB, free: 15.08 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009256/0.675, allocations: 379.1 kB / 0.6078 GB, free: 14.71 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008392/0.6758, allocations: 397.2 kB / 0.6081 GB, free: 14.31 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.829e-06/0.6758, allocations: 4 kB / 0.6081 GB, free: 14.31 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.177e-06/0.6758, allocations: 3.938 kB / 0.6081 GB, free: 14.3 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.945e-06/0.6759, allocations: 4 kB / 0.6081 GB, free: 14.3 MB / 462.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00807/0.6839, allocations: 4.757 MB / 0.6128 GB, free: 9.293 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.569e-05/0.684, allocations: 12 kB / 0.6128 GB, free: 9.281 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003045/0.6843, allocations: 115.8 kB / 0.6129 GB, free: 9.168 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 4.192e-05/0.6843, allocations: 23.94 kB / 0.6129 GB, free: 9.145 MB / 462.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.756e-05/0.6844, allocations: 32.31 kB / 0.613 GB, free: 9.117 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.334e-05/0.6844, allocations: 27.86 kB / 0.613 GB, free: 9.09 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.495e-05/0.6844, allocations: 23.94 kB / 0.613 GB, free: 9.066 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001731/0.6862, allocations: 1.228 MB / 0.6142 GB, free: 7.625 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001293/0.6875, allocations: 488 kB / 0.6147 GB, free: 7.125 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003802/0.6879, allocations: 31.98 kB / 0.6147 GB, free: 7.094 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009566/0.6888, allocations: 32.17 kB / 0.6147 GB, free: 7.062 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001119/0.689, allocations: 79.62 kB / 0.6148 GB, free: 6.984 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.169e-05/0.689, allocations: 39.94 kB / 0.6149 GB, free: 6.945 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.006148/0.6952, allocations: 3.14 MB / 0.6179 GB, free: 3.812 MB / 462.1 MB
Notification: Performance of sort global known variables: time 5.21e-07/0.6952, allocations: 0 / 0.6179 GB, free: 3.812 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.005573/0.7008, allocations: 0.8298 MB / 0.6187 GB, free: 2.98 MB / 462.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 61
 * Number of states: 0 ()
 * 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 (61):
 * Single equations (assignments): 61
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.002489/0.7033, allocations: 1.834 MB / 0.6205 GB, free: 0.9414 MB / 462.1 MB
Notification: Performance of simCode: created initialization part: time 0.003682/0.707, allocations: 1.934 MB / 0.6224 GB, free: 15.02 MB / 478.1 MB
Notification: Performance of simCode: created event and clocks part: time 1.036e-05/0.707, allocations: 4 kB / 0.6224 GB, free: 15.02 MB / 478.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0003703/0.7074, allocations: 266.4 kB / 0.6227 GB, free: 14.76 MB / 478.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004244/0.7116, allocations: 0.6623 MB / 0.6233 GB, free: 14.11 MB / 478.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06755/0.7792, allocations: 42.79 MB / 0.6651 GB, free: 12.41 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003761/0.783, allocations: 2.85 MB / 0.6679 GB, free: 9.539 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.003527/0.7866, allocations: 1.158 MB / 0.669 GB, free: 8.379 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002615/0.7892, allocations: 1.698 MB / 0.6707 GB, free: 6.68 MB / 0.5137 GB
Notification: Performance of SimCode: time 1.282e-06/0.7892, allocations: 0 / 0.6707 GB, free: 6.68 MB / 0.5137 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3324/1.122, allocations: 67.73 MB / 0.7368 GB, free: 189.1 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate platform static: time 9.831/10.95, allocations: 5.531 kB / 0.7368 GB, free: 189.1 MB / 0.545 GB
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies_fmu --startTime=0 --stopTime=1.1 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe 2>&1)