Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.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.001766/0.001766, allocations: 116.4 kB / 18.5 MB, free: 4.559 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.002215/0.002215, allocations: 208.9 kB / 21.46 MB, free: 1.605 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.542/1.542, allocations: 230.2 MB / 254.5 MB, free: 15.26 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.2394/0.2394, allocations: 46.88 MB / 357.4 MB, free: 10.53 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.Joints.Revolute2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Revolute2",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.Revolute2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Revolute2",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.002336/0.002336, allocations: 79.88 kB / 0.4912 GB, free: 8.547 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1351/0.1375, allocations: 65.99 MB / 0.5557 GB, free: 6.434 MB / 414.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.Revolute2): time 0.02206/0.1595, allocations: 18.22 MB / 0.5735 GB, free: 4.129 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.01328/0.1728, allocations: 5.323 MB / 0.5787 GB, free: 14.79 MB / 446.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.00354/0.1764, allocations: 107.7 kB / 0.5788 GB, free: 14.68 MB / 446.1 MB Notification: Performance of NFTyping.typeComponents: time 0.007293/0.1836, allocations: 3.099 MB / 0.5818 GB, free: 11.57 MB / 446.1 MB Notification: Performance of NFTyping.typeBindings: time 0.008128/0.1918, allocations: 2.607 MB / 0.5843 GB, free: 8.953 MB / 446.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003028/0.1948, allocations: 1.01 MB / 0.5853 GB, free: 7.941 MB / 446.1 MB Notification: Performance of NFFlatten.flatten: time 0.01387/0.2087, allocations: 7.673 MB / 0.5928 GB, free: 264 kB / 446.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.3314/0.5401, allocations: 3.636 MB / 0.5964 GB, free: 6.812 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.005474/0.5455, allocations: 2.263 MB / 0.5986 GB, free: 6.699 MB / 446.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.006932/0.5525, allocations: 3.516 MB / 0.602 GB, free: 6.355 MB / 446.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002496/0.555, allocations: 452.6 kB / 0.6024 GB, free: 6.355 MB / 446.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004464/0.5594, allocations: 1.33 MB / 0.6037 GB, free: 6.355 MB / 446.1 MB Notification: Performance of NFScalarize.scalarize: time 0.005161/0.5646, allocations: 2.879 MB / 0.6065 GB, free: 6.008 MB / 446.1 MB Notification: Performance of NFVerifyModel.verify: time 0.007197/0.5718, allocations: 3.222 MB / 0.6097 GB, free: 4.859 MB / 446.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01589/0.5877, allocations: 10.08 MB / 0.6195 GB, free: 1.809 MB / 446.1 MB Notification: Performance of FrontEnd - DAE generated: time 7.464e-06/0.5877, allocations: 0 / 0.6195 GB, free: 1.809 MB / 446.1 MB Notification: Performance of FrontEnd: time 2.284e-06/0.5877, allocations: 0 / 0.6195 GB, free: 1.809 MB / 446.1 MB Notification: Performance of Transformations before backend: time 0.0004621/0.5881, allocations: 4 kB / 0.6195 GB, free: 1.805 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: 2169 * Number of variables: 2169 Notification: Performance of Generate backend data structure: time 0.0344/0.6225, allocations: 7.511 MB / 0.6269 GB, free: 12.85 MB / 462.1 MB Notification: Performance of prepare preOptimizeDAE: time 5.514e-05/0.6226, allocations: 9.625 kB / 0.6269 GB, free: 12.85 MB / 462.1 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.004108/0.6267, allocations: 1.009 MB / 0.6279 GB, free: 12.21 MB / 462.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0301/0.6568, allocations: 5.652 MB / 0.6334 GB, free: 9.375 MB / 462.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01527/0.6721, allocations: 4.894 MB / 0.6382 GB, free: 6.77 MB / 462.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004064/0.6725, allocations: 318.7 kB / 0.6385 GB, free: 6.672 MB / 462.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002639/0.6751, allocations: 435.3 kB / 0.6389 GB, free: 6.566 MB / 462.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.03886/0.714, allocations: 15.52 MB / 0.654 GB, free: 7.012 MB / 478.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0004086/0.7144, allocations: 64 kB / 0.6541 GB, free: 6.969 MB / 478.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002491/0.7169, allocations: 198.8 kB / 0.6543 GB, free: 6.828 MB / 478.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.007416/0.7243, allocations: 3.385 MB / 0.6576 GB, free: 3.477 MB / 478.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03885/0.7632, allocations: 14.69 MB / 0.6719 GB, free: 4.969 MB / 494.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.1097/0.8729, allocations: 54.86 MB / 0.7255 GB, free: 13.76 MB / 0.545 GB Notification: Performance of preOpt comSubExp (simulation): time 0.02049/0.8933, allocations: 7.813 MB / 0.7331 GB, free: 5.723 MB / 0.545 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.007914/0.9013, allocations: 4.394 MB / 0.7374 GB, free: 1.316 MB / 0.545 GB Notification: Performance of preOpt evalFunc (simulation): time 0.003004/0.9043, allocations: 2.218 MB / 0.7396 GB, free: 14.73 MB / 0.5606 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.664e-05/0.9044, allocations: 68.58 kB / 0.7397 GB, free: 14.66 MB / 0.5606 GB Notification: Performance of pre-optimization done (n=367): time 8.246e-06/0.9044, allocations: 0.6562 kB / 0.7397 GB, free: 14.66 MB / 0.5606 GB Notification: Performance of matching and sorting (n=419): time 0.1265/1.031, allocations: 39.65 MB / 0.7784 GB, free: 7.074 MB / 0.5919 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001491/1.031, allocations: 123.2 kB / 0.7785 GB, free: 6.938 MB / 0.5919 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0101/1.041, allocations: 4.319 MB / 0.7827 GB, free: 2.602 MB / 0.5919 GB Notification: Performance of collectPreVariables (initialization): time 0.001244/1.042, allocations: 85.7 kB / 0.7828 GB, free: 2.512 MB / 0.5919 GB Notification: Performance of collectInitialEqns (initialization): time 0.003959/1.046, allocations: 3.964 MB / 0.7867 GB, free: 14.55 MB / 0.6075 GB Notification: Performance of collectInitialBindings (initialization): time 0.003195/1.05, allocations: 1.557 MB / 0.7882 GB, free: 13.02 MB / 0.6075 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.006131/1.056, allocations: 1.532 MB / 0.7897 GB, free: 11.48 MB / 0.6075 GB Notification: Performance of setup shared object (initialization): time 0.0001452/1.056, allocations: 301.1 kB / 0.79 GB, free: 11.19 MB / 0.6075 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.00775/1.064, allocations: 3.942 MB / 0.7938 GB, free: 7.242 MB / 0.6075 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01328/1.077, allocations: 7.697 MB / 0.8014 GB, free: 13.81 MB / 0.6231 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.02203/1.099, allocations: 12.65 MB / 0.8137 GB, free: 15.87 MB / 0.6387 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001465/1.099, allocations: 40 kB / 0.8137 GB, free: 15.83 MB / 0.6387 GB Notification: Performance of matching and sorting (n=1008) (initialization): time 0.0362/1.135, allocations: 14.17 MB / 0.8276 GB, free: 1.797 MB / 0.6387 GB Notification: Performance of prepare postOptimizeDAE: time 0.0002218/1.135, allocations: 60 kB / 0.8276 GB, free: 1.738 MB / 0.6387 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003207/1.136, allocations: 128 kB / 0.8278 GB, free: 1.613 MB / 0.6387 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.01342/1.149, allocations: 2.675 MB / 0.8304 GB, free: 14.93 MB / 0.6544 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01106/1.16, allocations: 1.762 MB / 0.8321 GB, free: 13.16 MB / 0.6544 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3541/1.514, allocations: 15.22 MB / 0.8469 GB, free: 223.2 MB / 0.6544 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008636/1.523, allocations: 491.2 kB / 0.8474 GB, free: 223.2 MB / 0.6544 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003165/1.526, allocations: 425.6 kB / 0.8478 GB, free: 223.2 MB / 0.6544 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 583 * Number of states: 0 () * Number of discrete variables: 50 (axis.arrowLine.twoHeadedArrow,axis.headAtOrigin,bodyCylinder.color[3],bodyCylinder.color[2],bodyCylinder.color[1],bodyCylinder.body.sphereColor[3],bodyCylinder.body.sphereColor[2],bodyCylinder.body.sphereColor[1],revolute3.cylinderColor[3],revolute3.cylinderColor[2],revolute3.cylinderColor[1],body2.sphereColor[3],body2.sphereColor[2],body2.sphereColor[1],revolute2.cylinderColor[3],revolute2.cylinderColor[2],revolute2.cylinderColor[1],body1.sphereColor[3],body1.sphereColor[2],body1.sphereColor[1],revolute1.cylinderColor[3],revolute1.cylinderColor[2],revolute1.cylinderColor[1],frame_a1.color_x[3],frame_a1.color_x[2],frame_a1.color_x[1],frame_a.color_x[3],frame_a.color_x[2],frame_a.color_x[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[1],axis.color[3],axis.color[2],axis.color[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (911): * Single equations (assignments): 903 * Array equations: 6 * 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 {(6,32,69.4%), (1,12,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.01056/1.537, allocations: 2.868 MB / 0.8506 GB, free: 223.1 MB / 0.6544 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001383/1.538, allocations: 485.3 kB / 0.8511 GB, free: 223.1 MB / 0.6544 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02222/1.56, allocations: 10.53 MB / 0.8614 GB, free: 220.8 MB / 0.6544 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001172/1.56, allocations: 62.12 kB / 0.8614 GB, free: 220.8 MB / 0.6544 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.232e-05/1.56, allocations: 11.44 kB / 0.8614 GB, free: 220.8 MB / 0.6544 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001014/1.561, allocations: 22.36 kB / 0.8615 GB, free: 220.8 MB / 0.6544 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04036/1.601, allocations: 21.19 MB / 0.8822 GB, free: 213.9 MB / 0.6544 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.875e-05/1.601, allocations: 8 kB / 0.8822 GB, free: 213.9 MB / 0.6544 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003667/1.605, allocations: 0.6812 MB / 0.8828 GB, free: 213.9 MB / 0.6544 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.006874/1.612, allocations: 2.485 MB / 0.8853 GB, free: 213.5 MB / 0.6544 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001158/1.613, allocations: 82.53 kB / 0.8853 GB, free: 213.5 MB / 0.6544 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01569/1.628, allocations: 13.84 MB / 0.8988 GB, free: 204.1 MB / 0.6544 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.238e-05/1.628, allocations: 16.59 kB / 0.8989 GB, free: 204.1 MB / 0.6544 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0255/1.654, allocations: 15.1 MB / 0.9136 GB, free: 189.7 MB / 0.6544 GB Notification: Performance of postOpt removeConstants (simulation): time 0.00186/1.656, allocations: 0.511 MB / 0.9141 GB, free: 189.3 MB / 0.6544 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001782/1.658, allocations: 75.98 kB / 0.9142 GB, free: 189.3 MB / 0.6544 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002903/1.66, allocations: 304.3 kB / 0.9145 GB, free: 189 MB / 0.6544 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007418/1.661, allocations: 270.9 kB / 0.9147 GB, free: 188.7 MB / 0.6544 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002932/1.661, allocations: 116.8 kB / 0.9148 GB, free: 188.6 MB / 0.6544 GB Notification: Performance of sorting global known variables: time 0.004141/1.666, allocations: 2.704 MB / 0.9175 GB, free: 185.9 MB / 0.6544 GB Notification: Performance of sort global known variables: time 8e-08/1.666, allocations: 0 / 0.9175 GB, free: 185.9 MB / 0.6544 GB Notification: Performance of remove unused functions: time 0.009591/1.675, allocations: 1.615 MB / 0.9191 GB, free: 184.3 MB / 0.6544 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 49 * Number of states: 7 (revolute.phi,revolute.w,constantSpeed.phi,revolute2.phi,revolute2.w,revolute3.phi,revolute3.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 (334): * Single equations (assignments): 326 * Array equations: 6 * 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,9,100.0%), (6,29,69.4%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0225/1.698, allocations: 15.83 MB / 0.9345 GB, free: 168.2 MB / 0.6544 GB Notification: Performance of simCode: created initialization part: time 0.01472/1.712, allocations: 9.429 MB / 0.9437 GB, free: 158.8 MB / 0.6544 GB Notification: Performance of simCode: created event and clocks part: time 1.589e-05/1.712, allocations: 4 kB / 0.9437 GB, free: 158.8 MB / 0.6544 GB Notification: Performance of simCode: created simulation system equations: time 0.007408/1.72, allocations: 4.009 MB / 0.9476 GB, free: 154.8 MB / 0.6544 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005831/1.726, allocations: 0.7339 MB / 0.9484 GB, free: 154.1 MB / 0.6544 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.118/1.844, allocations: 70.15 MB / 1.017 GB, free: 77.94 MB / 0.6544 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007947/1.852, allocations: 4.535 MB / 1.021 GB, free: 73.36 MB / 0.6544 GB Notification: Performance of simCode: alias equations: time 0.006463/1.858, allocations: 2.724 MB / 1.024 GB, free: 70.62 MB / 0.6544 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004866/1.863, allocations: 2.823 MB / 1.027 GB, free: 67.8 MB / 0.6544 GB Notification: Performance of SimCode: time 1.603e-06/1.863, allocations: 4 kB / 1.027 GB, free: 67.8 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4909/2.354, allocations: 113.8 MB / 1.138 GB, free: 204.2 MB / 0.6544 GB Notification: Performance of buildModelFMU: Generate platform static: time 22.05/24.41, allocations: 8 kB / 1.138 GB, free: 204.2 MB / 0.6544 GB " [Timeout remaining time 635] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Revolute2_fmu --startTime=0 --stopTime=1.1 --stepSize=0.000219912 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Revolute2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Revolute2.pipe 2>&1) [Timeout 52.5] [Calling os._exit(0), Time elapsed: 27.64240448223427] Failed to read output from testmodel.py, exit status != 0: ['time', 'revolute.phi', 'revolute.w', 'revolute1.phi', 'revolute2.phi', 'revolute2.w', 'revolute3.phi', 'revolute3.w'] 24.56565582472831 24.580309265 23.989285714 Calling exit ...