Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.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.00136/0.00136, allocations: 105.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.001475/0.001475, allocations: 220.3 kB / 22.92 MB, free: 5.531 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.283/1.283, allocations: 230.2 MB / 256.1 MB, free: 8.758 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.202/0.202, allocations: 46.89 MB / 359.3 MB, free: 4.984 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.Forces.FrameTorques,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_FrameTorques",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Forces.FrameTorques,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_FrameTorques",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.002165/0.002165, allocations: 79.86 kB / 0.494 GB, free: 9.098 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2522/0.2544, allocations: 65.98 MB / 0.5584 GB, free: 5.09 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.FrameTorques): time 0.01353/0.2679, allocations: 12.75 MB / 0.5709 GB, free: 8.285 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008638/0.2765, allocations: 4.544 MB / 0.5753 GB, free: 3.723 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001681/0.2782, allocations: 87.75 kB / 0.5754 GB, free: 3.637 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00625/0.2845, allocations: 3.765 MB / 0.5791 GB, free: 15.86 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004973/0.2894, allocations: 2.252 MB / 0.5813 GB, free: 13.6 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001981/0.2914, allocations: 0.9904 MB / 0.5822 GB, free: 12.61 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00759/0.299, allocations: 6.039 MB / 0.5881 GB, free: 6.559 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003795/0.3028, allocations: 3.261 MB / 0.5913 GB, free: 3.227 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002702/0.3055, allocations: 1.963 MB / 0.5932 GB, free: 1.258 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003763/0.3093, allocations: 2.827 MB / 0.596 GB, free: 14.42 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008175/0.3101, allocations: 408 kB / 0.5964 GB, free: 14.02 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002403/0.3125, allocations: 1.486 MB / 0.5978 GB, free: 12.54 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002255/0.3147, allocations: 2.505 MB / 0.6003 GB, free: 10.02 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002729/0.3175, allocations: 2.769 MB / 0.603 GB, free: 7.238 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00929/0.3268, allocations: 8.79 MB / 0.6116 GB, free: 14.41 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.771e-06/0.3268, allocations: 3.938 kB / 0.6116 GB, free: 14.41 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.923e-06/0.3268, allocations: 0 / 0.6116 GB, free: 14.41 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0001989/0.327, allocations: 0 / 0.6116 GB, free: 14.41 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1729
 * Number of variables: 1729
