Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.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.Forces.ForceWithTwoMasses,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_ForceWithTwoMasses",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Forces.ForceWithTwoMasses,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_ForceWithTwoMasses",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001734/0.001734, 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.001994/0.001994, 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.521/1.521, 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.2308/0.2308, allocations: 46.11 MB / 344.2 MB, free: 11.6 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.002261/0.002261, allocations: 83.66 kB / 417.8 MB, free: 19.64 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.114/0.1163, allocations: 62.45 MB / 480.2 MB, free: 5.16 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.ForceWithTwoMasses): time 0.01236/0.1287, allocations: 10.34 MB / 490.5 MB, free: 10.77 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00773/0.1365, allocations: 3.884 MB / 494.4 MB, free: 6.875 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001447/0.1379, allocations: 55.88 kB / 494.5 MB, free: 6.82 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004031/0.142, allocations: 2.071 MB / 496.6 MB, free: 4.742 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004282/0.1463, allocations: 1.665 MB / 498.2 MB, free: 3.07 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001676/0.148, allocations: 0.7095 MB / 498.9 MB, free: 2.359 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005803/0.1538, allocations: 3.989 MB / 0.4911 GB, free: 14.36 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003908/0.1578, allocations: 2.602 MB / 0.4937 GB, free: 11.71 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002408/0.1602, allocations: 1.3 MB / 0.4949 GB, free: 10.4 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002889/0.1631, allocations: 1.85 MB / 0.4968 GB, free: 8.547 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006954/0.1639, allocations: 264 kB / 0.497 GB, free: 8.289 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002336/0.1662, allocations: 1.166 MB / 0.4981 GB, free: 7.121 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001885/0.1681, allocations: 1.636 MB / 0.4997 GB, free: 5.48 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002714/0.1708, allocations: 1.957 MB / 0.5017 GB, free: 3.512 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.2231/0.3939, allocations: 6.102 MB / 0.5076 GB, free: 20.09 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.125e-06/0.394, allocations: 0.75 kB / 0.5076 GB, free: 20.09 MB / 382.1 MB
Notification: Performance of FrontEnd: time 1.302e-06/0.394, allocations: 0 / 0.5076 GB, free: 20.09 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 0.0002023/0.3942, allocations: 1.406 kB / 0.5076 GB, free: 20.09 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: 1319
 * Number of variables: 1319
