Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.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.001754/0.001754, allocations: 114.5 kB / 21.18 MB, free: 0.7422 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.002326/0.002326, allocations: 212.1 kB / 24.48 MB, free: 4.191 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.425/1.425, allocations: 230.2 MB / 257.8 MB, free: 8.492 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.2211/0.2211, allocations: 46.89 MB / 361.1 MB, free: 4.613 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: translateModel(ModelicaTest.MultiBody.Forces.FrameForces2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|Revolute1.phi|Revolute1.w|Revolute2.phi|Revolute2.w|Revolute3.phi|Revolute3.w|Revolute4.phi|Revolute4.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2") translateModel(ModelicaTest.MultiBody.Forces.FrameForces2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|Revolute1.phi|Revolute1.w|Revolute2.phi|Revolute2.w|Revolute3.phi|Revolute3.w|Revolute4.phi|Revolute4.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2") [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.002345/0.002345, allocations: 79.86 kB / 0.4968 GB, free: 7.793 MB / 366.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3113/0.3136, allocations: 66 MB / 0.5612 GB, free: 4.996 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.FrameForces2): time 0.02145/0.3351, allocations: 17.9 MB / 0.5787 GB, free: 3.027 MB / 414.1 MB Notification: Performance of NFInst.instExpressions: time 0.01312/0.3482, allocations: 5.665 MB / 0.5842 GB, free: 13.34 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003466/0.3516, allocations: 127.6 kB / 0.5844 GB, free: 13.21 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.01191/0.3636, allocations: 6.371 MB / 0.5906 GB, free: 6.824 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.008171/0.3717, allocations: 2.976 MB / 0.5935 GB, free: 3.836 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.004165/0.3759, allocations: 1.591 MB / 0.595 GB, free: 2.242 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.01454/0.3904, allocations: 9.859 MB / 0.6047 GB, free: 8.363 MB / 446.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.007588/0.398, allocations: 5.572 MB / 0.6101 GB, free: 2.695 MB / 446.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.008187/0.4062, allocations: 3.124 MB / 0.6132 GB, free: 15.56 MB / 462.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01072/0.4169, allocations: 4.526 MB / 0.6176 GB, free: 11.02 MB / 462.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002742/0.4197, allocations: 0.6758 MB / 0.6182 GB, free: 10.34 MB / 462.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004292/0.424, allocations: 1.736 MB / 0.6199 GB, free: 8.605 MB / 462.1 MB Notification: Performance of NFScalarize.scalarize: time 0.004909/0.4289, allocations: 3.927 MB / 0.6238 GB, free: 4.668 MB / 462.1 MB Notification: Performance of NFVerifyModel.verify: time 0.006678/0.4356, allocations: 4.188 MB / 0.6279 GB, free: 468 kB / 462.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01666/0.4522, allocations: 13 MB / 0.6405 GB, free: 3.406 MB / 478.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.045e-06/0.4522, allocations: 0 / 0.6405 GB, free: 3.406 MB / 478.1 MB Notification: Performance of FrontEnd: time 1.843e-06/0.4522, allocations: 0 / 0.6405 GB, free: 3.406 MB / 478.1 MB Notification: Performance of Transformations before backend: time 0.0004425/0.4527, allocations: 0 / 0.6405 GB, free: 3.406 MB / 478.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 2485 * Number of variables: 2485 Notification: Performance of Generate backend data structure: time 0.02378/0.4764, allocations: 9.239 MB / 0.6496 GB, free: 10.07 MB / 494.1 MB Notification: Performance of prepare preOptimizeDAE: time 5.988e-05/0.4765, allocations: 12.02 kB / 0.6496 GB, free: 10.06 MB / 494.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01865/0.4952, allocations: 3.537 MB / 0.653 GB, free: 6.512 MB / 494.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0156/0.5108, allocations: 6.032 MB / 0.6589 GB, free: 416 kB / 494.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004347/0.5112, allocations: 447.9 kB / 0.6594 GB, free: 15.96 MB / 0.4981 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002259/0.5134, allocations: 0.5943 MB / 0.6599 GB, free: 15.38 MB / 0.4981 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.04469/0.5581, allocations: 17.09 MB / 0.6766 GB, free: 13.23 MB / 0.5137 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003959/0.5585, allocations: 83.34 kB / 0.6767 GB, free: 13.15 MB / 0.5137 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001927/0.5605, allocations: 279.8 kB / 0.677 GB, free: 12.88 MB / 0.5137 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.008132/0.5686, allocations: 2.885 MB / 0.6798 GB, free: 9.988 MB / 0.5137 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04019/0.6088, allocations: 14.32 MB / 0.6938 GB, free: 11.63 MB / 0.5294 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.6076/1.216, allocations: 64.4 MB / 0.7567 GB, free: 110.5 MB / 0.5607 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01629/1.233, allocations: 6.459 MB / 0.763 GB, free: 109.8 MB / 0.5607 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.00395/1.237, allocations: 3.676 MB / 0.7666 GB, free: 108.5 MB / 0.5607 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0005375/1.237, allocations: 0.6447 MB / 0.7672 GB, free: 107.9 MB / 0.5607 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.805e-05/1.237, allocations: 62.47 kB / 0.7672 GB, free: 107.8 MB / 0.5607 GB Notification: Performance of pre-optimization done (n=239): time 5.741e-06/1.237, allocations: 3.5 kB / 0.7672 GB, free: 107.8 MB / 0.5607 GB Notification: Performance of matching and sorting (n=263): time 0.07809/1.315, allocations: 31.89 MB / 0.7984 GB, free: 84.09 MB / 0.5607 GB Notification: Performance of inlineWhenForInitialization (initialization): time 7.857e-05/1.315, allocations: 294.5 kB / 0.7987 GB, free: 83.75 MB / 0.5607 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007922/1.323, allocations: 7.285 MB / 0.8058 GB, free: 76.6 MB / 0.5607 GB Notification: Performance of collectPreVariables (initialization): time 0.0005903/1.324, allocations: 61.7 kB / 0.8058 GB, free: 76.53 MB / 0.5607 GB Notification: Performance of collectInitialEqns (initialization): time 0.002316/1.326, allocations: 5.252 MB / 0.811 GB, free: 71.84 MB / 0.5607 GB Notification: Performance of collectInitialBindings (initialization): time 0.001013/1.327, allocations: 1.064 MB / 0.812 GB, free: 70.79 MB / 0.5607 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001882/1.329, allocations: 1.865 MB / 0.8138 GB, free: 68.91 MB / 0.5607 GB Notification: Performance of setup shared object (initialization): time 4.491e-05/1.329, allocations: 301.1 kB / 0.8141 GB, free: 68.61 MB / 0.5607 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003796/1.333, allocations: 3.465 MB / 0.8175 GB, free: 65.14 MB / 0.5607 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.005411/1.338, allocations: 8.659 MB / 0.826 GB, free: 54.07 MB / 0.5607 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01085/1.349, allocations: 12.94 MB / 0.8386 GB, free: 38.73 MB / 0.5607 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001106/1.349, allocations: 52 kB / 0.8386 GB, free: 38.68 MB / 0.5607 GB Notification: Performance of matching and sorting (n=1172) (initialization): time 0.02027/1.37, allocations: 13.89 MB / 0.8522 GB, free: 24.77 MB / 0.5607 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001052/1.37, allocations: 84.03 kB / 0.8523 GB, free: 24.68 MB / 0.5607 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000159/1.37, allocations: 180 kB / 0.8525 GB, free: 24.51 MB / 0.5607 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.005264/1.375, allocations: 2.285 MB / 0.8547 GB, free: 22.21 MB / 0.5607 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00623/1.381, allocations: 2.074 MB / 0.8567 GB, free: 20.13 MB / 0.5607 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01476/1.396, allocations: 16.49 MB / 0.8728 GB, free: 3.102 MB / 0.5607 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00649/1.403, allocations: 0.4995 MB / 0.8733 GB, free: 2.602 MB / 0.5607 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009631/1.404, allocations: 0.5194 MB / 0.8738 GB, free: 2.082 MB / 0.5607 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 825 * Number of states: 0 () * Number of discrete variables: 74 (frameForce4.arrow.twoHeadedArrow,frameForce4.arrow.headAtOrigin,frameForce3.arrow.twoHeadedArrow,frameForce3.arrow.headAtOrigin,frameForce2.arrow.twoHeadedArrow,frameForce2.arrow.headAtOrigin,frameForce1.arrow.twoHeadedArrow,frameForce1.arrow.headAtOrigin,frameForce4.color[3],frameForce4.color[2],frameForce4.color[1],BodyCylinder4.color[3],BodyCylinder4.color[2],BodyCylinder4.color[1],BodyCylinder4.body.sphereColor[3],BodyCylinder4.body.sphereColor[2],BodyCylinder4.body.sphereColor[1],Revolute4.cylinderColor[3],Revolute4.cylinderColor[2],Revolute4.cylinderColor[1],frameForce3.color[3],frameForce3.color[2],frameForce3.color[1],BodyCylinder3.color[3],BodyCylinder3.color[2],BodyCylinder3.color[1],BodyCylinder3.body.sphereColor[3],BodyCylinder3.body.sphereColor[2],BodyCylinder3.body.sphereColor[1],Revolute3.cylinderColor[3],Revolute3.cylinderColor[2],Revolute3.cylinderColor[1],frameForce2.color[3],frameForce2.color[2],frameForce2.color[1],fixed2.color[3],fixed2.color[2],fixed2.color[1],BodyCylinder2.color[3],BodyCylinder2.color[2],BodyCylinder2.color[1],BodyCylinder2.body.sphereColor[3],BodyCylinder2.body.sphereColor[2],BodyCylinder2.body.sphereColor[1],Revolute2.cylinderColor[3],Revolute2.cylinderColor[2],Revolute2.cylinderColor[1],frameForce1.color[3],frameForce1.color[2],frameForce1.color[1],fixed1.color[3],fixed1.color[2],fixed1.color[1],BodyCylinder1.color[3],BodyCylinder1.color[2],BodyCylinder1.color[1],BodyCylinder1.body.sphereColor[3],BodyCylinder1.body.sphereColor[2],BodyCylinder1.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 (1096): * Single equations (assignments): 1089 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 4 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 4 systems {(1,13,100.0%), (1,13,100.0%), (2,12,100.0%), (1,13,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.004931/1.408, allocations: 3.402 MB / 0.8771 GB, free: 14.66 MB / 0.5763 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001279/1.41, allocations: 0.6325 MB / 0.8778 GB, free: 14.03 MB / 0.5763 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0125/1.422, allocations: 6.507 MB / 0.8841 GB, free: 7.488 MB / 0.5763 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.603e-05/1.422, allocations: 15.95 kB / 0.8841 GB, free: 7.473 MB / 0.5763 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.855e-06/1.422, allocations: 7.875 kB / 0.8841 GB, free: 7.465 MB / 0.5763 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001935/1.424, allocations: 0.6998 MB / 0.8848 GB, free: 6.742 MB / 0.5763 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001004/1.425, allocations: 44 kB / 0.8849 GB, free: 6.699 MB / 0.5763 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002463/1.428, allocations: 195.7 kB / 0.885 GB, free: 6.508 MB / 0.5763 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004281/1.428, allocations: 190.2 kB / 0.8852 GB, free: 6.328 MB / 0.5763 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01387/1.442, allocations: 6.987 MB / 0.892 GB, free: 15.38 MB / 0.5919 GB Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01231/1.454, allocations: 8.515 MB / 0.9004 GB, free: 6.793 MB / 0.5919 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002994/1.457, allocations: 2.092 MB / 0.9024 GB, free: 4.707 MB / 0.5919 GB Notification: Performance of sorting global known variables: time 0.007562/1.465, allocations: 4.639 MB / 0.9069 GB, free: 96 kB / 0.5919 GB Notification: Performance of Backend: time 2.3e-07/1.465, allocations: 0 / 0.9069 GB, free: 96 kB / 0.5919 GB Notification: Performance of simCode: created initialization part: time 0.01371/1.479, allocations: 6.515 MB / 0.9133 GB, free: 9.578 MB / 0.6075 GB Notification: Performance of SimCode: time 0.02779/1.506, allocations: 16.47 MB / 0.9294 GB, free: 9.055 MB / 0.6232 GB Notification: Performance of Templates: time 0.4059/1.912, allocations: 79.27 MB / 1.007 GB, free: 259.9 MB / 0.6857 GB " [Timeout remaining time 658] make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.makefile [Timeout 660] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2 -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.pipe 2>&1) [Timeout 480] diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Forces/FrameForces2/FrameForces2.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForces2.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660] "" [Timeout remaining time 660] Reference file matches [Calling sys.exit(0), Time elapsed: 8.615258476696908]