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) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001191/0.001191, allocations: 105 kB / 19.74 MB, free: 2.566 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001289/0.001289, allocations: 215.7 kB / 22.92 MB, free: 5.535 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.305/1.305, allocations: 230.2 MB / 256.1 MB, free: 8.754 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.1974/0.1974, allocations: 46.89 MB / 359.3 MB, free: 4.953 MB / 286.1 MB
"
[Timeout remaining time 180]
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"}) [Timeout 660]
"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.002132/0.002132, allocations: 87.84 kB / 0.494 GB, free: 9.094 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2502/0.2523, allocations: 65.98 MB / 0.5584 GB, free: 5.168 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.Bodies): time 0.01468/0.267, allocations: 14.71 MB / 0.5728 GB, free: 6.395 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00823/0.2752, allocations: 4.711 MB / 0.5774 GB, free: 1.664 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001093/0.2763, allocations: 103.6 kB / 0.5775 GB, free: 1.562 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005097/0.2814, allocations: 3.146 MB / 0.5805 GB, free: 14.41 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00354/0.2849, allocations: 2.081 MB / 0.5826 GB, free: 12.32 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001545/0.2865, allocations: 1.014 MB / 0.5836 GB, free: 11.3 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007165/0.2937, allocations: 6.949 MB / 0.5904 GB, free: 4.34 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00263/0.2963, allocations: 2.575 MB / 0.5929 GB, free: 1.699 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002043/0.2983, allocations: 1.826 MB / 0.5946 GB, free: 15.87 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003111/0.3014, allocations: 2.832 MB / 0.5974 GB, free: 13.03 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005052/0.3019, allocations: 356 kB / 0.5978 GB, free: 12.68 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001366/0.3033, allocations: 0.9053 MB / 0.5986 GB, free: 11.77 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001994/0.3053, allocations: 2.361 MB / 0.6009 GB, free: 9.406 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002127/0.3074, allocations: 2.39 MB / 0.6033 GB, free: 7.004 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006514/0.3139, allocations: 6.966 MB / 0.6101 GB, free: 12 kB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.983e-06/0.3139, allocations: 4 kB / 0.6101 GB, free: 8 kB / 446.1 MB
Notification: Performance of FrontEnd: time 1.293e-06/0.3139, allocations: 0 / 0.6101 GB, free: 8 kB / 446.1 MB
Notification: Performance of Transformations before backend: time 9.475e-05/0.314, allocations: 0 / 0.6101 GB, free: 8 kB / 446.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.01093/0.325, allocations: 5.571 MB / 0.6155 GB, free: 10.36 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.854e-05/0.325, allocations: 16.03 kB / 0.6155 GB, free: 10.34 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0006365/0.3256, allocations: 0.5042 MB / 0.616 GB, free: 9.828 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09665/0.4223, allocations: 8.226 MB / 0.6241 GB, free: 1.59 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009517/0.4318, allocations: 5.217 MB / 0.6292 GB, free: 12.3 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001544/0.432, allocations: 201.6 kB / 0.6294 GB, free: 12.1 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004625/0.4324, allocations: 295.8 kB / 0.6296 GB, free: 11.81 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01002/0.4424, allocations: 6.906 MB / 0.6364 GB, free: 4.348 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000117/0.4426, allocations: 59.59 kB / 0.6364 GB, free: 4.289 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002733/0.4428, allocations: 127.9 kB / 0.6366 GB, free: 4.164 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001436/0.4443, allocations: 0.9978 MB / 0.6375 GB, free: 3.164 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008666/0.4529, allocations: 4.932 MB / 0.6423 GB, free: 14.23 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.03014/0.4831, allocations: 27.32 MB / 0.669 GB, free: 2.172 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002382/0.4855, allocations: 0.7191 MB / 0.6697 GB, free: 1.406 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003023/0.4858, allocations: 212.2 kB / 0.6699 GB, free: 1.184 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 2.899e-05/0.4858, allocations: 8.297 kB / 0.6699 GB, free: 1.176 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.222e-05/0.4858, allocations: 42.5 kB / 0.67 GB, free: 1.125 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=37): time 3.717e-06/0.4858, allocations: 4 kB / 0.67 GB, free: 1.121 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=37): time 0.006209/0.492, allocations: 1.974 MB / 0.6719 GB, free: 15.14 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001202/0.4922, allocations: 235.3 kB / 0.6721 GB, free: 14.85 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005893/0.4981, allocations: 4.758 MB / 0.6768 GB, free: 10.09 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 4.7e-05/0.4981, allocations: 41.64 kB / 0.6768 GB, free: 10.05 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002143/0.5002, allocations: 2.829 MB / 0.6796 GB, free: 7.195 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001668/0.5004, allocations: 206.8 kB / 0.6798 GB, free: 6.992 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007686/0.5012, allocations: 0.7263 MB / 0.6805 GB, free: 6.258 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001125/0.5013, allocations: 301.1 kB / 0.6808 GB, free: 5.961 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001177/0.5025, allocations: 0.7954 MB / 0.6816 GB, free: 5.164 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003031/0.5055, allocations: 3.418 MB / 0.6849 GB, free: 428 kB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004651/0.5101, allocations: 4.554 MB / 0.6893 GB, free: 10.54 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.896e-05/0.5102, allocations: 28 kB / 0.6894 GB, free: 10.51 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=449) (initialization): time 0.006215/0.5164, allocations: 4.064 MB / 0.6933 GB, free: 6.441 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 5.473e-05/0.5165, allocations: 44 kB / 0.6934 GB, free: 6.398 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.743e-05/0.5165, allocations: 96 kB / 0.6935 GB, free: 6.305 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001094/0.5167, allocations: 123.9 kB / 0.6936 GB, free: 6.184 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002056/0.5187, allocations: 0.8538 MB / 0.6944 GB, free: 5.328 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.000139/0.5188, allocations: 183.3 kB / 0.6946 GB, free: 5.148 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00272/0.5216, allocations: 0.5508 MB / 0.6951 GB, free: 4.594 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002211/0.5218, allocations: 239.9 kB / 0.6954 GB, free: 4.359 MB / 0.5294 GB
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.001978/0.5238, allocations: 1.621 MB / 0.6969 GB, free: 2.727 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004873/0.5243, allocations: 401.4 kB / 0.6973 GB, free: 2.332 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0005032/0.5248, allocations: 405.1 kB / 0.6977 GB, free: 1.934 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.46e-06/0.5248, allocations: 4.188 kB / 0.6977 GB, free: 1.93 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.865e-06/0.5248, allocations: 4 kB / 0.6977 GB, free: 1.926 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.539e-06/0.5248, allocations: 4 kB / 0.6977 GB, free: 1.922 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00569/0.5305, allocations: 4.752 MB / 0.7024 GB, free: 12.9 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.502e-05/0.5305, allocations: 16 kB / 0.7024 GB, free: 12.89 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002842/0.5308, allocations: 116.1 kB / 0.7025 GB, free: 12.77 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.752e-05/0.5308, allocations: 16.78 kB / 0.7025 GB, free: 12.76 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.68e-05/0.5308, allocations: 28 kB / 0.7025 GB, free: 12.73 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.686e-05/0.5308, allocations: 27.88 kB / 0.7026 GB, free: 12.7 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.402e-05/0.5309, allocations: 23.88 kB / 0.7026 GB, free: 12.68 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001208/0.5321, allocations: 1.261 MB / 0.7038 GB, free: 11.2 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006641/0.5327, allocations: 0.4943 MB / 0.7043 GB, free: 10.68 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002883/0.533, allocations: 31.98 kB / 0.7043 GB, free: 10.65 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008555/0.5339, allocations: 35.98 kB / 0.7044 GB, free: 10.62 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.815e-05/0.534, allocations: 83.56 kB / 0.7044 GB, free: 10.54 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.366e-05/0.534, allocations: 36 kB / 0.7045 GB, free: 10.5 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.004026/0.538, allocations: 3.132 MB / 0.7075 GB, free: 7.375 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.2e-07/0.538, allocations: 0 / 0.7075 GB, free: 7.375 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.002898/0.5409, allocations: 0.8305 MB / 0.7083 GB, free: 6.543 MB / 0.545 GB
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.00161/0.5425, allocations: 1.842 MB / 0.7101 GB, free: 4.48 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.002784/0.5453, allocations: 1.875 MB / 0.712 GB, free: 2.629 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 4.198e-06/0.5453, allocations: 0 / 0.712 GB, free: 2.629 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003108/0.5456, allocations: 262.3 kB / 0.7122 GB, free: 2.375 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002829/0.5485, allocations: 0.6694 MB / 0.7129 GB, free: 1.707 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2363/0.7848, allocations: 34.72 MB / 0.7468 GB, free: 146.4 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00183/0.7866, allocations: 2.85 MB / 0.7496 GB, free: 143.9 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001894/0.7885, allocations: 1.15 MB / 0.7507 GB, free: 142.7 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001162/0.7897, allocations: 1.702 MB / 0.7524 GB, free: 141 MB / 0.5606 GB
Notification: Performance of SimCode: time 8.81e-07/0.7897, allocations: 0 / 0.7524 GB, free: 141 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07885/0.8685, allocations: 66.93 MB / 0.8177 GB, free: 74.52 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 13.91/14.78, allocations: 11.66 kB / 0.8177 GB, free: 74.52 MB / 0.5606 GB
"
[Timeout remaining time 645]
(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.000219912 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Bodies.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Bodies.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 17.376391768993926]