Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.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.001297/0.001297, allocations: 114.3 kB / 21.18 MB, free: 0.6289 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.001462/0.001462, allocations: 210.9 kB / 24.48 MB, free: 4.035 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.39/1.39, allocations: 230.2 MB / 257.8 MB, free: 8.496 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.2198/0.2198, allocations: 46.9 MB / 361.1 MB, free: 4.625 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.Force2,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|Revolute5.phi|Revolute5.w|Revolute6.phi|Revolute6.w|Revolute7.phi|Revolute7.w|Revolute8.phi|Revolute8.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2")
translateModel(ModelicaTest.MultiBody.Forces.Force2,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|Revolute5.phi|Revolute5.w|Revolute6.phi|Revolute6.w|Revolute7.phi|Revolute7.w|Revolute8.phi|Revolute8.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2") [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.002307/0.002307, allocations: 79.88 kB / 0.4968 GB, free: 7.785 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2843/0.2866, allocations: 65.99 MB / 0.5612 GB, free: 4.977 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.Force2): time 0.02813/0.3147, allocations: 26.13 MB / 0.5867 GB, free: 10.74 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01694/0.3317, allocations: 7.265 MB / 0.5938 GB, free: 3.445 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004336/0.336, allocations: 191.5 kB / 0.594 GB, free: 3.258 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01954/0.3555, allocations: 10.53 MB / 0.6043 GB, free: 8.699 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01026/0.3658, allocations: 4.069 MB / 0.6083 GB, free: 4.613 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004743/0.3705, allocations: 2.048 MB / 0.6103 GB, free: 2.562 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01809/0.3886, allocations: 12.68 MB / 0.6227 GB, free: 5.852 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0127/0.4013, allocations: 8.772 MB / 0.6312 GB, free: 12.97 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008786/0.4101, allocations: 4.615 MB / 0.6357 GB, free: 8.344 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01125/0.4214, allocations: 6.554 MB / 0.6421 GB, free: 1.77 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003484/0.4248, allocations: 1.02 MB / 0.6431 GB, free: 0.75 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005327/0.4302, allocations: 1.92 MB / 0.645 GB, free: 14.83 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.007795/0.438, allocations: 5.809 MB / 0.6507 GB, free: 9.004 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01188/0.4499, allocations: 6.258 MB / 0.6568 GB, free: 2.711 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02661/0.4765, allocations: 18.9 MB / 0.6753 GB, free: 15.73 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 6.152e-06/0.4765, allocations: 0 / 0.6753 GB, free: 15.73 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 2.184e-06/0.4765, allocations: 0 / 0.6753 GB, free: 15.73 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 0.0007036/0.4772, allocations: 0 / 0.6753 GB, free: 15.73 MB / 0.5137 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3835
 * Number of variables: 3835
Notification: Performance of Generate backend data structure: time 0.03612/0.5133, allocations: 13.83 MB / 0.6888 GB, free: 1.777 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 5.062e-05/0.5133, allocations: 12.03 kB / 0.6888 GB, free: 1.766 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02136/0.5347, allocations: 4.088 MB / 0.6928 GB, free: 13.66 MB / 0.5294 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02122/0.5559, allocations: 8.016 MB / 0.7006 GB, free: 5.555 MB / 0.5294 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007392/0.5567, allocations: 0.6701 MB / 0.7012 GB, free: 4.883 MB / 0.5294 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003503/0.5602, allocations: 0.9096 MB / 0.7021 GB, free: 3.973 MB / 0.5294 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.05588/0.616, allocations: 26.2 MB / 0.7277 GB, free: 8.246 MB / 0.5606 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004318/0.6165, allocations: 107.2 kB / 0.7278 GB, free: 8.141 MB / 0.5606 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00244/0.6189, allocations: 427.7 kB / 0.7282 GB, free: 7.723 MB / 0.5606 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.009311/0.6282, allocations: 4.57 MB / 0.7327 GB, free: 3.133 MB / 0.5606 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.4675/1.096, allocations: 22.09 MB / 0.7543 GB, free: 134.2 MB / 0.5607 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.1019/1.198, allocations: 101.8 MB / 0.8537 GB, free: 33.61 MB / 0.5607 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03208/1.23, allocations: 12.9 MB / 0.8663 GB, free: 20.54 MB / 0.5607 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008136/1.238, allocations: 7.28 MB / 0.8734 GB, free: 13.24 MB / 0.5607 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02944/1.267, allocations: 18.14 MB / 0.8911 GB, free: 11.2 MB / 0.5763 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.852e-05/1.267, allocations: 86.55 kB / 0.8912 GB, free: 11.1 MB / 0.5763 GB
Notification: Performance of pre-optimization done (n=469): time 7.213e-06/1.267, allocations: 0 / 0.8912 GB, free: 11.1 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=553): time 0.1872/1.455, allocations: 69.71 MB / 0.9593 GB, free: 5.535 MB / 0.6388 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002541/1.455, allocations: 368.7 kB / 0.9597 GB, free: 5.102 MB / 0.6388 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02111/1.476, allocations: 11.43 MB / 0.9708 GB, free: 9.777 MB / 0.6544 GB
Notification: Performance of collectPreVariables (initialization): time 0.001555/1.477, allocations: 117.7 kB / 0.9709 GB, free: 9.656 MB / 0.6544 GB
Notification: Performance of collectInitialEqns (initialization): time 0.008041/1.485, allocations: 7.71 MB / 0.9785 GB, free: 1.887 MB / 0.6544 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003366/1.489, allocations: 2.151 MB / 0.9806 GB, free: 15.71 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.005826/1.495, allocations: 2.96 MB / 0.9835 GB, free: 12.74 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001362/1.495, allocations: 305.1 kB / 0.9837 GB, free: 12.44 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01029/1.505, allocations: 6.521 MB / 0.9901 GB, free: 5.914 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.2876/1.793, allocations: 14.24 MB / 1.004 GB, free: 221.2 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02215/1.815, allocations: 22.27 MB / 1.026 GB, free: 206 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0004976/1.815, allocations: 79.62 kB / 1.026 GB, free: 206 MB / 0.67 GB
Notification: Performance of matching and sorting (n=1878) (initialization): time 0.04469/1.86, allocations: 24.68 MB / 1.05 GB, free: 197.8 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002516/1.86, allocations: 120.2 kB / 1.05 GB, free: 197.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003615/1.861, allocations: 267.6 kB / 1.05 GB, free: 197.8 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01059/1.871, allocations: 4.433 MB / 1.055 GB, free: 195.7 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0119/1.883, allocations: 3.344 MB / 1.058 GB, free: 192.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02809/1.911, allocations: 28.51 MB / 1.086 GB, free: 165.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01219/1.923, allocations: 0.8571 MB / 1.087 GB, free: 165.1 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002731/1.926, allocations: 0.8201 MB / 1.087 GB, free: 164.3 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1218
 * Number of states: 0 ()
 * Number of discrete variables: 132 (force4.torqueArrow.twoHeadedArrow,force4.torqueArrow.headAtOrigin,force4.forceArrow.twoHeadedArrow,force4.forceArrow.headAtOrigin,force3.torqueArrow.twoHeadedArrow,force3.torqueArrow.headAtOrigin,force3.forceArrow.twoHeadedArrow,force3.forceArrow.headAtOrigin,force2.forceArrow.twoHeadedArrow,force2.forceArrow.headAtOrigin,force1.forceArrow.twoHeadedArrow,force1.forceArrow.headAtOrigin,fixed8.color[3],fixed8.color[2],fixed8.color[1],fixed7.color[3],fixed7.color[2],fixed7.color[1],Revolute8.cylinderColor[3],Revolute8.cylinderColor[2],Revolute8.cylinderColor[1],Revolute7.cylinderColor[3],Revolute7.cylinderColor[2],Revolute7.cylinderColor[1],Revolute6.cylinderColor[3],Revolute6.cylinderColor[2],Revolute6.cylinderColor[1],force4.connectionLineColor[3],force4.connectionLineColor[2],force4.connectionLineColor[1],force4.torqueColor[3],force4.torqueColor[2],force4.torqueColor[1],force4.forceColor[3],force4.forceColor[2],force4.forceColor[1],force3.connectionLineColor[3],force3.connectionLineColor[2],force3.connectionLineColor[1],force3.torqueColor[3],force3.torqueColor[2],force3.torqueColor[1],force3.forceColor[3],force3.forceColor[2],force3.forceColor[1],fixedRotation1.color[3],fixedRotation1.color[2],fixedRotation1.color[1],fixed6.color[3],fixed6.color[2],fixed6.color[1],body4.color[3],body4.color[2],body4.color[1],body4.body.sphereColor[3],body4.body.sphereColor[2],body4.body.sphereColor[1],fixed5.color[3],fixed5.color[2],fixed5.color[1],body3.color[3],body3.color[2],body3.color[1],body3.body.sphereColor[3],body3.body.sphereColor[2],body3.body.sphereColor[1],Revolute5.cylinderColor[3],Revolute5.cylinderColor[2],Revolute5.cylinderColor[1],fixed4.color[3],fixed4.color[2],fixed4.color[1],fixed2.color[3],fixed2.color[2],fixed2.color[1],Revolute4.cylinderColor[3],Revolute4.cylinderColor[2],Revolute4.cylinderColor[1],Revolute3.cylinderColor[3],Revolute3.cylinderColor[2],Revolute3.cylinderColor[1],Revolute2.cylinderColor[3],Revolute2.cylinderColor[2],Revolute2.cylinderColor[1],force2.connectionLineColor[3],force2.connectionLineColor[2],force2.connectionLineColor[1],force2.forceColor[3],force2.forceColor[2],force2.forceColor[1],force1.connectionLineColor[3],force1.connectionLineColor[2],force1.connectionLineColor[1],force1.forceColor[3],force1.forceColor[2],force1.forceColor[1],fixedRotation.color[3],fixedRotation.color[2],fixedRotation.color[1],fixed3.color[3],fixed3.color[2],fixed3.color[1],body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[1],fixed1.color[3],fixed1.color[2],fixed1.color[1],body1.color[3],body1.color[2],body1.color[1],body1.body.sphereColor[3],body1.body.sphereColor[2],body1.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 (1740):
 * Single equations (assignments): 1729
 * Array equations: 7
 * 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
   {(2,23,100.0%), (2,22,100.0%), (2,22,100.0%), (2,23,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.00932/1.935, allocations: 5.098 MB / 1.092 GB, free: 159.4 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00211/1.938, allocations: 1.031 MB / 1.093 GB, free: 158.9 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02282/1.96, allocations: 14.63 MB / 1.108 GB, free: 144.2 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.000122/1.961, allocations: 23.91 kB / 1.108 GB, free: 144.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.105e-05/1.961, allocations: 4 kB / 1.108 GB, free: 144.2 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00317/1.964, allocations: 1.11 MB / 1.109 GB, free: 143.6 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002786/1.966, allocations: 92 kB / 1.109 GB, free: 143.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005985/1.972, allocations: 399.4 kB / 1.109 GB, free: 143.1 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001424/1.974, allocations: 391.9 kB / 1.11 GB, free: 142.7 MB / 0.67 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02641/2, allocations: 14.87 MB / 1.124 GB, free: 128 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.02391/2.024, allocations: 18.38 MB / 1.142 GB, free: 109.4 MB / 0.67 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.005673/2.03, allocations: 4.697 MB / 1.147 GB, free: 104.7 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.01241/2.042, allocations: 7.302 MB / 1.154 GB, free: 97.47 MB / 0.67 GB
Notification: Performance of Backend: time 7.62e-07/2.042, allocations: 0.6562 kB / 1.154 GB, free: 97.47 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.02457/2.067, allocations: 11.06 MB / 1.165 GB, free: 86.46 MB / 0.67 GB
Notification: Performance of SimCode: time 0.04386/2.111, allocations: 25.43 MB / 1.189 GB, free: 61 MB / 0.67 GB
Notification: Performance of Templates: time 0.4676/2.578, allocations: 139.4 MB / 1.326 GB, free: 159.8 MB / 0.67 GB
"
[Timeout remaining time 657]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Forces/Force2/Force2.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.Force2.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: 10.614553372841328]