Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.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.001837/0.001837, 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.001583/0.001583, 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.427/1.427, allocations: 230.2 MB / 257.8 MB, free: 8.508 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.2212/0.2212, allocations: 46.9 MB / 361.1 MB, free: 4.633 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.Sensors.CutForce,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|cutForce1a.force.3.|cutForce2a.force.3.|cutForce2b.force.3.|cutForce1a.force.2.|cutForce2a.force.2.|cutForce2b.force.2.|cutForce1a.force.1.|cutForce2a.force.1.|cutForce2b.force.1.",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce")
translateModel(ModelicaTest.MultiBody.Sensors.CutForce,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|cutForce1a.force.3.|cutForce2a.force.3.|cutForce2b.force.3.|cutForce1a.force.2.|cutForce2a.force.2.|cutForce2b.force.2.|cutForce1a.force.1.|cutForce2a.force.1.|cutForce2b.force.1.",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce") [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.002341/0.002341, allocations: 83.88 kB / 0.4968 GB, free: 7.828 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2999/0.3023, allocations: 65.99 MB / 0.5612 GB, free: 5.008 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Sensors.CutForce): time 0.01717/0.3195, allocations: 15.08 MB / 0.5759 GB, free: 5.867 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01052/0.33, allocations: 5.089 MB / 0.5809 GB, free: 0.7617 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002136/0.3321, allocations: 115.7 kB / 0.581 GB, free: 0.6484 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01081/0.3429, allocations: 6.071 MB / 0.5869 GB, free: 10.56 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006634/0.3496, allocations: 2.802 MB / 0.5897 GB, free: 7.746 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003226/0.3528, allocations: 1.579 MB / 0.5912 GB, free: 6.164 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01019/0.363, allocations: 7.803 MB / 0.5988 GB, free: 14.35 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006886/0.3699, allocations: 5.108 MB / 0.6038 GB, free: 9.145 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004567/0.3744, allocations: 2.956 MB / 0.6067 GB, free: 6.18 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006233/0.3807, allocations: 4.093 MB / 0.6107 GB, free: 2.074 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001718/0.3824, allocations: 0.5703 MB / 0.6113 GB, free: 1.504 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003298/0.3857, allocations: 1.432 MB / 0.6127 GB, free: 72 kB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003902/0.3896, allocations: 3.603 MB / 0.6162 GB, free: 12.45 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004211/0.3938, allocations: 3.841 MB / 0.6199 GB, free: 8.59 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0127/0.4065, allocations: 11.64 MB / 0.6313 GB, free: 12.91 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.599e-06/0.4065, allocations: 4 kB / 0.6313 GB, free: 12.9 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.573e-06/0.4065, allocations: 0 / 0.6313 GB, free: 12.9 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 0.0002411/0.4067, allocations: 4 kB / 0.6313 GB, free: 12.9 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: 2471
 * Number of variables: 2471