Notification: Performance of Generate backend data structure: time 0.01277/0.3397, allocations: 6.351 MB / 0.6178 GB, free: 7.973 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.272e-05/0.3398, allocations: 15.95 kB / 0.6178 GB, free: 7.957 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001283/0.3411, allocations: 0.8228 MB / 0.6186 GB, free: 7.125 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03568/0.3767, allocations: 6.059 MB / 0.6245 GB, free: 1.055 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00802/0.3848, allocations: 4.158 MB / 0.6286 GB, free: 12.84 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002033/0.385, allocations: 279.3 kB / 0.6288 GB, free: 12.57 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007121/0.3857, allocations: 379.8 kB / 0.6292 GB, free: 12.2 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01749/0.4032, allocations: 11.48 MB / 0.6404 GB, free: 15.87 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001406/0.4033, allocations: 55.58 kB / 0.6405 GB, free: 15.81 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004494/0.4038, allocations: 183.7 kB / 0.6406 GB, free: 15.64 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003374/0.4071, allocations: 2.151 MB / 0.6427 GB, free: 13.48 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01716/0.4243, allocations: 9.364 MB / 0.6519 GB, free: 4.102 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0521/0.4764, allocations: 45.36 MB / 0.6962 GB, free: 5.469 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009555/0.4859, allocations: 3.675 MB / 0.6998 GB, free: 1.645 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00253/0.4885, allocations: 1.861 MB / 0.7016 GB, free: 15.77 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.008686/0.4972, allocations: 5.645 MB / 0.7071 GB, free: 10.03 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.455e-05/0.4972, allocations: 63.97 kB / 0.7072 GB, free: 9.957 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=150): time 4.148e-06/0.4972, allocations: 0 / 0.7072 GB, free: 9.957 MB / 0.545 GB
Notification: Performance of matching and sorting (n=162): time 0.03801/0.5352, allocations: 15.92 MB / 0.7227 GB, free: 9.863 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001275/0.5353, allocations: 226.1 kB / 0.7229 GB, free: 9.59 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007283/0.5426, allocations: 5.64 MB / 0.7284 GB, free: 3.969 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002796/0.5429, allocations: 49.7 kB / 0.7285 GB, free: 3.914 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.1959/0.7388, allocations: 3.896 MB / 0.7323 GB, free: 155.3 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008006/0.7396, allocations: 0.6776 MB / 0.7329 GB, free: 155.3 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001957/0.7416, allocations: 1.346 MB / 0.7343 GB, free: 155.3 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 3.141e-05/0.7416, allocations: 305.4 kB / 0.7345 GB, free: 155 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002463/0.7441, allocations: 2.078 MB / 0.7366 GB, free: 154.4 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003143/0.7472, allocations: 6.315 MB / 0.7427 GB, free: 148.1 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005588/0.7528, allocations: 9.135 MB / 0.7517 GB, free: 140 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.053e-05/0.7529, allocations: 41.41 kB / 0.7517 GB, free: 140 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=860) (initialization): time 0.009596/0.7625, allocations: 8.604 MB / 0.7601 GB, free: 135.7 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 6.323e-05/0.7625, allocations: 72 kB / 0.7602 GB, free: 135.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.75e-05/0.7626, allocations: 151.9 kB / 0.7603 GB, free: 135.5 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001296/0.7639, allocations: 0.7258 MB / 0.761 GB, free: 134.9 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00326/0.7672, allocations: 1.507 MB / 0.7625 GB, free: 133.6 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005255/0.7725, allocations: 6.967 MB / 0.7693 GB, free: 127.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003599/0.7761, allocations: 342.1 kB / 0.7696 GB, free: 126.9 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003512/0.7764, allocations: 414.8 kB / 0.77 GB, free: 126.5 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 686
 * Number of states: 0 ()
 * Number of discrete variables: 49 (frameTorque2.arrow.twoHeadedArrow,frameTorque2.arrow.headAtOrigin,frameTorque1.arrow.twoHeadedArrow,frameTorque1.arrow.headAtOrigin,Revolute2.cylinderColor[3],Revolute2.cylinderColor[2],Revolute2.cylinderColor[1],frameTorque2.color[3],frameTorque2.color[2],frameTorque2.color[1],frameTorque1.color[3],frameTorque1.color[2],frameTorque1.color[1],FixedRotation1.color[3],FixedRotation1.color[2],FixedRotation1.color[1],fixed2.color[3],fixed2.color[2],fixed2.color[1],body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[1],fixed1.color[3],fixed1.color[2],fixed1.color[1],body1.color[3],body1.color[2],body1.color[1],body1.body.sphereColor[3],body1.body.sphereColor[2],body1.body.sphereColor[1],Revolute1.cylinderColor[3],Revolute1.cylinderColor[2],Revolute1.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 (798):
 * Single equations (assignments): 791
 * Array equations: 5
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,14,100.0%), (1,14,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.002367/0.7788, allocations: 2.457 MB / 0.7724 GB, free: 124.4 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005263/0.7793, allocations: 487.1 kB / 0.7729 GB, free: 124 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0034/0.7827, allocations: 2.641 MB / 0.7755 GB, free: 121.4 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.132e-05/0.7827, allocations: 21.08 kB / 0.7755 GB, free: 121.4 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.905e-06/0.7827, allocations: 4 kB / 0.7755 GB, free: 121.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.127e-05/0.7827, allocations: 11.97 kB / 0.7755 GB, free: 121.4 MB / 0.5606 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.009429/0.7922, allocations: 7.463 MB / 0.7828 GB, free: 113.9 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.886e-06/0.7922, allocations: 4 kB / 0.7828 GB, free: 113.9 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001032/0.7932, allocations: 255.9 kB / 0.783 GB, free: 113.6 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001096/0.7943, allocations: 0.4895 MB / 0.7835 GB, free: 113.1 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.828e-05/0.7944, allocations: 24 kB / 0.7835 GB, free: 113.1 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004597/0.799, allocations: 6.297 MB / 0.7897 GB, free: 106.5 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.697e-06/0.799, allocations: 9.5 kB / 0.7897 GB, free: 106.5 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004711/0.8037, allocations: 4.962 MB / 0.7945 GB, free: 101.5 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007248/0.8044, allocations: 0.536 MB / 0.7951 GB, free: 101 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003694/0.8048, allocations: 35.59 kB / 0.7951 GB, free: 100.9 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00126/0.8061, allocations: 71.92 kB / 0.7952 GB, free: 100.9 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001748/0.8062, allocations: 96.81 kB / 0.7953 GB, free: 100.8 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.318e-05/0.8063, allocations: 47.98 kB / 0.7953 GB, free: 100.7 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.003495/0.8098, allocations: 3.609 MB / 0.7988 GB, free: 97.13 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.1e-07/0.8098, allocations: 0 / 0.7988 GB, free: 97.13 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.003865/0.8137, allocations: 1.092 MB / 0.7999 GB, free: 96.04 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 4 (Revolute1.phi,Revolute1.w,Revolute2.phi,Revolute2.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 (120):
 * Single equations (assignments): 115
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,11,100.0%), (1,11,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.004779/0.8185, allocations: 5.603 MB / 0.8054 GB, free: 90.38 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.005342/0.8238, allocations: 4.985 MB / 0.8102 GB, free: 85.42 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 3.857e-06/0.8238, allocations: 0 / 0.8102 GB, free: 85.42 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.001356/0.8252, allocations: 1.407 MB / 0.8116 GB, free: 83.99 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002457/0.8276, allocations: 0.6284 MB / 0.8122 GB, free: 83.38 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.04532/0.8729, allocations: 54.05 MB / 0.865 GB, free: 22.62 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002684/0.8756, allocations: 3.769 MB / 0.8687 GB, free: 18.82 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.003302/0.8789, allocations: 2.125 MB / 0.8708 GB, free: 16.69 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001812/0.8807, allocations: 2.303 MB / 0.873 GB, free: 14.39 MB / 0.5606 GB
Notification: Performance of SimCode: time 7.92e-07/0.8807, allocations: 1.859 kB / 0.873 GB, free: 14.39 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1408/1.022, allocations: 85.13 MB / 0.9562 GB, free: 9.684 MB / 0.6387 GB
Notification: Performance of buildModelFMU: Generate platform static: time 25.23/26.25, allocations: 7.375 kB / 0.9562 GB, free: 9.68 MB / 0.6387 GB
"
[Timeout remaining time 634]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques_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_Forces_FrameTorques.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameTorques.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 28.827237746998435]