Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.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.001707/0.001707, allocations: 118.2 kB / 18.46 MB, free: 4.59 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.002127/0.002128, allocations: 219.3 kB / 21.41 MB, free: 1.637 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.562/1.562, allocations: 227.4 MB / 251.7 MB, free: 88 kB / 190.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.2336/0.2336, allocations: 46.14 MB / 351.1 MB, free: 14.35 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.LineForceWithMass,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_LineForceWithMass",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Forces.LineForceWithMass,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_LineForceWithMass",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.002258/0.002258, allocations: 82.31 kB / 489.7 MB, free: 28.17 MB / 366.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1087/0.1109, allocations: 62.44 MB / 0.5392 GB, free: 4.816 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.LineForceWithMass): time 0.01785/0.1288, allocations: 14.97 MB / 0.5538 GB, free: 5.77 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.01347/0.1423, allocations: 5.006 MB / 0.5587 GB, free: 0.75 MB / 414.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003377/0.1458, allocations: 103.7 kB / 0.5588 GB, free: 0.6484 MB / 414.1 MB Notification: Performance of NFTyping.typeComponents: time 0.008923/0.1548, allocations: 4.007 MB / 0.5627 GB, free: 12.63 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.00725/0.1621, allocations: 2.315 MB / 0.5649 GB, free: 10.3 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003208/0.1653, allocations: 1.166 MB / 0.5661 GB, free: 9.137 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.01085/0.1762, allocations: 6.581 MB / 0.5725 GB, free: 2.539 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.008015/0.1843, allocations: 4.69 MB / 0.5771 GB, free: 13.78 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.005087/0.1894, allocations: 2.212 MB / 0.5793 GB, free: 11.56 MB / 446.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005312/0.1948, allocations: 3.014 MB / 0.5822 GB, free: 8.539 MB / 446.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001678/0.1965, allocations: 456 kB / 0.5826 GB, free: 8.094 MB / 446.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.003954/0.2004, allocations: 1.525 MB / 0.5841 GB, free: 6.566 MB / 446.1 MB Notification: Performance of NFScalarize.scalarize: time 0.003758/0.2042, allocations: 2.661 MB / 0.5867 GB, free: 3.898 MB / 446.1 MB Notification: Performance of NFVerifyModel.verify: time 0.005481/0.2097, allocations: 3.142 MB / 0.5898 GB, free: 0.7383 MB / 446.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01452/0.2243, allocations: 9.763 MB / 0.5993 GB, free: 6.938 MB / 462.1 MB Notification: Performance of FrontEnd - DAE generated: time 6.412e-06/0.2243, allocations: 0 / 0.5993 GB, free: 6.938 MB / 462.1 MB Notification: Performance of FrontEnd: time 2.064e-06/0.2244, allocations: 4 kB / 0.5993 GB, free: 6.934 MB / 462.1 MB Notification: Performance of Transformations before backend: time 0.000328/0.2247, allocations: 0 / 0.5993 GB, free: 6.934 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: 1941 * Number of variables: 1941 Notification: Performance of Generate backend data structure: time 0.2507/0.4754, allocations: 7.165 MB / 0.6063 GB, free: 54.75 MB / 462.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.893e-05/0.4754, allocations: 9.719 kB / 0.6063 GB, free: 54.75 MB / 462.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.00235/0.4778, allocations: 0.9206 MB / 0.6072 GB, free: 54.66 MB / 462.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.1772/0.655, allocations: 12.54 MB / 0.6195 GB, free: 53.26 MB / 462.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.00949/0.6645, allocations: 4.585 MB / 0.624 GB, free: 50.84 MB / 462.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002261/0.6648, allocations: 310.6 kB / 0.6242 GB, free: 50.73 MB / 462.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001484/0.6663, allocations: 426.8 kB / 0.6247 GB, free: 50.62 MB / 462.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02179/0.6881, allocations: 13.7 MB / 0.638 GB, free: 38.98 MB / 462.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001586/0.6883, allocations: 55.58 kB / 0.6381 GB, free: 38.93 MB / 462.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005991/0.6889, allocations: 197.8 kB / 0.6383 GB, free: 38.78 MB / 462.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003208/0.6921, allocations: 2.525 MB / 0.6407 GB, free: 36.26 MB / 462.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01952/0.7117, allocations: 11.55 MB / 0.652 GB, free: 24.71 MB / 462.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06348/0.7752, allocations: 52.93 MB / 0.7037 GB, free: 2.652 MB / 494.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.01719/0.7924, allocations: 5.815 MB / 0.7094 GB, free: 12.69 MB / 0.4981 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.005181/0.7976, allocations: 3.381 MB / 0.7127 GB, free: 9.297 MB / 0.4981 GB Notification: Performance of preOpt evalFunc (simulation): time 0.02619/0.8239, allocations: 14.88 MB / 0.7272 GB, free: 9.941 MB / 0.5137 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.7e-05/0.824, allocations: 64.55 kB / 0.7273 GB, free: 9.871 MB / 0.5137 GB Notification: Performance of pre-optimization done (n=227): time 4.749e-06/0.824, allocations: 0 / 0.7273 GB, free: 9.871 MB / 0.5137 GB Notification: Performance of matching and sorting (n=264): time 0.09062/0.9146, allocations: 26.53 MB / 0.7532 GB, free: 15.3 MB / 0.545 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.000191/0.9149, allocations: 257.3 kB / 0.7534 GB, free: 14.99 MB / 0.545 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008754/0.9236, allocations: 5.078 MB / 0.7584 GB, free: 9.922 MB / 0.545 GB Notification: Performance of collectPreVariables (initialization): time 0.0005168/0.9242, allocations: 73.64 kB / 0.7585 GB, free: 9.844 MB / 0.545 GB Notification: Performance of collectInitialEqns (initialization): time 0.003588/0.9278, allocations: 4.103 MB / 0.7625 GB, free: 5.703 MB / 0.545 GB Notification: Performance of collectInitialBindings (initialization): time 0.001382/0.9292, allocations: 1.031 MB / 0.7635 GB, free: 4.664 MB / 0.545 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002427/0.9316, allocations: 1.477 MB / 0.7649 GB, free: 3.18 MB / 0.545 GB Notification: Performance of setup shared object (initialization): time 0.0001406/0.9318, allocations: 309.1 kB / 0.7652 GB, free: 2.875 MB / 0.545 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.004545/0.9363, allocations: 2.728 MB / 0.7679 GB, free: 148 kB / 0.545 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.008727/0.9451, allocations: 7.08 MB / 0.7748 GB, free: 6.922 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01436/0.9595, allocations: 10.64 MB / 0.7852 GB, free: 10.15 MB / 0.5762 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001131/0.9596, allocations: 44 kB / 0.7852 GB, free: 10.11 MB / 0.5762 GB Notification: Performance of matching and sorting (n=963) (initialization): time 0.02047/0.9801, allocations: 10.15 MB / 0.7951 GB, free: 15.94 MB / 0.5919 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001228/0.9802, allocations: 68 kB / 0.7952 GB, free: 15.87 MB / 0.5919 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001989/0.9805, allocations: 152 kB / 0.7953 GB, free: 15.72 MB / 0.5919 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.001957/0.9824, allocations: 0.7128 MB / 0.796 GB, free: 15 MB / 0.5919 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006548/0.989, allocations: 1.77 MB / 0.7978 GB, free: 13.23 MB / 0.5919 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009042/0.9981, allocations: 6.85 MB / 0.8045 GB, free: 6.109 MB / 0.5919 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00583/1.004, allocations: 403.5 kB / 0.8048 GB, free: 5.715 MB / 0.5919 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001511/1.005, allocations: 444 kB / 0.8053 GB, free: 5.281 MB / 0.5919 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 693 * Number of states: 0 () * Number of discrete variables: 57 (lineForceWithMass.lineShapeColor[3],lineForceWithMass.lineShapeColor[2],lineForceWithMass.lineShapeColor[1],lineForceWithMass.massColor[3],lineForceWithMass.massColor[2],lineForceWithMass.massColor[1],rod4.color[3],rod4.color[2],rod4.color[1],rod3.color[3],rod3.color[2],rod3.color[1],bodyBox2.color[3],bodyBox2.color[2],bodyBox2.color[1],bodyBox2.body.sphereColor[3],bodyBox2.body.sphereColor[2],bodyBox2.body.sphereColor[1],revolute2.cylinderColor[3],revolute2.cylinderColor[2],revolute2.cylinderColor[1],rod2.color[3],rod2.color[2],rod2.color[1],jointUPS.cylinderColor[3],jointUPS.cylinderColor[2],jointUPS.cylinderColor[1],jointUPS.sphereColor[3],jointUPS.sphereColor[2],jointUPS.sphereColor[1],jointUPS.axisColor[3],jointUPS.axisColor[2],jointUPS.axisColor[1],rod1.color[3],rod1.color[2],rod1.color[1],bodyBox1.color[3],bodyBox1.color[2],bodyBox1.color[1],bodyBox1.body.sphereColor[3],bodyBox1.body.sphereColor[2],bodyBox1.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 (913): * Single equations (assignments): 908 * 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,13,100.0%), (1,13,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.005871/1.011, allocations: 2.638 MB / 0.8078 GB, free: 2.629 MB / 0.5919 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001197/1.013, allocations: 0.4889 MB / 0.8083 GB, free: 2.137 MB / 0.5919 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008936/1.022, allocations: 4.385 MB / 0.8126 GB, free: 13.73 MB / 0.6075 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.576e-05/1.022, allocations: 28 kB / 0.8126 GB, free: 13.71 MB / 0.6075 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.569e-05/1.022, allocations: 4 kB / 0.8126 GB, free: 13.7 MB / 0.6075 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.735e-05/1.022, allocations: 16.61 kB / 0.8126 GB, free: 13.69 MB / 0.6075 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02205/1.044, allocations: 10.74 MB / 0.8231 GB, free: 2.875 MB / 0.6075 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.882e-05/1.044, allocations: 0 / 0.8231 GB, free: 2.875 MB / 0.6075 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00276/1.047, allocations: 0.4953 MB / 0.8236 GB, free: 2.379 MB / 0.6075 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.001585/1.048, allocations: 493.2 kB / 0.8241 GB, free: 1.891 MB / 0.6075 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003502/1.049, allocations: 44 kB / 0.8241 GB, free: 1.848 MB / 0.6075 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007905/1.056, allocations: 6.224 MB / 0.8302 GB, free: 11.34 MB / 0.6231 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.236e-06/1.057, allocations: 0 / 0.8302 GB, free: 11.34 MB / 0.6231 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01379/1.07, allocations: 7.659 MB / 0.8377 GB, free: 3.613 MB / 0.6231 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001421/1.072, allocations: 0.5095 MB / 0.8382 GB, free: 3.082 MB / 0.6231 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001117/1.073, allocations: 40 kB / 0.8382 GB, free: 3.043 MB / 0.6231 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002552/1.075, allocations: 163.8 kB / 0.8384 GB, free: 2.883 MB / 0.6231 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006152/1.076, allocations: 145.7 kB / 0.8385 GB, free: 2.738 MB / 0.6231 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002631/1.076, allocations: 71.98 kB / 0.8386 GB, free: 2.668 MB / 0.6231 GB Notification: Performance of sorting global known variables: time 0.2595/1.336, allocations: 3.171 MB / 0.8417 GB, free: 206.4 MB / 0.6231 GB Notification: Performance of sort global known variables: time 1.322e-06/1.336, allocations: 0.9062 kB / 0.8417 GB, free: 206.4 MB / 0.6231 GB Notification: Performance of remove unused functions: time 0.007244/1.343, allocations: 1.283 MB / 0.8429 GB, free: 206.4 MB / 0.6231 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 4 * 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 (224): * Single equations (assignments): 219 * 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,10,100.0%), (1,10,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.01561/1.359, allocations: 8.355 MB / 0.8511 GB, free: 204 MB / 0.6231 GB Notification: Performance of simCode: created initialization part: time 0.01166/1.371, allocations: 6.798 MB / 0.8577 GB, free: 201.6 MB / 0.6231 GB Notification: Performance of simCode: created event and clocks part: time 7.454e-06/1.371, allocations: 3.406 kB / 0.8577 GB, free: 201.6 MB / 0.6231 GB Notification: Performance of simCode: created simulation system equations: time 0.00333/1.374, allocations: 2.377 MB / 0.8601 GB, free: 200.6 MB / 0.6231 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003693/1.378, allocations: 0.6306 MB / 0.8607 GB, free: 200.3 MB / 0.6231 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.07283/1.451, allocations: 59.14 MB / 0.9184 GB, free: 151.9 MB / 0.6231 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004783/1.455, allocations: 4.108 MB / 0.9224 GB, free: 147.8 MB / 0.6231 GB Notification: Performance of simCode: alias equations: time 0.005076/1.46, allocations: 2.377 MB / 0.9248 GB, free: 145.4 MB / 0.6231 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003448/1.464, allocations: 2.62 MB / 0.9273 GB, free: 142.7 MB / 0.6231 GB Notification: Performance of SimCode: time 1.052e-06/1.464, allocations: 0 / 0.9273 GB, free: 142.7 MB / 0.6231 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1293/1.593, allocations: 91.72 MB / 1.017 GB, free: 51.48 MB / 0.6231 GB Notification: Performance of buildModelFMU: Generate platform static: time 32.56/34.15, allocations: 10.31 kB / 1.017 GB, free: 51.47 MB / 0.6231 GB " [Timeout remaining time 626] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.sim & python3 -m fmpy simulate --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass_res.csv --start-time 0 --stop-time 3 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00059976 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Forces_LineForceWithMass.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.LineForceWithMass.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 37.35917213000357] Failed to read output from testmodel.py, exit status != 0: ['time', 'revolute1.phi', 'revolute1.w', 'revolute2.phi', 'revolute2.w'] 34.2961459599901 34.311701668 34.084565499 Calling exit ...