Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.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.00181/0.00181, allocations: 122.6 kB / 18.5 MB, free: 4.578 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.002222/0.002222, allocations: 206.6 kB / 21.45 MB, free: 1.629 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.474/1.474, allocations: 230.5 MB / 254.8 MB, free: 15.11 MB / 206.1 MB " [Timeout remaining time 178] Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/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(Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Mechanics_MultiBody_Examples_Elementary_FreeBody",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Mechanics_MultiBody_Examples_Elementary_FreeBody",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions. Warning: Requested package Complex 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. Warning: Requested package ModelicaServices 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.002236/0.002236, allocations: 75.81 kB / 373.7 MB, free: 10.21 MB / 302.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1075/0.1097, allocations: 53.32 MB / 427 MB, free: 4.793 MB / 350.1 MB Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody): time 0.2283/0.338, allocations: 10.47 MB / 437.5 MB, free: 5.668 MB / 350.1 MB Notification: Performance of NFInst.instExpressions: time 0.007474/0.3455, allocations: 3.853 MB / 441.3 MB, free: 3.863 MB / 350.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.001952/0.3474, allocations: 77.69 kB / 441.4 MB, free: 3.84 MB / 350.1 MB Notification: Performance of NFTyping.typeComponents: time 0.00457/0.352, allocations: 2.33 MB / 443.7 MB, free: 2.789 MB / 350.1 MB Notification: Performance of NFTyping.typeBindings: time 0.00502/0.357, allocations: 1.693 MB / 445.4 MB, free: 2.023 MB / 350.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.001883/0.3589, allocations: 0.6073 MB / 446 MB, free: 1.758 MB / 350.1 MB Notification: Performance of NFFlatten.flatten: time 0.007289/0.3662, allocations: 3.491 MB / 449.5 MB, free: 464 kB / 350.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.005025/0.3712, allocations: 2.568 MB / 452.1 MB, free: 15.36 MB / 366.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.003923/0.3751, allocations: 1.375 MB / 453.5 MB, free: 14.77 MB / 366.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.004225/0.3794, allocations: 1.943 MB / 455.4 MB, free: 13.89 MB / 366.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001384/0.3807, allocations: 291.3 kB / 455.7 MB, free: 13.89 MB / 366.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.003735/0.3845, allocations: 1.168 MB / 456.9 MB, free: 13.41 MB / 366.1 MB Notification: Performance of NFScalarize.scalarize: time 0.003198/0.3877, allocations: 1.725 MB / 458.6 MB, free: 12.39 MB / 366.1 MB Notification: Performance of NFVerifyModel.verify: time 0.005843/0.3935, allocations: 1.947 MB / 460.5 MB, free: 10.92 MB / 366.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01247/0.406, allocations: 6.229 MB / 466.8 MB, free: 6.594 MB / 366.1 MB Notification: Performance of FrontEnd - DAE generated: time 7.625e-06/0.406, allocations: 0 / 466.8 MB, free: 6.594 MB / 366.1 MB Notification: Performance of FrontEnd: time 1.291e-05/0.406, allocations: 0 / 466.8 MB, free: 6.594 MB / 366.1 MB Notification: Performance of Transformations before backend: time 0.0003288/0.4063, allocations: 0 / 466.8 MB, free: 6.594 MB / 366.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1330 * Number of variables: 1330 Notification: Performance of Generate backend data structure: time 0.01495/0.4213, allocations: 4.325 MB / 471.1 MB, free: 3.516 MB / 366.1 MB Notification: Performance of prepare preOptimizeDAE: time 5.382e-05/0.4213, allocations: 9.219 kB / 471.1 MB, free: 3.516 MB / 366.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002363/0.4237, allocations: 0.6346 MB / 471.7 MB, free: 3.07 MB / 366.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008358/0.4321, allocations: 1.925 MB / 473.7 MB, free: 1.996 MB / 366.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.006126/0.4382, allocations: 2.288 MB / 476 MB, free: 15.64 MB / 382.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002211/0.4384, allocations: 214.9 kB / 476.2 MB, free: 15.44 MB / 382.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001531/0.4399, allocations: 371.7 kB / 476.5 MB, free: 15.07 MB / 382.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0196/0.4595, allocations: 8.897 MB / 485.4 MB, free: 5.539 MB / 382.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001247/0.4597, allocations: 35.7 kB / 485.5 MB, free: 5.504 MB / 382.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004879/0.4602, allocations: 139.9 kB / 485.6 MB, free: 5.367 MB / 382.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002812/0.463, allocations: 1.637 MB / 487.2 MB, free: 3.727 MB / 382.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01745/0.4804, allocations: 7.536 MB / 494.8 MB, free: 12.19 MB / 398.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05099/0.5314, allocations: 35.04 MB / 0.5174 GB, free: 8.145 MB / 430.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.009492/0.5409, allocations: 3.376 MB / 0.5207 GB, free: 4.621 MB / 430.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.002268/0.5432, allocations: 1.378 MB / 0.522 GB, free: 3.23 MB / 430.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.012/0.5552, allocations: 6.868 MB / 0.5287 GB, free: 12.03 MB / 446.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.535e-05/0.5552, allocations: 55.14 kB / 0.5288 GB, free: 11.96 MB / 446.1 MB Notification: Performance of pre-optimization done (n=132): time 5.23e-06/0.5552, allocations: 0 / 0.5288 GB, free: 11.96 MB / 446.1 MB Notification: Performance of matching and sorting (n=139): time 0.03074/0.586, allocations: 11.35 MB / 0.5399 GB, free: 480 kB / 446.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.001634/0.5876, allocations: 1.705 MB / 0.5416 GB, free: 14.58 MB / 462.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005862/0.5935, allocations: 3.54 MB / 0.545 GB, free: 11.04 MB / 462.1 MB Notification: Performance of collectPreVariables (initialization): time 0.000426/0.5939, allocations: 49.69 kB / 0.5451 GB, free: 10.99 MB / 462.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.002528/0.5964, allocations: 2.904 MB / 0.5479 GB, free: 8.059 MB / 462.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.0009539/0.5974, allocations: 0.5894 MB / 0.5485 GB, free: 7.465 MB / 462.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001805/0.5992, allocations: 1.104 MB / 0.5495 GB, free: 6.352 MB / 462.1 MB Notification: Performance of setup shared object (initialization): time 0.0001355/0.5993, allocations: 313.1 kB / 0.5498 GB, free: 6.043 MB / 462.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003303/0.6026, allocations: 1.92 MB / 0.5517 GB, free: 4.117 MB / 462.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.006582/0.6092, allocations: 5.361 MB / 0.557 GB, free: 13.02 MB / 478.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.01093/0.6201, allocations: 7.846 MB / 0.5646 GB, free: 3.465 MB / 478.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001157/0.6203, allocations: 36.28 kB / 0.5647 GB, free: 3.43 MB / 478.1 MB Notification: Performance of matching and sorting (n=710) (initialization): time 0.02381/0.6441, allocations: 8.539 MB / 0.573 GB, free: 10.88 MB / 494.1 MB Notification: Performance of prepare postOptimizeDAE: time 0.0001763/0.6442, allocations: 60.09 kB / 0.573 GB, free: 10.82 MB / 494.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.01156/0.6558, allocations: 3.207 MB / 0.5762 GB, free: 7.598 MB / 494.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.0104/0.6662, allocations: 1.952 MB / 0.5781 GB, free: 5.633 MB / 494.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004412/0.6706, allocations: 1.251 MB / 0.5793 GB, free: 4.379 MB / 494.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009756/0.6804, allocations: 6.061 MB / 0.5852 GB, free: 14.03 MB / 0.4981 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004876/0.6852, allocations: 382.4 kB / 0.5856 GB, free: 13.66 MB / 0.4981 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001063/0.6863, allocations: 340 kB / 0.5859 GB, free: 13.33 MB / 0.4981 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 557 * Number of states: 0 () * Number of discrete variables: 36 (spring2.color[3],spring2.color[2],spring2.color[1],spring2.massColor[3],spring2.massColor[2],spring2.massColor[1],body.color[3],body.color[2],body.color[1],body.body.sphereColor[3],body.body.sphereColor[2],body.body.sphereColor[1],body.frameTranslation.color[3],body.frameTranslation.color[2],body.frameTranslation.color[1],spring1.color[3],spring1.color[2],spring1.color[1],spring1.massColor[3],spring1.massColor[2],spring1.massColor[1],bar2.color[3],bar2.color[2],bar2.color[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 (672): * Single equations (assignments): 663 * Array equations: 5 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 3 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 2 systems {(4,100.0%), (3,100.0%)} * Non-linear Jacobian (size): 1 system {4} * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(2,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.003769/0.6901, allocations: 1.854 MB / 0.5877 GB, free: 11.46 MB / 0.4981 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006597/0.6907, allocations: 317.8 kB / 0.588 GB, free: 11.15 MB / 0.4981 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0009861/0.6917, allocations: 418.1 kB / 0.5884 GB, free: 10.74 MB / 0.4981 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.005367/0.6971, allocations: 2.359 MB / 0.5907 GB, free: 8.359 MB / 0.4981 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.584e-05/0.6971, allocations: 4 kB / 0.5907 GB, free: 8.355 MB / 0.4981 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.23e-05/0.6971, allocations: 7.969 kB / 0.5907 GB, free: 8.348 MB / 0.4981 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01685/0.714, allocations: 7.848 MB / 0.5984 GB, free: 396 kB / 0.4981 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.127e-05/0.714, allocations: 4 kB / 0.5984 GB, free: 392 kB / 0.4981 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00123/0.7152, allocations: 232.7 kB / 0.5986 GB, free: 160 kB / 0.4981 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.003473/0.7187, allocations: 1.058 MB / 0.5997 GB, free: 15.09 MB / 0.5137 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002937/0.719, allocations: 35.97 kB / 0.5997 GB, free: 15.05 MB / 0.5137 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007345/0.7263, allocations: 5.283 MB / 0.6049 GB, free: 9.492 MB / 0.5137 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.001495/0.7278, allocations: 1.572 MB / 0.6064 GB, free: 7.773 MB / 0.5137 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01014/0.738, allocations: 4.991 MB / 0.6113 GB, free: 2.734 MB / 0.5137 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001223/0.7392, allocations: 382.8 kB / 0.6116 GB, free: 2.34 MB / 0.5137 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008405/0.74, allocations: 31.94 kB / 0.6117 GB, free: 2.309 MB / 0.5137 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002013/0.7421, allocations: 127.8 kB / 0.6118 GB, free: 2.184 MB / 0.5137 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003647/0.7424, allocations: 80.52 kB / 0.6119 GB, free: 2.105 MB / 0.5137 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001426/0.7426, allocations: 43.97 kB / 0.6119 GB, free: 2.062 MB / 0.5137 GB Notification: Performance of sorting global known variables: time 0.2407/0.9833, allocations: 2.185 MB / 0.614 GB, free: 162 MB / 0.5137 GB Notification: Performance of sort global known variables: time 6.51e-07/0.9833, allocations: 0 / 0.614 GB, free: 162 MB / 0.5137 GB Notification: Performance of remove unused functions: time 0.006273/0.9896, allocations: 0.9399 MB / 0.615 GB, free: 162 MB / 0.5137 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 7 * Number of states: 12 ($STATESET1.x[3],$STATESET1.x[2],$STATESET1.x[1],body.v_0[1],body.v_0[2],body.v_0[3],body.body.frame_a.r_0[1],body.body.frame_a.r_0[2],body.body.frame_a.r_0[3],body.body.w_a[1],body.body.w_a[2],body.body.w_a[3]) * 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 (105): * Single equations (assignments): 98 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 3 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 2 systems {(4,100.0%), (3,100.0%)} * Non-linear Jacobian (size): 1 system {4} * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(2,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.00924/0.9988, allocations: 5.636 MB / 0.6205 GB, free: 161 MB / 0.5137 GB Notification: Performance of simCode: created initialization part: time 0.01017/1.009, allocations: 5.034 MB / 0.6254 GB, free: 159.2 MB / 0.5137 GB Notification: Performance of simCode: created event and clocks part: time 7.363e-06/1.009, allocations: 0 / 0.6254 GB, free: 159.2 MB / 0.5137 GB Notification: Performance of simCode: created simulation system equations: time 0.004272/1.013, allocations: 1.949 MB / 0.6273 GB, free: 158.4 MB / 0.5137 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002984/1.016, allocations: 397.5 kB / 0.6277 GB, free: 158.2 MB / 0.5137 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.07522/1.091, allocations: 42.68 MB / 0.6693 GB, free: 128 MB / 0.5137 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004092/1.096, allocations: 2.895 MB / 0.6722 GB, free: 125.2 MB / 0.5137 GB Notification: Performance of simCode: alias equations: time 0.003696/1.099, allocations: 1.697 MB / 0.6738 GB, free: 123.5 MB / 0.5137 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002453/1.102, allocations: 1.612 MB / 0.6754 GB, free: 121.9 MB / 0.5137 GB Notification: Performance of SimCode: time 1.402e-06/1.102, allocations: 0 / 0.6754 GB, free: 121.9 MB / 0.5137 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1121/1.214, allocations: 63.28 MB / 0.7372 GB, free: 59.14 MB / 0.5137 GB Notification: Performance of buildModelFMU: Generate platform static: time 21.6/22.82, allocations: 4 kB / 0.7372 GB, free: 59.14 MB / 0.5137 GB " [Timeout remaining time 637] (rm -f Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.sim & python3 -m fmpy simulate --output-file Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.0019992 Modelica_4_1_0-beta_om_Modelica_Mechanics_MultiBody_Examples_Elementary_FreeBody.fmu > Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Elementary.FreeBody.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 25.381735775154084] Failed to read output from testmodel.py, exit status != 0: ['time', 'body.body.frame_a.r_0[1]', 'body.body.frame_a.r_0[2]', 'body.body.frame_a.r_0[3]', 'body.body.w_a[1]', 'body.body.w_a[2]', 'body.body.w_a[3]', 'body.v_0[1]', 'body.v_0[2]', 'body.v_0[3]'] 22.958874483127147 22.973798877 22.564955377 Calling exit ...