Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.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.001762/0.001762, allocations: 116.8 kB / 18.5 MB, free: 4.52 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.00238/0.00238, allocations: 212.9 kB / 21.46 MB, free: 1.566 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.515/1.515, allocations: 230.4 MB / 254.7 MB, free: 15.03 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.2396/0.2396, allocations: 46.93 MB / 357.7 MB, free: 10.21 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.Parts.Rotor1D.Rotor1D_2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Rotor1D_Rotor1D_2",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Parts_Rotor1D_Rotor1D_2",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.002334/0.002334, allocations: 79.86 kB / 0.4917 GB, free: 7.738 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.136/0.1384, allocations: 66.04 MB / 0.5562 GB, free: 5.57 MB / 414.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2): time 0.01427/0.1526, allocations: 11.09 MB / 0.567 GB, free: 10.42 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.008993/0.1616, allocations: 3.839 MB / 0.5707 GB, free: 6.566 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002115/0.1637, allocations: 79.62 kB / 0.5708 GB, free: 6.488 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.005894/0.1696, allocations: 2.71 MB / 0.5735 GB, free: 3.77 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.005455/0.1751, allocations: 1.797 MB / 0.5752 GB, free: 1.969 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.002113/0.1772, allocations: 0.7018 MB / 0.5759 GB, free: 1.266 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.006092/0.1833, allocations: 3.593 MB / 0.5794 GB, free: 13.66 MB / 446.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.00439/0.1877, allocations: 3.07 MB / 0.5824 GB, free: 10.53 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002813/0.1905, allocations: 1.491 MB / 0.5839 GB, free: 9.031 MB / 446.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.003524/0.194, allocations: 2.157 MB / 0.586 GB, free: 6.867 MB / 446.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001079/0.1951, allocations: 320 kB / 0.5863 GB, free: 6.555 MB / 446.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002517/0.1976, allocations: 1.08 MB / 0.5873 GB, free: 5.473 MB / 446.1 MB Notification: Performance of NFScalarize.scalarize: time 0.002469/0.2001, allocations: 1.842 MB / 0.5891 GB, free: 3.625 MB / 446.1 MB Notification: Performance of NFVerifyModel.verify: time 0.003618/0.2037, allocations: 2.093 MB / 0.5912 GB, free: 1.52 MB / 446.1 MB Notification: Performance of NFConvertDAE.convert: time 0.3251/0.5289, allocations: 6.7 MB / 0.5977 GB, free: 13.41 MB / 446.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.286e-06/0.5289, allocations: 3.938 kB / 0.5977 GB, free: 13.41 MB / 446.1 MB Notification: Performance of FrontEnd: time 1.763e-06/0.5289, allocations: 0.5312 kB / 0.5977 GB, free: 13.41 MB / 446.1 MB Notification: Performance of Transformations before backend: time 0.0003407/0.5292, allocations: 0 / 0.5977 GB, free: 13.41 MB / 446.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1413 * Number of variables: 1413 Notification: Performance of Generate backend data structure: time 0.02634/0.5555, allocations: 5.2 MB / 0.6028 GB, free: 11.48 MB / 446.1 MB Notification: Performance of prepare preOptimizeDAE: time 7.024e-05/0.5556, allocations: 12.12 kB / 0.6028 GB, free: 11.48 MB / 446.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003887/0.5595, allocations: 0.6723 MB / 0.6035 GB, free: 11.41 MB / 446.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009858/0.5694, allocations: 1.893 MB / 0.6053 GB, free: 11.12 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.008585/0.5779, allocations: 2.494 MB / 0.6078 GB, free: 10.55 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004218/0.5784, allocations: 230.5 kB / 0.608 GB, free: 10.54 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002601/0.581, allocations: 297.9 kB / 0.6083 GB, free: 10.54 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02976/0.6107, allocations: 10.04 MB / 0.6181 GB, free: 7.48 MB / 446.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.000305/0.611, allocations: 46.14 kB / 0.6181 GB, free: 7.473 MB / 446.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00177/0.6128, allocations: 143.3 kB / 0.6182 GB, free: 7.406 MB / 446.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003568/0.6164, allocations: 1.802 MB / 0.62 GB, free: 7.121 MB / 446.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02072/0.6371, allocations: 9.034 MB / 0.6288 GB, free: 3.32 MB / 446.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05872/0.6958, allocations: 37.13 MB / 0.6651 GB, free: 15.41 MB / 494.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.0146/0.7104, allocations: 3.62 MB / 0.6686 GB, free: 11.66 MB / 494.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.002925/0.7133, allocations: 1.603 MB / 0.6702 GB, free: 10.05 MB / 494.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.001243/0.7146, allocations: 0.6796 MB / 0.6709 GB, free: 9.266 MB / 494.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.749e-05/0.7147, allocations: 54.44 kB / 0.6709 GB, free: 9.211 MB / 494.1 MB Notification: Performance of pre-optimization done (n=130): time 5.52e-06/0.7147, allocations: 0 / 0.6709 GB, free: 9.211 MB / 494.1 MB Notification: Performance of matching and sorting (n=136): time 0.05127/0.7659, allocations: 13.09 MB / 0.6837 GB, free: 12.05 MB / 0.4981 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001042/0.766, allocations: 144.8 kB / 0.6838 GB, free: 11.89 MB / 0.4981 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009042/0.7751, allocations: 3.972 MB / 0.6877 GB, free: 7.938 MB / 0.4981 GB Notification: Performance of collectPreVariables (initialization): time 0.0004945/0.7756, allocations: 49.55 kB / 0.6878 GB, free: 7.887 MB / 0.4981 GB Notification: Performance of collectInitialEqns (initialization): time 0.003568/0.7791, allocations: 3.087 MB / 0.6908 GB, free: 4.828 MB / 0.4981 GB Notification: Performance of collectInitialBindings (initialization): time 0.001294/0.7804, allocations: 0.5687 MB / 0.6913 GB, free: 4.266 MB / 0.4981 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.003542/0.784, allocations: 1.145 MB / 0.6924 GB, free: 3.113 MB / 0.4981 GB Notification: Performance of setup shared object (initialization): time 0.0001619/0.7841, allocations: 305.1 kB / 0.6927 GB, free: 2.812 MB / 0.4981 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003989/0.7881, allocations: 1.92 MB / 0.6946 GB, free: 0.8867 MB / 0.4981 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007653/0.7958, allocations: 5.495 MB / 0.7 GB, free: 9.656 MB / 0.5137 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0117/0.8075, allocations: 8.043 MB / 0.7078 GB, free: 368 kB / 0.5137 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001351/0.8076, allocations: 36 kB / 0.7079 GB, free: 332 kB / 0.5137 GB Notification: Performance of matching and sorting (n=716) (initialization): time 0.01788/0.8255, allocations: 7.897 MB / 0.7156 GB, free: 8.723 MB / 0.5294 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001414/0.8256, allocations: 60.06 kB / 0.7156 GB, free: 8.664 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002265/0.8259, allocations: 128 kB / 0.7158 GB, free: 8.539 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.003003/0.8289, allocations: 0.9034 MB / 0.7166 GB, free: 7.633 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004634/0.8335, allocations: 1.28 MB / 0.7179 GB, free: 6.352 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008275/0.8418, allocations: 5.188 MB / 0.723 GB, free: 1.023 MB / 0.5294 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004772/0.8465, allocations: 307 kB / 0.7232 GB, free: 0.7266 MB / 0.5294 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001284/0.8478, allocations: 356 kB / 0.7236 GB, free: 388 kB / 0.5294 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 579 * Number of states: 0 () * Number of discrete variables: 38 (torque.torqueArrow.twoHeadedArrow,torque.torqueArrow.headAtOrigin,rotor1D.cylinderColor[3],rotor1D.cylinderColor[2],rotor1D.cylinderColor[1],fixed.color[3],fixed.color[2],fixed.color[1],torque.connectionLineColor[3],torque.connectionLineColor[2],torque.connectionLineColor[1],torque.torqueColor[3],torque.torqueColor[2],torque.torqueColor[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],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[1],bodyCylinder.color[3],bodyCylinder.color[2],bodyCylinder.color[1],bodyCylinder.body.sphereColor[3],bodyCylinder.body.sphereColor[2],bodyCylinder.body.sphereColor[1],bodyCylinder.frameTranslation.color[3],bodyCylinder.frameTranslation.color[2],bodyCylinder.frameTranslation.color[1]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (677): * Single equations (assignments): 673 * 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 {(3,13,77.8%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.005022/0.8529, allocations: 1.967 MB / 0.7255 GB, free: 14.4 MB / 0.545 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000986/0.8538, allocations: 345.7 kB / 0.7258 GB, free: 14.06 MB / 0.545 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006994/0.8608, allocations: 2.78 MB / 0.7286 GB, free: 11.27 MB / 0.545 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.767e-05/0.8609, allocations: 16 kB / 0.7286 GB, free: 11.25 MB / 0.545 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.951e-05/0.8609, allocations: 4 kB / 0.7286 GB, free: 11.25 MB / 0.545 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.591e-05/0.8609, allocations: 7.984 kB / 0.7286 GB, free: 11.24 MB / 0.545 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01885/0.8798, allocations: 7.169 MB / 0.7356 GB, free: 4.027 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.882e-05/0.8798, allocations: 1.125 kB / 0.7356 GB, free: 4.027 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00151/0.8813, allocations: 247.6 kB / 0.7358 GB, free: 3.785 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.002584/0.8839, allocations: 0.7123 MB / 0.7365 GB, free: 3.07 MB / 0.545 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003109/0.8842, allocations: 39.97 kB / 0.7365 GB, free: 3.031 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007651/0.8919, allocations: 4.754 MB / 0.7412 GB, free: 14.15 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.381e-06/0.8919, allocations: 4.031 kB / 0.7412 GB, free: 14.14 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01008/0.9019, allocations: 5.157 MB / 0.7462 GB, free: 8.969 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001171/0.9031, allocations: 398.7 kB / 0.7466 GB, free: 8.559 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009813/0.9041, allocations: 24 kB / 0.7466 GB, free: 8.535 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002016/0.9061, allocations: 75.89 kB / 0.7467 GB, free: 8.461 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004924/0.9066, allocations: 100.5 kB / 0.7468 GB, free: 8.363 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002198/0.9068, allocations: 39.98 kB / 0.7468 GB, free: 8.324 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.00639/0.9132, allocations: 2.485 MB / 0.7493 GB, free: 5.859 MB / 0.5606 GB Notification: Performance of sort global known variables: time 1.9e-07/0.9132, allocations: 4 kB / 0.7493 GB, free: 5.855 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.007176/0.9204, allocations: 0.8749 MB / 0.7501 GB, free: 4.98 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 7 * Number of states: 4 (revolute.phi,revolute.w,rotor1D.phi,rotor1D.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 (104): * Single equations (assignments): 100 * 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,12,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.01141/0.9318, allocations: 5.776 MB / 0.7558 GB, free: 15.16 MB / 0.5762 GB Notification: Performance of simCode: created initialization part: time 0.009173/0.941, allocations: 4.436 MB / 0.7601 GB, free: 10.75 MB / 0.5762 GB Notification: Performance of simCode: created event and clocks part: time 1.018e-05/0.941, allocations: 0 / 0.7601 GB, free: 10.75 MB / 0.5762 GB Notification: Performance of simCode: created simulation system equations: time 0.002589/0.9436, allocations: 1.292 MB / 0.7614 GB, free: 9.445 MB / 0.5762 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003704/0.9473, allocations: 426.8 kB / 0.7618 GB, free: 9.039 MB / 0.5762 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.09257/1.04, allocations: 42.82 MB / 0.8036 GB, free: 8.809 MB / 0.6231 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005614/1.045, allocations: 3.061 MB / 0.8066 GB, free: 5.727 MB / 0.6231 GB Notification: Performance of simCode: alias equations: time 0.004765/1.05, allocations: 1.788 MB / 0.8083 GB, free: 3.938 MB / 0.6231 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003693/1.054, allocations: 1.745 MB / 0.81 GB, free: 2.191 MB / 0.6231 GB Notification: Performance of SimCode: time 1.372e-06/1.054, allocations: 0 / 0.81 GB, free: 2.191 MB / 0.6231 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4248/1.479, allocations: 64.52 MB / 0.873 GB, free: 233.3 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate platform static: time 23.12/24.59, allocations: 18.38 kB / 0.873 GB, free: 233.3 MB / 0.6544 GB " [Timeout remaining time 635] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.sim & python3 -m fmpy simulate --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2_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_Parts_Rotor1D_Rotor1D_2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Parts.Rotor1D.Rotor1D_2.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 27.73363808216527] Failed to read output from testmodel.py, exit status != 0: ['time', 'revolute.phi', 'revolute.w', 'rotor1D.phi', 'rotor1D.w'] 24.72397092776373 24.738896887 24.206577421 Calling exit ...