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.001453/0.001453, 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.001581/0.001581, 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.507/1.507, allocations: 227.4 MB / 246.8 MB, free: 10.6 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.2358/0.2358, 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.002588/0.002588, allocations: 86.28 kB / 417.8 MB, free: 19.62 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1226/0.1252, allocations: 62.44 MB / 480.2 MB, free: 5.156 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.Bodies): time 0.01823/0.1435, allocations: 14.63 MB / 494.8 MB, free: 6.453 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01128/0.1548, allocations: 4.671 MB / 499.5 MB, free: 1.77 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003197/0.1581, allocations: 103.7 kB / 499.6 MB, free: 1.668 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.006773/0.1649, allocations: 3.099 MB / 0.4909 GB, free: 14.56 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005457/0.1704, allocations: 2.027 MB / 0.4929 GB, free: 12.52 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00243/0.1729, allocations: 1.01 MB / 0.4939 GB, free: 11.51 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01009/0.183, allocations: 6.949 MB / 0.5007 GB, free: 4.547 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.005185/0.1882, allocations: 3.634 MB / 0.5042 GB, free: 0.8438 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.2194/0.4076, allocations: 1.81 MB / 0.506 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004323/0.412, allocations: 2.616 MB / 0.5086 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001111/0.4132, allocations: 358.8 kB / 0.5089 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002109/0.4153, allocations: 0.8894 MB / 0.5098 GB, free: 17.11 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002436/0.4177, allocations: 2.258 MB / 0.512 GB, free: 16.93 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002764/0.4205, allocations: 2.385 MB / 0.5143 GB, free: 16.19 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007291/0.4278, allocations: 6.826 MB / 0.521 GB, free: 15.08 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.791e-06/0.4278, allocations: 0 / 0.521 GB, free: 15.08 MB / 382.1 MB
Notification: Performance of FrontEnd: time 1.673e-06/0.4279, allocations: 0 / 0.521 GB, free: 15.08 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 0.0002098/0.4281, allocations: 0 / 0.521 GB, free: 15.08 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.01535/0.4434, allocations: 5.432 MB / 0.5263 GB, free: 12.37 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.142e-05/0.4435, allocations: 7.375 kB / 0.5263 GB, free: 12.37 MB / 382.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001078/0.4446, allocations: 0.5099 MB / 0.5268 GB, free: 12.12 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1015/0.5461, allocations: 8.201 MB / 0.5348 GB, free: 9.02 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009697/0.5559, allocations: 5.086 MB / 0.5398 GB, free: 6.191 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001355/0.556, allocations: 214.4 kB / 0.54 GB, free: 6.109 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007733/0.5568, allocations: 284 kB / 0.5402 GB, free: 6.023 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01226/0.5691, allocations: 6.92 MB / 0.547 GB, free: 14.81 MB / 398.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001892/0.5693, allocations: 59.58 kB / 0.547 GB, free: 14.77 MB / 398.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004047/0.5697, allocations: 130.2 kB / 0.5472 GB, free: 14.66 MB / 398.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001652/0.5714, allocations: 0.9965 MB / 0.5481 GB, free: 13.68 MB / 398.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01136/0.5828, allocations: 4.93 MB / 0.553 GB, free: 8.895 MB / 398.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.03794/0.6207, allocations: 27.29 MB / 0.5796 GB, free: 13.21 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002647/0.6234, allocations: 0.7299 MB / 0.5803 GB, free: 12.44 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003966/0.6239, allocations: 227 kB / 0.5805 GB, free: 12.2 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 3.607e-05/0.6239, allocations: 9.562 kB / 0.5805 GB, free: 12.2 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.602e-05/0.624, allocations: 42.55 kB / 0.5806 GB, free: 12.15 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=37): time 4.348e-06/0.624, allocations: 0 / 0.5806 GB, free: 12.15 MB / 430.1 MB
Notification: Performance of matching and sorting (n=37): time 0.007452/0.6314, allocations: 1.955 MB / 0.5825 GB, free: 10.2 MB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001603/0.6316, allocations: 242.8 kB / 0.5827 GB, free: 9.906 MB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009475/0.6411, allocations: 4.749 MB / 0.5874 GB, free: 5.156 MB / 430.1 MB
Notification: Performance of collectPreVariables (initialization): time 9.142e-05/0.6412, allocations: 33.7 kB / 0.5874 GB, free: 5.117 MB / 430.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002464/0.6437, allocations: 2.755 MB / 0.5901 GB, free: 2.363 MB / 430.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002576/0.644, allocations: 193 kB / 0.5903 GB, free: 2.176 MB / 430.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009029/0.6449, allocations: 0.7217 MB / 0.591 GB, free: 1.445 MB / 430.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001271/0.645, allocations: 307.9 kB / 0.5913 GB, free: 1.145 MB / 430.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001289/0.6463, allocations: 0.7954 MB / 0.592 GB, free: 356 kB / 430.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003349/0.6497, allocations: 3.407 MB / 0.5954 GB, free: 11.66 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005297/0.655, allocations: 4.549 MB / 0.5998 GB, free: 6.176 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.976e-05/0.6551, allocations: 28 kB / 0.5998 GB, free: 6.148 MB / 446.1 MB
Notification: Performance of matching and sorting (n=449) (initialization): time 0.007366/0.6624, allocations: 4.051 MB / 0.6038 GB, free: 2.648 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 6.693e-05/0.6625, allocations: 47.98 kB / 0.6038 GB, free: 2.602 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.705e-05/0.6626, allocations: 96.55 kB / 0.6039 GB, free: 2.508 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001245/0.6628, allocations: 124.1 kB / 0.6041 GB, free: 2.387 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002329/0.6651, allocations: 0.8481 MB / 0.6049 GB, free: 1.617 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001586/0.6653, allocations: 187.2 kB / 0.6051 GB, free: 1.434 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003919/0.6692, allocations: 0.5336 MB / 0.6056 GB, free: 0.8945 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003495/0.6696, allocations: 240.5 kB / 0.6058 GB, free: 0.6602 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.003472/0.673, allocations: 1.623 MB / 0.6074 GB, free: 15.03 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000849/0.6739, allocations: 383.1 kB / 0.6078 GB, free: 14.65 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007973/0.6747, allocations: 393.2 kB / 0.6081 GB, free: 14.26 MB / 462.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.618e-06/0.6747, allocations: 7.984 kB / 0.6081 GB, free: 14.25 MB / 462.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.384e-06/0.6747, allocations: 0 / 0.6081 GB, free: 14.25 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.973e-06/0.6748, allocations: 7.656 kB / 0.6082 GB, free: 14.25 MB / 462.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.007876/0.6826, allocations: 4.757 MB / 0.6128 GB, free: 9.238 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.647e-05/0.6827, allocations: 15.98 kB / 0.6128 GB, free: 9.223 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003005/0.683, allocations: 107.8 kB / 0.6129 GB, free: 9.117 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 2.193e-05/0.683, allocations: 23.98 kB / 0.6129 GB, free: 9.094 MB / 462.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.019e-05/0.6831, allocations: 27.94 kB / 0.613 GB, free: 9.066 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.039e-05/0.6831, allocations: 23.94 kB / 0.613 GB, free: 9.043 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.307e-05/0.6831, allocations: 19.94 kB / 0.613 GB, free: 9.023 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002054/0.6852, allocations: 1.244 MB / 0.6142 GB, free: 7.566 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001163/0.6864, allocations: 480 kB / 0.6147 GB, free: 7.074 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003999/0.6868, allocations: 28.19 kB / 0.6147 GB, free: 7.047 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009423/0.6877, allocations: 43.97 kB / 0.6147 GB, free: 7.004 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001292/0.6879, allocations: 83.56 kB / 0.6148 GB, free: 6.922 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.153e-05/0.6879, allocations: 32 kB / 0.6149 GB, free: 6.891 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.006341/0.6943, allocations: 3.14 MB / 0.6179 GB, free: 3.758 MB / 462.1 MB
Notification: Performance of sort global known variables: time 6.91e-07/0.6943, allocations: 0 / 0.6179 GB, free: 3.758 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.005635/0.6999, allocations: 0.8298 MB / 0.6187 GB, free: 2.926 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.002587/0.7026, allocations: 1.83 MB / 0.6205 GB, free: 0.8867 MB / 462.1 MB
Notification: Performance of simCode: created initialization part: time 0.003917/0.7065, allocations: 1.935 MB / 0.6224 GB, free: 14.96 MB / 478.1 MB
Notification: Performance of simCode: created event and clocks part: time 1.409e-05/0.7065, allocations: 4 kB / 0.6224 GB, free: 14.96 MB / 478.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0003825/0.7069, allocations: 262.4 kB / 0.6227 GB, free: 14.71 MB / 478.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004/0.7109, allocations: 0.6695 MB / 0.6233 GB, free: 14.05 MB / 478.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.07299/0.7839, allocations: 42.79 MB / 0.6651 GB, free: 12.36 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003728/0.7877, allocations: 2.838 MB / 0.6679 GB, free: 9.496 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.003229/0.791, allocations: 1.154 MB / 0.669 GB, free: 8.34 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002471/0.7934, allocations: 1.702 MB / 0.6707 GB, free: 6.637 MB / 0.5137 GB
Notification: Performance of SimCode: time 1.282e-06/0.7935, allocations: 0 / 0.6707 GB, free: 6.637 MB / 0.5137 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3486/1.142, allocations: 67.73 MB / 0.7368 GB, free: 189 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate platform static: time 10.01/11.15, allocations: 5.531 kB / 0.7368 GB, free: 189 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 & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies_res.csv --start-time 0 --stop-time 1.1 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00044 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe 2>&1)