Notification: Performance of Generate backend data structure: time 0.01223/0.4064, allocations: 4.321 MB / 0.5118 GB, free: 18.36 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.908e-05/0.4065, allocations: 10.33 kB / 0.5118 GB, free: 18.36 MB / 382.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001314/0.4078, allocations: 0.6233 MB / 0.5125 GB, free: 18.29 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09046/0.4983, allocations: 7.034 MB / 0.5193 GB, free: 17.98 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00686/0.5052, allocations: 2.582 MB / 0.5218 GB, free: 17.4 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001627/0.5054, allocations: 192.5 kB / 0.522 GB, free: 17.39 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001163/0.5066, allocations: 275.6 kB / 0.5223 GB, free: 17.39 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01565/0.5222, allocations: 8.52 MB / 0.5306 GB, free: 13.62 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001231/0.5224, allocations: 47.27 kB / 0.5307 GB, free: 13.62 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004039/0.5228, allocations: 125.9 kB / 0.5308 GB, free: 13.56 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002513/0.5253, allocations: 1.734 MB / 0.5325 GB, free: 12.91 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01156/0.5369, allocations: 7.175 MB / 0.5395 GB, free: 8.297 MB / 382.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0403/0.5772, allocations: 33.93 MB / 0.5726 GB, free: 5.766 MB / 414.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.009414/0.5867, allocations: 3.438 MB / 0.576 GB, free: 2.18 MB / 414.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002503/0.5892, allocations: 1.654 MB / 0.5776 GB, free: 0.5195 MB / 414.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01186/0.6011, allocations: 7.303 MB / 0.5847 GB, free: 8.992 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.114e-05/0.6012, allocations: 58.66 kB / 0.5848 GB, free: 8.93 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=151): time 4.859e-06/0.6012, allocations: 0 / 0.5848 GB, free: 8.93 MB / 430.1 MB
Notification: Performance of matching and sorting (n=188): time 0.06542/0.6666, allocations: 19.02 MB / 0.6033 GB, free: 5.832 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001402/0.6668, allocations: 201.2 kB / 0.6035 GB, free: 5.594 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005897/0.6727, allocations: 3.735 MB / 0.6072 GB, free: 1.871 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0004396/0.6732, allocations: 60.83 kB / 0.6072 GB, free: 1.809 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.00245/0.6756, allocations: 2.819 MB / 0.61 GB, free: 15.01 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0009596/0.6766, allocations: 0.7125 MB / 0.6107 GB, free: 14.3 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001828/0.6784, allocations: 1.165 MB / 0.6118 GB, free: 13.12 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001232/0.6786, allocations: 305.1 kB / 0.6121 GB, free: 12.82 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003445/0.682, allocations: 2.1 MB / 0.6142 GB, free: 10.72 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006425/0.6885, allocations: 5.651 MB / 0.6197 GB, free: 3.352 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01066/0.6992, allocations: 8.42 MB / 0.6279 GB, free: 9.695 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.09e-05/0.6993, allocations: 36 kB / 0.6279 GB, free: 9.66 MB / 478.1 MB
Notification: Performance of matching and sorting (n=763) (initialization): time 0.01912/0.7184, allocations: 8.695 MB / 0.6364 GB, free: 1.188 MB / 478.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001011/0.7185, allocations: 62.19 kB / 0.6365 GB, free: 1.129 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001671/0.7187, allocations: 124 kB / 0.6366 GB, free: 1.008 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005225/0.724, allocations: 1.396 MB / 0.638 GB, free: 15.61 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00451/0.7285, allocations: 1.302 MB / 0.6392 GB, free: 14.3 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01437/0.7429, allocations: 9.224 MB / 0.6483 GB, free: 4.949 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00462/0.7476, allocations: 303.5 kB / 0.6485 GB, free: 4.652 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009846/0.7486, allocations: 360 kB / 0.6489 GB, free: 4.301 MB / 494.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 570
 * Number of states: 0 ()
 * Number of discrete variables: 36 (FixedTranslation2.color[3],FixedTranslation2.color[2],FixedTranslation2.color[1],force.massColor[3],force.massColor[2],force.massColor[1],force.color_b[3],force.color_b[2],force.color_b[1],force.color_a[3],force.color_a[2],force.color_a[1],FixedTranslation1.color[3],FixedTranslation1.color[2],FixedTranslation1.color[1],BodyBox1.color[3],BodyBox1.color[2],BodyBox1.color[1],BodyBox1.body.sphereColor[3],BodyBox1.body.sphereColor[2],BodyBox1.body.sphereColor[1],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[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 (695):
 * Single equations (assignments): 691
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,44,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003614/0.7522, allocations: 1.885 MB / 0.6507 GB, free: 2.402 MB / 494.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007416/0.753, allocations: 335.8 kB / 0.651 GB, free: 2.07 MB / 494.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008827/0.7618, allocations: 3.781 MB / 0.6547 GB, free: 14.27 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.916e-05/0.7619, allocations: 20 kB / 0.6548 GB, free: 14.25 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.351e-05/0.7619, allocations: 8 kB / 0.6548 GB, free: 14.25 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.385e-05/0.7619, allocations: 7.969 kB / 0.6548 GB, free: 14.24 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0173/0.7792, allocations: 8.18 MB / 0.6628 GB, free: 6.043 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.005e-05/0.7793, allocations: 0 / 0.6628 GB, free: 6.043 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001293/0.7806, allocations: 254.2 kB / 0.663 GB, free: 5.797 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004629/0.7852, allocations: 1.187 MB / 0.6642 GB, free: 4.605 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001924/0.7854, allocations: 34.19 kB / 0.6642 GB, free: 4.574 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01325/0.7987, allocations: 8.48 MB / 0.6725 GB, free: 11.96 MB / 0.5137 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.274e-06/0.7987, allocations: 0 / 0.6725 GB, free: 11.96 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01264/0.8113, allocations: 6.291 MB / 0.6786 GB, free: 5.641 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001188/0.8126, allocations: 368.7 kB / 0.679 GB, free: 5.258 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007168/0.8133, allocations: 35.98 kB / 0.679 GB, free: 5.223 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00146/0.8148, allocations: 83.91 kB / 0.6791 GB, free: 5.141 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003909/0.8152, allocations: 111.7 kB / 0.6792 GB, free: 5.035 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001495/0.8153, allocations: 55.98 kB / 0.6792 GB, free: 4.98 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.004162/0.8195, allocations: 2.328 MB / 0.6815 GB, free: 2.668 MB / 0.5137 GB
Notification: Performance of sort global known variables: time 2.31e-07/0.8195, allocations: 64 / 0.6815 GB, free: 2.668 MB / 0.5137 GB
Notification: Performance of remove unused functions: time 0.005249/0.8248, allocations: 0.9514 MB / 0.6824 GB, free: 1.719 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 4
 * Number of states: 2 (revolute.phi,revolute.w)
 * 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 (130):
 * Single equations (assignments): 126
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,36,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.01272/0.8375, allocations: 6.98 MB / 0.6893 GB, free: 10.7 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01128/0.8489, allocations: 6.135 MB / 0.6953 GB, free: 4.586 MB / 0.5294 GB
Notification: Performance of simCode: created event and clocks part: time 7.404e-06/0.8489, allocations: 7.938 kB / 0.6953 GB, free: 4.578 MB / 0.5294 GB
Notification: Performance of simCode: created simulation system equations: time 0.00287/0.8518, allocations: 1.738 MB / 0.697 GB, free: 2.832 MB / 0.5294 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002755/0.8546, allocations: 412.9 kB / 0.6974 GB, free: 2.438 MB / 0.5294 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2743/1.129, allocations: 49.22 MB / 0.7454 GB, free: 179.2 MB / 0.545 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0025/1.131, allocations: 2.962 MB / 0.7483 GB, free: 178 MB / 0.545 GB
Notification: Performance of simCode: alias equations: time 0.003831/1.135, allocations: 1.815 MB / 0.7501 GB, free: 177.7 MB / 0.545 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00185/1.137, allocations: 1.647 MB / 0.7517 GB, free: 176.3 MB / 0.545 GB
Notification: Performance of SimCode: time 9.42e-07/1.137, allocations: 0 / 0.7517 GB, free: 176.3 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1002/1.237, allocations: 70.26 MB / 0.8203 GB, free: 107.1 MB / 0.545 GB
Notification: Performance of buildModelFMU: Generate platform static: time 12.17/13.4, allocations: 3.938 kB / 0.8203 GB, free: 107.1 MB / 0.545 GB
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_ForceWithTwoMasses_fmu --startTime=0 --stopTime=3 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_ForceWithTwoMasses.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.ForceWithTwoMasses.pipe 2>&1)