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.00143/0.00143, allocations: 106.5 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.001562/0.001562, allocations: 221.8 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.313/1.313, allocations: 230.2 MB / 256.1 MB, free: 8.746 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.1987/0.1987, allocations: 46.89 MB / 359.3 MB, free: 4.98 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.002215/0.002215, allocations: 87.84 kB / 0.494 GB, free: 9.121 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2508/0.253, allocations: 65.98 MB / 0.5584 GB, free: 5.246 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.Bodies): time 0.01474/0.2677, allocations: 14.72 MB / 0.5728 GB, free: 6.461 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0087/0.2764, allocations: 4.712 MB / 0.5774 GB, free: 1.73 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001302/0.2777, allocations: 99.69 kB / 0.5775 GB, free: 1.633 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005298/0.283, allocations: 3.146 MB / 0.5805 GB, free: 14.48 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004125/0.2871, allocations: 2.081 MB / 0.5826 GB, free: 12.39 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001704/0.2888, allocations: 1.022 MB / 0.5836 GB, free: 11.37 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008508/0.2973, allocations: 6.93 MB / 0.5903 GB, free: 4.422 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002989/0.3003, allocations: 2.587 MB / 0.5929 GB, free: 1.77 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002501/0.3028, allocations: 1.83 MB / 0.5947 GB, free: 15.93 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003568/0.3064, allocations: 2.832 MB / 0.5974 GB, free: 13.09 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005769/0.307, allocations: 356 kB / 0.5978 GB, free: 12.75 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001587/0.3086, allocations: 0.9053 MB / 0.5986 GB, free: 11.84 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002066/0.3106, allocations: 2.361 MB / 0.6009 GB, free: 9.473 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00227/0.3129, allocations: 2.374 MB / 0.6033 GB, free: 7.086 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006413/0.3193, allocations: 6.986 MB / 0.6101 GB, free: 76 kB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.827e-06/0.3193, allocations: 0 / 0.6101 GB, free: 76 kB / 446.1 MB
Notification: Performance of FrontEnd: time 1.142e-06/0.3193, allocations: 0 / 0.6101 GB, free: 76 kB / 446.1 MB
Notification: Performance of Transformations before backend: time 9.156e-05/0.3194, allocations: 0 / 0.6101 GB, free: 76 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.01065/0.3301, allocations: 5.563 MB / 0.6155 GB, free: 10.43 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.539e-05/0.3301, allocations: 12.03 kB / 0.6155 GB, free: 10.42 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0006043/0.3307, allocations: 0.512 MB / 0.616 GB, free: 9.898 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09845/0.4292, allocations: 8.222 MB / 0.6241 GB, free: 1.664 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009765/0.4389, allocations: 5.221 MB / 0.6292 GB, free: 12.37 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001651/0.4391, allocations: 205.6 kB / 0.6294 GB, free: 12.17 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004937/0.4396, allocations: 299.7 kB / 0.6296 GB, free: 11.88 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01044/0.45, allocations: 6.902 MB / 0.6364 GB, free: 4.414 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001194/0.4501, allocations: 59.59 kB / 0.6364 GB, free: 4.355 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000266/0.4504, allocations: 123.9 kB / 0.6366 GB, free: 4.234 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001464/0.4519, allocations: 1.006 MB / 0.6375 GB, free: 3.227 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008807/0.4607, allocations: 4.928 MB / 0.6424 GB, free: 14.3 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.02955/0.4902, allocations: 27.31 MB / 0.669 GB, free: 2.246 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00238/0.4926, allocations: 0.7271 MB / 0.6697 GB, free: 1.473 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003026/0.4929, allocations: 208.5 kB / 0.6699 GB, free: 1.254 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 3.413e-05/0.4929, allocations: 15.91 kB / 0.6699 GB, free: 1.238 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.684e-05/0.493, allocations: 38.56 kB / 0.67 GB, free: 1.191 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=37): time 1.854e-06/0.493, allocations: 0 / 0.67 GB, free: 1.191 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=37): time 0.006276/0.4993, allocations: 1.974 MB / 0.6719 GB, free: 15.21 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001196/0.4994, allocations: 235.3 kB / 0.6721 GB, free: 14.92 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005771/0.5051, allocations: 4.762 MB / 0.6768 GB, free: 10.16 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 3.679e-05/0.5052, allocations: 37.7 kB / 0.6768 GB, free: 10.12 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002127/0.5073, allocations: 2.829 MB / 0.6796 GB, free: 7.266 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001579/0.5075, allocations: 206.8 kB / 0.6798 GB, free: 7.062 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007641/0.5082, allocations: 0.7302 MB / 0.6805 GB, free: 6.324 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001163/0.5083, allocations: 305.1 kB / 0.6808 GB, free: 6.023 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001172/0.5095, allocations: 0.7915 MB / 0.6816 GB, free: 5.23 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003079/0.5126, allocations: 3.425 MB / 0.6849 GB, free: 488 kB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004648/0.5172, allocations: 4.538 MB / 0.6893 GB, free: 10.61 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.586e-05/0.5173, allocations: 24 kB / 0.6894 GB, free: 10.59 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=449) (initialization): time 0.006067/0.5233, allocations: 4.069 MB / 0.6933 GB, free: 6.516 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 5.446e-05/0.5234, allocations: 48.06 kB / 0.6934 GB, free: 6.469 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.055e-05/0.5235, allocations: 96 kB / 0.6935 GB, free: 6.375 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001058/0.5236, allocations: 120.4 kB / 0.6936 GB, free: 6.258 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002092/0.5257, allocations: 0.8577 MB / 0.6944 GB, free: 5.398 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001487/0.5258, allocations: 183.2 kB / 0.6946 GB, free: 5.219 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002789/0.5286, allocations: 0.5585 MB / 0.6951 GB, free: 4.656 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000231/0.5289, allocations: 239.9 kB / 0.6954 GB, free: 4.422 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.002046/0.5309, allocations: 1.621 MB / 0.6969 GB, free: 2.789 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005351/0.5314, allocations: 401.4 kB / 0.6973 GB, free: 2.395 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0005295/0.532, allocations: 397.2 kB / 0.6977 GB, free: 2.004 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.891e-06/0.532, allocations: 4 kB / 0.6977 GB, free: 2 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.233e-06/0.532, allocations: 0 / 0.6977 GB, free: 2 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.076e-06/0.532, allocations: 7.984 kB / 0.6977 GB, free: 1.992 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005733/0.5377, allocations: 4.752 MB / 0.7024 GB, free: 12.98 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.368e-05/0.5377, allocations: 12 kB / 0.7024 GB, free: 12.96 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002817/0.538, allocations: 115.8 kB / 0.7025 GB, free: 12.85 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.807e-05/0.538, allocations: 20 kB / 0.7025 GB, free: 12.83 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.414e-05/0.5381, allocations: 24.69 kB / 0.7025 GB, free: 12.81 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.399e-05/0.5381, allocations: 31.86 kB / 0.7026 GB, free: 12.78 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.45e-05/0.5381, allocations: 23.94 kB / 0.7026 GB, free: 12.75 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001228/0.5393, allocations: 1.257 MB / 0.7038 GB, free: 11.28 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006896/0.54, allocations: 0.4904 MB / 0.7043 GB, free: 10.77 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002939/0.5403, allocations: 31.98 kB / 0.7043 GB, free: 10.73 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008812/0.5412, allocations: 35.97 kB / 0.7044 GB, free: 10.7 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.487e-05/0.5413, allocations: 83.58 kB / 0.7044 GB, free: 10.62 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.668e-05/0.5413, allocations: 36.17 kB / 0.7045 GB, free: 10.58 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.004092/0.5454, allocations: 3.14 MB / 0.7075 GB, free: 7.449 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.31e-07/0.5454, allocations: 3.938 kB / 0.7075 GB, free: 7.445 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.002871/0.5483, allocations: 0.8305 MB / 0.7083 GB, free: 6.613 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.001596/0.5499, allocations: 1.85 MB / 0.7102 GB, free: 4.543 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.002724/0.5526, allocations: 1.883 MB / 0.712 GB, free: 2.684 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 4.328e-06/0.5526, allocations: 0 / 0.712 GB, free: 2.684 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003009/0.5529, allocations: 254.3 kB / 0.7122 GB, free: 2.438 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002652/0.5556, allocations: 0.6691 MB / 0.7129 GB, free: 1.77 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2384/0.794, allocations: 34.72 MB / 0.7468 GB, free: 146.2 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001806/0.7958, allocations: 2.846 MB / 0.7496 GB, free: 143.7 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.0019/0.7977, allocations: 1.154 MB / 0.7507 GB, free: 142.5 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001184/0.7989, allocations: 1.702 MB / 0.7524 GB, free: 140.8 MB / 0.5606 GB
Notification: Performance of SimCode: time 9.52e-07/0.7989, allocations: 0 / 0.7524 GB, free: 140.8 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.08075/0.8796, allocations: 66.93 MB / 0.8177 GB, free: 74.34 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 13.95/14.83, allocations: 4.969 kB / 0.8177 GB, free: 74.34 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 & /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) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 17.52725169500627]