Notification: Performance of Generate backend data structure: time 0.01527/0.422, allocations: 8.678 MB / 0.6398 GB, free: 4.125 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.851e-05/0.4221, allocations: 11.97 kB / 0.6398 GB, free: 4.113 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00938/0.4314, allocations: 2.527 MB / 0.6423 GB, free: 1.578 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01058/0.442, allocations: 4.53 MB / 0.6467 GB, free: 12.97 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004147/0.4424, allocations: 426.5 kB / 0.6471 GB, free: 12.55 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001503/0.4439, allocations: 0.5427 MB / 0.6476 GB, free: 12.01 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03397/0.4779, allocations: 16.67 MB / 0.6639 GB, free: 10.22 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002446/0.4781, allocations: 79.44 kB / 0.664 GB, free: 10.14 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001355/0.4795, allocations: 263.7 kB / 0.6642 GB, free: 9.883 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004883/0.4844, allocations: 2.924 MB / 0.6671 GB, free: 6.957 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02858/0.513, allocations: 12.78 MB / 0.6796 GB, free: 10.15 MB / 0.5137 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.5591/1.072, allocations: 64.1 MB / 0.7422 GB, free: 110.1 MB / 0.5607 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0145/1.087, allocations: 5.337 MB / 0.7474 GB, free: 109.5 MB / 0.5607 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002277/1.089, allocations: 2.004 MB / 0.7493 GB, free: 109.4 MB / 0.5607 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01382/1.103, allocations: 9.946 MB / 0.7591 GB, free: 105.6 MB / 0.5607 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.892e-05/1.103, allocations: 57.72 kB / 0.7591 GB, free: 105.6 MB / 0.5607 GB
Notification: Performance of pre-optimization done (n=182): time 5.069e-06/1.103, allocations: 0 / 0.7591 GB, free: 105.6 MB / 0.5607 GB
Notification: Performance of matching and sorting (n=206): time 0.03185/1.135, allocations: 14.44 MB / 0.7732 GB, free: 98.42 MB / 0.5607 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.355e-05/1.135, allocations: 149.4 kB / 0.7733 GB, free: 98.25 MB / 0.5607 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00603/1.141, allocations: 6.31 MB / 0.7795 GB, free: 92.06 MB / 0.5607 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001661/1.141, allocations: 53.7 kB / 0.7796 GB, free: 92 MB / 0.5607 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001828/1.143, allocations: 5.275 MB / 0.7847 GB, free: 87.12 MB / 0.5607 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004483/1.143, allocations: 0.856 MB / 0.7855 GB, free: 86.28 MB / 0.5607 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001407/1.144, allocations: 1.694 MB / 0.7872 GB, free: 84.57 MB / 0.5607 GB
Notification: Performance of setup shared object (initialization): time 3.879e-05/1.144, allocations: 305.1 kB / 0.7875 GB, free: 84.27 MB / 0.5607 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002608/1.147, allocations: 2.326 MB / 0.7898 GB, free: 81.94 MB / 0.5607 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003917/1.151, allocations: 7.678 MB / 0.7973 GB, free: 71.83 MB / 0.5607 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007049/1.158, allocations: 10.87 MB / 0.8079 GB, free: 58.63 MB / 0.5607 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.425e-05/1.158, allocations: 56 kB / 0.8079 GB, free: 58.58 MB / 0.5607 GB
Notification: Performance of matching and sorting (n=1107) (initialization): time 0.01106/1.169, allocations: 9.676 MB / 0.8174 GB, free: 48.91 MB / 0.5607 GB
Notification: Performance of prepare postOptimizeDAE: time 7.578e-05/1.169, allocations: 94.66 kB / 0.8175 GB, free: 48.82 MB / 0.5607 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001119/1.169, allocations: 192 kB / 0.8177 GB, free: 48.63 MB / 0.5607 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001519/1.17, allocations: 252 kB / 0.8179 GB, free: 48.38 MB / 0.5607 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004628/1.174, allocations: 1.922 MB / 0.8198 GB, free: 46.46 MB / 0.5607 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001642/1.174, allocations: 376.7 kB / 0.8201 GB, free: 46.09 MB / 0.5607 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0046/1.179, allocations: 411.6 kB / 0.8205 GB, free: 45.69 MB / 0.5607 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004309/1.179, allocations: 0.5191 MB / 0.821 GB, free: 45.17 MB / 0.5607 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 867
 * Number of states: 0 ()
 * Number of discrete variables: 68 (cutForce2b.forceArrow.twoHeadedArrow,cutForce2b.forceArrow.headAtOrigin,cutForce1b.forceArrow.twoHeadedArrow,cutForce1b.forceArrow.headAtOrigin,cutForce2a.forceArrow.twoHeadedArrow,cutForce2a.forceArrow.headAtOrigin,cutForce1a.forceArrow.twoHeadedArrow,cutForce1a.forceArrow.headAtOrigin,rotate2b.color[3],rotate2b.color[2],rotate2b.color[1],body2b.sphereColor[3],body2b.sphereColor[2],body2b.sphereColor[1],translate2b.color[3],translate2b.color[2],translate2b.color[1],cutForce2b.forceColor[3],cutForce2b.forceColor[2],cutForce2b.forceColor[1],rotate1b.color[3],rotate1b.color[2],rotate1b.color[1],body1b.sphereColor[3],body1b.sphereColor[2],body1b.sphereColor[1],translate1b.color[3],translate1b.color[2],translate1b.color[1],cutForce1b.forceColor[3],cutForce1b.forceColor[2],cutForce1b.forceColor[1],rotate2a.color[3],rotate2a.color[2],rotate2a.color[1],rotate1a.color[3],rotate1a.color[2],rotate1a.color[1],body2a.sphereColor[3],body2a.sphereColor[2],body2a.sphereColor[1],translate2a.color[3],translate2a.color[2],translate2a.color[1],cutForce2a.forceColor[3],cutForce2a.forceColor[2],cutForce2a.forceColor[1],body1a.sphereColor[3],body1a.sphereColor[2],body1a.sphereColor[1],translate1a.color[3],translate1a.color[2],translate1a.color[1],cutForce1a.forceColor[3],cutForce1a.forceColor[2],cutForce1a.forceColor[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 (1043):
 * Single equations (assignments): 1032
 * Array equations: 11
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.003303/1.183, allocations: 3.288 MB / 0.8242 GB, free: 41.87 MB / 0.5607 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006202/1.183, allocations: 0.5587 MB / 0.8248 GB, free: 41.3 MB / 0.5607 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008279/1.184, allocations: 0.6201 MB / 0.8254 GB, free: 40.68 MB / 0.5607 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.28e-05/1.184, allocations: 7.969 kB / 0.8254 GB, free: 40.67 MB / 0.5607 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.939e-06/1.184, allocations: 0 / 0.8254 GB, free: 40.67 MB / 0.5607 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007566/1.185, allocations: 0.5753 MB / 0.826 GB, free: 40.07 MB / 0.5607 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005004/1.185, allocations: 35.94 kB / 0.826 GB, free: 40.04 MB / 0.5607 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001386/1.187, allocations: 87.48 kB / 0.8261 GB, free: 39.95 MB / 0.5607 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001518/1.187, allocations: 101.2 kB / 0.8262 GB, free: 39.86 MB / 0.5607 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003018/1.19, allocations: 2.483 MB / 0.8286 GB, free: 37.39 MB / 0.5607 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.002347/1.192, allocations: 2.932 MB / 0.8315 GB, free: 34.44 MB / 0.5607 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0008487/1.193, allocations: 0.8924 MB / 0.8323 GB, free: 33.54 MB / 0.5607 GB
Notification: Performance of sorting global known variables: time 0.004103/1.197, allocations: 3.935 MB / 0.8362 GB, free: 29.62 MB / 0.5607 GB
Notification: Performance of Backend: time 3e-07/1.197, allocations: 0 / 0.8362 GB, free: 29.62 MB / 0.5607 GB
Notification: Performance of simCode: created initialization part: time 0.006869/1.204, allocations: 4.498 MB / 0.8406 GB, free: 25.17 MB / 0.5607 GB
Notification: Performance of SimCode: time 0.0181/1.222, allocations: 14.49 MB / 0.8547 GB, free: 10.61 MB / 0.5607 GB
Notification: Performance of Templates: time 0.1036/1.326, allocations: 52.14 MB / 0.9056 GB, free: 6.73 MB / 0.6075 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Sensors/CutForce/CutForce.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.CutForce.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: 7.488094168249518]