Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.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.001602/0.001602, allocations: 106.5 kB / 21.23 MB, free: 0.6562 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.002037/0.002037, allocations: 213.2 kB / 24.54 MB, free: 4.016 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.482/1.482, allocations: 230.5 MB / 258.2 MB, free: 8.016 MB / 206.1 MB " [Timeout remaining time 178] 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.3438/0.3438, allocations: 46.93 MB / 361.6 MB, free: 4.195 MB / 286.1 MB " [Timeout remaining time 179] 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.Joints.FreeMotion2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.FreeMotion2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2",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.002414/0.002414, allocations: 80.14 kB / 0.497 GB, free: 7.754 MB / 366.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3652/0.3676, allocations: 66.09 MB / 0.5615 GB, free: 4.312 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.FreeMotion2): time 0.0193/0.3869, allocations: 14.75 MB / 0.5759 GB, free: 5.496 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.01203/0.3989, allocations: 5.043 MB / 0.5809 GB, free: 448 kB / 414.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003313/0.4022, allocations: 119.4 kB / 0.581 GB, free: 328 kB / 414.1 MB Notification: Performance of NFTyping.typeComponents: time 0.008919/0.4111, allocations: 4.209 MB / 0.5851 GB, free: 12.1 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.007825/0.419, allocations: 2.392 MB / 0.5874 GB, free: 9.699 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003412/0.4224, allocations: 1.088 MB / 0.5885 GB, free: 8.609 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.01272/0.4351, allocations: 7.187 MB / 0.5955 GB, free: 1.41 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.007243/0.4423, allocations: 4.049 MB / 0.5994 GB, free: 13.29 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.005205/0.4475, allocations: 2.274 MB / 0.6017 GB, free: 11.01 MB / 446.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.006157/0.4537, allocations: 3.283 MB / 0.6049 GB, free: 7.719 MB / 446.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002016/0.4557, allocations: 496 kB / 0.6053 GB, free: 7.234 MB / 446.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004413/0.4601, allocations: 1.401 MB / 0.6067 GB, free: 5.832 MB / 446.1 MB Notification: Performance of NFScalarize.scalarize: time 0.004934/0.4651, allocations: 2.813 MB / 0.6095 GB, free: 3.012 MB / 446.1 MB Notification: Performance of NFVerifyModel.verify: time 0.007676/0.4727, allocations: 3.267 MB / 0.6126 GB, free: 15.72 MB / 462.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01769/0.4904, allocations: 10.15 MB / 0.6226 GB, free: 5.531 MB / 462.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.286e-06/0.4904, allocations: 0 / 0.6226 GB, free: 5.531 MB / 462.1 MB Notification: Performance of FrontEnd: time 1.953e-06/0.4904, allocations: 4 kB / 0.6226 GB, free: 5.527 MB / 462.1 MB Notification: Performance of Transformations before backend: time 0.0004057/0.4908, allocations: 0 / 0.6226 GB, free: 5.527 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: 1953 * Number of variables: 1953 Notification: Performance of Generate backend data structure: time 0.02234/0.5132, allocations: 7.23 MB / 0.6296 GB, free: 14.22 MB / 478.1 MB Notification: Performance of prepare preOptimizeDAE: time 6.092e-05/0.5132, allocations: 12.03 kB / 0.6296 GB, free: 14.21 MB / 478.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003113/0.5163, allocations: 0.93 MB / 0.6305 GB, free: 13.27 MB / 478.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1503/0.6667, allocations: 12.55 MB / 0.6428 GB, free: 0.7031 MB / 478.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01935/0.686, allocations: 4.986 MB / 0.6477 GB, free: 11.62 MB / 494.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005517/0.6866, allocations: 332.4 kB / 0.648 GB, free: 11.3 MB / 494.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002335/0.6889, allocations: 451.7 kB / 0.6484 GB, free: 10.86 MB / 494.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.04382/0.7327, allocations: 12.95 MB / 0.6611 GB, free: 13.04 MB / 0.4981 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0005082/0.7332, allocations: 75.45 kB / 0.6611 GB, free: 12.96 MB / 0.4981 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002023/0.7353, allocations: 207.8 kB / 0.6613 GB, free: 12.76 MB / 0.4981 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.008518/0.7438, allocations: 2.535 MB / 0.6638 GB, free: 10.22 MB / 0.4981 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03902/0.7828, allocations: 11.13 MB / 0.6747 GB, free: 15.07 MB / 0.5137 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.07868/0.8615, allocations: 52.03 MB / 0.7255 GB, free: 9.52 MB / 0.5606 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01346/0.8749, allocations: 4.891 MB / 0.7303 GB, free: 4.586 MB / 0.5606 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.004953/0.8799, allocations: 3.113 MB / 0.7333 GB, free: 1.465 MB / 0.5606 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0006954/0.8806, allocations: 249.6 kB / 0.7335 GB, free: 1.223 MB / 0.5606 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.374e-05/0.8807, allocations: 68.44 kB / 0.7336 GB, free: 1.145 MB / 0.5606 GB Notification: Performance of pre-optimization done (n=245): time 4.95e-06/0.8807, allocations: 0 / 0.7336 GB, free: 1.145 MB / 0.5606 GB Notification: Performance of matching and sorting (n=315): time 0.3992/1.28, allocations: 34.69 MB / 0.7675 GB, free: 135.9 MB / 0.5606 GB Notification: Performance of inlineWhenForInitialization (initialization): time 9.455e-05/1.28, allocations: 261.3 kB / 0.7677 GB, free: 135.6 MB / 0.5606 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006396/1.286, allocations: 5.205 MB / 0.7728 GB, free: 130.5 MB / 0.5606 GB Notification: Performance of collectPreVariables (initialization): time 0.0008106/1.287, allocations: 73.64 kB / 0.7729 GB, free: 130.4 MB / 0.5606 GB Notification: Performance of collectInitialEqns (initialization): time 0.002146/1.289, allocations: 4.069 MB / 0.7769 GB, free: 126.8 MB / 0.5606 GB Notification: Performance of collectInitialBindings (initialization): time 0.001484/1.291, allocations: 1.229 MB / 0.7781 GB, free: 125.6 MB / 0.5606 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002042/1.293, allocations: 1.524 MB / 0.7796 GB, free: 124.1 MB / 0.5606 GB Notification: Performance of setup shared object (initialization): time 4.732e-05/1.293, allocations: 301.1 kB / 0.7798 GB, free: 123.8 MB / 0.5606 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.004283/1.297, allocations: 3.403 MB / 0.7832 GB, free: 120.3 MB / 0.5606 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007255/1.304, allocations: 7.561 MB / 0.7905 GB, free: 110.8 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01227/1.317, allocations: 11.85 MB / 0.8021 GB, free: 96.9 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001328/1.317, allocations: 48 kB / 0.8022 GB, free: 96.86 MB / 0.5606 GB Notification: Performance of matching and sorting (n=982) (initialization): time 0.02256/1.339, allocations: 12.73 MB / 0.8146 GB, free: 84.08 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 8.67e-05/1.34, allocations: 74.28 kB / 0.8147 GB, free: 84.01 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001413/1.34, allocations: 144 kB / 0.8148 GB, free: 83.87 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.005279/1.345, allocations: 2.239 MB / 0.817 GB, free: 81.62 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005915/1.351, allocations: 1.712 MB / 0.8187 GB, free: 79.91 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01688/1.368, allocations: 16.2 MB / 0.8345 GB, free: 63.48 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007714/1.375, allocations: 447.4 kB / 0.8349 GB, free: 63.05 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001806/1.377, allocations: 443.9 kB / 0.8353 GB, free: 62.61 MB / 0.5606 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 656 * Number of states: 0 () * Number of discrete variables: 44 (freeMotion.arrow.twoHeadedArrow,freeMotion.arrow.headAtOrigin,universal1.cylinderColor[3],universal1.cylinderColor[2],universal1.cylinderColor[1],prismatic1.boxColor[3],prismatic1.boxColor[2],prismatic1.boxColor[1],bodyBox1.color[3],bodyBox1.color[2],bodyBox1.color[1],bodyBox1.body.sphereColor[3],bodyBox1.body.sphereColor[2],bodyBox1.body.sphereColor[1],universal.cylinderColor[3],universal.cylinderColor[2],universal.cylinderColor[1],prismatic.boxColor[3],prismatic.boxColor[2],prismatic.boxColor[1],bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],fixed.color[3],fixed.color[2],fixed.color[1],freeMotion.arrowColor[3],freeMotion.arrowColor[2],freeMotion.arrowColor[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 (906): * Single equations (assignments): 901 * 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 {(3,24,100.0%), (3,24,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.006011/1.383, allocations: 2.739 MB / 0.838 GB, free: 59.87 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001368/1.385, allocations: 0.5006 MB / 0.8385 GB, free: 59.36 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01154/1.396, allocations: 7.276 MB / 0.8456 GB, free: 52.02 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.255e-05/1.396, allocations: 32.28 kB / 0.8456 GB, free: 51.99 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.367e-05/1.396, allocations: 8 kB / 0.8456 GB, free: 51.98 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.225e-05/1.396, allocations: 27.86 kB / 0.8457 GB, free: 51.95 MB / 0.5606 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0265/1.423, allocations: 16.03 MB / 0.8613 GB, free: 35.84 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.016e-05/1.423, allocations: 8 kB / 0.8613 GB, free: 35.83 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002549/1.425, allocations: 0.4992 MB / 0.8618 GB, free: 35.33 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.004811/1.43, allocations: 2.011 MB / 0.8638 GB, free: 33.31 MB / 0.5606 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007343/1.431, allocations: 56 kB / 0.8638 GB, free: 33.26 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0169/1.448, allocations: 14.71 MB / 0.8782 GB, free: 18.33 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.75e-06/1.448, allocations: 7.938 kB / 0.8782 GB, free: 18.32 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01628/1.464, allocations: 11.67 MB / 0.8896 GB, free: 6.461 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001535/1.466, allocations: 492.7 kB / 0.8901 GB, free: 5.977 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009545/1.467, allocations: 56 kB / 0.8901 GB, free: 5.922 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002466/1.469, allocations: 215.5 kB / 0.8903 GB, free: 5.715 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006525/1.47, allocations: 221.2 kB / 0.8905 GB, free: 5.496 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002324/1.47, allocations: 87.98 kB / 0.8906 GB, free: 5.41 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.00507/1.475, allocations: 3.298 MB / 0.8938 GB, free: 2.125 MB / 0.5606 GB Notification: Performance of sort global known variables: time 1.8e-07/1.475, allocations: 0 / 0.8938 GB, free: 2.125 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.006791/1.482, allocations: 1.461 MB / 0.8953 GB, free: 0.6641 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 28 * Number of states: 12 (freeMotion.r_rel_a[1],freeMotion.v_rel_a[1],universal.phi_a,universal.phi_b,universal.w_a,universal.w_b,prismatic1.s,prismatic1.v,universal1.phi_a,universal1.phi_b,universal1.w_a,universal1.w_b) * 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 (253): * Single equations (assignments): 248 * 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 {(7,17,46.9%), (7,17,46.9%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.02228/1.504, allocations: 12.36 MB / 0.9073 GB, free: 4.102 MB / 0.5762 GB Notification: Performance of simCode: created initialization part: time 0.01542/1.52, allocations: 8.106 MB / 0.9152 GB, free: 11.98 MB / 0.5919 GB Notification: Performance of simCode: created event and clocks part: time 1.22e-05/1.52, allocations: 0 / 0.9152 GB, free: 11.98 MB / 0.5919 GB Notification: Performance of simCode: created simulation system equations: time 0.008796/1.528, allocations: 3.873 MB / 0.919 GB, free: 8.086 MB / 0.5919 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00509/1.533, allocations: 0.6974 MB / 0.9197 GB, free: 7.398 MB / 0.5919 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3997/1.933, allocations: 68.27 MB / 0.9864 GB, free: 227.7 MB / 0.6544 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005194/1.938, allocations: 4.278 MB / 0.9906 GB, free: 226.9 MB / 0.6544 GB Notification: Performance of simCode: alias equations: time 0.005303/1.944, allocations: 2.456 MB / 0.993 GB, free: 226.8 MB / 0.6544 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004537/1.948, allocations: 2.712 MB / 0.9956 GB, free: 225.4 MB / 0.6544 GB Notification: Performance of SimCode: time 1.593e-06/1.948, allocations: 0 / 0.9956 GB, free: 225.4 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1867/2.135, allocations: 119.1 MB / 1.112 GB, free: 115.4 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate platform static: time 22.44/24.57, allocations: 7.594 kB / 1.112 GB, free: 115.4 MB / 0.6544 GB " [Timeout remaining time 635] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.sim & python3 -m fmpy simulate --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2_res.csv --start-time 0 --stop-time 2 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00039984 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_FreeMotion2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotion2.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 27.804592741187662] Failed to read output from testmodel.py, exit status != 0: ['time', 'freeMotion.r_rel_a[1]', 'freeMotion.v_rel_a[1]', 'prismatic1.s', 'prismatic1.v', 'universal.phi_a', 'universal.phi_b', 'universal.w_a', 'universal.w_b', 'universal1.phi_a', 'universal1.phi_b', 'universal1.w_a', 'universal1.w_b'] 24.72945756604895 24.745899599 24.251538123 Calling exit ...